Apr 21 13:41:00 volumio systemd[1]: Started mpd.service - Music Player Daemon. Apr 21 13:41:00 volumio sudo[418610]: pam_unix(sudo:session): session closed for user root Apr 21 13:41:00 volumio volumio[418566]: info: Loading i18n strings for locale en Apr 21 13:41:00 volumio volumio[418566]: Updating browse sources language Apr 21 13:41:00 volumio volumio[418566]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 21 13:41:00 volumio volumio[418566]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 21 13:41:00 volumio volumio[418566]: info: CoreCommandRouter::initPlayerControls Apr 21 13:41:00 volumio volumio[418566]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 13:41:00 volumio volumio[418566]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 13:41:00 volumio volumio[418566]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 13:41:00 volumio volumio[418566]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 13:41:00 volumio volumio[418566]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 13:41:00 volumio volumio[418566]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 13:41:00 volumio volumio[418566]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 13:41:00 volumio volumio[418566]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 13:41:00 volumio volumio[418566]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 21 13:41:00 volumio volumio[418566]: /usr/share/nodejs/path-to-regexp/dist/index.js:114 Apr 21 13:41:00 volumio volumio[418566]: throw new TypeError("Unexpected ".concat(nextType, " at ").concat(index, ", expected ").concat(type)); Apr 21 13:41:00 volumio volumio[418566]: ^ Apr 21 13:41:00 volumio volumio[418566]: TypeError: Unexpected MODIFIER at 1, expected END Apr 21 13:41:00 volumio volumio[418566]: at mustConsume (/usr/share/nodejs/path-to-regexp/dist/index.js:114:15) Apr 21 13:41:00 volumio volumio[418566]: at parse (/usr/share/nodejs/path-to-regexp/dist/index.js:172:9) Apr 21 13:41:00 volumio volumio[418566]: at stringToRegexp (/usr/share/nodejs/path-to-regexp/dist/index.js:329:27) Apr 21 13:41:00 volumio volumio[418566]: at pathToRegexp (/usr/share/nodejs/path-to-regexp/dist/index.js:407:12) Apr 21 13:41:00 volumio volumio[418566]: at new Layer (/usr/share/nodejs/express/lib/router/layer.js:59:17) Apr 21 13:41:00 volumio volumio[418566]: at Function.route (/usr/share/nodejs/express/lib/router/index.js:505:15) Apr 21 13:41:00 volumio volumio[418566]: at app. [as get] (/usr/share/nodejs/express/lib/application.js:498:30) Apr 21 13:41:00 volumio volumio[418566]: at Object. (/volumio/index.js:59:12) Apr 21 13:41:00 volumio volumio[418566]: at Module._compile (node:internal/modules/cjs/loader:1356:14) Apr 21 13:41:00 volumio volumio[418566]: at Module._extensions..js (node:internal/modules/cjs/loader:1414:10) Apr 21 13:41:00 volumio volumio[418566]: at Module.load (node:internal/modules/cjs/loader:1197:32) Apr 21 13:41:00 volumio volumio[418566]: at Module._load (node:internal/modules/cjs/loader:1013:12) Apr 21 13:41:00 volumio volumio[418566]: at Function.executeUserEntryPoint [as runMain] (node:internal/modules/run_main:128:12) Apr 21 13:41:00 volumio volumio[418566]: at node:internal/main/run_main_module:28:49 Apr 21 13:41:00 volumio volumio[418566]: Node.js v18.19.0 Apr 21 13:41:00 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 21 13:41:00 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 21 13:41:00 volumio systemd[1]: volumio.service: Consumed 13.248s CPU time. Apr 21 13:41:00 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 6798. Apr 21 13:41:00 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Apr 21 13:41:00 volumio systemd[1]: volumio.service: Consumed 13.248s CPU time. Apr 21 13:41:00 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Apr 21 13:41:04 volumio volumio[418628]: info: ------------------------------------------- Apr 21 13:41:04 volumio volumio[418628]: info: ----- Volumio3 ---- Apr 21 13:41:04 volumio volumio[418628]: info: ------------------------------------------- Apr 21 13:41:04 volumio volumio[418628]: info: ----- System startup ---- Apr 21 13:41:04 volumio volumio[418628]: info: ------------------------------------------- Apr 21 13:41:05 volumio volumio[418628]: info: Plugin folders cleanup Apr 21 13:41:05 volumio volumio[418628]: info: Scanning into folder /volumio/app/plugins/ Apr 21 13:41:05 volumio volumio[418628]: info: Scanning category audio_interface Apr 21 13:41:05 volumio volumio[418628]: info: Scanning category miscellanea Apr 21 13:41:05 volumio volumio[418628]: info: Scanning category music_service Apr 21 13:41:05 volumio volumio[418628]: info: Scanning category plugins.json Apr 21 13:41:05 volumio volumio[418628]: info: Scanning category system_controller Apr 21 13:41:05 volumio volumio[418628]: info: Scanning category user_interface Apr 21 13:41:05 volumio volumio[418628]: info: Scanning into folder /data/plugins/ Apr 21 13:41:05 volumio volumio[418628]: info: Plugin folders cleanup completed Apr 21 13:41:05 volumio volumio[418628]: info: ------------------------------------------- Apr 21 13:41:05 volumio volumio[418628]: info: ----- Core plugins startup ---- Apr 21 13:41:05 volumio volumio[418628]: info: ------------------------------------------- Apr 21 13:41:05 volumio volumio[418628]: info: Loading plugins from folder /volumio/app/plugins/ Apr 21 13:41:05 volumio volumio[418628]: info: Adding plugin upnp to MyMusic Plugins Apr 21 13:41:05 volumio volumio[418628]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 21 13:41:05 volumio volumio[418628]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 21 13:41:05 volumio volumio[418628]: info: Loading plugins from folder /data/plugins/ Apr 21 13:41:05 volumio volumio[418628]: info: Loading plugin "system"... Apr 21 13:41:05 volumio volumio[418628]: info: No id defined. Creating one Apr 21 13:41:05 volumio volumio[418628]: info: Loading plugin "appearance"... Apr 21 13:41:06 volumio volumio[418628]: info: Loading plugin "network"... Apr 21 13:41:07 volumio volumio[418628]: info: Refreshing Cached IP Addresses Apr 21 13:41:07 volumio sudo[418652]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 21 13:41:07 volumio sudo[418652]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 21 13:41:07 volumio sudo[418652]: pam_unix(sudo:session): session closed for user root Apr 21 13:41:07 volumio sudo[418654]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 21 13:41:07 volumio sudo[418654]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 21 13:41:07 volumio sudo[418654]: pam_unix(sudo:session): session closed for user root Apr 21 13:41:07 volumio volumio[418628]: info: Loading plugin "services"... Apr 21 13:41:07 volumio volumio[418628]: info: Loading plugin "alsa_controller"... Apr 21 13:41:07 volumio sudo[418658]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 21 13:41:07 volumio sudo[418658]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 21 13:41:07 volumio volumio[418628]: warn: The ALSA output card is not set, defaulting to card [object Object] Apr 21 13:41:07 volumio volumio[418628]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 21 13:41:07 volumio volumio[418628]: error: Could not retrieve plugin system_controller i2s_dacs Apr 21 13:41:07 volumio volumio[418628]: info: Setting mixer ACODEC for card LIBRETECH-CC Apr 21 13:41:07 volumio volumio[418628]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 21 13:41:07 volumio volumio[418628]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 21 13:41:07 volumio volumio[418628]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 21 13:41:07 volumio volumio[418628]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 21 13:41:07 volumio volumio[418628]: info: Loading plugin "wizard"... Apr 21 13:41:07 volumio volumio[418628]: info: Loading plugin "volumio_command_line_client"... Apr 21 13:41:07 volumio volumio[418628]: info: Loading plugin "upnp"... Apr 21 13:41:07 volumio volumio[418628]: info: [1713724867382] Starting Upmpd Daemon Apr 21 13:41:07 volumio volumio[418628]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 21 13:41:07 volumio volumio[418628]: info: Loading plugin "my_music"... Apr 21 13:41:07 volumio volumio[418628]: info: Loading plugin "mpd"... Apr 21 13:41:07 volumio volumio[418628]: info: Creating MPD Configuration file Apr 21 13:41:07 volumio volumio[418628]: info: Loading plugin "upnp_browser"... Apr 21 13:41:07 volumio sudo[418675]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 21 13:41:08 volumio sudo[418675]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 21 13:41:08 volumio sudo[418675]: pam_unix(sudo:session): session closed for user root Apr 21 13:41:08 volumio sudo[418677]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 21 13:41:08 volumio sudo[418677]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 21 13:41:08 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Apr 21 13:41:08 volumio systemd[1]: mpd.service: Deactivated successfully. Apr 21 13:41:08 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Apr 21 13:41:08 volumio systemd[1]: mpd.service: Consumed 3.633s CPU time. Apr 21 13:41:08 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Apr 21 13:41:10 volumio volumio[418628]: error: !!!! WARNING !!!! Apr 21 13:41:10 volumio volumio[418628]: error: The plugin music_service/upnp_browser failed to load, setting it to stopped. Error: TypeError: Entities is not a constructor Apr 21 13:41:10 volumio volumio[418628]: error: Stack trace: TypeError: Entities is not a constructor Apr 21 13:41:10 volumio volumio[418628]: at Object. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:12:18) Apr 21 13:41:10 volumio volumio[418628]: at Module._compile (node:internal/modules/cjs/loader:1356:14) Apr 21 13:41:10 volumio volumio[418628]: at Module._extensions..js (node:internal/modules/cjs/loader:1414:10) Apr 21 13:41:10 volumio volumio[418628]: at Module.load (node:internal/modules/cjs/loader:1197:32) Apr 21 13:41:10 volumio volumio[418628]: at Module._load (node:internal/modules/cjs/loader:1013:12) Apr 21 13:41:10 volumio volumio[418628]: at Module.require (node:internal/modules/cjs/loader:1225:19) Apr 21 13:41:10 volumio volumio[418628]: at require (node:internal/modules/helpers:177:18) Apr 21 13:41:10 volumio volumio[418628]: at Object. (/volumio/app/plugins/music_service/upnp_browser/index.js:12:24) Apr 21 13:41:10 volumio volumio[418628]: at Module._compile (node:internal/modules/cjs/loader:1356:14) Apr 21 13:41:10 volumio volumio[418628]: at Module._extensions..js (node:internal/modules/cjs/loader:1414:10) Apr 21 13:41:10 volumio volumio[418628]: at Module.load (node:internal/modules/cjs/loader:1197:32) Apr 21 13:41:10 volumio volumio[418628]: at Module._load (node:internal/modules/cjs/loader:1013:12) Apr 21 13:41:10 volumio volumio[418628]: at Module.require (node:internal/modules/cjs/loader:1225:19) Apr 21 13:41:10 volumio volumio[418628]: at require (node:internal/modules/helpers:177:18) Apr 21 13:41:10 volumio volumio[418628]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:235:29) Apr 21 13:41:10 volumio volumio[418628]: at /volumio/app/pluginmanager.js:338:34 Apr 21 13:41:10 volumio volumio[418628]: error: !!!! WARNING !!!! Apr 21 13:41:10 volumio volumio[418628]: info: Loading plugin "networkfs"... Apr 21 13:41:10 volumio volumio[418628]: info: Starting Udev Watcher for removable devices Apr 21 13:41:11 volumio volumio[418628]: info: Ignoring mount for partition: boot Apr 21 13:41:11 volumio volumio[418628]: info: Ignoring mount for partition: root Apr 21 13:41:11 volumio volumio[418628]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 21 13:41:11 volumio volumio[418628]: info: Loading plugin "alarm-clock"... Apr 21 13:41:11 volumio sudo[418658]: pam_unix(sudo:session): session closed for user root Apr 21 13:41:11 volumio volumio[418628]: info: Loading plugin "airplay_emulation"... Apr 21 13:41:11 volumio volumio[418628]: info: Starting Shairport Sync Apr 21 13:41:11 volumio volumio[418628]: info: Loading plugin "last_100"... Apr 21 13:41:11 volumio volumio[418628]: info: Loading plugin "webradio"... Apr 21 13:41:11 volumio volumio[418628]: info: Loading plugin "i2s_dacs"... Apr 21 13:41:11 volumio volumio[418628]: info: I2S DAC not set, start Auto-detection Apr 21 13:41:11 volumio volumio[418628]: info: Loading plugin "volumiodiscovery"... Apr 21 13:41:11 volumio volumio[418628]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 21 13:41:11 volumio node[418628]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 21 13:41:11 volumio volumio[418628]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 21 13:41:11 volumio node[418628]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 21 13:41:11 volumio volumio[418628]: *** WARNING *** For more information see Apr 21 13:41:11 volumio node[418628]: *** WARNING *** For more information see Apr 21 13:41:11 volumio volumio[418628]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 21 13:41:11 volumio node[418628]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 21 13:41:11 volumio volumio[418628]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 21 13:41:11 volumio node[418628]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 21 13:41:11 volumio volumio[418628]: *** WARNING *** For more information see Apr 21 13:41:11 volumio node[418628]: *** WARNING *** For more information see Apr 21 13:41:11 volumio volumio[418628]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 21 13:41:11 volumio volumio[418628]: info: Discovery: Started advertising with name: Volumio Apr 21 13:41:11 volumio volumio[418628]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 21 13:41:11 volumio volumio[418628]: info: Loading plugin "outputs"... Apr 21 13:41:11 volumio volumio[418628]: info: Loading plugin "albumart"... Apr 21 13:41:12 volumio volumio[418628]: info: Plugin example_plugin is not enabled Apr 21 13:41:12 volumio volumio[418628]: info: Loading plugin "inputs"... Apr 21 13:41:12 volumio volumio[418628]: info: Loading plugin "updater_comm"... Apr 21 13:41:12 volumio volumio[418628]: error: !!!! WARNING !!!! Apr 21 13:41:12 volumio volumio[418628]: error: The plugin system_controller/updater_comm failed to load, setting it to stopped. Error: Error: Cannot find module 'inotify' Apr 21 13:41:12 volumio volumio[418628]: Require stack: Apr 21 13:41:12 volumio volumio[418628]: - /volumio/app/plugins/system_controller/updater_comm/index.js Apr 21 13:41:12 volumio volumio[418628]: - /volumio/app/pluginmanager.js Apr 21 13:41:12 volumio volumio[418628]: - /volumio/app/index.js Apr 21 13:41:12 volumio volumio[418628]: - /volumio/index.js Apr 21 13:41:12 volumio volumio[418628]: error: Stack trace: Error: Cannot find module 'inotify' Apr 21 13:41:12 volumio volumio[418628]: Require stack: Apr 21 13:41:12 volumio volumio[418628]: - /volumio/app/plugins/system_controller/updater_comm/index.js Apr 21 13:41:12 volumio volumio[418628]: - /volumio/app/pluginmanager.js Apr 21 13:41:12 volumio volumio[418628]: - /volumio/app/index.js Apr 21 13:41:12 volumio volumio[418628]: - /volumio/index.js Apr 21 13:41:12 volumio volumio[418628]: at Module._resolveFilename (node:internal/modules/cjs/loader:1134:15) Apr 21 13:41:12 volumio volumio[418628]: at Module._load (node:internal/modules/cjs/loader:975:27) Apr 21 13:41:12 volumio volumio[418628]: at Module.require (node:internal/modules/cjs/loader:1225:19) Apr 21 13:41:12 volumio volumio[418628]: at require (node:internal/modules/helpers:177:18) Apr 21 13:41:12 volumio volumio[418628]: at Object. (/volumio/app/plugins/system_controller/updater_comm/index.js:4:15) Apr 21 13:41:12 volumio volumio[418628]: at Module._compile (node:internal/modules/cjs/loader:1356:14) Apr 21 13:41:12 volumio volumio[418628]: at Module._extensions..js (node:internal/modules/cjs/loader:1414:10) Apr 21 13:41:12 volumio volumio[418628]: at Module.load (node:internal/modules/cjs/loader:1197:32) Apr 21 13:41:12 volumio volumio[418628]: at Module._load (node:internal/modules/cjs/loader:1013:12) Apr 21 13:41:12 volumio volumio[418628]: at Module.require (node:internal/modules/cjs/loader:1225:19) Apr 21 13:41:12 volumio volumio[418628]: at require (node:internal/modules/helpers:177:18) Apr 21 13:41:12 volumio volumio[418628]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:235:29) Apr 21 13:41:12 volumio volumio[418628]: at /volumio/app/pluginmanager.js:338:34 Apr 21 13:41:12 volumio volumio[418628]: at Array.forEach () Apr 21 13:41:12 volumio volumio[418628]: at HashMap. (/volumio/app/pluginmanager.js:337:20) Apr 21 13:41:12 volumio volumio[418628]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Apr 21 13:41:12 volumio volumio[418628]: error: !!!! WARNING !!!! Apr 21 13:41:12 volumio volumio[418628]: info: Plugin mpdemulation is not enabled Apr 21 13:41:12 volumio volumio[418628]: info: Loading plugin "rest_api"... Apr 21 13:41:12 volumio volumio[418628]: info: Loading plugin "websocket"... Apr 21 13:41:12 volumio volumio[418628]: info: Loading i18n strings for locale en Apr 21 13:41:12 volumio volumio[418628]: Updating browse sources language Apr 21 13:41:12 volumio volumio[418628]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 21 13:41:12 volumio volumio[418628]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 21 13:41:12 volumio volumio[418628]: info: CoreCommandRouter::initPlayerControls Apr 21 13:41:12 volumio volumio[418628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 13:41:12 volumio mpd[418680]: Apr 21 13:41 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 21 13:41:12 volumio volumio[418628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 13:41:12 volumio volumio[418628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 13:41:12 volumio volumio[418628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 13:41:12 volumio volumio[418628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 13:41:12 volumio volumio[418628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 13:41:12 volumio volumio[418628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 13:41:12 volumio volumio[418628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 13:41:12 volumio systemd[1]: Started mpd.service - Music Player Daemon. Apr 21 13:41:12 volumio volumio[418628]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 21 13:41:12 volumio sudo[418677]: pam_unix(sudo:session): session closed for user root Apr 21 13:41:12 volumio volumio[418628]: /usr/share/nodejs/path-to-regexp/dist/index.js:114 Apr 21 13:41:12 volumio volumio[418628]: throw new TypeError("Unexpected ".concat(nextType, " at ").concat(index, ", expected ").concat(type)); Apr 21 13:41:12 volumio volumio[418628]: ^ Apr 21 13:41:12 volumio volumio[418628]: TypeError: Unexpected MODIFIER at 1, expected END Apr 21 13:41:12 volumio volumio[418628]: at mustConsume (/usr/share/nodejs/path-to-regexp/dist/index.js:114:15) Apr 21 13:41:12 volumio volumio[418628]: at parse (/usr/share/nodejs/path-to-regexp/dist/index.js:172:9) Apr 21 13:41:12 volumio volumio[418628]: at stringToRegexp (/usr/share/nodejs/path-to-regexp/dist/index.js:329:27) Apr 21 13:41:12 volumio volumio[418628]: at pathToRegexp (/usr/share/nodejs/path-to-regexp/dist/index.js:407:12) Apr 21 13:41:12 volumio volumio[418628]: at new Layer (/usr/share/nodejs/express/lib/router/layer.js:59:17) Apr 21 13:41:12 volumio volumio[418628]: at Function.route (/usr/share/nodejs/express/lib/router/index.js:505:15) Apr 21 13:41:12 volumio volumio[418628]: at app. [as get] (/usr/share/nodejs/express/lib/application.js:498:30) Apr 21 13:41:12 volumio volumio[418628]: at Object. (/volumio/index.js:59:12) Apr 21 13:41:12 volumio volumio[418628]: at Module._compile (node:internal/modules/cjs/loader:1356:14) Apr 21 13:41:12 volumio volumio[418628]: at Module._extensions..js (node:internal/modules/cjs/loader:1414:10) Apr 21 13:41:12 volumio volumio[418628]: at Module.load (node:internal/modules/cjs/loader:1197:32) Apr 21 13:41:12 volumio volumio[418628]: at Module._load (node:internal/modules/cjs/loader:1013:12) Apr 21 13:41:12 volumio volumio[418628]: at Function.executeUserEntryPoint [as runMain] (node:internal/modules/run_main:128:12) Apr 21 13:41:12 volumio volumio[418628]: at node:internal/main/run_main_module:28:49 Apr 21 13:41:12 volumio volumio[418628]: Node.js v18.19.0 Apr 21 13:41:12 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 21 13:41:12 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 21 13:41:12 volumio systemd[1]: volumio.service: Consumed 13.282s CPU time. Apr 21 13:41:12 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 6799. Apr 21 13:41:12 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Apr 21 13:41:12 volumio systemd[1]: volumio.service: Consumed 13.282s CPU time. Apr 21 13:41:12 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Apr 21 13:41:16 volumio volumio[418695]: info: ------------------------------------------- Apr 21 13:41:16 volumio volumio[418695]: info: ----- Volumio3 ---- Apr 21 13:41:16 volumio volumio[418695]: info: ------------------------------------------- Apr 21 13:41:16 volumio volumio[418695]: info: ----- System startup ---- Apr 21 13:41:16 volumio volumio[418695]: info: ------------------------------------------- Apr 21 13:41:17 volumio volumio[418695]: info: Plugin folders cleanup Apr 21 13:41:17 volumio volumio[418695]: info: Scanning into folder /volumio/app/plugins/ Apr 21 13:41:17 volumio volumio[418695]: info: Scanning category audio_interface Apr 21 13:41:17 volumio volumio[418695]: info: Scanning category miscellanea Apr 21 13:41:17 volumio volumio[418695]: info: Scanning category music_service Apr 21 13:41:17 volumio volumio[418695]: info: Scanning category plugins.json Apr 21 13:41:17 volumio volumio[418695]: info: Scanning category system_controller Apr 21 13:41:17 volumio volumio[418695]: info: Scanning category user_interface Apr 21 13:41:17 volumio volumio[418695]: info: Scanning into folder /data/plugins/ Apr 21 13:41:17 volumio volumio[418695]: info: Plugin folders cleanup completed Apr 21 13:41:17 volumio volumio[418695]: info: ------------------------------------------- Apr 21 13:41:17 volumio volumio[418695]: info: ----- Core plugins startup ---- Apr 21 13:41:17 volumio volumio[418695]: info: ------------------------------------------- Apr 21 13:41:17 volumio volumio[418695]: info: Loading plugins from folder /volumio/app/plugins/ Apr 21 13:41:17 volumio volumio[418695]: info: Adding plugin upnp to MyMusic Plugins Apr 21 13:41:17 volumio volumio[418695]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 21 13:41:17 volumio volumio[418695]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 21 13:41:17 volumio volumio[418695]: info: Loading plugins from folder /data/plugins/ Apr 21 13:41:17 volumio volumio[418695]: info: Loading plugin "system"... Apr 21 13:41:17 volumio volumio[418695]: info: No id defined. Creating one Apr 21 13:41:17 volumio volumio[418695]: info: Loading plugin "appearance"... Apr 21 13:41:18 volumio volumio[418695]: info: Loading plugin "network"... Apr 21 13:41:18 volumio volumio[418695]: info: Refreshing Cached IP Addresses Apr 21 13:41:18 volumio sudo[418713]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 21 13:41:18 volumio sudo[418713]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 21 13:41:18 volumio volumio[418695]: info: Loading plugin "services"... Apr 21 13:41:18 volumio sudo[418715]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 21 13:41:18 volumio sudo[418715]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 21 13:41:18 volumio sudo[418713]: pam_unix(sudo:session): session closed for user root Apr 21 13:41:18 volumio volumio[418695]: info: Loading plugin "alsa_controller"... Apr 21 13:41:18 volumio sudo[418715]: pam_unix(sudo:session): session closed for user root Apr 21 13:41:18 volumio sudo[418718]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 21 13:41:18 volumio sudo[418718]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 21 13:41:18 volumio volumio[418695]: warn: The ALSA output card is not set, defaulting to card [object Object] Apr 21 13:41:18 volumio volumio[418695]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 21 13:41:18 volumio volumio[418695]: error: Could not retrieve plugin system_controller i2s_dacs Apr 21 13:41:18 volumio volumio[418695]: info: Setting mixer ACODEC for card LIBRETECH-CC Apr 21 13:41:18 volumio volumio[418695]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 21 13:41:19 volumio volumio[418695]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 21 13:41:19 volumio volumio[418695]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 21 13:41:19 volumio volumio[418695]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 21 13:41:19 volumio volumio[418695]: info: Loading plugin "wizard"... Apr 21 13:41:19 volumio volumio[418695]: info: Loading plugin "volumio_command_line_client"... Apr 21 13:41:19 volumio volumio[418695]: info: Loading plugin "upnp"... Apr 21 13:41:19 volumio volumio[418695]: info: [1713724879094] Starting Upmpd Daemon Apr 21 13:41:19 volumio volumio[418695]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 21 13:41:19 volumio volumio[418695]: info: Loading plugin "my_music"... Apr 21 13:41:19 volumio volumio[418695]: info: Loading plugin "mpd"... Apr 21 13:41:19 volumio volumio[418695]: info: Creating MPD Configuration file Apr 21 13:41:19 volumio sudo[418736]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 21 13:41:19 volumio sudo[418736]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 21 13:41:19 volumio volumio[418695]: info: Loading plugin "upnp_browser"... Apr 21 13:41:19 volumio sudo[418736]: pam_unix(sudo:session): session closed for user root Apr 21 13:41:19 volumio sudo[418738]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 21 13:41:19 volumio sudo[418738]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 21 13:41:19 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Apr 21 13:41:19 volumio systemd[1]: mpd.service: Deactivated successfully. Apr 21 13:41:19 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Apr 21 13:41:19 volumio systemd[1]: mpd.service: Consumed 3.879s CPU time. Apr 21 13:41:19 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Apr 21 13:41:22 volumio volumio[418695]: error: !!!! WARNING !!!! Apr 21 13:41:22 volumio volumio[418695]: error: The plugin music_service/upnp_browser failed to load, setting it to stopped. Error: TypeError: Entities is not a constructor Apr 21 13:41:22 volumio volumio[418695]: error: Stack trace: TypeError: Entities is not a constructor Apr 21 13:41:22 volumio volumio[418695]: at Object. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:12:18) Apr 21 13:41:22 volumio volumio[418695]: at Module._compile (node:internal/modules/cjs/loader:1356:14) Apr 21 13:41:22 volumio volumio[418695]: at Module._extensions..js (node:internal/modules/cjs/loader:1414:10) Apr 21 13:41:22 volumio volumio[418695]: at Module.load (node:internal/modules/cjs/loader:1197:32) Apr 21 13:41:22 volumio volumio[418695]: at Module._load (node:internal/modules/cjs/loader:1013:12) Apr 21 13:41:22 volumio volumio[418695]: at Module.require (node:internal/modules/cjs/loader:1225:19) Apr 21 13:41:22 volumio volumio[418695]: at require (node:internal/modules/helpers:177:18) Apr 21 13:41:22 volumio volumio[418695]: at Object. (/volumio/app/plugins/music_service/upnp_browser/index.js:12:24) Apr 21 13:41:22 volumio volumio[418695]: at Module._compile (node:internal/modules/cjs/loader:1356:14) Apr 21 13:41:22 volumio volumio[418695]: at Module._extensions..js (node:internal/modules/cjs/loader:1414:10) Apr 21 13:41:22 volumio volumio[418695]: at Module.load (node:internal/modules/cjs/loader:1197:32) Apr 21 13:41:22 volumio volumio[418695]: at Module._load (node:internal/modules/cjs/loader:1013:12) Apr 21 13:41:22 volumio volumio[418695]: at Module.require (node:internal/modules/cjs/loader:1225:19) Apr 21 13:41:22 volumio volumio[418695]: at require (node:internal/modules/helpers:177:18) Apr 21 13:41:22 volumio volumio[418695]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:235:29) Apr 21 13:41:22 volumio volumio[418695]: at /volumio/app/pluginmanager.js:338:34 Apr 21 13:41:22 volumio volumio[418695]: error: !!!! WARNING !!!! Apr 21 13:41:22 volumio volumio[418695]: info: Loading plugin "networkfs"... Apr 21 13:41:22 volumio volumio[418695]: info: Starting Udev Watcher for removable devices Apr 21 13:41:22 volumio volumio[418695]: info: Ignoring mount for partition: boot Apr 21 13:41:22 volumio volumio[418695]: info: Ignoring mount for partition: root Apr 21 13:41:22 volumio volumio[418695]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 21 13:41:22 volumio volumio[418695]: info: Loading plugin "alarm-clock"... Apr 21 13:41:23 volumio sudo[418718]: pam_unix(sudo:session): session closed for user root Apr 21 13:41:23 volumio volumio[418695]: info: Loading plugin "airplay_emulation"... Apr 21 13:41:23 volumio volumio[418695]: info: Starting Shairport Sync Apr 21 13:41:23 volumio volumio[418695]: info: Loading plugin "last_100"... Apr 21 13:41:23 volumio volumio[418695]: info: Loading plugin "webradio"... Apr 21 13:41:23 volumio volumio[418695]: info: Loading plugin "i2s_dacs"... Apr 21 13:41:23 volumio volumio[418695]: info: I2S DAC not set, start Auto-detection Apr 21 13:41:23 volumio volumio[418695]: info: Loading plugin "volumiodiscovery"... Apr 21 13:41:23 volumio volumio[418695]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 21 13:41:23 volumio node[418695]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 21 13:41:23 volumio volumio[418695]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 21 13:41:23 volumio volumio[418695]: *** WARNING *** For more information see Apr 21 13:41:23 volumio volumio[418695]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 21 13:41:23 volumio volumio[418695]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 21 13:41:23 volumio volumio[418695]: *** WARNING *** For more information see Apr 21 13:41:23 volumio node[418695]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 21 13:41:23 volumio node[418695]: *** WARNING *** For more information see Apr 21 13:41:23 volumio node[418695]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 21 13:41:23 volumio node[418695]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 21 13:41:23 volumio node[418695]: *** WARNING *** For more information see Apr 21 13:41:23 volumio volumio[418695]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 21 13:41:23 volumio volumio[418695]: info: Discovery: Started advertising with name: Volumio Apr 21 13:41:23 volumio volumio[418695]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 21 13:41:23 volumio volumio[418695]: info: Loading plugin "outputs"... Apr 21 13:41:23 volumio volumio[418695]: info: Loading plugin "albumart"... Apr 21 13:41:23 volumio volumio[418695]: info: Plugin example_plugin is not enabled Apr 21 13:41:23 volumio volumio[418695]: info: Loading plugin "inputs"... Apr 21 13:41:23 volumio volumio[418695]: info: Loading plugin "updater_comm"... Apr 21 13:41:23 volumio volumio[418695]: error: !!!! WARNING !!!! Apr 21 13:41:23 volumio volumio[418695]: error: The plugin system_controller/updater_comm failed to load, setting it to stopped. Error: Error: Cannot find module 'inotify' Apr 21 13:41:23 volumio volumio[418695]: Require stack: Apr 21 13:41:23 volumio volumio[418695]: - /volumio/app/plugins/system_controller/updater_comm/index.js Apr 21 13:41:23 volumio volumio[418695]: - /volumio/app/pluginmanager.js Apr 21 13:41:23 volumio volumio[418695]: - /volumio/app/index.js Apr 21 13:41:23 volumio volumio[418695]: - /volumio/index.js Apr 21 13:41:23 volumio volumio[418695]: error: Stack trace: Error: Cannot find module 'inotify' Apr 21 13:41:23 volumio volumio[418695]: Require stack: Apr 21 13:41:23 volumio volumio[418695]: - /volumio/app/plugins/system_controller/updater_comm/index.js Apr 21 13:41:23 volumio volumio[418695]: - /volumio/app/pluginmanager.js Apr 21 13:41:23 volumio volumio[418695]: - /volumio/app/index.js Apr 21 13:41:23 volumio volumio[418695]: - /volumio/index.js Apr 21 13:41:23 volumio volumio[418695]: at Module._resolveFilename (node:internal/modules/cjs/loader:1134:15) Apr 21 13:41:23 volumio volumio[418695]: at Module._load (node:internal/modules/cjs/loader:975:27) Apr 21 13:41:23 volumio volumio[418695]: at Module.require (node:internal/modules/cjs/loader:1225:19) Apr 21 13:41:23 volumio volumio[418695]: at require (node:internal/modules/helpers:177:18) Apr 21 13:41:23 volumio volumio[418695]: at Object. (/volumio/app/plugins/system_controller/updater_comm/index.js:4:15) Apr 21 13:41:23 volumio volumio[418695]: at Module._compile (node:internal/modules/cjs/loader:1356:14) Apr 21 13:41:23 volumio volumio[418695]: at Module._extensions..js (node:internal/modules/cjs/loader:1414:10) Apr 21 13:41:23 volumio volumio[418695]: at Module.load (node:internal/modules/cjs/loader:1197:32) Apr 21 13:41:23 volumio volumio[418695]: at Module._load (node:internal/modules/cjs/loader:1013:12) Apr 21 13:41:23 volumio volumio[418695]: at Module.require (node:internal/modules/cjs/loader:1225:19) Apr 21 13:41:23 volumio volumio[418695]: at require (node:internal/modules/helpers:177:18) Apr 21 13:41:23 volumio volumio[418695]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:235:29) Apr 21 13:41:23 volumio volumio[418695]: at /volumio/app/pluginmanager.js:338:34 Apr 21 13:41:23 volumio volumio[418695]: at Array.forEach () Apr 21 13:41:23 volumio volumio[418695]: at HashMap. (/volumio/app/pluginmanager.js:337:20) Apr 21 13:41:23 volumio volumio[418695]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Apr 21 13:41:23 volumio volumio[418695]: error: !!!! WARNING !!!! Apr 21 13:41:23 volumio volumio[418695]: info: Plugin mpdemulation is not enabled Apr 21 13:41:23 volumio volumio[418695]: info: Loading plugin "rest_api"... Apr 21 13:41:23 volumio volumio[418695]: info: Loading plugin "websocket"... Apr 21 13:41:24 volumio volumio[418695]: info: Loading i18n strings for locale en Apr 21 13:41:24 volumio volumio[418695]: Updating browse sources language Apr 21 13:41:24 volumio volumio[418695]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 21 13:41:24 volumio mpd[418741]: Apr 21 13:41 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 21 13:41:24 volumio systemd[1]: Started mpd.service - Music Player Daemon. Apr 21 13:41:24 volumio sudo[418738]: pam_unix(sudo:session): session closed for user root Apr 21 13:41:24 volumio volumio[418695]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 21 13:41:24 volumio volumio[418695]: info: CoreCommandRouter::initPlayerControls Apr 21 13:41:24 volumio volumio[418695]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 13:41:24 volumio volumio[418695]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 13:41:24 volumio volumio[418695]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 13:41:24 volumio volumio[418695]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 13:41:24 volumio volumio[418695]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 13:41:24 volumio volumio[418695]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 13:41:24 volumio volumio[418695]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 13:41:24 volumio volumio[418695]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 13:41:24 volumio volumio[418695]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 21 13:41:24 volumio volumio[418695]: /usr/share/nodejs/path-to-regexp/dist/index.js:114 Apr 21 13:41:24 volumio volumio[418695]: throw new TypeError("Unexpected ".concat(nextType, " at ").concat(index, ", expected ").concat(type)); Apr 21 13:41:24 volumio volumio[418695]: ^ Apr 21 13:41:24 volumio volumio[418695]: TypeError: Unexpected MODIFIER at 1, expected END Apr 21 13:41:24 volumio volumio[418695]: at mustConsume (/usr/share/nodejs/path-to-regexp/dist/index.js:114:15) Apr 21 13:41:24 volumio volumio[418695]: at parse (/usr/share/nodejs/path-to-regexp/dist/index.js:172:9) Apr 21 13:41:24 volumio volumio[418695]: at stringToRegexp (/usr/share/nodejs/path-to-regexp/dist/index.js:329:27) Apr 21 13:41:24 volumio volumio[418695]: at pathToRegexp (/usr/share/nodejs/path-to-regexp/dist/index.js:407:12) Apr 21 13:41:24 volumio volumio[418695]: at new Layer (/usr/share/nodejs/express/lib/router/layer.js:59:17) Apr 21 13:41:24 volumio volumio[418695]: at Function.route (/usr/share/nodejs/express/lib/router/index.js:505:15) Apr 21 13:41:24 volumio volumio[418695]: at app. [as get] (/usr/share/nodejs/express/lib/application.js:498:30) Apr 21 13:41:24 volumio volumio[418695]: at Object. (/volumio/index.js:59:12) Apr 21 13:41:24 volumio volumio[418695]: at Module._compile (node:internal/modules/cjs/loader:1356:14) Apr 21 13:41:24 volumio volumio[418695]: at Module._extensions..js (node:internal/modules/cjs/loader:1414:10) Apr 21 13:41:24 volumio volumio[418695]: at Module.load (node:internal/modules/cjs/loader:1197:32) Apr 21 13:41:24 volumio volumio[418695]: at Module._load (node:internal/modules/cjs/loader:1013:12) Apr 21 13:41:24 volumio volumio[418695]: at Function.executeUserEntryPoint [as runMain] (node:internal/modules/run_main:128:12) Apr 21 13:41:24 volumio volumio[418695]: at node:internal/main/run_main_module:28:49 Apr 21 13:41:24 volumio volumio[418695]: Node.js v18.19.0 Apr 21 13:41:24 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 21 13:41:24 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 21 13:41:24 volumio systemd[1]: volumio.service: Consumed 13.186s CPU time. Apr 21 13:41:24 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 6800. Apr 21 13:41:24 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Apr 21 13:41:24 volumio systemd[1]: volumio.service: Consumed 13.186s CPU time. Apr 21 13:41:24 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Apr 21 13:41:28 volumio volumio[418756]: info: ------------------------------------------- Apr 21 13:41:28 volumio volumio[418756]: info: ----- Volumio3 ---- Apr 21 13:41:28 volumio volumio[418756]: info: ------------------------------------------- Apr 21 13:41:28 volumio volumio[418756]: info: ----- System startup ---- Apr 21 13:41:28 volumio volumio[418756]: info: ------------------------------------------- Apr 21 13:41:29 volumio volumio[418756]: info: Plugin folders cleanup Apr 21 13:41:29 volumio volumio[418756]: info: Scanning into folder /volumio/app/plugins/ Apr 21 13:41:29 volumio volumio[418756]: info: Scanning category audio_interface Apr 21 13:41:29 volumio volumio[418756]: info: Scanning category miscellanea Apr 21 13:41:29 volumio volumio[418756]: info: Scanning category music_service Apr 21 13:41:29 volumio volumio[418756]: info: Scanning category plugins.json Apr 21 13:41:29 volumio volumio[418756]: info: Scanning category system_controller Apr 21 13:41:29 volumio volumio[418756]: info: Scanning category user_interface Apr 21 13:41:29 volumio volumio[418756]: info: Scanning into folder /data/plugins/ Apr 21 13:41:29 volumio volumio[418756]: info: Plugin folders cleanup completed Apr 21 13:41:29 volumio volumio[418756]: info: ------------------------------------------- Apr 21 13:41:29 volumio volumio[418756]: info: ----- Core plugins startup ---- Apr 21 13:41:29 volumio volumio[418756]: info: ------------------------------------------- Apr 21 13:41:29 volumio volumio[418756]: info: Loading plugins from folder /volumio/app/plugins/ Apr 21 13:41:29 volumio volumio[418756]: info: Adding plugin upnp to MyMusic Plugins Apr 21 13:41:29 volumio volumio[418756]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 21 13:41:29 volumio volumio[418756]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 21 13:41:29 volumio volumio[418756]: info: Loading plugins from folder /data/plugins/ Apr 21 13:41:29 volumio volumio[418756]: info: Loading plugin "system"... Apr 21 13:41:29 volumio volumio[418756]: info: No id defined. Creating one Apr 21 13:41:29 volumio volumio[418756]: info: Loading plugin "appearance"... Apr 21 13:41:30 volumio volumio[418756]: info: Loading plugin "network"... Apr 21 13:41:30 volumio volumio[418756]: info: Refreshing Cached IP Addresses Apr 21 13:41:30 volumio sudo[418774]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 21 13:41:30 volumio sudo[418774]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 21 13:41:30 volumio sudo[418774]: pam_unix(sudo:session): session closed for user root Apr 21 13:41:30 volumio volumio[418756]: info: Loading plugin "services"... Apr 21 13:41:30 volumio volumio[418756]: info: Loading plugin "alsa_controller"... Apr 21 13:41:30 volumio sudo[418777]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 21 13:41:30 volumio sudo[418779]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 21 13:41:30 volumio sudo[418777]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 21 13:41:30 volumio sudo[418779]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 21 13:41:30 volumio sudo[418777]: pam_unix(sudo:session): session closed for user root Apr 21 13:41:30 volumio volumio[418756]: warn: The ALSA output card is not set, defaulting to card [object Object] Apr 21 13:41:31 volumio volumio[418756]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 21 13:41:31 volumio volumio[418756]: error: Could not retrieve plugin system_controller i2s_dacs Apr 21 13:41:31 volumio volumio[418756]: info: Setting mixer ACODEC for card LIBRETECH-CC Apr 21 13:41:31 volumio volumio[418756]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 21 13:41:31 volumio volumio[418756]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 21 13:41:31 volumio volumio[418756]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 21 13:41:31 volumio volumio[418756]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 21 13:41:31 volumio volumio[418756]: info: Loading plugin "wizard"... Apr 21 13:41:31 volumio volumio[418756]: info: Loading plugin "volumio_command_line_client"... Apr 21 13:41:31 volumio volumio[418756]: info: Loading plugin "upnp"... Apr 21 13:41:31 volumio volumio[418756]: info: [1713724891212] Starting Upmpd Daemon Apr 21 13:41:31 volumio volumio[418756]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 21 13:41:31 volumio volumio[418756]: info: Loading plugin "my_music"... Apr 21 13:41:31 volumio volumio[418756]: info: Loading plugin "mpd"... Apr 21 13:41:31 volumio volumio[418756]: info: Creating MPD Configuration file Apr 21 13:41:31 volumio sudo[418797]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 21 13:41:31 volumio volumio[418756]: info: Loading plugin "upnp_browser"... Apr 21 13:41:31 volumio sudo[418797]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 21 13:41:31 volumio sudo[418799]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 21 13:41:31 volumio sudo[418797]: pam_unix(sudo:session): session closed for user root Apr 21 13:41:31 volumio sudo[418799]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 21 13:41:31 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Apr 21 13:41:31 volumio systemd[1]: mpd.service: Deactivated successfully. Apr 21 13:41:31 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Apr 21 13:41:31 volumio systemd[1]: mpd.service: Consumed 3.752s CPU time. Apr 21 13:41:32 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Apr 21 13:41:34 volumio volumio[418756]: error: !!!! WARNING !!!! Apr 21 13:41:34 volumio volumio[418756]: error: The plugin music_service/upnp_browser failed to load, setting it to stopped. Error: TypeError: Entities is not a constructor Apr 21 13:41:34 volumio volumio[418756]: error: Stack trace: TypeError: Entities is not a constructor Apr 21 13:41:34 volumio volumio[418756]: at Object. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:12:18) Apr 21 13:41:34 volumio volumio[418756]: at Module._compile (node:internal/modules/cjs/loader:1356:14) Apr 21 13:41:34 volumio volumio[418756]: at Module._extensions..js (node:internal/modules/cjs/loader:1414:10) Apr 21 13:41:34 volumio volumio[418756]: at Module.load (node:internal/modules/cjs/loader:1197:32) Apr 21 13:41:34 volumio volumio[418756]: at Module._load (node:internal/modules/cjs/loader:1013:12) Apr 21 13:41:34 volumio volumio[418756]: at Module.require (node:internal/modules/cjs/loader:1225:19) Apr 21 13:41:34 volumio volumio[418756]: at require (node:internal/modules/helpers:177:18) Apr 21 13:41:34 volumio volumio[418756]: at Object. (/volumio/app/plugins/music_service/upnp_browser/index.js:12:24) Apr 21 13:41:34 volumio volumio[418756]: at Module._compile (node:internal/modules/cjs/loader:1356:14) Apr 21 13:41:34 volumio volumio[418756]: at Module._extensions..js (node:internal/modules/cjs/loader:1414:10) Apr 21 13:41:34 volumio volumio[418756]: at Module.load (node:internal/modules/cjs/loader:1197:32) Apr 21 13:41:34 volumio volumio[418756]: at Module._load (node:internal/modules/cjs/loader:1013:12) Apr 21 13:41:34 volumio volumio[418756]: at Module.require (node:internal/modules/cjs/loader:1225:19) Apr 21 13:41:34 volumio volumio[418756]: at require (node:internal/modules/helpers:177:18) Apr 21 13:41:34 volumio volumio[418756]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:235:29) Apr 21 13:41:34 volumio volumio[418756]: at /volumio/app/pluginmanager.js:338:34 Apr 21 13:41:34 volumio volumio[418756]: error: !!!! WARNING !!!! Apr 21 13:41:34 volumio volumio[418756]: info: Loading plugin "networkfs"... Apr 21 13:41:34 volumio volumio[418756]: info: Starting Udev Watcher for removable devices Apr 21 13:41:34 volumio volumio[418756]: info: Ignoring mount for partition: boot Apr 21 13:41:34 volumio volumio[418756]: info: Ignoring mount for partition: root Apr 21 13:41:34 volumio volumio[418756]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 21 13:41:34 volumio volumio[418756]: info: Loading plugin "alarm-clock"... Apr 21 13:41:35 volumio volumio[418756]: info: Loading plugin "airplay_emulation"... Apr 21 13:41:35 volumio volumio[418756]: info: Starting Shairport Sync Apr 21 13:41:35 volumio volumio[418756]: info: Loading plugin "last_100"... Apr 21 13:41:35 volumio volumio[418756]: info: Loading plugin "webradio"... Apr 21 13:41:35 volumio sudo[418779]: pam_unix(sudo:session): session closed for user root Apr 21 13:41:35 volumio volumio[418756]: info: Loading plugin "i2s_dacs"... Apr 21 13:41:35 volumio volumio[418756]: info: I2S DAC not set, start Auto-detection Apr 21 13:41:35 volumio volumio[418756]: info: Loading plugin "volumiodiscovery"... Apr 21 13:41:35 volumio volumio[418756]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 21 13:41:35 volumio volumio[418756]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 21 13:41:35 volumio volumio[418756]: *** WARNING *** For more information see Apr 21 13:41:35 volumio volumio[418756]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 21 13:41:35 volumio volumio[418756]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 21 13:41:35 volumio volumio[418756]: *** WARNING *** For more information see Apr 21 13:41:35 volumio node[418756]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 21 13:41:35 volumio node[418756]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 21 13:41:35 volumio node[418756]: *** WARNING *** For more information see Apr 21 13:41:35 volumio node[418756]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 21 13:41:35 volumio node[418756]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 21 13:41:35 volumio node[418756]: *** WARNING *** For more information see Apr 21 13:41:35 volumio volumio[418756]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 21 13:41:35 volumio volumio[418756]: info: Discovery: Started advertising with name: Volumio Apr 21 13:41:35 volumio volumio[418756]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 21 13:41:35 volumio volumio[418756]: info: Loading plugin "outputs"... Apr 21 13:41:35 volumio volumio[418756]: info: Loading plugin "albumart"... Apr 21 13:41:35 volumio volumio[418756]: info: Plugin example_plugin is not enabled Apr 21 13:41:35 volumio volumio[418756]: info: Loading plugin "inputs"... Apr 21 13:41:35 volumio volumio[418756]: info: Loading plugin "updater_comm"... Apr 21 13:41:35 volumio volumio[418756]: error: !!!! WARNING !!!! Apr 21 13:41:35 volumio volumio[418756]: error: The plugin system_controller/updater_comm failed to load, setting it to stopped. Error: Error: Cannot find module 'inotify' Apr 21 13:41:35 volumio volumio[418756]: Require stack: Apr 21 13:41:35 volumio volumio[418756]: - /volumio/app/plugins/system_controller/updater_comm/index.js Apr 21 13:41:35 volumio volumio[418756]: - /volumio/app/pluginmanager.js Apr 21 13:41:35 volumio volumio[418756]: - /volumio/app/index.js Apr 21 13:41:35 volumio volumio[418756]: - /volumio/index.js Apr 21 13:41:35 volumio volumio[418756]: error: Stack trace: Error: Cannot find module 'inotify' Apr 21 13:41:35 volumio volumio[418756]: Require stack: Apr 21 13:41:35 volumio volumio[418756]: - /volumio/app/plugins/system_controller/updater_comm/index.js Apr 21 13:41:35 volumio volumio[418756]: - /volumio/app/pluginmanager.js Apr 21 13:41:35 volumio volumio[418756]: - /volumio/app/index.js Apr 21 13:41:35 volumio volumio[418756]: - /volumio/index.js Apr 21 13:41:35 volumio volumio[418756]: at Module._resolveFilename (node:internal/modules/cjs/loader:1134:15) Apr 21 13:41:35 volumio volumio[418756]: at Module._load (node:internal/modules/cjs/loader:975:27) Apr 21 13:41:35 volumio volumio[418756]: at Module.require (node:internal/modules/cjs/loader:1225:19) Apr 21 13:41:35 volumio volumio[418756]: at require (node:internal/modules/helpers:177:18) Apr 21 13:41:35 volumio volumio[418756]: at Object. (/volumio/app/plugins/system_controller/updater_comm/index.js:4:15) Apr 21 13:41:35 volumio volumio[418756]: at Module._compile (node:internal/modules/cjs/loader:1356:14) Apr 21 13:41:35 volumio volumio[418756]: at Module._extensions..js (node:internal/modules/cjs/loader:1414:10) Apr 21 13:41:35 volumio volumio[418756]: at Module.load (node:internal/modules/cjs/loader:1197:32) Apr 21 13:41:35 volumio volumio[418756]: at Module._load (node:internal/modules/cjs/loader:1013:12) Apr 21 13:41:35 volumio volumio[418756]: at Module.require (node:internal/modules/cjs/loader:1225:19) Apr 21 13:41:35 volumio volumio[418756]: at require (node:internal/modules/helpers:177:18) Apr 21 13:41:35 volumio volumio[418756]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:235:29) Apr 21 13:41:35 volumio volumio[418756]: at /volumio/app/pluginmanager.js:338:34 Apr 21 13:41:35 volumio volumio[418756]: at Array.forEach () Apr 21 13:41:35 volumio volumio[418756]: at HashMap. (/volumio/app/pluginmanager.js:337:20) Apr 21 13:41:35 volumio volumio[418756]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Apr 21 13:41:35 volumio volumio[418756]: error: !!!! WARNING !!!! Apr 21 13:41:35 volumio volumio[418756]: info: Plugin mpdemulation is not enabled Apr 21 13:41:35 volumio volumio[418756]: info: Loading plugin "rest_api"... Apr 21 13:41:35 volumio volumio[418756]: info: Loading plugin "websocket"... Apr 21 13:41:35 volumio mpd[418802]: Apr 21 13:41 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 21 13:41:35 volumio systemd[1]: Started mpd.service - Music Player Daemon. Apr 21 13:41:35 volumio sudo[418799]: pam_unix(sudo:session): session closed for user root Apr 21 13:41:35 volumio volumio[418756]: info: Loading i18n strings for locale en Apr 21 13:41:35 volumio volumio[418756]: Updating browse sources language Apr 21 13:41:35 volumio volumio[418756]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 21 13:41:36 volumio volumio[418756]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 21 13:41:36 volumio volumio[418756]: info: CoreCommandRouter::initPlayerControls Apr 21 13:41:36 volumio volumio[418756]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 13:41:36 volumio volumio[418756]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 13:41:36 volumio volumio[418756]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 13:41:36 volumio volumio[418756]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 13:41:36 volumio volumio[418756]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 13:41:36 volumio volumio[418756]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 13:41:36 volumio volumio[418756]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 13:41:36 volumio volumio[418756]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 13:41:36 volumio volumio[418756]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 21 13:41:36 volumio volumio[418756]: /usr/share/nodejs/path-to-regexp/dist/index.js:114 Apr 21 13:41:36 volumio volumio[418756]: throw new TypeError("Unexpected ".concat(nextType, " at ").concat(index, ", expected ").concat(type)); Apr 21 13:41:36 volumio volumio[418756]: ^ Apr 21 13:41:36 volumio volumio[418756]: TypeError: Unexpected MODIFIER at 1, expected END Apr 21 13:41:36 volumio volumio[418756]: at mustConsume (/usr/share/nodejs/path-to-regexp/dist/index.js:114:15) Apr 21 13:41:36 volumio volumio[418756]: at parse (/usr/share/nodejs/path-to-regexp/dist/index.js:172:9) Apr 21 13:41:36 volumio volumio[418756]: at stringToRegexp (/usr/share/nodejs/path-to-regexp/dist/index.js:329:27) Apr 21 13:41:36 volumio volumio[418756]: at pathToRegexp (/usr/share/nodejs/path-to-regexp/dist/index.js:407:12) Apr 21 13:41:36 volumio volumio[418756]: at new Layer (/usr/share/nodejs/express/lib/router/layer.js:59:17) Apr 21 13:41:36 volumio volumio[418756]: at Function.route (/usr/share/nodejs/express/lib/router/index.js:505:15) Apr 21 13:41:36 volumio volumio[418756]: at app. [as get] (/usr/share/nodejs/express/lib/application.js:498:30) Apr 21 13:41:36 volumio volumio[418756]: at Object. (/volumio/index.js:59:12) Apr 21 13:41:36 volumio volumio[418756]: at Module._compile (node:internal/modules/cjs/loader:1356:14) Apr 21 13:41:36 volumio volumio[418756]: at Module._extensions..js (node:internal/modules/cjs/loader:1414:10) Apr 21 13:41:36 volumio volumio[418756]: at Module.load (node:internal/modules/cjs/loader:1197:32) Apr 21 13:41:36 volumio volumio[418756]: at Module._load (node:internal/modules/cjs/loader:1013:12) Apr 21 13:41:36 volumio volumio[418756]: at Function.executeUserEntryPoint [as runMain] (node:internal/modules/run_main:128:12) Apr 21 13:41:36 volumio volumio[418756]: at node:internal/main/run_main_module:28:49 Apr 21 13:41:36 volumio volumio[418756]: Node.js v18.19.0 Apr 21 13:41:36 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 21 13:41:36 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 21 13:41:36 volumio systemd[1]: volumio.service: Consumed 13.163s CPU time. Apr 21 13:41:36 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 6801. Apr 21 13:41:36 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Apr 21 13:41:36 volumio systemd[1]: volumio.service: Consumed 13.163s CPU time. Apr 21 13:41:36 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Apr 21 13:41:39 volumio volumio[418817]: info: ------------------------------------------- Apr 21 13:41:39 volumio volumio[418817]: info: ----- Volumio3 ---- Apr 21 13:41:39 volumio volumio[418817]: info: ------------------------------------------- Apr 21 13:41:39 volumio volumio[418817]: info: ----- System startup ---- Apr 21 13:41:39 volumio volumio[418817]: info: ------------------------------------------- Apr 21 13:41:40 volumio volumio[418817]: info: Plugin folders cleanup Apr 21 13:41:40 volumio volumio[418817]: info: Scanning into folder /volumio/app/plugins/ Apr 21 13:41:40 volumio volumio[418817]: info: Scanning category audio_interface Apr 21 13:41:40 volumio volumio[418817]: info: Scanning category miscellanea Apr 21 13:41:40 volumio volumio[418817]: info: Scanning category music_service Apr 21 13:41:40 volumio volumio[418817]: info: Scanning category plugins.json Apr 21 13:41:40 volumio volumio[418817]: info: Scanning category system_controller Apr 21 13:41:40 volumio volumio[418817]: info: Scanning category user_interface Apr 21 13:41:40 volumio volumio[418817]: info: Scanning into folder /data/plugins/ Apr 21 13:41:40 volumio volumio[418817]: info: Plugin folders cleanup completed Apr 21 13:41:40 volumio volumio[418817]: info: ------------------------------------------- Apr 21 13:41:40 volumio volumio[418817]: info: ----- Core plugins startup ---- Apr 21 13:41:40 volumio volumio[418817]: info: ------------------------------------------- Apr 21 13:41:40 volumio volumio[418817]: info: Loading plugins from folder /volumio/app/plugins/ Apr 21 13:41:40 volumio volumio[418817]: info: Adding plugin upnp to MyMusic Plugins Apr 21 13:41:40 volumio volumio[418817]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 21 13:41:40 volumio volumio[418817]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 21 13:41:40 volumio volumio[418817]: info: Loading plugins from folder /data/plugins/ Apr 21 13:41:40 volumio volumio[418817]: info: Loading plugin "system"... Apr 21 13:41:40 volumio volumio[418817]: info: No id defined. Creating one Apr 21 13:41:41 volumio volumio[418817]: info: Loading plugin "appearance"... Apr 21 13:41:41 volumio volumio[418817]: info: Loading plugin "network"... Apr 21 13:41:41 volumio volumio[418817]: info: Refreshing Cached IP Addresses Apr 21 13:41:42 volumio sudo[418835]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 21 13:41:42 volumio sudo[418835]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 21 13:41:42 volumio sudo[418835]: pam_unix(sudo:session): session closed for user root Apr 21 13:41:42 volumio sudo[418837]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 21 13:41:42 volumio sudo[418837]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 21 13:41:42 volumio volumio[418817]: info: Loading plugin "services"... Apr 21 13:41:42 volumio volumio[418817]: info: Loading plugin "alsa_controller"... Apr 21 13:41:42 volumio sudo[418837]: pam_unix(sudo:session): session closed for user root Apr 21 13:41:42 volumio sudo[418840]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 21 13:41:42 volumio sudo[418840]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 21 13:41:42 volumio volumio[418817]: warn: The ALSA output card is not set, defaulting to card [object Object] Apr 21 13:41:42 volumio volumio[418817]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 21 13:41:42 volumio volumio[418817]: error: Could not retrieve plugin system_controller i2s_dacs Apr 21 13:41:42 volumio volumio[418817]: info: Setting mixer ACODEC for card LIBRETECH-CC Apr 21 13:41:42 volumio volumio[418817]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 21 13:41:42 volumio volumio[418817]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 21 13:41:42 volumio volumio[418817]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 21 13:41:42 volumio volumio[418817]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 21 13:41:42 volumio volumio[418817]: info: Loading plugin "wizard"... Apr 21 13:41:42 volumio volumio[418817]: info: Loading plugin "volumio_command_line_client"... Apr 21 13:41:42 volumio volumio[418817]: info: Loading plugin "upnp"... Apr 21 13:41:42 volumio volumio[418817]: info: [1713724902318] Starting Upmpd Daemon Apr 21 13:41:42 volumio volumio[418817]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 21 13:41:42 volumio volumio[418817]: info: Loading plugin "my_music"... Apr 21 13:41:42 volumio volumio[418817]: info: Loading plugin "mpd"... Apr 21 13:41:42 volumio volumio[418817]: info: Creating MPD Configuration file Apr 21 13:41:42 volumio volumio[418817]: info: Loading plugin "upnp_browser"... Apr 21 13:41:42 volumio sudo[418858]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 21 13:41:42 volumio sudo[418858]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 21 13:41:42 volumio sudo[418858]: pam_unix(sudo:session): session closed for user root Apr 21 13:41:42 volumio sudo[418860]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 21 13:41:42 volumio sudo[418860]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 21 13:41:42 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Apr 21 13:41:43 volumio systemd[1]: mpd.service: Deactivated successfully. Apr 21 13:41:43 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Apr 21 13:41:43 volumio systemd[1]: mpd.service: Consumed 3.526s CPU time. Apr 21 13:41:43 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Apr 21 13:41:45 volumio volumio[418817]: error: !!!! WARNING !!!! Apr 21 13:41:45 volumio volumio[418817]: error: The plugin music_service/upnp_browser failed to load, setting it to stopped. Error: TypeError: Entities is not a constructor Apr 21 13:41:45 volumio volumio[418817]: error: Stack trace: TypeError: Entities is not a constructor Apr 21 13:41:45 volumio volumio[418817]: at Object. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:12:18) Apr 21 13:41:45 volumio volumio[418817]: at Module._compile (node:internal/modules/cjs/loader:1356:14) Apr 21 13:41:45 volumio volumio[418817]: at Module._extensions..js (node:internal/modules/cjs/loader:1414:10) Apr 21 13:41:45 volumio volumio[418817]: at Module.load (node:internal/modules/cjs/loader:1197:32) Apr 21 13:41:45 volumio volumio[418817]: at Module._load (node:internal/modules/cjs/loader:1013:12) Apr 21 13:41:45 volumio volumio[418817]: at Module.require (node:internal/modules/cjs/loader:1225:19) Apr 21 13:41:45 volumio volumio[418817]: at require (node:internal/modules/helpers:177:18) Apr 21 13:41:45 volumio volumio[418817]: at Object. (/volumio/app/plugins/music_service/upnp_browser/index.js:12:24) Apr 21 13:41:45 volumio volumio[418817]: at Module._compile (node:internal/modules/cjs/loader:1356:14) Apr 21 13:41:45 volumio volumio[418817]: at Module._extensions..js (node:internal/modules/cjs/loader:1414:10) Apr 21 13:41:45 volumio volumio[418817]: at Module.load (node:internal/modules/cjs/loader:1197:32) Apr 21 13:41:45 volumio volumio[418817]: at Module._load (node:internal/modules/cjs/loader:1013:12) Apr 21 13:41:45 volumio volumio[418817]: at Module.require (node:internal/modules/cjs/loader:1225:19) Apr 21 13:41:45 volumio volumio[418817]: at require (node:internal/modules/helpers:177:18) Apr 21 13:41:45 volumio volumio[418817]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:235:29) Apr 21 13:41:45 volumio volumio[418817]: at /volumio/app/pluginmanager.js:338:34 Apr 21 13:41:45 volumio volumio[418817]: error: !!!! WARNING !!!! Apr 21 13:41:45 volumio volumio[418817]: info: Loading plugin "networkfs"... Apr 21 13:41:45 volumio volumio[418817]: info: Starting Udev Watcher for removable devices Apr 21 13:41:46 volumio volumio[418817]: info: Ignoring mount for partition: boot Apr 21 13:41:46 volumio volumio[418817]: info: Ignoring mount for partition: root Apr 21 13:41:46 volumio volumio[418817]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 21 13:41:46 volumio volumio[418817]: info: Loading plugin "alarm-clock"... Apr 21 13:41:46 volumio volumio[418817]: info: Loading plugin "airplay_emulation"... Apr 21 13:41:46 volumio volumio[418817]: info: Starting Shairport Sync Apr 21 13:41:46 volumio volumio[418817]: info: Loading plugin "last_100"... Apr 21 13:41:46 volumio volumio[418817]: info: Loading plugin "webradio"... Apr 21 13:41:46 volumio sudo[418840]: pam_unix(sudo:session): session closed for user root Apr 21 13:41:46 volumio volumio[418817]: info: Loading plugin "i2s_dacs"... Apr 21 13:41:46 volumio volumio[418817]: info: I2S DAC not set, start Auto-detection Apr 21 13:41:46 volumio volumio[418817]: info: Loading plugin "volumiodiscovery"... Apr 21 13:41:46 volumio volumio[418817]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 21 13:41:46 volumio volumio[418817]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 21 13:41:46 volumio volumio[418817]: *** WARNING *** For more information see Apr 21 13:41:46 volumio volumio[418817]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 21 13:41:46 volumio volumio[418817]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 21 13:41:46 volumio node[418817]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 21 13:41:46 volumio volumio[418817]: *** WARNING *** For more information see Apr 21 13:41:46 volumio node[418817]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 21 13:41:46 volumio node[418817]: *** WARNING *** For more information see Apr 21 13:41:46 volumio node[418817]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 21 13:41:46 volumio node[418817]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 21 13:41:46 volumio node[418817]: *** WARNING *** For more information see Apr 21 13:41:46 volumio volumio[418817]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 21 13:41:46 volumio volumio[418817]: info: Discovery: Started advertising with name: Volumio Apr 21 13:41:46 volumio volumio[418817]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 21 13:41:46 volumio volumio[418817]: info: Loading plugin "outputs"... Apr 21 13:41:46 volumio volumio[418817]: info: Loading plugin "albumart"... Apr 21 13:41:46 volumio volumio[418817]: info: Plugin example_plugin is not enabled Apr 21 13:41:46 volumio volumio[418817]: info: Loading plugin "inputs"... Apr 21 13:41:46 volumio volumio[418817]: info: Loading plugin "updater_comm"... Apr 21 13:41:46 volumio volumio[418817]: error: !!!! WARNING !!!! Apr 21 13:41:46 volumio volumio[418817]: error: The plugin system_controller/updater_comm failed to load, setting it to stopped. Error: Error: Cannot find module 'inotify' Apr 21 13:41:46 volumio volumio[418817]: Require stack: Apr 21 13:41:46 volumio volumio[418817]: - /volumio/app/plugins/system_controller/updater_comm/index.js Apr 21 13:41:46 volumio volumio[418817]: - /volumio/app/pluginmanager.js Apr 21 13:41:46 volumio volumio[418817]: - /volumio/app/index.js Apr 21 13:41:46 volumio volumio[418817]: - /volumio/index.js Apr 21 13:41:46 volumio volumio[418817]: error: Stack trace: Error: Cannot find module 'inotify' Apr 21 13:41:46 volumio volumio[418817]: Require stack: Apr 21 13:41:46 volumio volumio[418817]: - /volumio/app/plugins/system_controller/updater_comm/index.js Apr 21 13:41:46 volumio volumio[418817]: - /volumio/app/pluginmanager.js Apr 21 13:41:46 volumio volumio[418817]: - /volumio/app/index.js Apr 21 13:41:46 volumio volumio[418817]: - /volumio/index.js Apr 21 13:41:46 volumio volumio[418817]: at Module._resolveFilename (node:internal/modules/cjs/loader:1134:15) Apr 21 13:41:46 volumio volumio[418817]: at Module._load (node:internal/modules/cjs/loader:975:27) Apr 21 13:41:46 volumio volumio[418817]: at Module.require (node:internal/modules/cjs/loader:1225:19) Apr 21 13:41:46 volumio volumio[418817]: at require (node:internal/modules/helpers:177:18) Apr 21 13:41:46 volumio volumio[418817]: at Object. (/volumio/app/plugins/system_controller/updater_comm/index.js:4:15) Apr 21 13:41:46 volumio volumio[418817]: at Module._compile (node:internal/modules/cjs/loader:1356:14) Apr 21 13:41:46 volumio volumio[418817]: at Module._extensions..js (node:internal/modules/cjs/loader:1414:10) Apr 21 13:41:46 volumio volumio[418817]: at Module.load (node:internal/modules/cjs/loader:1197:32) Apr 21 13:41:46 volumio volumio[418817]: at Module._load (node:internal/modules/cjs/loader:1013:12) Apr 21 13:41:46 volumio volumio[418817]: at Module.require (node:internal/modules/cjs/loader:1225:19) Apr 21 13:41:46 volumio volumio[418817]: at require (node:internal/modules/helpers:177:18) Apr 21 13:41:46 volumio volumio[418817]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:235:29) Apr 21 13:41:46 volumio volumio[418817]: at /volumio/app/pluginmanager.js:338:34 Apr 21 13:41:46 volumio volumio[418817]: at Array.forEach () Apr 21 13:41:46 volumio volumio[418817]: at HashMap. (/volumio/app/pluginmanager.js:337:20) Apr 21 13:41:46 volumio volumio[418817]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Apr 21 13:41:46 volumio volumio[418817]: error: !!!! WARNING !!!! Apr 21 13:41:46 volumio volumio[418817]: info: Plugin mpdemulation is not enabled Apr 21 13:41:46 volumio volumio[418817]: info: Loading plugin "rest_api"... Apr 21 13:41:46 volumio volumio[418817]: info: Loading plugin "websocket"... Apr 21 13:41:46 volumio mpd[418863]: Apr 21 13:41 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 21 13:41:46 volumio systemd[1]: Started mpd.service - Music Player Daemon. Apr 21 13:41:46 volumio sudo[418860]: pam_unix(sudo:session): session closed for user root Apr 21 13:41:47 volumio volumio[418817]: info: Loading i18n strings for locale en Apr 21 13:41:47 volumio volumio[418817]: Updating browse sources language Apr 21 13:41:47 volumio volumio[418817]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 21 13:41:47 volumio volumio[418817]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 21 13:41:47 volumio volumio[418817]: info: CoreCommandRouter::initPlayerControls Apr 21 13:41:47 volumio volumio[418817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 13:41:47 volumio volumio[418817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 13:41:47 volumio volumio[418817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 13:41:47 volumio volumio[418817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 13:41:47 volumio volumio[418817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 13:41:47 volumio volumio[418817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 13:41:47 volumio volumio[418817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 13:41:47 volumio volumio[418817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 13:41:47 volumio volumio[418817]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 21 13:41:47 volumio volumio[418817]: /usr/share/nodejs/path-to-regexp/dist/index.js:114 Apr 21 13:41:47 volumio volumio[418817]: throw new TypeError("Unexpected ".concat(nextType, " at ").concat(index, ", expected ").concat(type)); Apr 21 13:41:47 volumio volumio[418817]: ^ Apr 21 13:41:47 volumio volumio[418817]: TypeError: Unexpected MODIFIER at 1, expected END Apr 21 13:41:47 volumio volumio[418817]: at mustConsume (/usr/share/nodejs/path-to-regexp/dist/index.js:114:15) Apr 21 13:41:47 volumio volumio[418817]: at parse (/usr/share/nodejs/path-to-regexp/dist/index.js:172:9) Apr 21 13:41:47 volumio volumio[418817]: at stringToRegexp (/usr/share/nodejs/path-to-regexp/dist/index.js:329:27) Apr 21 13:41:47 volumio volumio[418817]: at pathToRegexp (/usr/share/nodejs/path-to-regexp/dist/index.js:407:12) Apr 21 13:41:47 volumio volumio[418817]: at new Layer (/usr/share/nodejs/express/lib/router/layer.js:59:17) Apr 21 13:41:47 volumio volumio[418817]: at Function.route (/usr/share/nodejs/express/lib/router/index.js:505:15) Apr 21 13:41:47 volumio volumio[418817]: at app. [as get] (/usr/share/nodejs/express/lib/application.js:498:30) Apr 21 13:41:47 volumio volumio[418817]: at Object. (/volumio/index.js:59:12) Apr 21 13:41:47 volumio volumio[418817]: at Module._compile (node:internal/modules/cjs/loader:1356:14) Apr 21 13:41:47 volumio volumio[418817]: at Module._extensions..js (node:internal/modules/cjs/loader:1414:10) Apr 21 13:41:47 volumio volumio[418817]: at Module.load (node:internal/modules/cjs/loader:1197:32) Apr 21 13:41:47 volumio volumio[418817]: at Module._load (node:internal/modules/cjs/loader:1013:12) Apr 21 13:41:47 volumio volumio[418817]: at Function.executeUserEntryPoint [as runMain] (node:internal/modules/run_main:128:12) Apr 21 13:41:47 volumio volumio[418817]: at node:internal/main/run_main_module:28:49 Apr 21 13:41:47 volumio volumio[418817]: Node.js v18.19.0 Apr 21 13:41:47 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 21 13:41:47 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 21 13:41:47 volumio systemd[1]: volumio.service: Consumed 12.811s CPU time. Apr 21 13:41:47 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 6802. Apr 21 13:41:47 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Apr 21 13:41:47 volumio systemd[1]: volumio.service: Consumed 12.811s CPU time. Apr 21 13:41:47 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Apr 21 13:41:51 volumio volumio[418878]: info: ------------------------------------------- Apr 21 13:41:51 volumio volumio[418878]: info: ----- Volumio3 ---- Apr 21 13:41:51 volumio volumio[418878]: info: ------------------------------------------- Apr 21 13:41:51 volumio volumio[418878]: info: ----- System startup ---- Apr 21 13:41:51 volumio volumio[418878]: info: ------------------------------------------- Apr 21 13:41:52 volumio volumio[418878]: info: Plugin folders cleanup Apr 21 13:41:52 volumio volumio[418878]: info: Scanning into folder /volumio/app/plugins/ Apr 21 13:41:52 volumio volumio[418878]: info: Scanning category audio_interface Apr 21 13:41:52 volumio volumio[418878]: info: Scanning category miscellanea Apr 21 13:41:52 volumio volumio[418878]: info: Scanning category music_service Apr 21 13:41:52 volumio volumio[418878]: info: Scanning category plugins.json Apr 21 13:41:52 volumio volumio[418878]: info: Scanning category system_controller Apr 21 13:41:52 volumio volumio[418878]: info: Scanning category user_interface Apr 21 13:41:52 volumio volumio[418878]: info: Scanning into folder /data/plugins/ Apr 21 13:41:52 volumio volumio[418878]: info: Plugin folders cleanup completed Apr 21 13:41:52 volumio volumio[418878]: info: ------------------------------------------- Apr 21 13:41:52 volumio volumio[418878]: info: ----- Core plugins startup ---- Apr 21 13:41:52 volumio volumio[418878]: info: ------------------------------------------- Apr 21 13:41:52 volumio volumio[418878]: info: Loading plugins from folder /volumio/app/plugins/ Apr 21 13:41:52 volumio volumio[418878]: info: Adding plugin upnp to MyMusic Plugins Apr 21 13:41:52 volumio volumio[418878]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 21 13:41:52 volumio volumio[418878]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 21 13:41:52 volumio volumio[418878]: info: Loading plugins from folder /data/plugins/ Apr 21 13:41:52 volumio volumio[418878]: info: Loading plugin "system"... Apr 21 13:41:52 volumio volumio[418878]: info: No id defined. Creating one Apr 21 13:41:52 volumio volumio[418878]: info: Loading plugin "appearance"... Apr 21 13:41:53 volumio volumio[418878]: info: Loading plugin "network"... Apr 21 13:41:53 volumio volumio[418878]: info: Refreshing Cached IP Addresses Apr 21 13:41:53 volumio sudo[418896]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 21 13:41:53 volumio sudo[418896]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 21 13:41:53 volumio sudo[418896]: pam_unix(sudo:session): session closed for user root Apr 21 13:41:53 volumio sudo[418898]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 21 13:41:53 volumio sudo[418898]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 21 13:41:53 volumio volumio[418878]: info: Loading plugin "services"... Apr 21 13:41:53 volumio volumio[418878]: info: Loading plugin "alsa_controller"... Apr 21 13:41:53 volumio sudo[418898]: pam_unix(sudo:session): session closed for user root Apr 21 13:41:53 volumio sudo[418901]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 21 13:41:53 volumio sudo[418901]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 21 13:41:53 volumio volumio[418878]: warn: The ALSA output card is not set, defaulting to card [object Object] Apr 21 13:41:53 volumio volumio[418878]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 21 13:41:53 volumio volumio[418878]: error: Could not retrieve plugin system_controller i2s_dacs Apr 21 13:41:53 volumio volumio[418878]: info: Setting mixer ACODEC for card LIBRETECH-CC Apr 21 13:41:54 volumio volumio[418878]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 21 13:41:54 volumio volumio[418878]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 21 13:41:54 volumio volumio[418878]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 21 13:41:54 volumio volumio[418878]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 21 13:41:54 volumio volumio[418878]: info: Loading plugin "wizard"... Apr 21 13:41:54 volumio volumio[418878]: info: Loading plugin "volumio_command_line_client"... Apr 21 13:41:54 volumio volumio[418878]: info: Loading plugin "upnp"... Apr 21 13:41:54 volumio volumio[418878]: info: [1713724914123] Starting Upmpd Daemon Apr 21 13:41:54 volumio volumio[418878]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 21 13:41:54 volumio volumio[418878]: info: Loading plugin "my_music"... Apr 21 13:41:54 volumio volumio[418878]: info: Loading plugin "mpd"... Apr 21 13:41:54 volumio volumio[418878]: info: Creating MPD Configuration file Apr 21 13:41:54 volumio volumio[418878]: info: Loading plugin "upnp_browser"... Apr 21 13:41:54 volumio sudo[418919]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 21 13:41:54 volumio sudo[418919]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 21 13:41:54 volumio sudo[418919]: pam_unix(sudo:session): session closed for user root Apr 21 13:41:54 volumio sudo[418921]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 21 13:41:54 volumio sudo[418921]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 21 13:41:54 volumio volumio[418878]: error: !!!! WARNING !!!! Apr 21 13:41:54 volumio volumio[418878]: error: The plugin music_service/upnp_browser failed to load, setting it to stopped. Error: Error: Cannot find module 'bindings' Apr 21 13:41:54 volumio volumio[418878]: Require stack: Apr 21 13:41:54 volumio volumio[418878]: - /volumio/node_modules/libxmljs/dist/lib/bindings/index.js Apr 21 13:41:54 volumio volumio[418878]: - /volumio/node_modules/libxmljs/dist/lib/node.js Apr 21 13:41:54 volumio volumio[418878]: - /volumio/node_modules/libxmljs/dist/lib/document.js Apr 21 13:41:54 volumio volumio[418878]: - /volumio/node_modules/libxmljs/dist/lib/index.js Apr 21 13:41:54 volumio volumio[418878]: - /volumio/node_modules/libxmljs/dist/index.js Apr 21 13:41:54 volumio volumio[418878]: - /volumio/app/plugins/music_service/upnp_browser/index.js Apr 21 13:41:54 volumio volumio[418878]: - /volumio/app/pluginmanager.js Apr 21 13:41:54 volumio volumio[418878]: - /volumio/app/index.js Apr 21 13:41:54 volumio volumio[418878]: - /volumio/index.js Apr 21 13:41:54 volumio volumio[418878]: error: Stack trace: Error: Cannot find module 'bindings' Apr 21 13:41:54 volumio volumio[418878]: Require stack: Apr 21 13:41:54 volumio volumio[418878]: - /volumio/node_modules/libxmljs/dist/lib/bindings/index.js Apr 21 13:41:54 volumio volumio[418878]: - /volumio/node_modules/libxmljs/dist/lib/node.js Apr 21 13:41:54 volumio volumio[418878]: - /volumio/node_modules/libxmljs/dist/lib/document.js Apr 21 13:41:54 volumio volumio[418878]: - /volumio/node_modules/libxmljs/dist/lib/index.js Apr 21 13:41:54 volumio volumio[418878]: - /volumio/node_modules/libxmljs/dist/index.js Apr 21 13:41:54 volumio volumio[418878]: - /volumio/app/plugins/music_service/upnp_browser/index.js Apr 21 13:41:54 volumio volumio[418878]: - /volumio/app/pluginmanager.js Apr 21 13:41:54 volumio volumio[418878]: - /volumio/app/index.js Apr 21 13:41:54 volumio volumio[418878]: - /volumio/index.js Apr 21 13:41:54 volumio volumio[418878]: at Module._resolveFilename (node:internal/modules/cjs/loader:1134:15) Apr 21 13:41:54 volumio volumio[418878]: at Module._load (node:internal/modules/cjs/loader:975:27) Apr 21 13:41:54 volumio volumio[418878]: at Module.require (node:internal/modules/cjs/loader:1225:19) Apr 21 13:41:54 volumio volumio[418878]: at require (node:internal/modules/helpers:177:18) Apr 21 13:41:54 volumio volumio[418878]: at Object. (/volumio/node_modules/libxmljs/dist/lib/bindings/index.js:4:19) Apr 21 13:41:54 volumio volumio[418878]: at Module._compile (node:internal/modules/cjs/loader:1356:14) Apr 21 13:41:54 volumio volumio[418878]: at Module._extensions..js (node:internal/modules/cjs/loader:1414:10) Apr 21 13:41:54 volumio volumio[418878]: at Module.load (node:internal/modules/cjs/loader:1197:32) Apr 21 13:41:54 volumio volumio[418878]: at Module._load (node:internal/modules/cjs/loader:1013:12) Apr 21 13:41:54 volumio volumio[418878]: at Module.require (node:internal/modules/cjs/loader:1225:19) Apr 21 13:41:54 volumio volumio[418878]: error: !!!! WARNING !!!! Apr 21 13:41:54 volumio volumio[418878]: info: Loading plugin "networkfs"... Apr 21 13:41:54 volumio volumio[418878]: error: !!!! WARNING !!!! Apr 21 13:41:54 volumio volumio[418878]: error: The plugin system_controller/networkfs failed to load, setting it to stopped. Error: Error: Cannot find module 'udev' Apr 21 13:41:54 volumio volumio[418878]: Require stack: Apr 21 13:41:54 volumio volumio[418878]: - /volumio/app/plugins/system_controller/networkfs/index.js Apr 21 13:41:54 volumio volumio[418878]: - /volumio/app/pluginmanager.js Apr 21 13:41:54 volumio volumio[418878]: - /volumio/app/index.js Apr 21 13:41:54 volumio volumio[418878]: - /volumio/index.js Apr 21 13:41:54 volumio volumio[418878]: error: Stack trace: Error: Cannot find module 'udev' Apr 21 13:41:54 volumio volumio[418878]: Require stack: Apr 21 13:41:54 volumio volumio[418878]: - /volumio/app/plugins/system_controller/networkfs/index.js Apr 21 13:41:54 volumio volumio[418878]: - /volumio/app/pluginmanager.js Apr 21 13:41:54 volumio volumio[418878]: - /volumio/app/index.js Apr 21 13:41:54 volumio volumio[418878]: - /volumio/index.js Apr 21 13:41:54 volumio volumio[418878]: at Module._resolveFilename (node:internal/modules/cjs/loader:1134:15) Apr 21 13:41:54 volumio volumio[418878]: at Module._load (node:internal/modules/cjs/loader:975:27) Apr 21 13:41:54 volumio volumio[418878]: at Module.require (node:internal/modules/cjs/loader:1225:19) Apr 21 13:41:54 volumio volumio[418878]: at require (node:internal/modules/helpers:177:18) Apr 21 13:41:54 volumio volumio[418878]: at Object. (/volumio/app/plugins/system_controller/networkfs/index.js:9:12) Apr 21 13:41:54 volumio volumio[418878]: at Module._compile (node:internal/modules/cjs/loader:1356:14) Apr 21 13:41:54 volumio volumio[418878]: at Module._extensions..js (node:internal/modules/cjs/loader:1414:10) Apr 21 13:41:54 volumio volumio[418878]: at Module.load (node:internal/modules/cjs/loader:1197:32) Apr 21 13:41:54 volumio volumio[418878]: at Module._load (node:internal/modules/cjs/loader:1013:12) Apr 21 13:41:54 volumio volumio[418878]: at Module.require (node:internal/modules/cjs/loader:1225:19) Apr 21 13:41:54 volumio volumio[418878]: error: !!!! WARNING !!!! Apr 21 13:41:54 volumio volumio[418878]: info: Loading plugin "alarm-clock"... Apr 21 13:41:54 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Apr 21 13:41:54 volumio systemd[1]: mpd.service: Deactivated successfully. Apr 21 13:41:54 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Apr 21 13:41:54 volumio systemd[1]: mpd.service: Consumed 3.662s CPU time. Apr 21 13:41:54 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Apr 21 13:41:55 volumio volumio[418878]: info: Loading plugin "airplay_emulation"... Apr 21 13:41:55 volumio volumio[418878]: info: Starting Shairport Sync Apr 21 13:41:55 volumio volumio[418878]: info: Loading plugin "last_100"... Apr 21 13:41:55 volumio volumio[418878]: info: Loading plugin "webradio"... Apr 21 13:41:55 volumio volumio[418878]: error: !!!! WARNING !!!! Apr 21 13:41:55 volumio volumio[418878]: error: The plugin music_service/webradio failed to load, setting it to stopped. Error: Error: Cannot find module 'bindings' Apr 21 13:41:55 volumio volumio[418878]: Require stack: Apr 21 13:41:55 volumio volumio[418878]: - /volumio/node_modules/libxmljs/dist/lib/bindings/index.js Apr 21 13:41:55 volumio volumio[418878]: - /volumio/node_modules/libxmljs/dist/lib/node.js Apr 21 13:41:55 volumio volumio[418878]: - /volumio/node_modules/libxmljs/dist/lib/document.js Apr 21 13:41:55 volumio volumio[418878]: - /volumio/node_modules/libxmljs/dist/lib/index.js Apr 21 13:41:55 volumio volumio[418878]: - /volumio/node_modules/libxmljs/dist/index.js Apr 21 13:41:55 volumio volumio[418878]: - /volumio/app/plugins/music_service/webradio/index.js Apr 21 13:41:55 volumio volumio[418878]: - /volumio/app/pluginmanager.js Apr 21 13:41:55 volumio volumio[418878]: - /volumio/app/index.js Apr 21 13:41:55 volumio volumio[418878]: - /volumio/index.js Apr 21 13:41:55 volumio volumio[418878]: error: Stack trace: Error: Cannot find module 'bindings' Apr 21 13:41:55 volumio volumio[418878]: Require stack: Apr 21 13:41:55 volumio volumio[418878]: - /volumio/node_modules/libxmljs/dist/lib/bindings/index.js Apr 21 13:41:55 volumio volumio[418878]: - /volumio/node_modules/libxmljs/dist/lib/node.js Apr 21 13:41:55 volumio volumio[418878]: - /volumio/node_modules/libxmljs/dist/lib/document.js Apr 21 13:41:55 volumio volumio[418878]: - /volumio/node_modules/libxmljs/dist/lib/index.js Apr 21 13:41:55 volumio volumio[418878]: - /volumio/node_modules/libxmljs/dist/index.js Apr 21 13:41:55 volumio volumio[418878]: - /volumio/app/plugins/music_service/webradio/index.js Apr 21 13:41:55 volumio volumio[418878]: - /volumio/app/pluginmanager.js Apr 21 13:41:55 volumio volumio[418878]: - /volumio/app/index.js Apr 21 13:41:55 volumio volumio[418878]: - /volumio/index.js Apr 21 13:41:55 volumio volumio[418878]: at Module._resolveFilename (node:internal/modules/cjs/loader:1134:15) Apr 21 13:41:55 volumio volumio[418878]: at Module._load (node:internal/modules/cjs/loader:975:27) Apr 21 13:41:55 volumio volumio[418878]: at Module.require (node:internal/modules/cjs/loader:1225:19) Apr 21 13:41:55 volumio volumio[418878]: at require (node:internal/modules/helpers:177:18) Apr 21 13:41:55 volumio volumio[418878]: at Object. (/volumio/node_modules/libxmljs/dist/lib/bindings/index.js:4:19) Apr 21 13:41:55 volumio volumio[418878]: at Module._compile (node:internal/modules/cjs/loader:1356:14) Apr 21 13:41:55 volumio volumio[418878]: at Module._extensions..js (node:internal/modules/cjs/loader:1414:10) Apr 21 13:41:55 volumio volumio[418878]: at Module.load (node:internal/modules/cjs/loader:1197:32) Apr 21 13:41:55 volumio volumio[418878]: at Module._load (node:internal/modules/cjs/loader:1013:12) Apr 21 13:41:55 volumio volumio[418878]: at Module.require (node:internal/modules/cjs/loader:1225:19) Apr 21 13:41:55 volumio volumio[418878]: error: !!!! WARNING !!!! Apr 21 13:41:55 volumio volumio[418878]: info: Loading plugin "i2s_dacs"... Apr 21 13:41:55 volumio volumio[418878]: info: I2S DAC not set, start Auto-detection Apr 21 13:41:55 volumio volumio[418878]: info: Loading plugin "volumiodiscovery"... Apr 21 13:41:55 volumio volumio[418878]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 21 13:41:55 volumio volumio[418878]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 21 13:41:55 volumio volumio[418878]: *** WARNING *** For more information see Apr 21 13:41:55 volumio node[418878]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 21 13:41:55 volumio volumio[418878]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 21 13:41:55 volumio volumio[418878]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 21 13:41:55 volumio volumio[418878]: *** WARNING *** For more information see Apr 21 13:41:55 volumio node[418878]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 21 13:41:55 volumio node[418878]: *** WARNING *** For more information see Apr 21 13:41:55 volumio node[418878]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 21 13:41:55 volumio node[418878]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 21 13:41:55 volumio node[418878]: *** WARNING *** For more information see Apr 21 13:41:55 volumio volumio[418878]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 21 13:41:55 volumio volumio[418878]: info: Discovery: Started advertising with name: Volumio Apr 21 13:41:55 volumio volumio[418878]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 21 13:41:55 volumio volumio[418878]: info: Loading plugin "outputs"... Apr 21 13:41:55 volumio volumio[418878]: info: Loading plugin "albumart"... Apr 21 13:41:55 volumio volumio[418878]: info: Plugin example_plugin is not enabled Apr 21 13:41:55 volumio volumio[418878]: info: Loading plugin "inputs"... Apr 21 13:41:55 volumio volumio[418878]: info: Loading plugin "updater_comm"... Apr 21 13:41:55 volumio volumio[418878]: error: !!!! WARNING !!!! Apr 21 13:41:55 volumio volumio[418878]: error: The plugin system_controller/updater_comm failed to load, setting it to stopped. Error: Error: Cannot find module 'inotify' Apr 21 13:41:55 volumio volumio[418878]: Require stack: Apr 21 13:41:55 volumio volumio[418878]: - /volumio/app/plugins/system_controller/updater_comm/index.js Apr 21 13:41:55 volumio volumio[418878]: - /volumio/app/pluginmanager.js Apr 21 13:41:55 volumio volumio[418878]: - /volumio/app/index.js Apr 21 13:41:55 volumio volumio[418878]: - /volumio/index.js Apr 21 13:41:55 volumio volumio[418878]: error: Stack trace: Error: Cannot find module 'inotify' Apr 21 13:41:55 volumio volumio[418878]: Require stack: Apr 21 13:41:55 volumio volumio[418878]: - /volumio/app/plugins/system_controller/updater_comm/index.js Apr 21 13:41:55 volumio volumio[418878]: - /volumio/app/pluginmanager.js Apr 21 13:41:55 volumio volumio[418878]: - /volumio/app/index.js Apr 21 13:41:55 volumio volumio[418878]: - /volumio/index.js Apr 21 13:41:55 volumio volumio[418878]: at Module._resolveFilename (node:internal/modules/cjs/loader:1134:15) Apr 21 13:41:55 volumio volumio[418878]: at Module._load (node:internal/modules/cjs/loader:975:27) Apr 21 13:41:55 volumio volumio[418878]: at Module.require (node:internal/modules/cjs/loader:1225:19) Apr 21 13:41:55 volumio volumio[418878]: at require (node:internal/modules/helpers:177:18) Apr 21 13:41:55 volumio volumio[418878]: at Object. (/volumio/app/plugins/system_controller/updater_comm/index.js:4:15) Apr 21 13:41:55 volumio volumio[418878]: at Module._compile (node:internal/modules/cjs/loader:1356:14) Apr 21 13:41:55 volumio volumio[418878]: at Module._extensions..js (node:internal/modules/cjs/loader:1414:10) Apr 21 13:41:55 volumio volumio[418878]: at Module.load (node:internal/modules/cjs/loader:1197:32) Apr 21 13:41:55 volumio volumio[418878]: at Module._load (node:internal/modules/cjs/loader:1013:12) Apr 21 13:41:55 volumio volumio[418878]: at Module.require (node:internal/modules/cjs/loader:1225:19) Apr 21 13:41:55 volumio volumio[418878]: error: !!!! WARNING !!!! Apr 21 13:41:55 volumio volumio[418878]: info: Plugin mpdemulation is not enabled Apr 21 13:41:55 volumio volumio[418878]: info: Loading plugin "rest_api"... Apr 21 13:41:55 volumio volumio[418878]: info: Loading plugin "websocket"... Apr 21 13:41:55 volumio volumio[418878]: info: Loading i18n strings for locale en Apr 21 13:41:55 volumio volumio[418878]: Updating browse sources language Apr 21 13:41:55 volumio volumio[418878]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 21 13:41:56 volumio volumio[418878]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 21 13:41:56 volumio volumio[418878]: info: CoreCommandRouter::initPlayerControls Apr 21 13:41:56 volumio volumio[418878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 13:41:56 volumio volumio[418878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 13:41:56 volumio volumio[418878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 13:41:56 volumio volumio[418878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 13:41:56 volumio volumio[418878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 13:41:56 volumio volumio[418878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 13:41:56 volumio volumio[418878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 13:41:56 volumio volumio[418878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 13:41:56 volumio volumio[418878]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 21 13:41:56 volumio volumio[418878]: /usr/share/nodejs/path-to-regexp/dist/index.js:114 Apr 21 13:41:56 volumio volumio[418878]: throw new TypeError("Unexpected ".concat(nextType, " at ").concat(index, ", expected ").concat(type)); Apr 21 13:41:56 volumio volumio[418878]: ^ Apr 21 13:41:56 volumio volumio[418878]: TypeError: Unexpected MODIFIER at 1, expected END Apr 21 13:41:56 volumio volumio[418878]: at mustConsume (/usr/share/nodejs/path-to-regexp/dist/index.js:114:15) Apr 21 13:41:56 volumio volumio[418878]: at parse (/usr/share/nodejs/path-to-regexp/dist/index.js:172:9) Apr 21 13:41:56 volumio volumio[418878]: at stringToRegexp (/usr/share/nodejs/path-to-regexp/dist/index.js:329:27) Apr 21 13:41:56 volumio volumio[418878]: at pathToRegexp (/usr/share/nodejs/path-to-regexp/dist/index.js:407:12) Apr 21 13:41:56 volumio volumio[418878]: at new Layer (/usr/share/nodejs/express/lib/router/layer.js:59:17) Apr 21 13:41:56 volumio volumio[418878]: at Function.route (/usr/share/nodejs/express/lib/router/index.js:505:15) Apr 21 13:41:56 volumio volumio[418878]: at app. [as get] (/usr/share/nodejs/express/lib/application.js:498:30) Apr 21 13:41:56 volumio volumio[418878]: at Object. (/volumio/index.js:59:12) Apr 21 13:41:56 volumio volumio[418878]: at Module._compile (node:internal/modules/cjs/loader:1356:14) Apr 21 13:41:56 volumio volumio[418878]: at Module._extensions..js (node:internal/modules/cjs/loader:1414:10) Apr 21 13:41:56 volumio volumio[418878]: Node.js v18.19.0 Apr 21 13:41:56 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 21 13:41:56 volumio sudo[418901]: pam_unix(sudo:session): session closed for user root Apr 21 13:41:56 volumio sudo[418921]: pam_unix(sudo:session): session closed for user root Apr 21 13:41:56 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 21 13:41:56 volumio systemd[1]: volumio.service: Consumed 10.072s CPU time. Apr 21 13:41:56 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 6803. Apr 21 13:41:56 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Apr 21 13:41:56 volumio systemd[1]: volumio.service: Consumed 10.072s CPU time. Apr 21 13:41:56 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Apr 21 13:41:59 volumio mpd[418924]: Apr 21 13:41 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 21 13:41:59 volumio systemd[1]: Started mpd.service - Music Player Daemon. Apr 21 13:41:59 volumio volumio[418934]: node:internal/modules/cjs/loader:1137 Apr 21 13:41:59 volumio volumio[418934]: throw err; Apr 21 13:41:59 volumio volumio[418934]: ^ Apr 21 13:41:59 volumio volumio[418934]: Error: Cannot find module 'connect-busboy' Apr 21 13:41:59 volumio volumio[418934]: Require stack: Apr 21 13:41:59 volumio volumio[418934]: - /volumio/http/index.js Apr 21 13:41:59 volumio volumio[418934]: - /volumio/index.js Apr 21 13:41:59 volumio volumio[418934]: at Module._resolveFilename (node:internal/modules/cjs/loader:1134:15) Apr 21 13:41:59 volumio volumio[418934]: at Module._load (node:internal/modules/cjs/loader:975:27) Apr 21 13:41:59 volumio volumio[418934]: at Module.require (node:internal/modules/cjs/loader:1225:19) Apr 21 13:41:59 volumio volumio[418934]: at require (node:internal/modules/helpers:177:18) Apr 21 13:41:59 volumio volumio[418934]: at Object. (/volumio/http/index.js:7:14) Apr 21 13:41:59 volumio volumio[418934]: at Module._compile (node:internal/modules/cjs/loader:1356:14) Apr 21 13:41:59 volumio volumio[418934]: at Module._extensions..js (node:internal/modules/cjs/loader:1414:10) Apr 21 13:41:59 volumio volumio[418934]: at Module.load (node:internal/modules/cjs/loader:1197:32) Apr 21 13:41:59 volumio volumio[418934]: at Module._load (node:internal/modules/cjs/loader:1013:12) Apr 21 13:41:59 volumio volumio[418934]: at Module.require (node:internal/modules/cjs/loader:1225:19) { Apr 21 13:41:59 volumio volumio[418934]: code: 'MODULE_NOT_FOUND', Apr 21 13:41:59 volumio volumio[418934]: requireStack: [ '/volumio/http/index.js', '/volumio/index.js' ] Apr 21 13:41:59 volumio volumio[418934]: } Apr 21 13:41:59 volumio volumio[418934]: Node.js v18.19.0 Apr 21 13:41:59 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 21 13:41:59 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 21 13:41:59 volumio systemd[1]: volumio.service: Consumed 2.743s CPU time. Apr 21 13:41:59 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 6804. Apr 21 13:41:59 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Apr 21 13:41:59 volumio systemd[1]: volumio.service: Consumed 2.743s CPU time. Apr 21 13:41:59 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Apr 21 13:42:02 volumio volumio[418945]: node:internal/modules/cjs/loader:1137 Apr 21 13:42:02 volumio volumio[418945]: throw err; Apr 21 13:42:02 volumio volumio[418945]: ^ Apr 21 13:42:02 volumio volumio[418945]: Error: Cannot find module 'connect-busboy' Apr 21 13:42:02 volumio volumio[418945]: Require stack: Apr 21 13:42:02 volumio volumio[418945]: - /volumio/http/index.js Apr 21 13:42:02 volumio volumio[418945]: - /volumio/index.js Apr 21 13:42:02 volumio volumio[418945]: at Module._resolveFilename (node:internal/modules/cjs/loader:1134:15) Apr 21 13:42:02 volumio volumio[418945]: at Module._load (node:internal/modules/cjs/loader:975:27) Apr 21 13:42:02 volumio volumio[418945]: at Module.require (node:internal/modules/cjs/loader:1225:19) Apr 21 13:42:02 volumio volumio[418945]: at require (node:internal/modules/helpers:177:18) Apr 21 13:42:02 volumio volumio[418945]: at Object. (/volumio/http/index.js:7:14) Apr 21 13:42:02 volumio volumio[418945]: at Module._compile (node:internal/modules/cjs/loader:1356:14) Apr 21 13:42:02 volumio volumio[418945]: at Module._extensions..js (node:internal/modules/cjs/loader:1414:10) Apr 21 13:42:02 volumio volumio[418945]: at Module.load (node:internal/modules/cjs/loader:1197:32) Apr 21 13:42:02 volumio volumio[418945]: at Module._load (node:internal/modules/cjs/loader:1013:12) Apr 21 13:42:02 volumio volumio[418945]: at Module.require (node:internal/modules/cjs/loader:1225:19) { Apr 21 13:42:02 volumio volumio[418945]: code: 'MODULE_NOT_FOUND', Apr 21 13:42:02 volumio volumio[418945]: requireStack: [ '/volumio/http/index.js', '/volumio/index.js' ] Apr 21 13:42:02 volumio volumio[418945]: } Apr 21 13:42:02 volumio volumio[418945]: Node.js v18.19.0 Apr 21 13:42:02 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 21 13:42:02 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 21 13:42:02 volumio systemd[1]: volumio.service: Consumed 2.533s CPU time. Apr 21 13:42:02 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 6805. Apr 21 13:42:02 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Apr 21 13:42:02 volumio systemd[1]: volumio.service: Consumed 2.533s CPU time. Apr 21 13:42:02 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Apr 21 13:42:05 volumio volumio[418952]: node:internal/modules/cjs/loader:1137 Apr 21 13:42:05 volumio volumio[418952]: throw err; Apr 21 13:42:05 volumio volumio[418952]: ^ Apr 21 13:42:05 volumio volumio[418952]: Error: Cannot find module 'connect-busboy' Apr 21 13:42:05 volumio volumio[418952]: Require stack: Apr 21 13:42:05 volumio volumio[418952]: - /volumio/http/index.js Apr 21 13:42:05 volumio volumio[418952]: - /volumio/index.js Apr 21 13:42:05 volumio volumio[418952]: at Module._resolveFilename (node:internal/modules/cjs/loader:1134:15) Apr 21 13:42:05 volumio volumio[418952]: at Module._load (node:internal/modules/cjs/loader:975:27) Apr 21 13:42:05 volumio volumio[418952]: at Module.require (node:internal/modules/cjs/loader:1225:19) Apr 21 13:42:05 volumio volumio[418952]: at require (node:internal/modules/helpers:177:18) Apr 21 13:42:05 volumio volumio[418952]: at Object. (/volumio/http/index.js:7:14) Apr 21 13:42:05 volumio volumio[418952]: at Module._compile (node:internal/modules/cjs/loader:1356:14) Apr 21 13:42:05 volumio volumio[418952]: at Module._extensions..js (node:internal/modules/cjs/loader:1414:10) Apr 21 13:42:05 volumio volumio[418952]: at Module.load (node:internal/modules/cjs/loader:1197:32) Apr 21 13:42:05 volumio volumio[418952]: at Module._load (node:internal/modules/cjs/loader:1013:12) Apr 21 13:42:05 volumio volumio[418952]: at Module.require (node:internal/modules/cjs/loader:1225:19) { Apr 21 13:42:05 volumio volumio[418952]: code: 'MODULE_NOT_FOUND', Apr 21 13:42:05 volumio volumio[418952]: requireStack: [ '/volumio/http/index.js', '/volumio/index.js' ] Apr 21 13:42:05 volumio volumio[418952]: } Apr 21 13:42:05 volumio volumio[418952]: Node.js v18.19.0 Apr 21 13:42:05 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 21 13:42:05 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 21 13:42:05 volumio systemd[1]: volumio.service: Consumed 2.534s CPU time. Apr 21 13:42:05 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 6806. Apr 21 13:42:05 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Apr 21 13:42:05 volumio systemd[1]: volumio.service: Consumed 2.534s CPU time. Apr 21 13:42:05 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Apr 21 13:42:08 volumio volumio[418959]: node:internal/modules/package_json_reader:92 Apr 21 13:42:08 volumio volumio[418959]: throw error; Apr 21 13:42:08 volumio volumio[418959]: ^ Apr 21 13:42:08 volumio volumio[418959]: SyntaxError: Error parsing /volumio/node_modules/connect-busboy/package.json: Unexpected end of JSON input Apr 21 13:42:08 volumio volumio[418959]: at parse () Apr 21 13:42:08 volumio volumio[418959]: at read (node:internal/modules/package_json_reader:79:16) Apr 21 13:42:08 volumio volumio[418959]: at readPackage (node:internal/modules/package_json_reader:140:10) Apr 21 13:42:08 volumio volumio[418959]: at resolveExports (node:internal/modules/cjs/loader:580:15) Apr 21 13:42:08 volumio volumio[418959]: at Module._findPath (node:internal/modules/cjs/loader:658:31) Apr 21 13:42:08 volumio volumio[418959]: at Module._resolveFilename (node:internal/modules/cjs/loader:1120:27) Apr 21 13:42:08 volumio volumio[418959]: at Module._load (node:internal/modules/cjs/loader:975:27) Apr 21 13:42:08 volumio volumio[418959]: at Module.require (node:internal/modules/cjs/loader:1225:19) Apr 21 13:42:08 volumio volumio[418959]: at require (node:internal/modules/helpers:177:18) Apr 21 13:42:08 volumio volumio[418959]: at Object. (/volumio/http/index.js:7:14) { Apr 21 13:42:08 volumio volumio[418959]: path: '/volumio/node_modules/connect-busboy/package.json' Apr 21 13:42:08 volumio volumio[418959]: } Apr 21 13:42:08 volumio volumio[418959]: Node.js v18.19.0 Apr 21 13:42:08 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 21 13:42:08 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 21 13:42:08 volumio systemd[1]: volumio.service: Consumed 2.730s CPU time. Apr 21 13:42:08 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 6807. Apr 21 13:42:08 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Apr 21 13:42:08 volumio systemd[1]: volumio.service: Consumed 2.730s CPU time. Apr 21 13:42:08 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Apr 21 13:42:10 volumio volumio[418966]: /volumio/http/routes.js:2 Apr 21 13:42:10 volumio volumio[418966]: var router = express.Router(); Apr 21 13:42:10 volumio volumio[418966]: ^ Apr 21 13:42:10 volumio volumio[418966]: TypeError: express.Router is not a function Apr 21 13:42:10 volumio volumio[418966]: at Object. (/volumio/http/routes.js:2:22) Apr 21 13:42:10 volumio volumio[418966]: at Module._compile (node:internal/modules/cjs/loader:1356:14) Apr 21 13:42:10 volumio volumio[418966]: at Module._extensions..js (node:internal/modules/cjs/loader:1414:10) Apr 21 13:42:10 volumio volumio[418966]: at Module.load (node:internal/modules/cjs/loader:1197:32) Apr 21 13:42:10 volumio volumio[418966]: at Module._load (node:internal/modules/cjs/loader:1013:12) Apr 21 13:42:10 volumio volumio[418966]: at Module.require (node:internal/modules/cjs/loader:1225:19) Apr 21 13:42:10 volumio volumio[418966]: at require (node:internal/modules/helpers:177:18) Apr 21 13:42:10 volumio volumio[418966]: at Object. (/volumio/http/index.js:5:14) Apr 21 13:42:10 volumio volumio[418966]: at Module._compile (node:internal/modules/cjs/loader:1356:14) Apr 21 13:42:10 volumio volumio[418966]: at Module._extensions..js (node:internal/modules/cjs/loader:1414:10) Apr 21 13:42:10 volumio volumio[418966]: Node.js v18.19.0 Apr 21 13:42:10 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 21 13:42:10 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 21 13:42:10 volumio systemd[1]: volumio.service: Consumed 1.641s CPU time. Apr 21 13:42:10 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 6808. Apr 21 13:42:10 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Apr 21 13:42:10 volumio systemd[1]: volumio.service: Consumed 1.641s CPU time. Apr 21 13:42:10 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Apr 21 13:42:11 volumio volumio[418973]: node:internal/modules/cjs/loader:1137 Apr 21 13:42:11 volumio volumio[418973]: throw err; Apr 21 13:42:11 volumio volumio[418973]: ^ Apr 21 13:42:11 volumio volumio[418973]: Error: Cannot find module './route' Apr 21 13:42:11 volumio volumio[418973]: Require stack: Apr 21 13:42:11 volumio volumio[418973]: - /volumio/node_modules/express/lib/router/index.js Apr 21 13:42:11 volumio volumio[418973]: - /volumio/node_modules/express/lib/application.js Apr 21 13:42:11 volumio volumio[418973]: - /volumio/node_modules/express/lib/express.js Apr 21 13:42:11 volumio volumio[418973]: - /volumio/node_modules/express/index.js Apr 21 13:42:11 volumio volumio[418973]: - /volumio/http/index.js Apr 21 13:42:11 volumio volumio[418973]: - /volumio/index.js Apr 21 13:42:11 volumio volumio[418973]: at Module._resolveFilename (node:internal/modules/cjs/loader:1134:15) Apr 21 13:42:11 volumio volumio[418973]: at Module._load (node:internal/modules/cjs/loader:975:27) Apr 21 13:42:11 volumio volumio[418973]: at Module.require (node:internal/modules/cjs/loader:1225:19) Apr 21 13:42:11 volumio volumio[418973]: at require (node:internal/modules/helpers:177:18) Apr 21 13:42:11 volumio volumio[418973]: at Object. (/volumio/node_modules/express/lib/router/index.js:16:13) Apr 21 13:42:11 volumio volumio[418973]: at Module._compile (node:internal/modules/cjs/loader:1356:14) Apr 21 13:42:11 volumio volumio[418973]: at Module._extensions..js (node:internal/modules/cjs/loader:1414:10) Apr 21 13:42:11 volumio volumio[418973]: at Module.load (node:internal/modules/cjs/loader:1197:32) Apr 21 13:42:11 volumio volumio[418973]: at Module._load (node:internal/modules/cjs/loader:1013:12) Apr 21 13:42:11 volumio volumio[418973]: at Module.require (node:internal/modules/cjs/loader:1225:19) { Apr 21 13:42:11 volumio volumio[418973]: code: 'MODULE_NOT_FOUND', Apr 21 13:42:11 volumio volumio[418973]: requireStack: [ Apr 21 13:42:11 volumio volumio[418973]: '/volumio/node_modules/express/lib/router/index.js', Apr 21 13:42:11 volumio volumio[418973]: '/volumio/node_modules/express/lib/application.js', Apr 21 13:42:11 volumio volumio[418973]: '/volumio/node_modules/express/lib/express.js', Apr 21 13:42:11 volumio volumio[418973]: '/volumio/node_modules/express/index.js', Apr 21 13:42:11 volumio volumio[418973]: '/volumio/http/index.js', Apr 21 13:42:11 volumio volumio[418973]: '/volumio/index.js' Apr 21 13:42:11 volumio volumio[418973]: ] Apr 21 13:42:11 volumio volumio[418973]: } Apr 21 13:42:11 volumio volumio[418973]: Node.js v18.19.0 Apr 21 13:42:11 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 21 13:42:11 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 21 13:42:11 volumio systemd[1]: volumio.service: Consumed 1.561s CPU time. Apr 21 13:42:12 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 6809. Apr 21 13:42:12 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Apr 21 13:42:12 volumio systemd[1]: volumio.service: Consumed 1.561s CPU time. Apr 21 13:42:12 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Apr 21 13:42:13 volumio volumio[418980]: node:internal/modules/cjs/loader:1137 Apr 21 13:42:13 volumio volumio[418980]: throw err; Apr 21 13:42:13 volumio volumio[418980]: ^ Apr 21 13:42:13 volumio volumio[418980]: Error: Cannot find module './route' Apr 21 13:42:13 volumio volumio[418980]: Require stack: Apr 21 13:42:13 volumio volumio[418980]: - /volumio/node_modules/express/lib/router/index.js Apr 21 13:42:13 volumio volumio[418980]: - /volumio/node_modules/express/lib/application.js Apr 21 13:42:13 volumio volumio[418980]: - /volumio/node_modules/express/lib/express.js Apr 21 13:42:13 volumio volumio[418980]: - /volumio/node_modules/express/index.js Apr 21 13:42:13 volumio volumio[418980]: - /volumio/http/index.js Apr 21 13:42:13 volumio volumio[418980]: - /volumio/index.js Apr 21 13:42:13 volumio volumio[418980]: at Module._resolveFilename (node:internal/modules/cjs/loader:1134:15) Apr 21 13:42:13 volumio volumio[418980]: at Module._load (node:internal/modules/cjs/loader:975:27) Apr 21 13:42:13 volumio volumio[418980]: at Module.require (node:internal/modules/cjs/loader:1225:19) Apr 21 13:42:13 volumio volumio[418980]: at require (node:internal/modules/helpers:177:18) Apr 21 13:42:13 volumio volumio[418980]: at Object. (/volumio/node_modules/express/lib/router/index.js:16:13) Apr 21 13:42:13 volumio volumio[418980]: at Module._compile (node:internal/modules/cjs/loader:1356:14) Apr 21 13:42:13 volumio volumio[418980]: at Module._extensions..js (node:internal/modules/cjs/loader:1414:10) Apr 21 13:42:13 volumio volumio[418980]: at Module.load (node:internal/modules/cjs/loader:1197:32) Apr 21 13:42:13 volumio volumio[418980]: at Module._load (node:internal/modules/cjs/loader:1013:12) Apr 21 13:42:13 volumio volumio[418980]: at Module.require (node:internal/modules/cjs/loader:1225:19) { Apr 21 13:42:13 volumio volumio[418980]: code: 'MODULE_NOT_FOUND', Apr 21 13:42:13 volumio volumio[418980]: requireStack: [ Apr 21 13:42:13 volumio volumio[418980]: '/volumio/node_modules/express/lib/router/index.js', Apr 21 13:42:13 volumio volumio[418980]: '/volumio/node_modules/express/lib/application.js', Apr 21 13:42:13 volumio volumio[418980]: '/volumio/node_modules/express/lib/express.js', Apr 21 13:42:13 volumio volumio[418980]: '/volumio/node_modules/express/index.js', Apr 21 13:42:13 volumio volumio[418980]: '/volumio/http/index.js', Apr 21 13:42:13 volumio volumio[418980]: '/volumio/index.js' Apr 21 13:42:13 volumio volumio[418980]: ] Apr 21 13:42:13 volumio volumio[418980]: } Apr 21 13:42:13 volumio volumio[418980]: Node.js v18.19.0 Apr 21 13:42:13 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 21 13:42:13 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 21 13:42:13 volumio systemd[1]: volumio.service: Consumed 1.612s CPU time. Apr 21 13:42:13 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 6810. Apr 21 13:42:13 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Apr 21 13:42:13 volumio systemd[1]: volumio.service: Consumed 1.612s CPU time. Apr 21 13:42:13 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Apr 21 13:42:17 volumio volumio[418987]: node:internal/modules/cjs/loader:1137 Apr 21 13:42:17 volumio volumio[418987]: throw err; Apr 21 13:42:17 volumio volumio[418987]: ^ Apr 21 13:42:17 volumio volumio[418987]: Error: Cannot find module './util/assign' Apr 21 13:42:17 volumio volumio[418987]: Require stack: Apr 21 13:42:17 volumio volumio[418987]: - /volumio/node_modules/v-conf/node_modules/fs-extra/lib/index.js Apr 21 13:42:17 volumio volumio[418987]: - /volumio/node_modules/v-conf/index.js Apr 21 13:42:17 volumio volumio[418987]: - /volumio/app/index.js Apr 21 13:42:17 volumio volumio[418987]: - /volumio/index.js Apr 21 13:42:17 volumio volumio[418987]: at Module._resolveFilename (node:internal/modules/cjs/loader:1134:15) Apr 21 13:42:17 volumio volumio[418987]: at Module._load (node:internal/modules/cjs/loader:975:27) Apr 21 13:42:17 volumio volumio[418987]: at Module.require (node:internal/modules/cjs/loader:1225:19) Apr 21 13:42:17 volumio volumio[418987]: at require (node:internal/modules/helpers:177:18) Apr 21 13:42:17 volumio volumio[418987]: at Object. (/volumio/node_modules/v-conf/node_modules/fs-extra/lib/index.js:3:16) Apr 21 13:42:17 volumio volumio[418987]: at Module._compile (node:internal/modules/cjs/loader:1356:14) Apr 21 13:42:17 volumio volumio[418987]: at Module._extensions..js (node:internal/modules/cjs/loader:1414:10) Apr 21 13:42:17 volumio volumio[418987]: at Module.load (node:internal/modules/cjs/loader:1197:32) Apr 21 13:42:17 volumio volumio[418987]: at Module._load (node:internal/modules/cjs/loader:1013:12) Apr 21 13:42:17 volumio volumio[418987]: at Module.require (node:internal/modules/cjs/loader:1225:19) { Apr 21 13:42:17 volumio volumio[418987]: code: 'MODULE_NOT_FOUND', Apr 21 13:42:17 volumio volumio[418987]: requireStack: [ Apr 21 13:42:17 volumio volumio[418987]: '/volumio/node_modules/v-conf/node_modules/fs-extra/lib/index.js', Apr 21 13:42:17 volumio volumio[418987]: '/volumio/node_modules/v-conf/index.js', Apr 21 13:42:17 volumio volumio[418987]: '/volumio/app/index.js', Apr 21 13:42:17 volumio volumio[418987]: '/volumio/index.js' Apr 21 13:42:17 volumio volumio[418987]: ] Apr 21 13:42:17 volumio volumio[418987]: } Apr 21 13:42:17 volumio volumio[418987]: Node.js v18.19.0 Apr 21 13:42:17 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 21 13:42:17 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 21 13:42:17 volumio systemd[1]: volumio.service: Consumed 3.987s CPU time. Apr 21 13:42:17 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 6811. Apr 21 13:42:17 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Apr 21 13:42:17 volumio systemd[1]: volumio.service: Consumed 3.987s CPU time. Apr 21 13:42:17 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Apr 21 13:42:21 volumio volumio[419009]: node:internal/modules/cjs/loader:1137 Apr 21 13:42:21 volumio volumio[419009]: throw err; Apr 21 13:42:21 volumio volumio[419009]: ^ Apr 21 13:42:21 volumio volumio[419009]: Error: Cannot find module './internal/isArrayLike.js' Apr 21 13:42:21 volumio volumio[419009]: Require stack: Apr 21 13:42:21 volumio volumio[419009]: - /volumio/node_modules/async/eachOf.js Apr 21 13:42:21 volumio volumio[419009]: - /volumio/node_modules/async/forEach.js Apr 21 13:42:21 volumio volumio[419009]: - /volumio/node_modules/winston/lib/winston/logger.js Apr 21 13:42:21 volumio volumio[419009]: - /volumio/node_modules/winston/lib/winston/create-logger.js Apr 21 13:42:21 volumio volumio[419009]: - /volumio/node_modules/winston/lib/winston.js Apr 21 13:42:21 volumio volumio[419009]: - /volumio/app/plugins/miscellanea/albumart/albumart.js Apr 21 13:42:21 volumio volumio[419009]: - /volumio/index.js Apr 21 13:42:21 volumio volumio[419009]: at Module._resolveFilename (node:internal/modules/cjs/loader:1134:15) Apr 21 13:42:21 volumio volumio[419009]: at Module._load (node:internal/modules/cjs/loader:975:27) Apr 21 13:42:21 volumio volumio[419009]: at Module.require (node:internal/modules/cjs/loader:1225:19) Apr 21 13:42:21 volumio volumio[419009]: at require (node:internal/modules/helpers:177:18) Apr 21 13:42:21 volumio volumio[419009]: at Object. (/volumio/node_modules/async/eachOf.js:7:20) Apr 21 13:42:21 volumio volumio[419009]: at Module._compile (node:internal/modules/cjs/loader:1356:14) Apr 21 13:42:21 volumio volumio[419009]: at Module._extensions..js (node:internal/modules/cjs/loader:1414:10) Apr 21 13:42:21 volumio volumio[419009]: at Module.load (node:internal/modules/cjs/loader:1197:32) Apr 21 13:42:21 volumio volumio[419009]: at Module._load (node:internal/modules/cjs/loader:1013:12) Apr 21 13:42:21 volumio volumio[419009]: at Module.require (node:internal/modules/cjs/loader:1225:19) { Apr 21 13:42:21 volumio volumio[419009]: code: 'MODULE_NOT_FOUND', Apr 21 13:42:21 volumio volumio[419009]: requireStack: [ Apr 21 13:42:21 volumio volumio[419009]: '/volumio/node_modules/async/eachOf.js', Apr 21 13:42:21 volumio volumio[419009]: '/volumio/node_modules/async/forEach.js', Apr 21 13:42:21 volumio volumio[419009]: '/volumio/node_modules/winston/lib/winston/logger.js', Apr 21 13:42:21 volumio volumio[419009]: '/volumio/node_modules/winston/lib/winston/create-logger.js', Apr 21 13:42:21 volumio volumio[419009]: '/volumio/node_modules/winston/lib/winston.js', Apr 21 13:42:21 volumio volumio[419009]: '/volumio/app/plugins/miscellanea/albumart/albumart.js', Apr 21 13:42:21 volumio volumio[419009]: '/volumio/index.js' Apr 21 13:42:21 volumio volumio[419009]: ] Apr 21 13:42:21 volumio volumio[419009]: } Apr 21 13:42:21 volumio volumio[419009]: Node.js v18.19.0 Apr 21 13:42:21 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 21 13:42:21 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 21 13:42:21 volumio systemd[1]: volumio.service: Consumed 3.436s CPU time. Apr 21 13:42:21 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 6812. Apr 21 13:42:21 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Apr 21 13:42:21 volumio systemd[1]: volumio.service: Consumed 3.436s CPU time. Apr 21 13:42:21 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Apr 21 13:42:24 volumio volumio[419016]: node:internal/modules/cjs/loader:1137 Apr 21 13:42:24 volumio volumio[419016]: throw err; Apr 21 13:42:24 volumio volumio[419016]: ^ Apr 21 13:42:24 volumio volumio[419016]: Error: Cannot find module './wrapAsync.js' Apr 21 13:42:24 volumio volumio[419016]: Require stack: Apr 21 13:42:24 volumio volumio[419016]: - /volumio/node_modules/async/internal/eachOfLimit.js Apr 21 13:42:24 volumio volumio[419016]: - /volumio/node_modules/async/eachOfLimit.js Apr 21 13:42:24 volumio volumio[419016]: - /volumio/node_modules/async/eachOf.js Apr 21 13:42:24 volumio volumio[419016]: - /volumio/node_modules/async/forEach.js Apr 21 13:42:24 volumio volumio[419016]: - /volumio/node_modules/winston/lib/winston/logger.js Apr 21 13:42:24 volumio volumio[419016]: - /volumio/node_modules/winston/lib/winston/create-logger.js Apr 21 13:42:24 volumio volumio[419016]: - /volumio/node_modules/winston/lib/winston.js Apr 21 13:42:24 volumio volumio[419016]: - /volumio/app/plugins/miscellanea/albumart/albumart.js Apr 21 13:42:24 volumio volumio[419016]: - /volumio/index.js Apr 21 13:42:24 volumio volumio[419016]: at Module._resolveFilename (node:internal/modules/cjs/loader:1134:15) Apr 21 13:42:24 volumio volumio[419016]: at Module._load (node:internal/modules/cjs/loader:975:27) Apr 21 13:42:24 volumio volumio[419016]: at Module.require (node:internal/modules/cjs/loader:1225:19) Apr 21 13:42:24 volumio volumio[419016]: at require (node:internal/modules/helpers:177:18) Apr 21 13:42:24 volumio volumio[419016]: at Object. (/volumio/node_modules/async/internal/eachOfLimit.js:19:18) Apr 21 13:42:24 volumio volumio[419016]: at Module._compile (node:internal/modules/cjs/loader:1356:14) Apr 21 13:42:24 volumio volumio[419016]: at Module._extensions..js (node:internal/modules/cjs/loader:1414:10) Apr 21 13:42:24 volumio volumio[419016]: at Module.load (node:internal/modules/cjs/loader:1197:32) Apr 21 13:42:24 volumio volumio[419016]: at Module._load (node:internal/modules/cjs/loader:1013:12) Apr 21 13:42:24 volumio volumio[419016]: at Module.require (node:internal/modules/cjs/loader:1225:19) { Apr 21 13:42:24 volumio volumio[419016]: code: 'MODULE_NOT_FOUND', Apr 21 13:42:24 volumio volumio[419016]: requireStack: [ Apr 21 13:42:24 volumio volumio[419016]: '/volumio/node_modules/async/internal/eachOfLimit.js', Apr 21 13:42:24 volumio volumio[419016]: '/volumio/node_modules/async/eachOfLimit.js', Apr 21 13:42:24 volumio volumio[419016]: '/volumio/node_modules/async/eachOf.js', Apr 21 13:42:24 volumio volumio[419016]: '/volumio/node_modules/async/forEach.js', Apr 21 13:42:24 volumio volumio[419016]: '/volumio/node_modules/winston/lib/winston/logger.js', Apr 21 13:42:24 volumio volumio[419016]: '/volumio/node_modules/winston/lib/winston/create-logger.js', Apr 21 13:42:24 volumio volumio[419016]: '/volumio/node_modules/winston/lib/winston.js', Apr 21 13:42:24 volumio volumio[419016]: '/volumio/app/plugins/miscellanea/albumart/albumart.js', Apr 21 13:42:24 volumio volumio[419016]: '/volumio/index.js' Apr 21 13:42:24 volumio volumio[419016]: ] Apr 21 13:42:24 volumio volumio[419016]: } Apr 21 13:42:24 volumio volumio[419016]: Node.js v18.19.0 Apr 21 13:42:24 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 21 13:42:24 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 21 13:42:24 volumio systemd[1]: volumio.service: Consumed 3.521s CPU time. Apr 21 13:42:25 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 6813. Apr 21 13:42:25 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Apr 21 13:42:25 volumio systemd[1]: volumio.service: Consumed 3.521s CPU time. Apr 21 13:42:25 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Apr 21 13:42:29 volumio volumio[419027]: info: ------------------------------------------- Apr 21 13:42:29 volumio volumio[419027]: info: ----- Volumio3 ---- Apr 21 13:42:29 volumio volumio[419027]: info: ------------------------------------------- Apr 21 13:42:29 volumio volumio[419027]: info: ----- System startup ---- Apr 21 13:42:29 volumio volumio[419027]: info: ------------------------------------------- Apr 21 13:42:30 volumio volumio[419027]: info: Plugin folders cleanup Apr 21 13:42:30 volumio volumio[419027]: info: Scanning into folder /volumio/app/plugins/ Apr 21 13:42:30 volumio volumio[419027]: info: Scanning category audio_interface Apr 21 13:42:30 volumio volumio[419027]: info: Scanning category miscellanea Apr 21 13:42:30 volumio volumio[419027]: info: Scanning category music_service Apr 21 13:42:30 volumio volumio[419027]: info: Scanning category plugins.json Apr 21 13:42:30 volumio volumio[419027]: info: Scanning category system_controller Apr 21 13:42:30 volumio volumio[419027]: info: Scanning category user_interface Apr 21 13:42:30 volumio volumio[419027]: info: Scanning into folder /data/plugins/ Apr 21 13:42:30 volumio volumio[419027]: info: Plugin folders cleanup completed Apr 21 13:42:30 volumio volumio[419027]: info: ------------------------------------------- Apr 21 13:42:30 volumio volumio[419027]: info: ----- Core plugins startup ---- Apr 21 13:42:30 volumio volumio[419027]: info: ------------------------------------------- Apr 21 13:42:30 volumio volumio[419027]: info: Loading plugins from folder /volumio/app/plugins/ Apr 21 13:42:30 volumio volumio[419027]: info: Adding plugin upnp to MyMusic Plugins Apr 21 13:42:30 volumio volumio[419027]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 21 13:42:30 volumio volumio[419027]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 21 13:42:30 volumio volumio[419027]: info: Loading plugins from folder /data/plugins/ Apr 21 13:42:30 volumio volumio[419027]: info: Loading plugin "system"... Apr 21 13:42:31 volumio volumio[419027]: info: No id defined. Creating one Apr 21 13:42:31 volumio volumio[419027]: info: Loading plugin "appearance"... Apr 21 13:42:32 volumio volumio[419027]: info: Loading plugin "network"... Apr 21 13:42:32 volumio volumio[419027]: info: Refreshing Cached IP Addresses Apr 21 13:42:32 volumio volumio[419027]: info: Loading plugin "services"... Apr 21 13:42:32 volumio volumio[419027]: info: Loading plugin "alsa_controller"... Apr 21 13:42:32 volumio volumio[419027]: warn: The ALSA output card is not set, defaulting to card [object Object] Apr 21 13:42:32 volumio volumio[419027]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 21 13:42:32 volumio volumio[419027]: error: Could not retrieve plugin system_controller i2s_dacs Apr 21 13:42:32 volumio sudo[419049]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 21 13:42:32 volumio sudo[419047]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 21 13:42:32 volumio sudo[419045]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 21 13:42:32 volumio sudo[419049]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 21 13:42:32 volumio sudo[419045]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 21 13:42:32 volumio sudo[419047]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 21 13:42:32 volumio volumio[419027]: info: Setting mixer ACODEC for card LIBRETECH-CC Apr 21 13:42:32 volumio sudo[419047]: pam_unix(sudo:session): session closed for user root Apr 21 13:42:32 volumio sudo[419045]: pam_unix(sudo:session): session closed for user root Apr 21 13:42:32 volumio volumio[419027]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 21 13:42:32 volumio volumio[419027]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 21 13:42:32 volumio volumio[419027]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 21 13:42:32 volumio volumio[419027]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 21 13:42:36 volumio volumio[419027]: info: Loading plugin "wizard"... Apr 21 13:42:36 volumio volumio[419027]: info: Loading plugin "volumio_command_line_client"... Apr 21 13:42:36 volumio volumio[419027]: info: Loading plugin "upnp"... Apr 21 13:42:36 volumio volumio[419027]: info: [1713724952872] Starting Upmpd Daemon Apr 21 13:42:36 volumio volumio[419027]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 21 13:42:36 volumio volumio[419027]: info: Loading plugin "my_music"... Apr 21 13:42:36 volumio volumio[419027]: info: Loading plugin "mpd"... Apr 21 13:42:36 volumio volumio[419027]: info: Creating MPD Configuration file Apr 21 13:42:36 volumio volumio[419027]: info: Loading plugin "upnp_browser"... Apr 21 13:42:36 volumio volumio[419027]: error: !!!! WARNING !!!! Apr 21 13:42:36 volumio volumio[419027]: error: The plugin music_service/upnp_browser failed to load, setting it to stopped. Error: TypeError: Entities is not a constructor Apr 21 13:42:36 volumio volumio[419027]: error: Stack trace: TypeError: Entities is not a constructor Apr 21 13:42:36 volumio volumio[419027]: at Object. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:12:18) Apr 21 13:42:36 volumio volumio[419027]: at Module._compile (node:internal/modules/cjs/loader:1356:14) Apr 21 13:42:36 volumio volumio[419027]: at Module._extensions..js (node:internal/modules/cjs/loader:1414:10) Apr 21 13:42:36 volumio volumio[419027]: at Module.load (node:internal/modules/cjs/loader:1197:32) Apr 21 13:42:36 volumio volumio[419027]: at Module._load (node:internal/modules/cjs/loader:1013:12) Apr 21 13:42:36 volumio volumio[419027]: at Module.require (node:internal/modules/cjs/loader:1225:19) Apr 21 13:42:36 volumio volumio[419027]: at require (node:internal/modules/helpers:177:18) Apr 21 13:42:36 volumio volumio[419027]: at Object. (/volumio/app/plugins/music_service/upnp_browser/index.js:12:24) Apr 21 13:42:36 volumio volumio[419027]: at Module._compile (node:internal/modules/cjs/loader:1356:14) Apr 21 13:42:36 volumio volumio[419027]: at Module._extensions..js (node:internal/modules/cjs/loader:1414:10) Apr 21 13:42:36 volumio volumio[419027]: at Module.load (node:internal/modules/cjs/loader:1197:32) Apr 21 13:42:36 volumio volumio[419027]: at Module._load (node:internal/modules/cjs/loader:1013:12) Apr 21 13:42:36 volumio volumio[419027]: at Module.require (node:internal/modules/cjs/loader:1225:19) Apr 21 13:42:36 volumio volumio[419027]: at require (node:internal/modules/helpers:177:18) Apr 21 13:42:36 volumio volumio[419027]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:235:29) Apr 21 13:42:36 volumio volumio[419027]: at /volumio/app/pluginmanager.js:338:34 Apr 21 13:42:36 volumio volumio[419027]: error: !!!! WARNING !!!! Apr 21 13:42:36 volumio volumio[419027]: info: Loading plugin "networkfs"... Apr 21 13:42:36 volumio volumio[419027]: error: !!!! WARNING !!!! Apr 21 13:42:36 volumio volumio[419027]: error: The plugin system_controller/networkfs failed to load, setting it to stopped. Error: Error: Cannot find module 'udev' Apr 21 13:42:36 volumio volumio[419027]: Require stack: Apr 21 13:42:36 volumio volumio[419027]: - /volumio/app/plugins/system_controller/networkfs/index.js Apr 21 13:42:36 volumio volumio[419027]: - /volumio/app/pluginmanager.js Apr 21 13:42:36 volumio volumio[419027]: - /volumio/app/index.js Apr 21 13:42:36 volumio volumio[419027]: - /volumio/index.js Apr 21 13:42:33 volumio sudo[419069]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 21 13:42:36 volumio volumio[419027]: error: Stack trace: Error: Cannot find module 'udev' Apr 21 13:42:36 volumio volumio[419027]: Require stack: Apr 21 13:42:36 volumio volumio[419027]: - /volumio/app/plugins/system_controller/networkfs/index.js Apr 21 13:42:36 volumio volumio[419027]: - /volumio/app/pluginmanager.js Apr 21 13:42:36 volumio volumio[419027]: - /volumio/app/index.js Apr 21 13:42:36 volumio volumio[419027]: - /volumio/index.js Apr 21 13:42:36 volumio volumio[419027]: at Module._resolveFilename (node:internal/modules/cjs/loader:1134:15) Apr 21 13:42:36 volumio volumio[419027]: at Module._load (node:internal/modules/cjs/loader:975:27) Apr 21 13:42:36 volumio volumio[419027]: at Module.require (node:internal/modules/cjs/loader:1225:19) Apr 21 13:42:36 volumio volumio[419027]: at require (node:internal/modules/helpers:177:18) Apr 21 13:42:36 volumio volumio[419027]: at Object. (/volumio/app/plugins/system_controller/networkfs/index.js:9:12) Apr 21 13:42:36 volumio volumio[419027]: at Module._compile (node:internal/modules/cjs/loader:1356:14) Apr 21 13:42:36 volumio volumio[419027]: at Module._extensions..js (node:internal/modules/cjs/loader:1414:10) Apr 21 13:42:36 volumio volumio[419027]: at Module.load (node:internal/modules/cjs/loader:1197:32) Apr 21 13:42:36 volumio volumio[419027]: at Module._load (node:internal/modules/cjs/loader:1013:12) Apr 21 13:42:36 volumio volumio[419027]: at Module.require (node:internal/modules/cjs/loader:1225:19) Apr 21 13:42:36 volumio volumio[419027]: at require (node:internal/modules/helpers:177:18) Apr 21 13:42:36 volumio volumio[419027]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:235:29) Apr 21 13:42:36 volumio volumio[419027]: at /volumio/app/pluginmanager.js:338:34 Apr 21 13:42:36 volumio volumio[419027]: at Array.forEach () Apr 21 13:42:36 volumio volumio[419027]: at HashMap. (/volumio/app/pluginmanager.js:337:20) Apr 21 13:42:36 volumio volumio[419027]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Apr 21 13:42:36 volumio volumio[419027]: error: !!!! WARNING !!!! Apr 21 13:42:36 volumio volumio[419027]: info: Loading plugin "alarm-clock"... Apr 21 13:42:36 volumio volumio[419027]: info: Loading plugin "airplay_emulation"... Apr 21 13:42:36 volumio volumio[419027]: info: Starting Shairport Sync Apr 21 13:42:36 volumio volumio[419027]: info: Loading plugin "last_100"... Apr 21 13:42:36 volumio volumio[419027]: info: Loading plugin "webradio"... Apr 21 13:42:36 volumio volumio[419027]: info: Loading plugin "i2s_dacs"... Apr 21 13:42:36 volumio volumio[419027]: info: I2S DAC not set, start Auto-detection Apr 21 13:42:36 volumio volumio[419027]: info: Loading plugin "volumiodiscovery"... Apr 21 13:42:36 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Apr 21 13:42:33 volumio sudo[419069]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 21 13:42:33 volumio sudo[419069]: pam_unix(sudo:session): session closed for user root Apr 21 13:42:33 volumio sudo[419071]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 21 13:42:33 volumio sudo[419071]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 21 13:42:36 volumio systemd[1]: mpd.service: Deactivated successfully. Apr 21 13:42:36 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Apr 21 13:42:36 volumio systemd[1]: mpd.service: Consumed 4.079s CPU time. Apr 21 13:42:36 volumio volumio[419027]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 21 13:42:36 volumio volumio[419027]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 21 13:42:36 volumio volumio[419027]: *** WARNING *** For more information see Apr 21 13:42:36 volumio node[419027]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 21 13:42:36 volumio volumio[419027]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 21 13:42:36 volumio volumio[419027]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 21 13:42:36 volumio volumio[419027]: *** WARNING *** For more information see Apr 21 13:42:36 volumio node[419027]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 21 13:42:36 volumio node[419027]: *** WARNING *** For more information see Apr 21 13:42:36 volumio node[419027]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 21 13:42:36 volumio node[419027]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 21 13:42:36 volumio node[419027]: *** WARNING *** For more information see Apr 21 13:42:36 volumio volumio[419027]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 21 13:42:36 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Apr 21 13:42:36 volumio volumio[419027]: info: Discovery: Started advertising with name: Volumio Apr 21 13:42:36 volumio volumio[419027]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 21 13:42:36 volumio volumio[419027]: info: Loading plugin "outputs"... Apr 21 13:42:36 volumio volumio[419027]: info: Loading plugin "albumart"... Apr 21 13:42:36 volumio volumio[419027]: info: Plugin example_plugin is not enabled Apr 21 13:42:36 volumio volumio[419027]: info: Loading plugin "inputs"... Apr 21 13:42:36 volumio volumio[419027]: info: Loading plugin "updater_comm"... Apr 21 13:42:36 volumio sudo[419049]: pam_unix(sudo:session): session closed for user root Apr 21 13:42:36 volumio volumio[419027]: error: !!!! WARNING !!!! Apr 21 13:42:36 volumio volumio[419027]: error: The plugin system_controller/updater_comm failed to load, setting it to stopped. Error: Error: Could not locate the bindings file. Tried: Apr 21 13:42:36 volumio volumio[419027]: → /volumio/node_modules/inotify/build/inotify.node Apr 21 13:42:36 volumio volumio[419027]: → /volumio/node_modules/inotify/build/Debug/inotify.node Apr 21 13:42:36 volumio volumio[419027]: → /volumio/node_modules/inotify/build/Release/inotify.node Apr 21 13:42:36 volumio volumio[419027]: → /volumio/node_modules/inotify/out/Debug/inotify.node Apr 21 13:42:36 volumio volumio[419027]: → /volumio/node_modules/inotify/Debug/inotify.node Apr 21 13:42:36 volumio volumio[419027]: → /volumio/node_modules/inotify/out/Release/inotify.node Apr 21 13:42:36 volumio volumio[419027]: → /volumio/node_modules/inotify/Release/inotify.node Apr 21 13:42:36 volumio volumio[419027]: → /volumio/node_modules/inotify/build/default/inotify.node Apr 21 13:42:36 volumio volumio[419027]: → /volumio/node_modules/inotify/compiled/18.19.0/linux/arm64/inotify.node Apr 21 13:42:36 volumio volumio[419027]: → /volumio/node_modules/inotify/addon-build/release/install-root/inotify.node Apr 21 13:42:36 volumio volumio[419027]: → /volumio/node_modules/inotify/addon-build/debug/install-root/inotify.node Apr 21 13:42:36 volumio volumio[419027]: → /volumio/node_modules/inotify/addon-build/default/install-root/inotify.node Apr 21 13:42:36 volumio volumio[419027]: → /volumio/node_modules/inotify/lib/binding/node-v108-linux-arm64/inotify.node Apr 21 13:42:36 volumio volumio[419027]: error: Stack trace: Error: Could not locate the bindings file. Tried: Apr 21 13:42:36 volumio volumio[419027]: → /volumio/node_modules/inotify/build/inotify.node Apr 21 13:42:36 volumio volumio[419027]: → /volumio/node_modules/inotify/build/Debug/inotify.node Apr 21 13:42:36 volumio volumio[419027]: → /volumio/node_modules/inotify/build/Release/inotify.node Apr 21 13:42:36 volumio volumio[419027]: → /volumio/node_modules/inotify/out/Debug/inotify.node Apr 21 13:42:36 volumio volumio[419027]: → /volumio/node_modules/inotify/Debug/inotify.node Apr 21 13:42:36 volumio volumio[419027]: → /volumio/node_modules/inotify/out/Release/inotify.node Apr 21 13:42:36 volumio volumio[419027]: → /volumio/node_modules/inotify/Release/inotify.node Apr 21 13:42:36 volumio volumio[419027]: → /volumio/node_modules/inotify/build/default/inotify.node Apr 21 13:42:36 volumio volumio[419027]: → /volumio/node_modules/inotify/compiled/18.19.0/linux/arm64/inotify.node Apr 21 13:42:36 volumio volumio[419027]: → /volumio/node_modules/inotify/addon-build/release/install-root/inotify.node Apr 21 13:42:36 volumio volumio[419027]: → /volumio/node_modules/inotify/addon-build/debug/install-root/inotify.node Apr 21 13:42:36 volumio volumio[419027]: → /volumio/node_modules/inotify/addon-build/default/install-root/inotify.node Apr 21 13:42:36 volumio volumio[419027]: → /volumio/node_modules/inotify/lib/binding/node-v108-linux-arm64/inotify.node Apr 21 13:42:36 volumio volumio[419027]: at bindings (/volumio/node_modules/bindings/bindings.js:126:9) Apr 21 13:42:36 volumio volumio[419027]: at Object. (/volumio/node_modules/inotify/inotify.js:1:37) Apr 21 13:42:36 volumio volumio[419027]: at Module._compile (node:internal/modules/cjs/loader:1356:14) Apr 21 13:42:36 volumio volumio[419027]: at Module._extensions..js (node:internal/modules/cjs/loader:1414:10) Apr 21 13:42:36 volumio volumio[419027]: at Module.load (node:internal/modules/cjs/loader:1197:32) Apr 21 13:42:36 volumio volumio[419027]: at Module._load (node:internal/modules/cjs/loader:1013:12) Apr 21 13:42:36 volumio volumio[419027]: at Module.require (node:internal/modules/cjs/loader:1225:19) Apr 21 13:42:36 volumio volumio[419027]: at require (node:internal/modules/helpers:177:18) Apr 21 13:42:36 volumio volumio[419027]: at Object. (/volumio/app/plugins/system_controller/updater_comm/index.js:4:15) Apr 21 13:42:36 volumio volumio[419027]: at Module._compile (node:internal/modules/cjs/loader:1356:14) Apr 21 13:42:36 volumio volumio[419027]: at Module._extensions..js (node:internal/modules/cjs/loader:1414:10) Apr 21 13:42:36 volumio volumio[419027]: at Module.load (node:internal/modules/cjs/loader:1197:32) Apr 21 13:42:36 volumio volumio[419027]: at Module._load (node:internal/modules/cjs/loader:1013:12) Apr 21 13:42:36 volumio volumio[419027]: at Module.require (node:internal/modules/cjs/loader:1225:19) Apr 21 13:42:36 volumio volumio[419027]: at require (node:internal/modules/helpers:177:18) Apr 21 13:42:36 volumio volumio[419027]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:235:29) Apr 21 13:42:37 volumio volumio[419027]: error: !!!! WARNING !!!! Apr 21 13:42:37 volumio volumio[419027]: info: Plugin mpdemulation is not enabled Apr 21 13:42:37 volumio volumio[419027]: info: Loading plugin "rest_api"... Apr 21 13:42:37 volumio volumio[419027]: info: Loading plugin "websocket"... Apr 21 13:42:37 volumio volumio[419027]: info: Loading i18n strings for locale en Apr 21 13:42:37 volumio volumio[419027]: Updating browse sources language Apr 21 13:42:37 volumio volumio[419027]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 21 13:42:37 volumio volumio[419027]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 21 13:42:37 volumio volumio[419027]: info: CoreCommandRouter::initPlayerControls Apr 21 13:42:37 volumio volumio[419027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 13:42:37 volumio volumio[419027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 13:42:37 volumio volumio[419027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 13:42:37 volumio volumio[419027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 13:42:37 volumio volumio[419027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 13:42:37 volumio volumio[419027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 13:42:37 volumio volumio[419027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 13:42:37 volumio volumio[419027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 13:42:37 volumio volumio[419027]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 21 13:42:37 volumio volumio[419027]: Express server listening on port 3000 Apr 21 13:42:37 volumio volumio[419027]: [Metrics] WebUI: 9s 793.69ms Apr 21 13:42:37 volumio volumio[419027]: info: CoreStateMachine::resetVolumioState Apr 21 13:42:37 volumio volumio[419027]: info: CoreStateMachine::getcurrentVolume Apr 21 13:42:37 volumio volumio[419027]: info: CoreCommandRouter::volumioRetrievevolume Apr 21 13:42:38 volumio volumio[419027]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 21 13:42:38 volumio volumio[419027]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Apr 21 13:42:38 volumio volumio[419027]: info: CoreStateMachine::getcurrentVolume Apr 21 13:42:38 volumio volumio[419027]: info: CoreCommandRouter::volumioRetrievevolume Apr 21 13:42:38 volumio volumio[419079]: Forking 3 albumart workers Apr 21 13:42:38 volumio volumio[419027]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Apr 21 13:42:38 volumio volumio[419027]: info: Completed loading Core Plugins Apr 21 13:42:38 volumio volumio[419027]: info: Preparing to generate the ALSA configuration file Apr 21 13:42:38 volumio volumio[419027]: info: Cannot read play queue from file Apr 21 13:42:38 volumio volumio[419027]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Hardware Apr 21 13:42:38 volumio volumio[419027]: info: MPD Permissions set Apr 21 13:42:38 volumio volumio[419027]: info: VolumeController:: Volume=0 Mute =false Apr 21 13:42:38 volumio volumio[419027]: info: CoreStateMachine::pushState Apr 21 13:42:38 volumio volumio[419027]: info: CorePlayQueue::getTrack 0 Apr 21 13:42:38 volumio volumio[419027]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 21 13:42:38 volumio volumio[419027]: info: CoreCommandRouter::volumioPushState Apr 21 13:42:38 volumio volumio[419027]: info: CoreStateMachine::updateTrackBlock Apr 21 13:42:38 volumio volumio[419027]: info: CorePlayQueue::getTrackBlock Apr 21 13:42:38 volumio volumio[419027]: info: CoreCommandRouter::volumioRetrievevolume Apr 21 13:42:38 volumio volumio[419027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 13:42:38 volumio volumio[419027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 13:42:38 volumio volumio[419027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 13:42:39 volumio volumio[419027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 13:42:39 volumio volumio[419027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 13:42:39 volumio volumio[419027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 13:42:39 volumio volumio[419027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 13:42:39 volumio volumio[419027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 13:42:39 volumio volumio[419027]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 21 13:42:39 volumio volumio[419027]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 21 13:42:39 volumio volumio[419027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 13:42:39 volumio volumio[419027]: info: VolumeController:: Volume=0 Mute =false Apr 21 13:42:39 volumio volumio[419027]: info: CoreStateMachine::pushState Apr 21 13:42:39 volumio volumio[419027]: info: CorePlayQueue::getTrack 0 Apr 21 13:42:39 volumio volumio[419027]: info: CoreCommandRouter::volumioPushState Apr 21 13:42:39 volumio volumio[419027]: info: CoreStateMachine::updateTrackBlock Apr 21 13:42:39 volumio volumio[419027]: info: CorePlayQueue::getTrackBlock Apr 21 13:42:39 volumio volumio[419027]: info: CoreCommandRouter::volumioRetrievevolume Apr 21 13:42:39 volumio volumio[419027]: info: Asound.conf file written Apr 21 13:42:39 volumio sudo[419139]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Apr 21 13:42:39 volumio sudo[419139]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 21 13:42:39 volumio sudo[419139]: pam_unix(sudo:session): session closed for user root Apr 21 13:42:39 volumio volumio[419027]: info: Output device has changed, restarting MPD Apr 21 13:42:39 volumio volumio[419027]: info: Output device has changed, restarting Shairport Sync Apr 21 13:42:39 volumio volumio[419027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 13:42:39 volumio volumio[419027]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 21 13:42:39 volumio sudo[419145]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 21 13:42:39 volumio sudo[419145]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 21 13:42:39 volumio sudo[419145]: pam_unix(sudo:session): session closed for user root Apr 21 13:42:39 volumio volumio[419027]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 21 13:42:39 volumio volumio[419027]: info: ___________ START PLUGINS ___________ Apr 21 13:42:39 volumio sudo[419147]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 21 13:42:39 volumio sudo[419147]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 21 13:42:39 volumio volumio[419027]: info: Setting Hotspot Unique name on first start: Volumio-A4812 Apr 21 13:42:39 volumio volumio[419027]: info: CoreCommandRouter::executeOnPlugin: network , getConfigParam Apr 21 13:42:39 volumio volumio[419027]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getConfigParam] in plugin network Apr 21 13:42:39 volumio volumio[419027]: info: CoreCommandRouter::executeOnPlugin: network , saveHotspotSettings Apr 21 13:42:39 volumio volumio[419027]: info: CoreCommandRouter::Reload Ui Apr 21 13:42:39 volumio systemd[1]: mpd.service: Deactivated successfully. Apr 21 13:42:39 volumio volumio[419027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 13:42:39 volumio volumio[419027]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 21 13:42:39 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Apr 21 13:42:39 volumio systemd[1]: mpd.service: Consumed 1.134s CPU time. Apr 21 13:42:39 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Apr 21 13:42:40 volumio volumio[419027]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 21 13:42:40 volumio volumio[419027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 13:42:40 volumio volumio[419027]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 21 13:42:40 volumio volumio[419027]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 21 13:42:40 volumio volumio[419027]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 21 13:42:40 volumio volumio[419027]: info: [1713724960131] CoreMusicLibrary::Adding element Last_100 Apr 21 13:42:40 volumio volumio[419027]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 21 13:42:40 volumio volumio[419027]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 21 13:42:40 volumio volumio[419027]: info: [1713724960150] CoreMusicLibrary::Adding element Webradio Apr 21 13:42:40 volumio volumio[419027]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 21 13:42:40 volumio volumio[419027]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 21 13:42:40 volumio volumio[419027]: info: Cannot retrieve data for calling home Apr 21 13:42:40 volumio volumio[419027]: info: Completed starting Core Plugins Apr 21 13:42:40 volumio volumio[419027]: info: BOOT COMPLETED Apr 21 13:42:40 volumio volumio[419027]: [Metrics] CommandRouter: 11s 245.68ms Apr 21 13:42:40 volumio volumio[419027]: info: CoreCommandRouter::volumiosetStartupVolume Apr 21 13:42:40 volumio volumio[419027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 13:42:40 volumio volumio[419027]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 21 13:42:40 volumio volumio[419027]: info: CoreCommandRouter::Close All Modals sent Apr 21 13:42:40 volumio volumio[419027]: info: CoreCommandRouter::Close All Modals sent Apr 21 13:42:42 volumio volumio[419027]: info: VolumeController:: Volume=0 Mute =false Apr 21 13:42:42 volumio volumio[419027]: info: CoreStateMachine::pushState Apr 21 13:42:42 volumio volumio[419027]: info: CorePlayQueue::getTrack 0 Apr 21 13:42:42 volumio volumio[419027]: info: CoreCommandRouter::volumioPushState Apr 21 13:42:42 volumio volumio[419027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Apr 21 13:42:42 volumio volumio[419027]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 21 13:42:42 volumio sudo[419173]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hostapd/hostapd.conf Apr 21 13:42:42 volumio sudo[419173]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 21 13:42:42 volumio sudo[419173]: pam_unix(sudo:session): session closed for user root Apr 21 13:42:42 volumio volumio[419027]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Apr 21 13:42:42 volumio volumio[419027]: info: Permissions for /etc/hostapd/hostapd.conf Apr 21 13:42:42 volumio volumio[419027]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Apr 21 13:42:42 volumio volumio[419027]: info: Discovery: Restarting Advertising due to device name change Apr 21 13:42:42 volumio volumio[419027]: info: MPD Permissions set Apr 21 13:42:42 volumio sudo[419179]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Apr 21 13:42:42 volumio volumio[419098]: Starting albumart workers Apr 21 13:42:42 volumio sudo[419179]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 21 13:42:42 volumio volumio[419097]: Starting albumart workers Apr 21 13:42:42 volumio systemd[1]: Starting wireless.service - Wireless Services... Apr 21 13:42:43 volumio sudo[419189]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 21 13:42:43 volumio sudo[419189]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 21 13:42:43 volumio sudo[419189]: pam_unix(sudo:session): session closed for user root Apr 21 13:42:43 volumio sudo[419191]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 21 13:42:43 volumio sudo[419191]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 21 13:42:43 volumio volumio[419027]: info: Discovery: adding undefined Apr 21 13:42:43 volumio volumio[419027]: info: Discovery: Found device Volumio Apr 21 13:42:43 volumio sudo[419191]: pam_unix(sudo:session): session closed for user root Apr 21 13:42:43 volumio volumio[419027]: info: CoreCommandRouter::volumioGetState Apr 21 13:42:43 volumio volumio[419027]: info: CorePlayQueue::getTrack 0 Apr 21 13:42:43 volumio volumio[419027]: error: Could not retrieve plugin audio_interface multiroom Apr 21 13:42:43 volumio volumio[419099]: Starting albumart workers Apr 21 13:42:43 volumio volumio[419027]: info: VolumeController:: Volume=0 Mute =false Apr 21 13:42:43 volumio volumio[419027]: info: CoreStateMachine::pushState Apr 21 13:42:43 volumio volumio[419027]: info: CorePlayQueue::getTrack 0 Apr 21 13:42:43 volumio volumio[419027]: info: CoreCommandRouter::volumioPushState Apr 21 13:42:43 volumio volumio[419027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 13:42:43 volumio volumio[419027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 13:42:43 volumio volumio[419027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 13:42:43 volumio volumio[419027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 13:42:43 volumio volumio[419027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 13:42:43 volumio volumio[419027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 13:42:43 volumio volumio[419027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 13:42:43 volumio volumio[419027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 13:42:43 volumio volumio[419027]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 21 13:42:43 volumio volumio[419027]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 21 13:42:43 volumio volumio[419027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 13:42:43 volumio volumio[419027]: info: Discovery: this is already registered, undefined Apr 21 13:42:43 volumio volumio[419027]: info: Discovery: Found device Volumio Apr 21 13:42:43 volumio volumio[419027]: info: CoreCommandRouter::volumioGetState Apr 21 13:42:43 volumio volumio[419027]: info: CorePlayQueue::getTrack 0 Apr 21 13:42:43 volumio volumio[419027]: error: Could not retrieve plugin audio_interface multiroom Apr 21 13:42:43 volumio volumio[419027]: info: Discovery: this is already registered, undefined Apr 21 13:42:43 volumio volumio[419027]: info: Discovery: Found device Volumio Apr 21 13:42:43 volumio volumio[419027]: info: CoreCommandRouter::volumioGetState Apr 21 13:42:43 volumio volumio[419027]: info: CorePlayQueue::getTrack 0 Apr 21 13:42:43 volumio volumio[419027]: error: Could not retrieve plugin audio_interface multiroom Apr 21 13:42:44 volumio volumio[419027]: info: CorePlayQueue::getTrack 0 Apr 21 13:42:44 volumio volumio[419027]: info: Discovery: this is already registered, undefined Apr 21 13:42:44 volumio volumio[419027]: info: Discovery: Found device Volumio Apr 21 13:42:44 volumio volumio[419027]: info: CoreCommandRouter::volumioGetState Apr 21 13:42:44 volumio volumio[419027]: info: CorePlayQueue::getTrack 0 Apr 21 13:42:44 volumio volumio[419027]: error: Could not retrieve plugin audio_interface multiroom Apr 21 13:42:44 volumio volumio[419027]: info: Starting Shairport Sync Apr 21 13:42:44 volumio volumio[419027]: info: Starting Shairport Sync Apr 21 13:42:44 volumio volumio[419027]: info: Starting Shairport Sync Apr 21 13:42:44 volumio sudo[419215]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 21 13:42:44 volumio sudo[419215]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 21 13:42:44 volumio volumio[419027]: info: Discovery: this is already registered, undefined Apr 21 13:42:44 volumio volumio[419027]: info: Discovery: Found device Volumio Apr 21 13:42:44 volumio volumio[419027]: info: CoreCommandRouter::volumioGetState Apr 21 13:42:44 volumio volumio[419027]: info: CorePlayQueue::getTrack 0 Apr 21 13:42:44 volumio volumio[419027]: error: Could not retrieve plugin audio_interface multiroom Apr 21 13:42:44 volumio sudo[419217]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 21 13:42:44 volumio sudo[419217]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 21 13:42:44 volumio (ort-sync)[419222]: shairport-sync.service: Failed to determine user credentials: No such process Apr 21 13:42:44 volumio (ort-sync)[419222]: shairport-sync.service: Failed at step USER spawning /usr/bin/shairport-sync: No such process Apr 21 13:42:44 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 21 13:42:44 volumio systemd[1]: shairport-sync.service: Main process exited, code=exited, status=217/USER Apr 21 13:42:44 volumio systemd[1]: shairport-sync.service: Failed with result 'exit-code'. Apr 21 13:42:44 volumio sudo[419217]: pam_unix(sudo:session): session closed for user root Apr 21 13:42:45 volumio (ort-sync)[419223]: shairport-sync.service: Failed to determine user credentials: No such process Apr 21 13:42:45 volumio (ort-sync)[419223]: shairport-sync.service: Failed at step USER spawning /usr/bin/shairport-sync: No such process Apr 21 13:42:45 volumio sudo[419221]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 21 13:42:45 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 21 13:42:45 volumio systemd[1]: shairport-sync.service: Main process exited, code=exited, status=217/USER Apr 21 13:42:45 volumio volumio[419027]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 1 Apr 21 13:42:45 volumio sudo[419221]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 21 13:42:45 volumio systemd[1]: shairport-sync.service: Failed with result 'exit-code'. Apr 21 13:42:45 volumio sudo[419215]: pam_unix(sudo:session): session closed for user root Apr 21 13:42:45 volumio (ort-sync)[419225]: shairport-sync.service: Failed to determine user credentials: No such process Apr 21 13:42:45 volumio (ort-sync)[419225]: shairport-sync.service: Failed at step USER spawning /usr/bin/shairport-sync: No such process Apr 21 13:42:45 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 21 13:42:45 volumio systemd[1]: shairport-sync.service: Main process exited, code=exited, status=217/USER Apr 21 13:42:45 volumio systemd[1]: shairport-sync.service: Failed with result 'exit-code'. Apr 21 13:42:45 volumio sudo[419221]: pam_unix(sudo:session): session closed for user root Apr 21 13:42:45 volumio volumio[419027]: info: Shairport-Sync Started Apr 21 13:42:45 volumio volumio[419027]: Error adding Membership: Error: addMembership EINVAL Apr 21 13:42:45 volumio volumio[419027]: info: Shairport-Sync Started Apr 21 13:42:45 volumio volumio[419027]: info: Shairport-Sync Started Apr 21 13:42:45 volumio sudo[419251]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Apr 21 13:42:45 volumio sudo[419251]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 21 13:42:45 volumio volumio[419027]: info: CoreCommandRouter::volumioGetState Apr 21 13:42:45 volumio volumio[419027]: info: CorePlayQueue::getTrack 0 Apr 21 13:42:45 volumio systemd[1]: /lib/systemd/system/upmpdcli.service:8: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Apr 21 13:42:45 volumio systemd[1]: /lib/systemd/system/upmpdcli.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Apr 21 13:42:45 volumio volumio[419027]: info: Discovery: A device disappeared from network Apr 21 13:42:45 volumio volumio[419027]: info: Discovery: Device volumio disappeared from network Apr 21 13:42:45 volumio volumio[419027]: error: Could not retrieve plugin audio_interface multiroom Apr 21 13:42:45 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Apr 21 13:42:45 volumio (upmpdcli)[419253]: upmpdcli.service: Failed to locate executable /usr/bin/upmpdcli: No such file or directory Apr 21 13:42:45 volumio (upmpdcli)[419253]: upmpdcli.service: Failed at step EXEC spawning /usr/bin/upmpdcli: No such file or directory Apr 21 13:42:45 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=203/EXEC Apr 21 13:42:45 volumio volumio[419027]: info: Discovery: A device disappeared from network Apr 21 13:42:45 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Apr 21 13:42:45 volumio volumio[419027]: error: Could not retrieve plugin audio_interface multiroom Apr 21 13:42:45 volumio sudo[419251]: pam_unix(sudo:session): session closed for user root Apr 21 13:42:45 volumio volumio[419027]: info: Discovery: A device disappeared from network Apr 21 13:42:46 volumio volumio[419027]: error: Could not retrieve plugin audio_interface multiroom Apr 21 13:42:46 volumio volumio[419027]: info: Upmpdcli Daemon Started Apr 21 13:42:46 volumio volumio[419027]: info: Discovery: A device disappeared from network Apr 21 13:42:46 volumio volumio[419027]: error: Could not retrieve plugin audio_interface multiroom Apr 21 13:42:46 volumio volumio[419027]: info: Discovery: A device disappeared from network Apr 21 13:42:46 volumio volumio[419027]: error: Could not retrieve plugin audio_interface multiroom Apr 21 13:42:46 volumio wireless.js[419182]: Cleaning previous... Apr 21 13:42:46 volumio sudo[419261]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ip addr flush dev wlan0 Apr 21 13:42:46 volumio sudo[419261]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 21 13:42:47 volumio volumio[419027]: info: Discovery: Started advertising with name: Volumio Apr 21 13:42:48 volumio avahi-daemon[578]: Withdrawing address record for 192.168.1.223 on wlan0. Apr 21 13:42:48 volumio avahi-daemon[578]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.223. Apr 21 13:42:48 volumio avahi-daemon[578]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.224. Apr 21 13:42:48 volumio avahi-daemon[578]: Withdrawing address record for 192.168.1.224 on wlan0. Apr 21 13:42:48 volumio avahi-daemon[578]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.224. Apr 21 13:42:48 volumio avahi-daemon[578]: Interface wlan0.IPv4 no longer relevant for mDNS. Apr 21 13:42:48 volumio avahi-daemon[578]: Withdrawing address record for fe80::a247:d7ff:fe10:55b4 on wlan0. Apr 21 13:42:48 volumio avahi-daemon[578]: Leaving mDNS multicast group on interface wlan0.IPv6 with address fe80::a247:d7ff:fe10:55b4. Apr 21 13:42:48 volumio avahi-daemon[578]: Interface wlan0.IPv6 no longer relevant for mDNS. Apr 21 13:42:48 volumio dhcpcd[871]: wlan0: pid 419262 deleted IP address 192.168.1.224/24 Apr 21 13:42:48 volumio dhcpcd[871]: wlan0: deleting route to 192.168.1.0/24 Apr 21 13:42:48 volumio dhcpcd[871]: wlan0: deleting default route via 192.168.1.1 Apr 21 13:42:48 volumio sudo[419261]: pam_unix(sudo:session): session closed for user root Apr 21 13:42:48 volumio sudo[419267]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down Apr 21 13:42:48 volumio sudo[419267]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 21 13:42:48 volumio kernel: wlan0: deauthenticating from 5c:62:8b:46:9a:ec by local choice (Reason: 3=DEAUTH_LEAVING) Apr 21 13:42:48 volumio wpa_supplicant[675]: wlan0: CTRL-EVENT-DISCONNECTED bssid=5c:62:8b:46:9a:ec reason=3 locally_generated=1 Apr 21 13:42:48 volumio wpa_supplicant[675]: BSSID 5c:62:8b:46:9a:ec ignore list count incremented to 2, ignoring for 10 seconds Apr 21 13:42:48 volumio systemd-networkd[386]: wlan0: Link DOWN Apr 21 13:42:48 volumio systemd-networkd[386]: wlan0: Lost carrier Apr 21 13:42:48 volumio dbus-daemon[580]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.1' (uid=998 pid=386 comm="/lib/systemd/systemd-networkd") Apr 21 13:42:48 volumio systemd-networkd[386]: wlan0: DHCP lease lost Apr 21 13:42:48 volumio sudo[419267]: pam_unix(sudo:session): session closed for user root Apr 21 13:42:48 volumio systemd-networkd[386]: wlan0: DHCPv6 lease lost Apr 21 13:42:48 volumio systemd[1]: Starting systemd-hostnamed.service - Hostname Service... Apr 21 13:42:49 volumio dbus-daemon[580]: [system] Successfully activated service 'org.freedesktop.hostname1' Apr 21 13:42:49 volumio systemd[1]: Started systemd-hostnamed.service - Hostname Service. Apr 21 13:42:49 volumio systemd-hostnamed[419271]: Hostname set to (static) Apr 21 13:42:49 volumio dhcpcd[871]: wlan0: rebinding lease of 192.168.1.224 Apr 21 13:42:51 volumio kernel: wlan0: authenticate with 5c:e9:31:6c:bb:20 Apr 21 13:42:51 volumio kernel: wlan0: 80 MHz not supported, disabling VHT Apr 21 13:42:51 volumio kernel: wlan0: send auth to 5c:e9:31:6c:bb:20 (try 1/3) Apr 21 13:42:51 volumio kernel: wlan0: authenticated Apr 21 13:42:51 volumio kernel: wlan0: associate with 5c:e9:31:6c:bb:20 (try 1/3) Apr 21 13:42:51 volumio kernel: wlan0: RX AssocResp from 5c:e9:31:6c:bb:20 (capab=0x431 status=0 aid=1) Apr 21 13:42:51 volumio kernel: wlan0: associated Apr 21 13:42:51 volumio kernel: IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready Apr 21 13:42:51 volumio wireless.js[419182]: Stopped aP Apr 21 13:42:51 volumio wpa_supplicant[675]: wlan0: SME: Trying to authenticate with 5c:e9:31:6c:bb:20 (SSID='Deep Thought' freq=2412 MHz) Apr 21 13:42:51 volumio wpa_supplicant[675]: wlan0: Trying to associate with 5c:e9:31:6c:bb:20 (SSID='Deep Thought' freq=2412 MHz) Apr 21 13:42:51 volumio wpa_supplicant[675]: wlan0: Associated with 5c:e9:31:6c:bb:20 Apr 21 13:42:51 volumio wpa_supplicant[675]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Apr 21 13:42:51 volumio wpa_supplicant[675]: wlan0: WPA: Key negotiation completed with 5c:e9:31:6c:bb:20 [PTK=CCMP GTK=CCMP] Apr 21 13:42:51 volumio wpa_supplicant[675]: wlan0: CTRL-EVENT-CONNECTED - Connection to 5c:e9:31:6c:bb:20 completed [id=0 id_str=] Apr 21 13:42:50 volumio systemd-networkd[386]: wlan0: Link UP Apr 21 13:42:49 volumio dhcpcd[871]: wlan0: if_sendraw: Network is down Apr 21 13:42:51 volumio volumio[419027]: info: Cannot restart MPD: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.service Apr 21 13:42:51 volumio volumio[419027]: Terminated Apr 21 13:42:51 volumio volumio[419027]: error: Cannot start MPD on system Start: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.service Apr 21 13:42:51 volumio volumio[419027]: Terminated Apr 21 13:42:50 volumio systemd-networkd[386]: wlan0: Gained carrier Apr 21 13:42:49 volumio dhcpcd[871]: dhcp_readpacket: wlan0: Network is down Apr 21 13:42:50 volumio systemd-networkd[386]: wlan0: found matching network '/etc/systemd/network/10-dhcp.network', based on potentially unpredictable interface name. Apr 21 13:42:49 volumio dhcpcd[871]: wlan0: carrier lost Apr 21 13:42:50 volumio sudo[419071]: pam_unix(sudo:session): session closed for user root Apr 21 13:42:50 volumio sudo[419147]: pam_unix(sudo:session): session closed for user root Apr 21 13:42:50 volumio sudo[419285]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Apr 21 13:42:50 volumio sudo[419285]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 21 13:42:50 volumio sudo[419285]: pam_unix(sudo:session): session closed for user root Apr 21 13:42:50 volumio sudo[419287]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Apr 21 13:42:50 volumio sudo[419287]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 21 13:42:50 volumio sudo[419287]: pam_unix(sudo:session): session closed for user root Apr 21 13:42:50 volumio dhcpcd[871]: wlan0: carrier acquired Apr 21 13:42:50 volumio dhcpcd[871]: wlan0: IAID d7:10:55:b4 Apr 21 13:42:50 volumio dhcpcd[871]: wlan0: soliciting an IPv6 router Apr 21 13:42:51 volumio sudo[419295]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Apr 21 13:42:51 volumio sudo[419295]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 21 13:42:51 volumio sudo[419295]: pam_unix(sudo:session): session closed for user root Apr 21 13:42:51 volumio sudo[419297]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Apr 21 13:42:51 volumio sudo[419297]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 21 13:42:51 volumio mpd[419155]: Apr 21 13:42 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 21 13:42:51 volumio volumio[419027]: error: MPD error: The expression evaluated to a falsy value: Apr 21 13:42:51 volumio volumio[419027]: assert.ok(self.idling) Apr 21 13:42:51 volumio volumio[419027]: error: The expression evaluated to a falsy value: Apr 21 13:42:51 volumio volumio[419027]: assert.ok(self.idling) Apr 21 13:42:51 volumio volumio[419027]: error: updateQueue error: null Apr 21 13:42:51 volumio dhcpcd[871]: wlan0: rebinding lease of 192.168.1.224 Apr 21 13:42:51 volumio systemd[1]: Started mpd.service - Music Player Daemon. Apr 21 13:42:54 volumio systemd-networkd[386]: wlan0: DHCPv4 address 192.168.1.223/24, gateway 192.168.1.1 acquired from 192.168.1.1 Apr 21 13:42:54 volumio avahi-daemon[578]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.223. Apr 21 13:42:54 volumio avahi-daemon[578]: New relevant interface wlan0.IPv4 for mDNS. Apr 21 13:42:54 volumio avahi-daemon[578]: Registering new address record for 192.168.1.223 on wlan0.IPv4. Apr 21 13:42:55 volumio sudo[419297]: pam_unix(sudo:session): session closed for user root Apr 21 13:42:55 volumio sudo[419341]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ip addr flush dev wlan0 Apr 21 13:42:55 volumio sudo[419341]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 21 13:42:55 volumio avahi-daemon[578]: Withdrawing address record for 192.168.1.223 on wlan0. Apr 21 13:42:55 volumio avahi-daemon[578]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.223. Apr 21 13:42:55 volumio sudo[419341]: pam_unix(sudo:session): session closed for user root Apr 21 13:42:55 volumio avahi-daemon[578]: Interface wlan0.IPv4 no longer relevant for mDNS. Apr 21 13:42:55 volumio volumio[419027]: info: Discovery: A device disappeared from network Apr 21 13:42:55 volumio volumio[419027]: error: Could not retrieve plugin audio_interface multiroom Apr 21 13:42:55 volumio sudo[419343]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down Apr 21 13:42:55 volumio sudo[419343]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 21 13:42:55 volumio kernel: wlan0: deauthenticating from 5c:e9:31:6c:bb:20 by local choice (Reason: 3=DEAUTH_LEAVING) Apr 21 13:42:55 volumio wpa_supplicant[675]: wlan0: CTRL-EVENT-DISCONNECTED bssid=5c:e9:31:6c:bb:20 reason=3 locally_generated=1 Apr 21 13:42:55 volumio dhcpcd[871]: wlan0: carrier lost Apr 21 13:42:55 volumio systemd-networkd[386]: wlan0: Link DOWN Apr 21 13:42:55 volumio systemd-networkd[386]: wlan0: Lost carrier Apr 21 13:42:55 volumio systemd-networkd[386]: wlan0: DHCP lease lost Apr 21 13:42:55 volumio sudo[419343]: pam_unix(sudo:session): session closed for user root Apr 21 13:42:55 volumio systemd-networkd[386]: wlan0: DHCPv6 lease lost Apr 21 13:42:55 volumio avahi-daemon[578]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Apr 21 13:42:55 volumio avahi-daemon[578]: New relevant interface wlan0.IPv4 for mDNS. Apr 21 13:42:55 volumio systemd-networkd[386]: wlan0: Link UP Apr 21 13:42:55 volumio avahi-daemon[578]: Registering new address record for 192.168.211.1 on wlan0.IPv4. Apr 21 13:42:55 volumio systemd[1]: Starting dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Apr 21 13:42:55 volumio systemd[1]: Starting hostapd.service - Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Apr 21 13:42:56 volumio hostapd[419358]: Configuration file: /etc/hostapd/hostapd.conf Apr 21 13:42:56 volumio dnsmasq[419356]: Usage: /etc/init.d/dnsmasq {start|stop|restart|force-reload|dump-stats|status} Apr 21 13:42:56 volumio systemd[1]: dnsmasq.service: Control process exited, code=exited, status=3/NOTIMPLEMENTED Apr 21 13:42:56 volumio systemd[1]: dnsmasq.service: Failed with result 'exit-code'. Apr 21 13:42:56 volumio systemd[1]: Failed to start dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Apr 21 13:42:56 volumio avahi-daemon[578]: Interface wlan0.IPv4 no longer relevant for mDNS. Apr 21 13:42:56 volumio avahi-daemon[578]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Apr 21 13:42:56 volumio systemd-networkd[386]: wlan0: Link DOWN Apr 21 13:42:56 volumio avahi-daemon[578]: Withdrawing address record for 192.168.211.1 on wlan0. Apr 21 13:42:56 volumio avahi-daemon[578]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Apr 21 13:42:56 volumio systemd-networkd[386]: wlan0: Link UP Apr 21 13:42:56 volumio avahi-daemon[578]: New relevant interface wlan0.IPv4 for mDNS. Apr 21 13:42:56 volumio avahi-daemon[578]: Registering new address record for 192.168.211.1 on wlan0.IPv4. Apr 21 13:42:56 volumio hostapd[419358]: Using interface wlan0 with hwaddr a0:47:d7:10:55:b4 and ssid "Volumio-A4812" Apr 21 13:42:56 volumio dhcpcd[871]: wlan0: carrier acquired Apr 21 13:42:56 volumio kernel: IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready Apr 21 13:42:56 volumio systemd-networkd[386]: wlan0: Gained carrier Apr 21 13:42:56 volumio systemd-networkd[386]: wlan0: found matching network '/etc/systemd/network/10-dhcp.network', based on potentially unpredictable interface name. Apr 21 13:42:56 volumio dhcpcd[871]: wlan0: IAID d7:10:55:b4 Apr 21 13:42:56 volumio hostapd[419358]: wlan0: interface state UNINITIALIZED->ENABLED Apr 21 13:42:56 volumio hostapd[419358]: wlan0: AP-ENABLED Apr 21 13:42:56 volumio systemd[1]: Started hostapd.service - Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Apr 21 13:42:56 volumio iwconfig[419369]: Error for wireless request "Set Power Management" (8B2C) : Apr 21 13:42:56 volumio iwconfig[419369]: SET failed on device wlan0 ; Invalid argument. Apr 21 13:42:56 volumio systemd[1]: wireless.service: Deactivated successfully. Apr 21 13:42:56 volumio systemd[1]: Started wireless.service - Wireless Services. Apr 21 13:42:56 volumio systemd[1]: wireless.service: Consumed 2.274s CPU time. Apr 21 13:42:56 volumio sudo[419179]: pam_unix(sudo:session): session closed for user root Apr 21 13:42:56 volumio dhcpcd[871]: wlan0: soliciting an IPv6 router Apr 21 13:42:56 volumio volumio[419027]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 21 13:42:56 volumio volumio[419027]: TypeError: memoryCache.get is not a function Apr 21 13:42:56 volumio volumio[419027]: at ControllerMpd.listAlbums (/volumio/app/plugins/music_service/mpd/index.js:2958:30) Apr 21 13:42:56 volumio volumio[419027]: at Timeout._onTimeout (/volumio/app/plugins/music_service/mpd/index.js:682:14) Apr 21 13:42:56 volumio volumio[419027]: at listOnTimeout (node:internal/timers:569:17) Apr 21 13:42:56 volumio volumio[419027]: at process.processTimers (node:internal/timers:512:7) Apr 21 13:42:56 volumio volumio[419027]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 21 13:42:57 volumio wpa_supplicant[675]: wlan0: SME: Trying to authenticate with 5c:62:8b:46:9a:ec (SSID='Deep Thought' freq=2412 MHz) Apr 21 13:42:57 volumio wpa_supplicant[675]: wlan0: SME: Authentication request to the driver failed Apr 21 13:42:57 volumio wpa_supplicant[675]: BSSID 5c:62:8b:46:9a:ec ignore list count incremented to 2, ignoring for 10 seconds Apr 21 13:42:57 volumio dhcpcd[871]: wlan0: carrier lost Apr 21 13:42:57 volumio systemd-networkd[386]: wlan0: Lost carrier Apr 21 13:42:57 volumio systemd-networkd[386]: wlan0: DHCPv6 lease lost Apr 21 13:42:57 volumio systemd-networkd[386]: wlan0: Gained carrier Apr 21 13:42:57 volumio systemd-networkd[386]: wlan0: found matching network '/etc/systemd/network/10-dhcp.network', based on potentially unpredictable interface name. Apr 21 13:42:57 volumio dhcpcd[871]: wlan0: carrier acquired Apr 21 13:42:57 volumio dhcpcd[871]: wlan0: IAID d7:10:55:b4 Apr 21 13:42:57 volumio dhcpcd[871]: wlan0: soliciting an IPv6 router Apr 21 13:42:57 volumio wpa_supplicant[675]: wlan0: CTRL-EVENT-SCAN-FAILED ret=-95 Apr 21 13:42:57 volumio systemd-networkd[386]: wlan0: Lost carrier Apr 21 13:42:57 volumio dhcpcd[871]: wlan0: carrier lost Apr 21 13:42:57 volumio systemd-networkd[386]: wlan0: DHCPv6 lease lost Apr 21 13:42:57 volumio systemd-networkd[386]: wlan0: Gained carrier Apr 21 13:42:57 volumio systemd-networkd[386]: wlan0: found matching network '/etc/systemd/network/10-dhcp.network', based on potentially unpredictable interface name. Apr 21 13:42:57 volumio dhcpcd[871]: wlan0: carrier acquired Apr 21 13:42:57 volumio sudo[419388]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2024-04-21 13:41' Apr 21 13:42:57 volumio sudo[419388]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 21 13:42:57 volumio dhcpcd[871]: wlan0: IAID d7:10:55:b4 Apr 21 13:42:57 volumio dhcpcd[871]: wlan0: rebinding lease of 192.168.1.224 Apr 21 13:42:58 volumio dhcpcd[871]: wlan0: soliciting an IPv6 router PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/"