-- Logs begin at Tue 2023-03-28 01:54:08 CST, end at Mon 2024-08-19 11:30:47 CST. -- Aug 19 11:29:04 volumio1234 volumio[2909]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart Aug 19 11:29:04 volumio1234 volumio[2909]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart Aug 19 11:29:04 volumio1234 volumio[2909]: info: Refreshing Cached IP Addresses Aug 19 11:29:04 volumio1234 sudo[3290]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Aug 19 11:29:04 volumio1234 sudo[3294]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 19 11:29:04 volumio1234 sudo[3290]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 11:29:04 volumio1234 sudo[3301]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 19 11:29:04 volumio1234 sudo[3294]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 11:29:04 volumio1234 sudo[3301]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 11:29:04 volumio1234 sudo[3290]: pam_unix(sudo:session): session closed for user root Aug 19 11:29:04 volumio1234 sudo[3294]: pam_unix(sudo:session): session closed for user root Aug 19 11:29:05 volumio1234 sudo[3301]: pam_unix(sudo:session): session closed for user root Aug 19 11:29:05 volumio1234 volumio[2909]: Upnp client error: Error: read ECONNRESET Aug 19 11:29:05 volumio1234 systemd[1]: upmpdcli.service: Succeeded. Aug 19 11:29:15 volumio1234 sudo[3364]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 19 11:29:15 volumio1234 sudo[3364]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 11:29:15 volumio1234 sudo[3364]: pam_unix(sudo:session): session closed for user root Aug 19 11:29:15 volumio1234 sudo[3369]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 19 11:29:15 volumio1234 sudo[3369]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 11:29:15 volumio1234 sudo[3372]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Aug 19 11:29:15 volumio1234 sudo[3372]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 11:29:15 volumio1234 sudo[3369]: pam_unix(sudo:session): session closed for user root Aug 19 11:29:15 volumio1234 systemd[1]: Started UPnP Renderer front-end to MPD. Aug 19 11:29:15 volumio1234 sudo[3372]: pam_unix(sudo:session): session closed for user root Aug 19 11:29:15 volumio1234 volumio[2909]: info: Upmpdcli Daemon Started Aug 19 11:29:15 volumio1234 volumio[3410]: writing RSA key Aug 19 11:29:18 volumio1234 volumio[2909]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 19 11:29:18 volumio1234 volumio[2909]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 19 11:29:23 volumio1234 volumio[2909]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 19 11:29:23 volumio1234 volumio[2909]: TypeError: Cannot read property 'UUID' of undefined Aug 19 11:29:23 volumio1234 volumio[2909]: at Browser. (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:172:53) Aug 19 11:29:23 volumio1234 volumio[2909]: at Browser.emit (events.js:198:13) Aug 19 11:29:23 volumio1234 volumio[2909]: at on_resolver_done (/volumio/node_modules/mdns/lib/browser.js:31:14) Aug 19 11:29:23 volumio1234 volumio[2909]: at next (/volumio/node_modules/mdns/lib/browser.js:106:7) Aug 19 11:29:23 volumio1234 volumio[2909]: at /volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:160:11 Aug 19 11:29:23 volumio1234 volumio[2909]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:108:7) Aug 19 11:29:23 volumio1234 volumio[2909]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) Aug 19 11:29:23 volumio1234 volumio[2909]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 19 11:29:24 volumio1234 sudo[3462]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-19 11:28 Aug 19 11:29:24 volumio1234 sudo[3462]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 11:29:24 volumio1234 sudo[3462]: pam_unix(sudo:session): session closed for user root Aug 19 11:29:51 volumio1234 rc.local[2297]: [2024-08-19 11:29:51] [error] handle_read_frame error: asio.system:104 (Connection reset by peer) Aug 19 11:29:51 volumio1234 rc.local[2297]: [2024-08-19 11:29:51] [disconnect] Disconnect close local:[1006,Connection reset by peer] remote:[1006] Aug 19 11:29:51 volumio1234 systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Aug 19 11:29:51 volumio1234 rc.local[3452]: curl: (56) Recv failure: Connection reset by peer Aug 19 11:29:51 volumio1234 systemd[1]: volumio.service: Failed with result 'exit-code'. Aug 19 11:29:51 volumio1234 systemd[1]: Started dynamicswap service. Aug 19 11:29:51 volumio1234 systemd[1]: dynamicswap.service: Succeeded. Aug 19 11:29:51 volumio1234 systemd[1]: volumio.service: Scheduled restart job, restart counter is at 2. Aug 19 11:29:51 volumio1234 systemd[1]: Started dynamicswap service. Aug 19 11:29:51 volumio1234 systemd[1]: Stopped Volumio Backend Module. Aug 19 11:29:51 volumio1234 systemd[1]: Started Volumio Backend Module. Aug 19 11:29:51 volumio1234 systemd[1]: dynamicswap.service: Succeeded. Aug 19 11:29:51 volumio1234 rc.local[3519]: curl: (7) Failed to connect to 127.0.0.1 port 3000: Connection refused Aug 19 11:29:52 volumio1234 rc.local[3521]: curl: (7) Failed to connect to 127.0.0.1 port 3000: Connection refused Aug 19 11:29:53 volumio1234 rc.local[3523]: curl: (7) Failed to connect to 127.0.0.1 port 3000: Connection refused Aug 19 11:29:54 volumio1234 volumio[3504]: info: ------------------------------------------- Aug 19 11:29:54 volumio1234 volumio[3504]: info: ----- Volumio2 ---- Aug 19 11:29:54 volumio1234 volumio[3504]: info: ------------------------------------------- Aug 19 11:29:54 volumio1234 volumio[3504]: info: ----- System startup ---- Aug 19 11:29:54 volumio1234 volumio[3504]: info: ------------------------------------------- Aug 19 11:29:55 volumio1234 volumio[3504]: info: Plugin folders cleanup Aug 19 11:29:55 volumio1234 volumio[3504]: info: Scanning into folder /volumio/app/plugins/ Aug 19 11:29:55 volumio1234 volumio[3504]: info: Scanning category audio_interface Aug 19 11:29:55 volumio1234 volumio[3504]: info: Scanning category miscellanea Aug 19 11:29:55 volumio1234 volumio[3504]: info: Scanning category music_service Aug 19 11:29:55 volumio1234 volumio[3504]: info: Scanning category plugins.json Aug 19 11:29:55 volumio1234 volumio[3504]: info: Scanning category system_controller Aug 19 11:29:55 volumio1234 volumio[3504]: info: Scanning category user_interface Aug 19 11:29:55 volumio1234 volumio[3504]: info: Scanning into folder /data/plugins/ Aug 19 11:29:55 volumio1234 volumio[3504]: info: Plugin folders cleanup completed Aug 19 11:29:55 volumio1234 volumio[3504]: info: ------------------------------------------- Aug 19 11:29:55 volumio1234 volumio[3504]: info: ----- Core plugins startup ---- Aug 19 11:29:55 volumio1234 volumio[3504]: info: ------------------------------------------- Aug 19 11:29:55 volumio1234 volumio[3504]: info: Loading plugins from folder /volumio/app/plugins/ Aug 19 11:29:55 volumio1234 volumio[3504]: info: Adding plugin upnp to MyMusic Plugins Aug 19 11:29:55 volumio1234 volumio[3504]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 19 11:29:55 volumio1234 volumio[3504]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 19 11:29:55 volumio1234 volumio[3504]: info: Loading plugins from folder /data/plugins/ Aug 19 11:29:55 volumio1234 volumio[3504]: info: Loading plugin "system"... Aug 19 11:29:55 volumio1234 volumio[3504]: info: Loading plugin "appearance"... Aug 19 11:29:56 volumio1234 rc.local[2297]: [2024-08-19 11:29:56] [connect] Successful connection Aug 19 11:29:59 volumio1234 volumio[3504]: info: Loading plugin "network"... Aug 19 11:29:59 volumio1234 volumio[3504]: info: Refreshing Cached IP Addresses Aug 19 11:29:59 volumio1234 sudo[3584]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 19 11:29:59 volumio1234 sudo[3584]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 11:29:59 volumio1234 volumio[3504]: info: Loading plugin "services"... Aug 19 11:29:59 volumio1234 volumio[3504]: info: Loading plugin "alsa_controller"... Aug 19 11:30:00 volumio1234 sudo[3584]: pam_unix(sudo:session): session closed for user root Aug 19 11:30:00 volumio1234 volumio[3504]: info: Loading plugin "wizard"... Aug 19 11:30:00 volumio1234 volumio[3504]: info: Loading plugin "volumio_command_line_client"... Aug 19 11:30:00 volumio1234 sudo[3589]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 19 11:30:00 volumio1234 sudo[3592]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hostapd/hostapd.conf Aug 19 11:30:00 volumio1234 sudo[3592]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 11:30:00 volumio1234 sudo[3589]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 11:30:00 volumio1234 sudo[3592]: pam_unix(sudo:session): session closed for user root Aug 19 11:30:00 volumio1234 sudo[3589]: pam_unix(sudo:session): session closed for user root Aug 19 11:30:00 volumio1234 volumio[3504]: info: Loading plugin "upnp"... Aug 19 11:30:00 volumio1234 volumio[3504]: info: [1724038200360] Starting Upmpd Daemon Aug 19 11:30:00 volumio1234 volumio[3504]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 19 11:30:00 volumio1234 volumio[3504]: info: Loading plugin "my_music"... Aug 19 11:30:00 volumio1234 volumio[3504]: info: Loading plugin "mpd"... Aug 19 11:30:01 volumio1234 volumio[3504]: info: Loading plugin "upnp_browser"... Aug 19 11:30:01 volumio1234 volumio[3504]: info: Loading plugin "networkfs"... Aug 19 11:30:02 volumio1234 volumio[3504]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 19 11:30:02 volumio1234 volumio[3504]: info: Loading plugin "alarm-clock"... Aug 19 11:30:02 volumio1234 volumio[3504]: info: Loading plugin "airplay_emulation"... Aug 19 11:30:02 volumio1234 volumio[3504]: info: Starting Shairport Sync Aug 19 11:30:02 volumio1234 volumio[3504]: info: Loading plugin "last_100"... Aug 19 11:30:02 volumio1234 volumio[3504]: info: Loading plugin "webradio"... Aug 19 11:30:02 volumio1234 volumio[3504]: info: Plugin youtube2 is not enabled Aug 19 11:30:02 volumio1234 volumio[3504]: info: Loading plugin "i2s_dacs"... Aug 19 11:30:02 volumio1234 volumio[3504]: info: I2S DAC not set, start Auto-detection Aug 19 11:30:02 volumio1234 volumio[3504]: info: Loading plugin "volumiodiscovery"... Aug 19 11:30:02 volumio1234 volumio[3504]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 19 11:30:02 volumio1234 volumio[3504]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 19 11:30:02 volumio1234 node[3504]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 19 11:30:02 volumio1234 volumio[3504]: *** WARNING *** For more information see Aug 19 11:30:02 volumio1234 volumio[3504]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 19 11:30:02 volumio1234 volumio[3504]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 19 11:30:02 volumio1234 volumio[3504]: *** WARNING *** For more information see Aug 19 11:30:02 volumio1234 node[3504]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 19 11:30:02 volumio1234 node[3504]: *** WARNING *** For more information see Aug 19 11:30:02 volumio1234 node[3504]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 19 11:30:02 volumio1234 node[3504]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 19 11:30:02 volumio1234 node[3504]: *** WARNING *** For more information see Aug 19 11:30:02 volumio1234 volumio[3504]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 19 11:30:02 volumio1234 volumio[3504]: Discovery: StartAdv! undefined Aug 19 11:30:02 volumio1234 volumio[3504]: Discovery: Started advertising... Volumio - undefined Aug 19 11:30:02 volumio1234 volumio[3504]: Name conflict due to Shairport Sync, discarding error Aug 19 11:30:02 volumio1234 volumio[3504]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 19 11:30:02 volumio1234 volumio[3504]: info: Loading plugin "outputs"... Aug 19 11:30:02 volumio1234 volumio[3504]: info: Loading plugin "albumart"... Aug 19 11:30:02 volumio1234 volumio[3504]: info: Plugin example_plugin is not enabled Aug 19 11:30:02 volumio1234 volumio[3504]: info: Loading plugin "inputs"... Aug 19 11:30:02 volumio1234 volumio[3504]: info: Loading plugin "pandora"... Aug 19 11:30:03 volumio1234 volumio[3504]: info: Applying required configuration parameters for plugin pandora Aug 19 11:30:03 volumio1234 volumio[3504]: info: Loading plugin "qobuz"... Aug 19 11:30:03 volumio1234 volumio[3504]: error: ATTENTION!!!: Plugin qobuz does not return adequate promise from onVolumioStart: please update! Aug 19 11:30:03 volumio1234 volumio[3504]: info: Loading plugin "tidal"... Aug 19 11:30:04 volumio1234 volumio[3504]: info: [1724038204547] ControllerTidalPlugin::constructor Aug 19 11:30:04 volumio1234 volumio[3504]: info: [1724038204549] ControllerTidalPlugin::onVolumioStart Aug 19 11:30:04 volumio1234 volumio[3504]: info: Loading plugin "volspotconnect2"... Aug 19 11:30:05 volumio1234 volumio[3504]: [SpotifyConnect2] Creating VLS config file Aug 19 11:30:05 volumio1234 volumio[3504]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 19 11:30:05 volumio1234 volumio[3504]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 19 11:30:05 volumio1234 volumio[3504]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 19 11:30:05 volumio1234 volumio[3504]: [SpotifyConnect2] <> or Mixer found, using softvol Aug 19 11:30:05 volumio1234 volumio[3504]: info: Loading plugin "updater_comm"... Aug 19 11:30:05 volumio1234 volumio[3504]: info: Plugin mpdemulation is not enabled Aug 19 11:30:05 volumio1234 volumio[3504]: info: Loading plugin "rest_api"... Aug 19 11:30:05 volumio1234 volumio[3504]: info: Loading plugin "websocket"... Aug 19 11:30:05 volumio1234 volumio[3504]: info: ___________ START PLUGINS ___________ Aug 19 11:30:05 volumio1234 volumio[3504]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 19 11:30:05 volumio1234 volumio[3504]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 19 11:30:05 volumio1234 volumio[3504]: info: [1724038205873] CoreMusicLibrary::Adding element Media Servers Aug 19 11:30:05 volumio1234 volumio[3504]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 19 11:30:05 volumio1234 volumio[3504]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 19 11:30:06 volumio1234 volumio[3504]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 19 11:30:06 volumio1234 volumio[3504]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 19 11:30:06 volumio1234 volumio[3504]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 19 11:30:06 volumio1234 volumio[3504]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 19 11:30:06 volumio1234 volumio[3504]: info: [1724038206085] CoreMusicLibrary::Adding element Last_100 Aug 19 11:30:06 volumio1234 volumio[3504]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 19 11:30:06 volumio1234 volumio[3504]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 19 11:30:06 volumio1234 volumio[3504]: info: [1724038206095] CoreMusicLibrary::Adding element Webradio Aug 19 11:30:06 volumio1234 volumio[3504]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 19 11:30:06 volumio1234 volumio[3504]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 19 11:30:06 volumio1234 volumio[3504]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 19 11:30:06 volumio1234 volumio[3504]: info: [1724038206124] CoreMusicLibrary::Adding element Pandora Radio Aug 19 11:30:06 volumio1234 volumio[3504]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 19 11:30:06 volumio1234 volumio[3504]: info: [1724038206129] ControllerPandora::initializeMQTT Aug 19 11:30:06 volumio1234 volumio[3504]: info: [1724038206131] [Pandora] initializeMQTT: MQTT is not enabled in Plugin Configuration Aug 19 11:30:06 volumio1234 volumio[3504]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 19 11:30:06 volumio1234 volumio[3504]: info: [1724038206134] CoreMusicLibrary::Adding element Qobuz Aug 19 11:30:06 volumio1234 volumio[3504]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 19 11:30:06 volumio1234 volumio[3504]: info: [1724038206145] ControllerTidalPlugin::onStart Aug 19 11:30:06 volumio1234 volumio[3504]: info: [1724038206148] ControllerTidalPlugin::addToBrowseSources Aug 19 11:30:06 volumio1234 volumio[3504]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 19 11:30:06 volumio1234 volumio[3504]: info: [1724038206149] CoreMusicLibrary::Adding element Tidal Aug 19 11:30:06 volumio1234 volumio[3504]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 19 11:30:06 volumio1234 volumio[3504]: Loading session from tidal-session-oauth.json... Aug 19 11:30:06 volumio1234 volumio[3504]: Could not load session from tidal-session-oauth.json: Error: ENOENT: no such file or directory, open 'tidal-session-oauth.json' Aug 19 11:30:06 volumio1234 volumio[3504]: [SpotifyConnect2] Creating VLS config file Aug 19 11:30:06 volumio1234 volumio[3504]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 19 11:30:06 volumio1234 volumio[3504]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 19 11:30:06 volumio1234 volumio[3504]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 19 11:30:06 volumio1234 volumio[3504]: [SpotifyConnect2] <> or Mixer found, using softvol Aug 19 11:30:06 volumio1234 volumio[3504]: [SpotifyConnect2] Starting metadata listener Aug 19 11:30:06 volumio1234 volumio[3504]: info: Loading i18n strings for locale zh Aug 19 11:30:06 volumio1234 volumio[3504]: Updating browse sources language Aug 19 11:30:06 volumio1234 volumio[3504]: Cannot find translation for sourceMedia Servers Aug 19 11:30:06 volumio1234 volumio[3504]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 19 11:30:06 volumio1234 volumio[3504]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 19 11:30:06 volumio1234 volumio[3504]: info: CoreCommandRouter::initPlayerControls Aug 19 11:30:06 volumio1234 volumio[3504]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 19 11:30:06 volumio1234 volumio[3504]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 19 11:30:06 volumio1234 volumio[3504]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 19 11:30:06 volumio1234 volumio[3504]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 19 11:30:06 volumio1234 volumio[3504]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 19 11:30:06 volumio1234 volumio[3504]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 19 11:30:06 volumio1234 volumio[3504]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 19 11:30:06 volumio1234 sudo[3638]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect2.service Aug 19 11:30:06 volumio1234 volumio[3504]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 19 11:30:06 volumio1234 volumio[3504]: info: BOOT COMPLETED Aug 19 11:30:06 volumio1234 volumio[3504]: [Metrics] CommandRouter: 11s 751.68ms Aug 19 11:30:06 volumio1234 volumio[3504]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 19 11:30:06 volumio1234 sudo[3638]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 11:30:06 volumio1234 volumio[3504]: ALSA lib pcm.c:2642:(snd_pcm_open_noupdate) Unknown PCM softvolume Aug 19 11:30:06 volumio1234 volumio[3504]: aplay: main:852: audio open error: No such file or directory Aug 19 11:30:06 volumio1234 volumio[3504]: Cannot play startup sound Aug 19 11:30:06 volumio1234 volumio[3504]: info: CoreCommandRouter::Close All Modals sent Aug 19 11:30:06 volumio1234 volumio[3504]: info: CoreCommandRouter::Close All Modals sent Aug 19 11:30:06 volumio1234 sudo[3638]: pam_unix(sudo:session): session closed for user root Aug 19 11:30:06 volumio1234 volumio[3504]: Express server listening on port 3000 Aug 19 11:30:06 volumio1234 volumio[3504]: [Metrics] WebUI: 12s 831.87ms Aug 19 11:30:06 volumio1234 volumio[3504]: info: [1724038206598] ControllerPandora::validateAndSetAccountOptions Aug 19 11:30:06 volumio1234 volumio[3504]: error: [1724038206603] [Pandora] validateAndSetAccountOptions: Need email address and password. See plugin settings. Aug 19 11:30:06 volumio1234 volumio[3504]: info: CoreStateMachine::resetVolumioState Aug 19 11:30:06 volumio1234 volumio[3504]: info: CoreStateMachine::getcurrentVolume Aug 19 11:30:06 volumio1234 volumio[3504]: info: CoreCommandRouter::volumioRetrievevolume Aug 19 11:30:06 volumio1234 volumio[3504]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Aug 19 11:30:06 volumio1234 volumio[3504]: info: Cannot read play queue from file Aug 19 11:30:07 volumio1234 volumio[3504]: info: CoreStateMachine::pushState Aug 19 11:30:07 volumio1234 volumio[3504]: info: CoreCommandRouter::volumioPushState Aug 19 11:30:07 volumio1234 volumio[3504]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 19 11:30:07 volumio1234 volumio[3504]: info: CoreStateMachine::updateTrackBlock Aug 19 11:30:07 volumio1234 volumio[3504]: info: CorePlayQueue::getTrackBlock Aug 19 11:30:07 volumio1234 volumio[3504]: info: CoreCommandRouter::volumioRetrievevolume Aug 19 11:30:07 volumio1234 volumio[3504]: [SpotifyConnect2] Vollibrespot Daemon service started! Aug 19 11:30:07 volumio1234 volumio[3504]: [Metrics] SpotifyConnect: 1s 88.89ms Aug 19 11:30:07 volumio1234 volumio[3504]: info: MPD running with PID2285 Aug 19 11:30:07 volumio1234 volumio[3504]: ,establishing connection Aug 19 11:30:07 volumio1234 volumio[3504]: info: Permissions for /etc/hostapd/hostapd.conf Aug 19 11:30:07 volumio1234 volumio[3504]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Aug 19 11:30:07 volumio1234 volumio[3504]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 19 11:30:07 volumio1234 volumio[3504]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Aug 19 11:30:07 volumio1234 sudo[3679]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Aug 19 11:30:07 volumio1234 sudo[3679]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 11:30:07 volumio1234 volumio[3504]: info: Cannot read I2C interface or I2C interface not presentError: Command failed: /usr/bin/sudo /usr/sbin/i2cdetect -y 1 Aug 19 11:30:07 volumio1234 volumio[3504]: sudo: /usr/sbin/i2cdetect: command not found Aug 19 11:30:07 volumio1234 volumio[3504]: info: CoreStateMachine::pushState Aug 19 11:30:07 volumio1234 volumio[3504]: info: CoreCommandRouter::volumioPushState Aug 19 11:30:07 volumio1234 volumio[3504]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 19 11:30:07 volumio1234 volumio[3504]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 19 11:30:07 volumio1234 systemd[1]: Starting Wireless Services... Aug 19 11:30:08 volumio1234 volumio[3504]: info: Aug 19 11:30:08 volumio1234 volumio[3504]: ---------------------------- Client requests Volume 100 Aug 19 11:30:08 volumio1234 volumio[3504]: info: VolumeController::SetAlsaVolume100 Aug 19 11:30:08 volumio1234 volumio[3504]: info: CoreStateMachine::pushState Aug 19 11:30:08 volumio1234 volumio[3504]: info: CoreCommandRouter::volumioPushState Aug 19 11:30:08 volumio1234 volumio[3504]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 19 11:30:08 volumio1234 volumio[3504]: Upnp client error: Error: This socket has been ended by the other party Aug 19 11:30:08 volumio1234 sudo[3687]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 set power_save off Aug 19 11:30:08 volumio1234 sudo[3687]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 11:30:08 volumio1234 sudo[3687]: pam_unix(sudo:session): session closed for user root Aug 19 11:30:08 volumio1234 volumio[3504]: error: updateQueue error: null Aug 19 11:30:08 volumio1234 rc.local[2297]: [2024-08-19 11:30:08] [connect] Successful connection Aug 19 11:30:09 volumio1234 volumio[3504]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'SoftMaster',0 Aug 19 11:30:09 volumio1234 volumio[3504]: info: Setting volume on startup at 100 Aug 19 11:30:09 volumio1234 volumio[3504]: info: CoreStateMachine::setRepeat null single undefined Aug 19 11:30:09 volumio1234 volumio[3504]: info: CoreStateMachine::pushState Aug 19 11:30:09 volumio1234 volumio[3504]: info: CoreCommandRouter::volumioPushState Aug 19 11:30:09 volumio1234 volumio[3504]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 19 11:30:09 volumio1234 volumio[3504]: info: CoreStateMachine::setRandom null Aug 19 11:30:09 volumio1234 volumio[3504]: info: CoreStateMachine::pushState Aug 19 11:30:09 volumio1234 volumio[3504]: info: CoreCommandRouter::volumioPushState Aug 19 11:30:09 volumio1234 volumio[3504]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 19 11:30:09 volumio1234 volumio[3667]: Forking 1 albumart workers Aug 19 11:30:09 volumio1234 rc.local[2297]: [2024-08-19 11:30:09] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1724038208 101 Aug 19 11:30:09 volumio1234 rc.local[2297]: sio connected Aug 19 11:30:09 volumio1234 rc.local[2297]: socket send Aug 19 11:30:09 volumio1234 rc.local[2297]: command_len 54 Aug 19 11:30:09 volumio1234 rc.local[2297]: pushSrctotal Aug 19 11:30:09 volumio1234 rc.local[2297]: { Aug 19 11:30:09 volumio1234 rc.local[2297]: "total": 0, Aug 19 11:30:09 volumio1234 rc.local[2297]: "cur": 0, Aug 19 11:30:09 volumio1234 rc.local[2297]: "source": [] Aug 19 11:30:09 volumio1234 rc.local[2297]: } Aug 19 11:30:09 volumio1234 volumio[3504]: info: Starting Shairport Sync Aug 19 11:30:09 volumio1234 volumio[3504]: info: Starting Shairport Sync Aug 19 11:30:09 volumio1234 sudo[3718]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 19 11:30:09 volumio1234 sudo[3720]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 19 11:30:09 volumio1234 sudo[3720]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 11:30:09 volumio1234 sudo[3718]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 11:30:10 volumio1234 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 19 11:30:10 volumio1234 systemd[1]: shairport-sync.service: Succeeded. Aug 19 11:30:10 volumio1234 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 19 11:30:10 volumio1234 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 19 11:30:10 volumio1234 sudo[3720]: pam_unix(sudo:session): session closed for user root Aug 19 11:30:10 volumio1234 sudo[3718]: pam_unix(sudo:session): session closed for user root Aug 19 11:30:10 volumio1234 sudo[3747]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 19 11:30:10 volumio1234 sudo[3752]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 19 11:30:11 volumio1234 sudo[3747]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 11:30:11 volumio1234 sudo[3747]: pam_unix(sudo:session): session closed for user root Aug 19 11:30:11 volumio1234 sudo[3752]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 11:30:11 volumio1234 sudo[3752]: pam_unix(sudo:session): session closed for user root Aug 19 11:30:11 volumio1234 volumio[3504]: info: Shairport-Sync Started Aug 19 11:30:11 volumio1234 volumio[3504]: Error adding Membership: Error: addMembership EINVAL Aug 19 11:30:11 volumio1234 volumio[3504]: info: Shairport-Sync Started Aug 19 11:30:11 volumio1234 wireless.js[3698]: Cleaning previous... Aug 19 11:30:12 volumio1234 sudo[3778]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Aug 19 11:30:12 volumio1234 systemd[1]: Stopping dhcpd.service... Aug 19 11:30:12 volumio1234 systemd[1]: dhcpd.service: Succeeded. Aug 19 11:30:12 volumio1234 sudo[3778]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 11:30:12 volumio1234 systemd[1]: Stopped dhcpd.service. Aug 19 11:30:12 volumio1234 systemd[1]: Stopping hotspot.service... Aug 19 11:30:12 volumio1234 sudo[3778]: pam_unix(sudo:session): session closed for user root Aug 19 11:30:12 volumio1234 volumio[3504]: info: Upmpdcli Daemon Started Aug 19 11:30:12 volumio1234 volumio[3709]: Starting albumart workers Aug 19 11:30:17 volumio1234 hotspot[3784]: /bin/hotspot.sh: line 4: warning: command substitution: ignored null byte in input Aug 19 11:30:17 volumio1234 hotspot[3784]: Killing Hostapd Aug 19 11:30:17 volumio1234 sudo[3829]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall hostapd Aug 19 11:30:17 volumio1234 sudo[3829]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 11:30:17 volumio1234 hotspot[3190]: wlan0: interface state ENABLED->DISABLED Aug 19 11:30:17 volumio1234 hotspot[3190]: wlan0: AP-DISABLED Aug 19 11:30:17 volumio1234 hotspot[3190]: wlan0: CTRL-EVENT-TERMINATING Aug 19 11:30:17 volumio1234 sudo[3829]: pam_unix(sudo:session): session closed for user root Aug 19 11:30:17 volumio1234 hotspot[3190]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Aug 19 11:30:17 volumio1234 hotspot[3784]: Killing Dhcpd Aug 19 11:30:17 volumio1234 sudo[3838]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall dhcpd Aug 19 11:30:17 volumio1234 sudo[3838]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 11:30:17 volumio1234 hotspot[3843]: dhcpd: no process found Aug 19 11:30:17 volumio1234 sudo[3838]: pam_unix(sudo:session): session closed for user root Aug 19 11:30:17 volumio1234 systemd[1]: hotspot.service: Control process exited, code=exited, status=1/FAILURE Aug 19 11:30:17 volumio1234 systemd[1]: hotspot.service: Failed with result 'exit-code'. Aug 19 11:30:17 volumio1234 systemd[1]: Stopped hotspot.service. Aug 19 11:30:18 volumio1234 wireless.js[3698]: Stopped aP Aug 19 11:30:18 volumio1234 systemd[1]: Started hotspot.service. Aug 19 11:30:18 volumio1234 systemd[1]: Starting dhcpd.service... Aug 19 11:30:18 volumio1234 systemd[1]: Starting dnsmasq.service... Aug 19 11:30:18 volumio1234 systemd[1]: Started dhcpd.service. Aug 19 11:30:18 volumio1234 systemd[3869]: dnsmasq.service: Failed to execute command: No such file or directory Aug 19 11:30:18 volumio1234 systemd[3869]: dnsmasq.service: Failed at step EXEC spawning /usr/sbin/dnsmasq: No such file or directory Aug 19 11:30:18 volumio1234 systemd[1]: wireless.service: Succeeded. Aug 19 11:30:18 volumio1234 systemd[1]: Started Wireless Services. Aug 19 11:30:18 volumio1234 sudo[3679]: pam_unix(sudo:session): session closed for user root Aug 19 11:30:18 volumio1234 systemd[1]: dnsmasq.service: Control process exited, code=exited, status=203/EXEC Aug 19 11:30:18 volumio1234 systemd[1]: dnsmasq.service: Failed with result 'exit-code'. Aug 19 11:30:18 volumio1234 systemd[1]: Failed to start dnsmasq.service. Aug 19 11:30:18 volumio1234 hotspot[3867]: /bin/hotspot.sh: line 4: warning: command substitution: ignored null byte in input Aug 19 11:30:18 volumio1234 hotspot[3867]: Launching Ordinary Hostapd Aug 19 11:30:18 volumio1234 hotspot[3886]: Configuration file: /etc/hostapd/hostapd.conf Aug 19 11:30:18 volumio1234 hotspot[3886]: Using interface wlan0 with hwaddr 98:03:cf:a5:e1:36 and ssid "StreamPlayer" Aug 19 11:30:18 volumio1234 hotspot[3886]: wlan0: interface state UNINITIALIZED->ENABLED Aug 19 11:30:18 volumio1234 hotspot[3886]: wlan0: AP-ENABLED Aug 19 11:30:19 volumio1234 rc.local[2297]: socket send Aug 19 11:30:19 volumio1234 rc.local[2297]: command_len 54 Aug 19 11:30:19 volumio1234 rc.local[2297]: pushSrctotal Aug 19 11:30:19 volumio1234 rc.local[2297]: { Aug 19 11:30:19 volumio1234 rc.local[2297]: "total": 0, Aug 19 11:30:19 volumio1234 rc.local[2297]: "cur": 0, Aug 19 11:30:19 volumio1234 rc.local[2297]: "source": [] Aug 19 11:30:19 volumio1234 rc.local[2297]: } Aug 19 11:30:23 volumio1234 volumio[3504]: info: CoreCommandRouter::executeOnPlugin: wizard , reportWirelessConnection Aug 19 11:30:23 volumio1234 volumio[3504]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessInfo Aug 19 11:30:23 volumio1234 sudo[3905]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 19 11:30:23 volumio1234 sudo[3905]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 11:30:23 volumio1234 sudo[3905]: pam_unix(sudo:session): session closed for user root Aug 19 11:30:28 volumio1234 volumio[3504]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart Aug 19 11:30:28 volumio1234 volumio[3504]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart Aug 19 11:30:28 volumio1234 volumio[3504]: info: Refreshing Cached IP Addresses Aug 19 11:30:28 volumio1234 sudo[3931]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Aug 19 11:30:28 volumio1234 sudo[3931]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 11:30:28 volumio1234 sudo[3933]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 19 11:30:28 volumio1234 sudo[3933]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 11:30:28 volumio1234 sudo[3933]: pam_unix(sudo:session): session closed for user root Aug 19 11:30:29 volumio1234 sudo[3931]: pam_unix(sudo:session): session closed for user root Aug 19 11:30:29 volumio1234 sudo[3938]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 19 11:30:29 volumio1234 sudo[3938]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 11:30:29 volumio1234 sudo[3938]: pam_unix(sudo:session): session closed for user root Aug 19 11:30:29 volumio1234 volumio[3504]: Upnp client error: Error: read ECONNRESET Aug 19 11:30:29 volumio1234 systemd[1]: upmpdcli.service: Succeeded. Aug 19 11:30:36 volumio1234 volumio[3504]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 19 11:30:36 volumio1234 volumio[3504]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 19 11:30:39 volumio1234 sudo[4010]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 19 11:30:39 volumio1234 sudo[4010]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 11:30:39 volumio1234 sudo[4010]: pam_unix(sudo:session): session closed for user root Aug 19 11:30:39 volumio1234 sudo[4014]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 19 11:30:39 volumio1234 sudo[4014]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 11:30:39 volumio1234 sudo[4014]: pam_unix(sudo:session): session closed for user root Aug 19 11:30:39 volumio1234 sudo[4024]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Aug 19 11:30:39 volumio1234 sudo[4024]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 11:30:39 volumio1234 systemd[1]: Started UPnP Renderer front-end to MPD. Aug 19 11:30:39 volumio1234 sudo[4024]: pam_unix(sudo:session): session closed for user root Aug 19 11:30:39 volumio1234 volumio[3504]: info: Upmpdcli Daemon Started Aug 19 11:30:39 volumio1234 volumio[4057]: writing RSA key Aug 19 11:30:46 volumio1234 volumio[3504]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 19 11:30:46 volumio1234 volumio[3504]: TypeError: Cannot read property 'UUID' of undefined Aug 19 11:30:46 volumio1234 volumio[3504]: at Browser. (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:172:53) Aug 19 11:30:46 volumio1234 volumio[3504]: at Browser.emit (events.js:198:13) Aug 19 11:30:46 volumio1234 volumio[3504]: at on_resolver_done (/volumio/node_modules/mdns/lib/browser.js:31:14) Aug 19 11:30:46 volumio1234 volumio[3504]: at next (/volumio/node_modules/mdns/lib/browser.js:106:7) Aug 19 11:30:46 volumio1234 volumio[3504]: at /volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:160:11 Aug 19 11:30:46 volumio1234 volumio[3504]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:108:7) Aug 19 11:30:46 volumio1234 volumio[3504]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) Aug 19 11:30:46 volumio1234 volumio[3504]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 19 11:30:47 volumio1234 sudo[4102]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-19 11:29 Aug 19 11:30:47 volumio1234 sudo[4102]: 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"