-- Logs begin at Tue 2023-03-28 01:54:07 CST, end at Mon 2024-09-02 11:32:16 CST. -- Sep 02 11:31:07 volumio socketio[2295]: [2024-09-02 11:31:07] [connect] Successful connection Sep 02 11:31:37 volumio socketio[2295]: [2024-09-02 11:31:37] [connect] Successful connection Sep 02 11:31:52 volumio demo[4300]: curl: (56) Recv failure: Connection reset by peer Sep 02 11:31:52 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 02 11:31:52 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 02 11:31:52 volumio systemd[1]: Started dynamicswap service. Sep 02 11:31:52 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 02 11:31:52 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 4. Sep 02 11:31:52 volumio systemd[1]: Started dynamicswap service. Sep 02 11:31:52 volumio systemd[1]: Stopped Volumio Backend Module. Sep 02 11:31:52 volumio systemd[1]: Started Volumio Backend Module. Sep 02 11:31:52 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 02 11:31:53 volumio demo[4538]: curl: (7) Failed to connect to 127.0.0.1 port 3000: Connection refused Sep 02 11:31:53 volumio demo[4542]: curl: (7) Failed to connect to 127.0.0.1 port 3000: Connection refused Sep 02 11:31:54 volumio demo[4544]: curl: (7) Failed to connect to 127.0.0.1 port 3000: Connection refused Sep 02 11:31:56 volumio volumio[4521]: info: ------------------------------------------- Sep 02 11:31:56 volumio volumio[4521]: info: ----- Volumio2 ---- Sep 02 11:31:56 volumio volumio[4521]: info: ------------------------------------------- Sep 02 11:31:56 volumio volumio[4521]: info: ----- System startup ---- Sep 02 11:31:56 volumio volumio[4521]: info: ------------------------------------------- Sep 02 11:31:57 volumio volumio[4521]: info: Found new core plugin music_service/youtube2. Adding it Sep 02 11:31:57 volumio volumio[4521]: info: Configured plugin music_service/youtube2 cannot be loaded. Removing from configuration Sep 02 11:31:57 volumio volumio[4521]: info: Plugin folders cleanup Sep 02 11:31:57 volumio volumio[4521]: info: Scanning into folder /volumio/app/plugins/ Sep 02 11:31:57 volumio volumio[4521]: info: Scanning category audio_interface Sep 02 11:31:57 volumio volumio[4521]: info: Scanning category miscellanea Sep 02 11:31:57 volumio volumio[4521]: info: Scanning category music_service Sep 02 11:31:57 volumio volumio[4521]: info: Scanning category plugins.json Sep 02 11:31:57 volumio volumio[4521]: info: Scanning category system_controller Sep 02 11:31:57 volumio volumio[4521]: info: Scanning category user_interface Sep 02 11:31:57 volumio volumio[4521]: info: Scanning into folder /data/plugins/ Sep 02 11:31:57 volumio volumio[4521]: info: Plugin folders cleanup completed Sep 02 11:31:57 volumio volumio[4521]: info: ------------------------------------------- Sep 02 11:31:57 volumio volumio[4521]: info: ----- Core plugins startup ---- Sep 02 11:31:57 volumio volumio[4521]: info: ------------------------------------------- Sep 02 11:31:57 volumio volumio[4521]: info: Loading plugins from folder /volumio/app/plugins/ Sep 02 11:31:57 volumio volumio[4521]: info: Adding plugin upnp to MyMusic Plugins Sep 02 11:31:57 volumio volumio[4521]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 02 11:31:57 volumio volumio[4521]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 02 11:31:57 volumio volumio[4521]: info: Loading plugins from folder /data/plugins/ Sep 02 11:31:57 volumio volumio[4521]: info: Loading plugin "system"... Sep 02 11:31:57 volumio volumio[4521]: info: Loading plugin "appearance"... Sep 02 11:32:01 volumio volumio[4521]: info: Loading plugin "network"... Sep 02 11:32:01 volumio volumio[4521]: info: Refreshing Cached IP Addresses Sep 02 11:32:01 volumio sudo[4558]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 02 11:32:01 volumio volumio[4521]: info: Loading plugin "services"... Sep 02 11:32:01 volumio volumio[4521]: info: Plugin sources is not enabled Sep 02 11:32:01 volumio volumio[4521]: info: Loading plugin "alsa_controller"... Sep 02 11:32:01 volumio sudo[4558]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 11:32:01 volumio sudo[4558]: pam_unix(sudo:session): session closed for user root Sep 02 11:32:01 volumio sudo[4563]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 02 11:32:01 volumio sudo[4565]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hostapd/hostapd.conf Sep 02 11:32:01 volumio sudo[4563]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 11:32:01 volumio volumio[4521]: info: Loading plugin "wizard"... Sep 02 11:32:01 volumio volumio[4521]: info: Loading plugin "volumio_command_line_client"... Sep 02 11:32:01 volumio sudo[4565]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 11:32:01 volumio sudo[4563]: pam_unix(sudo:session): session closed for user root Sep 02 11:32:01 volumio sudo[4565]: pam_unix(sudo:session): session closed for user root Sep 02 11:32:01 volumio volumio[4521]: info: Loading plugin "upnp"... Sep 02 11:32:01 volumio volumio[4521]: info: [1725247921612] Starting Upmpd Daemon Sep 02 11:32:01 volumio volumio[4521]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 02 11:32:01 volumio volumio[4521]: info: Loading plugin "my_music"... Sep 02 11:32:01 volumio volumio[4521]: info: Loading plugin "mpd"... Sep 02 11:32:02 volumio volumio[4521]: info: Creating MPD Configuration file Sep 02 11:32:02 volumio volumio[4521]: info: Loading plugin "upnp_browser"... Sep 02 11:32:02 volumio sudo[4594]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 02 11:32:02 volumio sudo[4594]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 11:32:02 volumio volumio[4521]: error: !!!! WARNING !!!! Sep 02 11:32:02 volumio volumio[4521]: error: The plugin music_service/upnp_browser failed to load, setting it to stopped. Error: Error: Could not locate the bindings file. Tried: Sep 02 11:32:02 volumio volumio[4521]: → /volumio/node_modules/libxmljs/build/xmljs.node Sep 02 11:32:02 volumio volumio[4521]: → /volumio/node_modules/libxmljs/build/Debug/xmljs.node Sep 02 11:32:02 volumio volumio[4521]: → /volumio/node_modules/libxmljs/build/Release/xmljs.node Sep 02 11:32:02 volumio volumio[4521]: → /volumio/node_modules/libxmljs/out/Debug/xmljs.node Sep 02 11:32:02 volumio volumio[4521]: → /volumio/node_modules/libxmljs/Debug/xmljs.node Sep 02 11:32:02 volumio volumio[4521]: → /volumio/node_modules/libxmljs/out/Release/xmljs.node Sep 02 11:32:02 volumio volumio[4521]: → /volumio/node_modules/libxmljs/Release/xmljs.node Sep 02 11:32:02 volumio volumio[4521]: → /volumio/node_modules/libxmljs/build/default/xmljs.node Sep 02 11:32:02 volumio volumio[4521]: → /volumio/node_modules/libxmljs/compiled/10.24.1/linux/arm/xmljs.node Sep 02 11:32:02 volumio volumio[4521]: → /volumio/node_modules/libxmljs/addon-build/release/install-root/xmljs.node Sep 02 11:32:02 volumio volumio[4521]: → /volumio/node_modules/libxmljs/addon-build/debug/install-root/xmljs.node Sep 02 11:32:02 volumio volumio[4521]: → /volumio/node_modules/libxmljs/addon-build/default/install-root/xmljs.node Sep 02 11:32:02 volumio volumio[4521]: → /volumio/node_modules/libxmljs/lib/binding/node-v64-linux-arm/xmljs.node Sep 02 11:32:02 volumio volumio[4521]: error: Stack trace: Error: Could not locate the bindings file. Tried: Sep 02 11:32:02 volumio volumio[4521]: → /volumio/node_modules/libxmljs/build/xmljs.node Sep 02 11:32:02 volumio volumio[4521]: → /volumio/node_modules/libxmljs/build/Debug/xmljs.node Sep 02 11:32:02 volumio volumio[4521]: → /volumio/node_modules/libxmljs/build/Release/xmljs.node Sep 02 11:32:02 volumio volumio[4521]: → /volumio/node_modules/libxmljs/out/Debug/xmljs.node Sep 02 11:32:02 volumio volumio[4521]: → /volumio/node_modules/libxmljs/Debug/xmljs.node Sep 02 11:32:02 volumio volumio[4521]: → /volumio/node_modules/libxmljs/out/Release/xmljs.node Sep 02 11:32:02 volumio volumio[4521]: → /volumio/node_modules/libxmljs/Release/xmljs.node Sep 02 11:32:02 volumio volumio[4521]: → /volumio/node_modules/libxmljs/build/default/xmljs.node Sep 02 11:32:02 volumio volumio[4521]: → /volumio/node_modules/libxmljs/compiled/10.24.1/linux/arm/xmljs.node Sep 02 11:32:02 volumio volumio[4521]: → /volumio/node_modules/libxmljs/addon-build/release/install-root/xmljs.node Sep 02 11:32:02 volumio volumio[4521]: → /volumio/node_modules/libxmljs/addon-build/debug/install-root/xmljs.node Sep 02 11:32:02 volumio volumio[4521]: → /volumio/node_modules/libxmljs/addon-build/default/install-root/xmljs.node Sep 02 11:32:02 volumio volumio[4521]: → /volumio/node_modules/libxmljs/lib/binding/node-v64-linux-arm/xmljs.node Sep 02 11:32:02 volumio volumio[4521]: at bindings (/volumio/node_modules/bindings/bindings.js:126:9) Sep 02 11:32:02 volumio volumio[4521]: at Object. (/volumio/node_modules/libxmljs/lib/bindings.js:1:37) Sep 02 11:32:02 volumio volumio[4521]: at Module._compile (internal/modules/cjs/loader.js:778:30) Sep 02 11:32:02 volumio volumio[4521]: at Object.Module._extensions..js (internal/modules/cjs/loader.js:789:10) Sep 02 11:32:02 volumio volumio[4521]: at Module.load (internal/modules/cjs/loader.js:653:32) Sep 02 11:32:02 volumio volumio[4521]: at tryModuleLoad (internal/modules/cjs/loader.js:593:12) Sep 02 11:32:02 volumio volumio[4521]: at Function.Module._load (internal/modules/cjs/loader.js:585:3) Sep 02 11:32:02 volumio volumio[4521]: at Module.require (internal/modules/cjs/loader.js:692:17) Sep 02 11:32:02 volumio volumio[4521]: at require (internal/modules/cjs/helpers.js:25:18) Sep 02 11:32:02 volumio volumio[4521]: at Object. (/volumio/node_modules/libxmljs/index.js:4:16) Sep 02 11:32:02 volumio volumio[4521]: error: !!!! WARNING !!!! Sep 02 11:32:02 volumio volumio[4521]: info: Loading plugin "networkfs"... Sep 02 11:32:02 volumio sudo[4594]: pam_unix(sudo:session): session closed for user root Sep 02 11:32:02 volumio sudo[4597]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 02 11:32:02 volumio sudo[4597]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 11:32:02 volumio systemd[1]: Stopping Music Player Daemon... Sep 02 11:32:02 volumio systemd[1]: mpd.service: Succeeded. Sep 02 11:32:02 volumio systemd[1]: Stopped Music Player Daemon. Sep 02 11:32:02 volumio systemd[1]: Starting Music Player Daemon... Sep 02 11:32:02 volumio volumio[4521]: error: !!!! WARNING !!!! Sep 02 11:32:02 volumio volumio[4521]: error: The plugin system_controller/networkfs failed to load, setting it to stopped. Error: Error: Cannot find module '/volumio/node_modules/udev/build/node-v64-linux-arm/udev.node' Sep 02 11:32:02 volumio volumio[4521]: error: Stack trace: Error: Cannot find module '/volumio/node_modules/udev/build/node-v64-linux-arm/udev.node' Sep 02 11:32:02 volumio volumio[4521]: at Function.Module._resolveFilename (internal/modules/cjs/loader.js:636:15) Sep 02 11:32:02 volumio volumio[4521]: at Function.Module._load (internal/modules/cjs/loader.js:562:25) Sep 02 11:32:02 volumio volumio[4521]: at Module.require (internal/modules/cjs/loader.js:692:17) Sep 02 11:32:02 volumio volumio[4521]: at require (internal/modules/cjs/helpers.js:25:18) Sep 02 11:32:02 volumio volumio[4521]: at Object. (/volumio/node_modules/udev/udev.js:4:12) Sep 02 11:32:02 volumio volumio[4521]: at Module._compile (internal/modules/cjs/loader.js:778:30) Sep 02 11:32:02 volumio volumio[4521]: at Object.Module._extensions..js (internal/modules/cjs/loader.js:789:10) Sep 02 11:32:02 volumio volumio[4521]: at Module.load (internal/modules/cjs/loader.js:653:32) Sep 02 11:32:02 volumio volumio[4521]: at tryModuleLoad (internal/modules/cjs/loader.js:593:12) Sep 02 11:32:02 volumio volumio[4521]: at Function.Module._load (internal/modules/cjs/loader.js:585:3) Sep 02 11:32:02 volumio volumio[4521]: error: !!!! WARNING !!!! Sep 02 11:32:02 volumio volumio[4521]: info: Loading plugin "alarm-clock"... Sep 02 11:32:03 volumio volumio[4521]: info: Loading plugin "airplay_emulation"... Sep 02 11:32:03 volumio volumio[4521]: info: Starting Shairport Sync Sep 02 11:32:03 volumio volumio[4521]: info: Loading plugin "last_100"... Sep 02 11:32:03 volumio volumio[4521]: info: Loading plugin "webradio"... Sep 02 11:32:03 volumio volumio[4521]: error: !!!! WARNING !!!! Sep 02 11:32:03 volumio volumio[4521]: error: The plugin music_service/webradio failed to load, setting it to stopped. Error: Error: Could not locate the bindings file. Tried: Sep 02 11:32:03 volumio volumio[4521]: → /volumio/node_modules/libxmljs/build/xmljs.node Sep 02 11:32:03 volumio volumio[4521]: → /volumio/node_modules/libxmljs/build/Debug/xmljs.node Sep 02 11:32:03 volumio volumio[4521]: → /volumio/node_modules/libxmljs/build/Release/xmljs.node Sep 02 11:32:03 volumio volumio[4521]: → /volumio/node_modules/libxmljs/out/Debug/xmljs.node Sep 02 11:32:03 volumio volumio[4521]: → /volumio/node_modules/libxmljs/Debug/xmljs.node Sep 02 11:32:03 volumio volumio[4521]: → /volumio/node_modules/libxmljs/out/Release/xmljs.node Sep 02 11:32:03 volumio volumio[4521]: → /volumio/node_modules/libxmljs/Release/xmljs.node Sep 02 11:32:03 volumio volumio[4521]: → /volumio/node_modules/libxmljs/build/default/xmljs.node Sep 02 11:32:03 volumio volumio[4521]: → /volumio/node_modules/libxmljs/compiled/10.24.1/linux/arm/xmljs.node Sep 02 11:32:03 volumio volumio[4521]: → /volumio/node_modules/libxmljs/addon-build/release/install-root/xmljs.node Sep 02 11:32:03 volumio volumio[4521]: → /volumio/node_modules/libxmljs/addon-build/debug/install-root/xmljs.node Sep 02 11:32:03 volumio volumio[4521]: → /volumio/node_modules/libxmljs/addon-build/default/install-root/xmljs.node Sep 02 11:32:03 volumio volumio[4521]: → /volumio/node_modules/libxmljs/lib/binding/node-v64-linux-arm/xmljs.node Sep 02 11:32:03 volumio volumio[4521]: error: Stack trace: Error: Could not locate the bindings file. Tried: Sep 02 11:32:03 volumio volumio[4521]: → /volumio/node_modules/libxmljs/build/xmljs.node Sep 02 11:32:03 volumio volumio[4521]: → /volumio/node_modules/libxmljs/build/Debug/xmljs.node Sep 02 11:32:03 volumio volumio[4521]: → /volumio/node_modules/libxmljs/build/Release/xmljs.node Sep 02 11:32:03 volumio volumio[4521]: → /volumio/node_modules/libxmljs/out/Debug/xmljs.node Sep 02 11:32:03 volumio volumio[4521]: → /volumio/node_modules/libxmljs/Debug/xmljs.node Sep 02 11:32:03 volumio volumio[4521]: → /volumio/node_modules/libxmljs/out/Release/xmljs.node Sep 02 11:32:03 volumio volumio[4521]: → /volumio/node_modules/libxmljs/Release/xmljs.node Sep 02 11:32:03 volumio volumio[4521]: → /volumio/node_modules/libxmljs/build/default/xmljs.node Sep 02 11:32:03 volumio volumio[4521]: → /volumio/node_modules/libxmljs/compiled/10.24.1/linux/arm/xmljs.node Sep 02 11:32:03 volumio volumio[4521]: → /volumio/node_modules/libxmljs/addon-build/release/install-root/xmljs.node Sep 02 11:32:03 volumio volumio[4521]: → /volumio/node_modules/libxmljs/addon-build/debug/install-root/xmljs.node Sep 02 11:32:03 volumio volumio[4521]: → /volumio/node_modules/libxmljs/addon-build/default/install-root/xmljs.node Sep 02 11:32:03 volumio volumio[4521]: → /volumio/node_modules/libxmljs/lib/binding/node-v64-linux-arm/xmljs.node Sep 02 11:32:03 volumio volumio[4521]: at bindings (/volumio/node_modules/bindings/bindings.js:126:9) Sep 02 11:32:03 volumio volumio[4521]: at Object. (/volumio/node_modules/libxmljs/lib/bindings.js:1:37) Sep 02 11:32:03 volumio volumio[4521]: at Module._compile (internal/modules/cjs/loader.js:778:30) Sep 02 11:32:03 volumio volumio[4521]: at Object.Module._extensions..js (internal/modules/cjs/loader.js:789:10) Sep 02 11:32:03 volumio volumio[4521]: at Module.load (internal/modules/cjs/loader.js:653:32) Sep 02 11:32:03 volumio volumio[4521]: at tryModuleLoad (internal/modules/cjs/loader.js:593:12) Sep 02 11:32:03 volumio volumio[4521]: at Function.Module._load (internal/modules/cjs/loader.js:585:3) Sep 02 11:32:03 volumio volumio[4521]: at Module.require (internal/modules/cjs/loader.js:692:17) Sep 02 11:32:03 volumio volumio[4521]: at require (internal/modules/cjs/helpers.js:25:18) Sep 02 11:32:03 volumio volumio[4521]: at Object. (/volumio/node_modules/libxmljs/index.js:4:16) Sep 02 11:32:03 volumio volumio[4521]: error: !!!! WARNING !!!! Sep 02 11:32:03 volumio volumio[4521]: info: Loading plugin "i2s_dacs"... Sep 02 11:32:03 volumio volumio[4521]: info: I2S DAC not set, start Auto-detection Sep 02 11:32:03 volumio volumio[4521]: info: Loading plugin "volumiodiscovery"... Sep 02 11:32:03 volumio volumio[4521]: error: !!!! WARNING !!!! Sep 02 11:32:03 volumio volumio[4521]: error: The plugin system_controller/volumiodiscovery failed to load, setting it to stopped. Error: Error: Cannot find module '../build/Release/dns_sd_bindings' Sep 02 11:32:03 volumio volumio[4521]: error: Stack trace: Error: Cannot find module '../build/Release/dns_sd_bindings' Sep 02 11:32:03 volumio volumio[4521]: at Function.Module._resolveFilename (internal/modules/cjs/loader.js:636:15) Sep 02 11:32:03 volumio volumio[4521]: at Function.Module._load (internal/modules/cjs/loader.js:562:25) Sep 02 11:32:03 volumio volumio[4521]: at Module.require (internal/modules/cjs/loader.js:692:17) Sep 02 11:32:03 volumio volumio[4521]: at require (internal/modules/cjs/helpers.js:25:18) Sep 02 11:32:03 volumio volumio[4521]: at Object. (/volumio/node_modules/mdns/lib/dns_sd.js:32:22) Sep 02 11:32:03 volumio volumio[4521]: at Module._compile (internal/modules/cjs/loader.js:778:30) Sep 02 11:32:03 volumio volumio[4521]: at Object.Module._extensions..js (internal/modules/cjs/loader.js:789:10) Sep 02 11:32:03 volumio volumio[4521]: at Module.load (internal/modules/cjs/loader.js:653:32) Sep 02 11:32:03 volumio volumio[4521]: at tryModuleLoad (internal/modules/cjs/loader.js:593:12) Sep 02 11:32:03 volumio volumio[4521]: at Function.Module._load (internal/modules/cjs/loader.js:585:3) Sep 02 11:32:03 volumio volumio[4521]: error: !!!! WARNING !!!! Sep 02 11:32:03 volumio volumio[4521]: info: Loading plugin "outputs"... Sep 02 11:32:03 volumio volumio[4521]: info: Loading plugin "albumart"... Sep 02 11:32:03 volumio volumio[4521]: info: Plugin chromecast is not enabled Sep 02 11:32:03 volumio volumio[4521]: info: Plugin example_plugin is not enabled Sep 02 11:32:03 volumio volumio[4521]: info: Loading plugin "inputs"... Sep 02 11:32:03 volumio volumio[4521]: info: Loading plugin "pandora"... Sep 02 11:32:05 volumio volumio[4521]: info: Applying required configuration parameters for plugin pandora Sep 02 11:32:05 volumio volumio[4521]: info: Loading plugin "podcast"... Sep 02 11:32:05 volumio mpd[4610]: config_file: config parameter "gapless_mp3_playback" on line 19 is deprecated Sep 02 11:32:05 volumio mpd[4610]: config_file: config parameter "buffer_before_play" on line 102 is deprecated Sep 02 11:32:05 volumio mpd[4610]: config_file: config parameter "id3v1_encoding" on line 116 is deprecated Sep 02 11:32:05 volumio mpd[4610]: Sep 02 11:32 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 02 11:32:05 volumio mpd[4610]: Sep 02 11:32 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Sep 02 11:32:05 volumio mpd[4610]: Sep 02 11:32 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Sep 02 11:32:05 volumio systemd[1]: Started Music Player Daemon. Sep 02 11:32:05 volumio sudo[4597]: pam_unix(sudo:session): session closed for user root Sep 02 11:32:05 volumio volumio[4521]: info: ControllerPodcast::constructor Sep 02 11:32:05 volumio volumio[4521]: info: Loading plugin "qobuz"... Sep 02 11:32:05 volumio volumio[4521]: error: ATTENTION!!!: Plugin qobuz does not return adequate promise from onVolumioStart: please update! Sep 02 11:32:05 volumio volumio[4521]: info: Loading plugin "tidal"... Sep 02 11:32:06 volumio volumio[4521]: info: [1725247926629] ControllerTidalPlugin::constructor Sep 02 11:32:06 volumio volumio[4521]: info: [1725247926631] ControllerTidalPlugin::onVolumioStart Sep 02 11:32:06 volumio volumio[4521]: info: Loading plugin "volspotconnect2"... Sep 02 11:32:07 volumio socketio[2295]: [2024-09-02 11:32:07] [connect] Successful connection Sep 02 11:32:07 volumio volumio[4521]: [SpotifyConnect2] Creating VLS config file Sep 02 11:32:07 volumio volumio[4521]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 11:32:07 volumio volumio[4521]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 11:32:07 volumio volumio[4521]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 11:32:07 volumio volumio[4521]: [SpotifyConnect2] <> or Mixer found, using softvol Sep 02 11:32:07 volumio volumio[4521]: info: Loading plugin "updater_comm"... Sep 02 11:32:07 volumio volumio[4521]: info: Plugin mpdemulation is not enabled Sep 02 11:32:07 volumio volumio[4521]: info: Loading plugin "rest_api"... Sep 02 11:32:07 volumio volumio[4521]: info: Loading plugin "websocket"... Sep 02 11:32:07 volumio volumio[4521]: info: ___________ START PLUGINS ___________ Sep 02 11:32:07 volumio volumio[4521]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 11:32:07 volumio volumio[4521]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 02 11:32:08 volumio volumio[4521]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 02 11:32:08 volumio volumio[4521]: info: [1725247928002] CoreMusicLibrary::Adding element Last_100 Sep 02 11:32:08 volumio volumio[4521]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 11:32:08 volumio volumio[4521]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 02 11:32:08 volumio volumio[4521]: info: [1725247928029] CoreMusicLibrary::Adding element Pandora Sep 02 11:32:08 volumio volumio[4521]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 11:32:08 volumio volumio[4521]: info: [1725247928037] ControllerPandora::initializeMQTT Sep 02 11:32:08 volumio volumio[4521]: info: [1725247928039] [Pandora] initializeMQTT: MQTT is not enabled in Plugin Configuration Sep 02 11:32:08 volumio volumio[4521]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 02 11:32:08 volumio volumio[4521]: info: [1725247928053] CoreMusicLibrary::Adding element Podcast Sep 02 11:32:08 volumio volumio[4521]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 11:32:08 volumio volumio[4521]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 02 11:32:08 volumio volumio[4521]: info: [1725247928059] CoreMusicLibrary::Adding element Qobuz Sep 02 11:32:08 volumio volumio[4521]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 11:32:08 volumio volumio[4521]: info: [1725247928074] ControllerTidalPlugin::onStart Sep 02 11:32:08 volumio volumio[4521]: info: [1725247928082] ControllerTidalPlugin::addToBrowseSources Sep 02 11:32:08 volumio volumio[4521]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 02 11:32:08 volumio volumio[4521]: info: [1725247928084] CoreMusicLibrary::Adding element Tidal Sep 02 11:32:08 volumio volumio[4521]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 11:32:08 volumio volumio[4521]: Loading session from /data/configuration/music_service/tidal/tidal-session-oauth.json... Sep 02 11:32:08 volumio volumio[4521]: Could not load session from /data/configuration/music_service/tidal/tidal-session-oauth.json: Error: ENOENT: no such file or directory, open '/data/configuration/music_service/tidal/tidal-session-oauth.json' Sep 02 11:32:08 volumio volumio[4521]: [SpotifyConnect2] Creating VLS config file Sep 02 11:32:08 volumio volumio[4521]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 11:32:08 volumio volumio[4521]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 11:32:08 volumio volumio[4521]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 11:32:08 volumio volumio[4521]: [SpotifyConnect2] <> or Mixer found, using softvol Sep 02 11:32:08 volumio volumio[4521]: [SpotifyConnect2] Starting metadata listener Sep 02 11:32:08 volumio sudo[4628]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect2.service Sep 02 11:32:08 volumio volumio[4521]: info: Loading i18n strings for locale en Sep 02 11:32:08 volumio sudo[4628]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 11:32:08 volumio volumio[4521]: Updating browse sources language Sep 02 11:32:08 volumio volumio[4521]: Cannot find translation for sourcePodcast Sep 02 11:32:08 volumio volumio[4521]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 11:32:08 volumio volumio[4521]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 02 11:32:08 volumio volumio[4521]: info: CoreCommandRouter::initPlayerControls Sep 02 11:32:08 volumio volumio[4521]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 11:32:08 volumio volumio[4521]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 11:32:08 volumio volumio[4521]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 11:32:08 volumio volumio[4521]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 11:32:08 volumio volumio[4521]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 11:32:08 volumio volumio[4521]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 11:32:08 volumio volumio[4521]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 11:32:08 volumio sudo[4628]: pam_unix(sudo:session): session closed for user root Sep 02 11:32:08 volumio volumio[4521]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 02 11:32:08 volumio volumio[4521]: info: BOOT COMPLETED Sep 02 11:32:08 volumio volumio[4521]: [Metrics] CommandRouter: 12s 336.04ms Sep 02 11:32:08 volumio volumio[4521]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 11:32:08 volumio volumio[4521]: ALSA lib pcm.c:2642:(snd_pcm_open_noupdate) Unknown PCM softvolume Sep 02 11:32:08 volumio volumio[4521]: aplay: main:852: audio open error: No such file or directory Sep 02 11:32:08 volumio volumio[4521]: Cannot play startup sound Sep 02 11:32:08 volumio volumio[4521]: info: CoreCommandRouter::Close All Modals sent Sep 02 11:32:08 volumio volumio[4521]: info: CoreCommandRouter::Close All Modals sent Sep 02 11:32:08 volumio volumio[4521]: Express server listening on port 3000 Sep 02 11:32:08 volumio volumio[4521]: [Metrics] WebUI: 13s 361.17ms Sep 02 11:32:08 volumio volumio[4521]: info: [1725247928498] ControllerPandora::validateAndSetAccountOptions Sep 02 11:32:08 volumio volumio[4521]: error: [1725247928501] [Pandora] validateAndSetAccountOptions: Need email address and password. See plugin settings. Sep 02 11:32:08 volumio volumio[4521]: info: CoreStateMachine::resetVolumioState Sep 02 11:32:08 volumio volumio[4521]: info: CoreStateMachine::getcurrentVolume Sep 02 11:32:08 volumio volumio[4521]: info: CoreCommandRouter::volumioRetrievevolume Sep 02 11:32:08 volumio volumio[4521]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Sep 02 11:32:08 volumio volumio[4521]: info: Cannot read play queue from file Sep 02 11:32:08 volumio volumio[4521]: info: CoreStateMachine::pushState Sep 02 11:32:08 volumio volumio[4521]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 11:32:08 volumio volumio[4521]: info: CoreStateMachine::updateTrackBlock Sep 02 11:32:08 volumio volumio[4521]: info: CorePlayQueue::getTrackBlock Sep 02 11:32:08 volumio volumio[4521]: info: CoreCommandRouter::volumioRetrievevolume Sep 02 11:32:08 volumio volumio[4521]: [SpotifyConnect2] Vollibrespot Daemon service started! Sep 02 11:32:08 volumio volumio[4521]: [Metrics] SpotifyConnect: 0s 884.65ms Sep 02 11:32:09 volumio volumio[4521]: info: MPD Permissions set Sep 02 11:32:09 volumio volumio[4521]: info: Permissions for /etc/hostapd/hostapd.conf Sep 02 11:32:09 volumio volumio[4521]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 11:32:09 volumio sudo[4674]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Sep 02 11:32:09 volumio sudo[4674]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 11:32:09 volumio socketio[2295]: [2024-09-02 11:32:09] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1725247927 101 Sep 02 11:32:09 volumio socketio[2295]: sio connected Sep 02 11:32:09 volumio systemd[1]: Starting Wireless Services... Sep 02 11:32:09 volumio volumio[4660]: Forking 1 albumart workers Sep 02 11:32:09 volumio sudo[4681]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 set power_save off Sep 02 11:32:10 volumio sudo[4681]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 11:32:10 volumio socketio[2295]: socket send Sep 02 11:32:10 volumio socketio[2295]: command_len 54 Sep 02 11:32:10 volumio socketio[2295]: pushSrctotal Sep 02 11:32:10 volumio socketio[2295]: { Sep 02 11:32:10 volumio socketio[2295]: "total": 0, Sep 02 11:32:10 volumio socketio[2295]: "cur": 0, Sep 02 11:32:10 volumio socketio[2295]: "source": [] Sep 02 11:32:10 volumio socketio[2295]: } Sep 02 11:32:10 volumio wifistart.sh[4687]: command failed: No such device (-19) Sep 02 11:32:10 volumio sudo[4681]: pam_unix(sudo:session): session closed for user root Sep 02 11:32:10 volumio volumio[4521]: info: MPD running with PID4610 Sep 02 11:32:10 volumio volumio[4521]: ,establishing connection Sep 02 11:32:10 volumio volumio[4521]: info: Cannot read I2C interface or I2C interface not presentError: Command failed: /usr/bin/sudo /usr/sbin/i2cdetect -y 1 Sep 02 11:32:10 volumio volumio[4521]: sudo: /usr/sbin/i2cdetect: command not found Sep 02 11:32:10 volumio volumio[4521]: info: CoreStateMachine::pushState Sep 02 11:32:10 volumio volumio[4521]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 11:32:10 volumio volumio[4521]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Sep 02 11:32:10 volumio volumio[4521]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 02 11:32:10 volumio volumio[4521]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Sep 02 11:32:10 volumio volumio[4521]: info: Sep 02 11:32:10 volumio volumio[4521]: ---------------------------- Client requests Volume 100 Sep 02 11:32:10 volumio volumio[4521]: info: VolumeController::SetAlsaVolume100 Sep 02 11:32:10 volumio volumio[4521]: info: CoreStateMachine::pushState Sep 02 11:32:10 volumio volumio[4521]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 11:32:10 volumio volumio[4521]: Upnp client error 2: Error: This socket has been ended by the other party Sep 02 11:32:10 volumio volumio[4521]: sendCommand: Command { name: 'playlistinfo', args: [] } Sep 02 11:32:11 volumio volumio[4521]: error: MPD error: The expression evaluated to a falsy value: Sep 02 11:32:11 volumio volumio[4521]: assert.ok(self.idling) Sep 02 11:32:11 volumio volumio[4521]: sendCommand: Command { name: 'search album ""', args: [] } Sep 02 11:32:11 volumio volumio[4521]: error: MPD error: The expression evaluated to a falsy value: Sep 02 11:32:11 volumio volumio[4521]: assert.ok(self.idling) Sep 02 11:32:11 volumio socketio[2295]: socket send Sep 02 11:32:11 volumio socketio[2295]: command_len 54 Sep 02 11:32:11 volumio socketio[2295]: pushSrctotal Sep 02 11:32:11 volumio socketio[2295]: { Sep 02 11:32:11 volumio socketio[2295]: "total": 0, Sep 02 11:32:11 volumio socketio[2295]: "cur": 0, Sep 02 11:32:11 volumio socketio[2295]: "source": [] Sep 02 11:32:11 volumio socketio[2295]: } Sep 02 11:32:12 volumio volumio[4521]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 11:32:12 volumio volumio[4521]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 11:32:12 volumio volumio[4521]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 11:32:12 volumio volumio[4521]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 11:32:12 volumio volumio[4521]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 11:32:12 volumio volumio[4521]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 11:32:12 volumio volumio[4521]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 11:32:12 volumio volumio[4521]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 11:32:12 volumio sudo[4711]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 02 11:32:12 volumio volumio[4521]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 11:32:12 volumio volumio[4521]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Sep 02 11:32:12 volumio sudo[4711]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 11:32:12 volumio volumio[4521]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 11:32:12 volumio sudo[4711]: pam_unix(sudo:session): session closed for user root Sep 02 11:32:12 volumio sudo[4712]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 02 11:32:12 volumio volumio[4521]: sendCommand: Command { name: 'playlistinfo', args: [] } Sep 02 11:32:12 volumio volumio[4521]: sendCommand: Command { name: 'lsinfo "USB"', args: [] } Sep 02 11:32:12 volumio sudo[4712]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 11:32:12 volumio sudo[4712]: pam_unix(sudo:session): session closed for user root Sep 02 11:32:12 volumio volumio[4521]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'SoftMaster',0 Sep 02 11:32:12 volumio volumio[4521]: info: Setting volume on startup at 100 Sep 02 11:32:12 volumio volumio[4521]: info: CoreStateMachine::setRepeat null single undefined Sep 02 11:32:12 volumio volumio[4521]: info: CoreStateMachine::pushState Sep 02 11:32:12 volumio volumio[4521]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 11:32:12 volumio volumio[4521]: info: CoreStateMachine::setRandom null Sep 02 11:32:12 volumio volumio[4521]: info: CoreStateMachine::pushState Sep 02 11:32:12 volumio volumio[4521]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 11:32:12 volumio volumio[4521]: error: updateQueue error: null Sep 02 11:32:12 volumio volumio[4521]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Sep 02 11:32:12 volumio socketio[2295]: socket send Sep 02 11:32:12 volumio socketio[2295]: command_len 54 Sep 02 11:32:12 volumio socketio[2295]: pushSrctotal Sep 02 11:32:12 volumio socketio[2295]: { Sep 02 11:32:12 volumio socketio[2295]: "total": 0, Sep 02 11:32:12 volumio socketio[2295]: "cur": 0, Sep 02 11:32:12 volumio socketio[2295]: "source": [] Sep 02 11:32:12 volumio socketio[2295]: } Sep 02 11:32:12 volumio volumio[4521]: info: Starting Shairport Sync Sep 02 11:32:12 volumio sudo[4738]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 02 11:32:13 volumio sudo[4738]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 11:32:13 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 02 11:32:13 volumio systemd[1]: shairport-sync.service: Succeeded. Sep 02 11:32:13 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 02 11:32:13 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 02 11:32:13 volumio sudo[4746]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 02 11:32:13 volumio sudo[4738]: pam_unix(sudo:session): session closed for user root Sep 02 11:32:13 volumio sudo[4746]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 11:32:13 volumio volumio[4685]: Starting albumart workers Sep 02 11:32:13 volumio wireless.js[4698]: WIRELESS: No wireless interface, exiting Sep 02 11:32:13 volumio volumio[4521]: info: Shairport-Sync Started Sep 02 11:32:13 volumio sudo[4746]: pam_unix(sudo:session): session closed for user root Sep 02 11:32:13 volumio volumio[4521]: Error adding Membership: Error: addMembership EINVAL Sep 02 11:32:14 volumio systemd[1]: wireless.service: Control process exited, code=exited, status=1/FAILURE Sep 02 11:32:14 volumio systemd[1]: wireless.service: Failed with result 'exit-code'. Sep 02 11:32:14 volumio systemd[1]: Failed to start Wireless Services. Sep 02 11:32:14 volumio sudo[4674]: pam_unix(sudo:session): session closed for user root Sep 02 11:32:14 volumio volumio[4521]: info: Upmpdcli Daemon Started Sep 02 11:32:14 volumio volumio[4521]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 02 11:32:14 volumio volumio[4521]: TypeError: f.split(...)[0].capitalize is not a function Sep 02 11:32:14 volumio volumio[4521]: at /volumio/app/plugins/miscellanea/appearance/index.js:198:56 Sep 02 11:32:14 volumio volumio[4521]: at Array.forEach () Sep 02 11:32:14 volumio volumio[4521]: at /volumio/app/plugins/miscellanea/appearance/index.js:196:11 Sep 02 11:32:14 volumio volumio[4521]: at go$readdir$cb (/volumio/node_modules/graceful-fs/graceful-fs.js:195:14) Sep 02 11:32:14 volumio volumio[4521]: at FSReqWrap.args [as oncomplete] (fs.js:140:20) Sep 02 11:32:14 volumio volumio[4521]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 02 11:32:16 volumio sudo[4793]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-02 11:31 Sep 02 11:32:16 volumio sudo[4793]: pam_unix(sudo:session): session opened for user root by (uid=0) NAME="Ubuntu" VERSION="20.04.5 LTS (Focal Fossa)" ID=ubuntu ID_LIKE=debian PRETTY_NAME="Ubuntu 20.04.5 LTS" VERSION_ID="20.04" HOME_URL="https://www.ubuntu.com/" SUPPORT_URL="https://help.ubuntu.com/" BUG_REPORT_URL="https://bugs.launchpad.net/ubuntu/" PRIVACY_POLICY_URL="https://www.ubuntu.com/legal/terms-and-policies/privacy-policy" VERSION_CODENAME=focal UBUNTU_CODENAME=focal VOLUMIO_BUILD_VERSION="" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_BE_VERSION="3e944f35d34b575025d16b976b4cb4d9aed53b66" VOLUMIO_ARCH="armv7"