-- Logs begin at Thu 2019-02-14 13:12:04 MSK, end at Sat 2024-03-30 23:15:32 MSK. -- Mar 30 23:14:00 volumio volumio[3953]: info: Loading plugin "mixcloud"... Mar 30 23:14:00 volumio volumio[3953]: info: Loading plugin "soundcloud"... Mar 30 23:14:01 volumio volumio[3953]: info: Loading plugin "spop"... Mar 30 23:14:02 volumio volumio[3953]: info: Loading plugin "youtube2"... Mar 30 23:14:03 volumio volumio[3953]: info: Loading plugin "outputs"... Mar 30 23:14:03 volumio volumio[3953]: info: Loading plugin "albumart"... Mar 30 23:14:03 volumio volumio[3953]: info: Plugin example_plugin is not enabled Mar 30 23:14:03 volumio volumio[3953]: info: Loading plugin "inputs"... Mar 30 23:14:03 volumio volumio[3953]: info: Loading plugin "updater_comm"... Mar 30 23:14:03 volumio volumio[3953]: info: Plugin mpdemulation is not enabled Mar 30 23:14:03 volumio volumio[3953]: info: Loading plugin "rest_api"... Mar 30 23:14:03 volumio volumio[3953]: info: Loading plugin "websocket"... Mar 30 23:14:03 volumio volumio[3953]: info: Loading plugin "RoonBridge"... Mar 30 23:14:03 volumio volumio[3953]: Forking 3 albumart workers Mar 30 23:14:03 volumio volumio[3953]: info: Applying required configuration parameters for plugin RoonBridge Mar 30 23:14:03 volumio volumio[3953]: info: Loading plugin "motherearthradio"... Mar 30 23:14:04 volumio volumio[3953]: info: Applying required configuration parameters for plugin motherearthradio Mar 30 23:14:04 volumio volumio[3953]: info: [1711829644445] [MotherEarth] API delay: 5 Mar 30 23:14:04 volumio volumio[3953]: info: Loading plugin "music_services_shield"... Mar 30 23:14:05 volumio volumio[3953]: Starting albumart workers Mar 30 23:14:05 volumio volumio[3953]: info: Applying required configuration parameters for plugin music_services_shield Mar 30 23:14:05 volumio volumio[3953]: info: Loading plugin "lastfm"... Mar 30 23:14:05 volumio volumio[3953]: Starting albumart workers Mar 30 23:14:05 volumio volumio[3953]: Starting albumart workers Mar 30 23:14:05 volumio volumio[3953]: info: Loading i18n strings for locale en Mar 30 23:14:05 volumio volumio[3953]: Updating browse sources language Mar 30 23:14:05 volumio volumio[3953]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 30 23:14:06 volumio volumio[3953]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 30 23:14:06 volumio volumio[3953]: info: CoreCommandRouter::initPlayerControls Mar 30 23:14:06 volumio volumio[3953]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 23:14:06 volumio volumio[3953]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 23:14:06 volumio volumio[3953]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 23:14:06 volumio volumio[3953]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 23:14:06 volumio volumio[3953]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 23:14:06 volumio volumio[3953]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 23:14:06 volumio volumio[3953]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 23:14:06 volumio volumio[3953]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 23:14:06 volumio volumio[3953]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 30 23:14:06 volumio volumio[3953]: Express server listening on port 3000 Mar 30 23:14:06 volumio volumio[3953]: [Metrics] WebUI: 12s 136.72ms Mar 30 23:14:06 volumio volumio[3953]: info: CoreStateMachine::resetVolumioState Mar 30 23:14:06 volumio volumio[3953]: info: CoreStateMachine::getcurrentVolume Mar 30 23:14:06 volumio volumio[3953]: info: CoreCommandRouter::volumioRetrievevolume Mar 30 23:14:06 volumio volumio[3953]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 30 23:14:06 volumio volumio[3953]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Mar 30 23:14:06 volumio volumio[3953]: info: Completed loading Core Plugins Mar 30 23:14:06 volumio volumio[3953]: info: Preparing to generate the ALSA configuration file Mar 30 23:14:06 volumio volumio[3953]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Mar 30 23:14:06 volumio volumio[3953]: wlan0 Interface doesn't support scanning. Mar 30 23:14:06 volumio volumio[3953]: info: Cannot use regular scanning, forcing with ap-force Mar 30 23:14:06 volumio sudo[4072]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Mar 30 23:14:06 volumio sudo[4072]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 23:14:06 volumio sudo[4072]: pam_unix(sudo:session): session closed for user root Mar 30 23:14:06 volumio volumio[3953]: command failed: No such device (-19) Mar 30 23:14:06 volumio volumio[3953]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Mar 30 23:14:06 volumio volumio[3953]: command failed: No such device (-19) Mar 30 23:14:06 volumio volumio[3953]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Mar 30 23:14:06 volumio volumio[3953]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Mar 30 23:14:06 volumio volumio[3953]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 1 Mar 30 23:14:06 volumio volumio[3953]: info: Reloading queue from file Mar 30 23:14:06 volumio volumio[3953]: info: VolumeController:: Volume=undefined Mute =false Mar 30 23:14:06 volumio volumio[3953]: info: CoreStateMachine::pushState Mar 30 23:14:06 volumio volumio[3953]: info: CorePlayQueue::getTrack 0 Mar 30 23:14:06 volumio volumio[3953]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 30 23:14:06 volumio volumio[3953]: info: CoreCommandRouter::volumioPushState Mar 30 23:14:06 volumio volumio[3953]: info: CoreStateMachine::updateTrackBlock Mar 30 23:14:06 volumio volumio[3953]: info: CorePlayQueue::getTrackBlock Mar 30 23:14:06 volumio volumio[3953]: info: CoreCommandRouter::volumioRetrievevolume Mar 30 23:14:06 volumio volumio[3953]: info: Asound.conf file unchanged, so no further update is needed Mar 30 23:14:06 volumio volumio[3953]: info: Output device has changed, restarting MPD Mar 30 23:14:06 volumio sudo[4092]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 30 23:14:06 volumio volumio[3953]: info: Output device has changed, restarting Shairport Sync Mar 30 23:14:06 volumio sudo[4092]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 23:14:06 volumio volumio[3953]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 23:14:06 volumio volumio[3953]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 30 23:14:06 volumio sudo[4092]: pam_unix(sudo:session): session closed for user root Mar 30 23:14:06 volumio sudo[4094]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 30 23:14:06 volumio sudo[4094]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 23:14:06 volumio systemd[1]: musicservicesshield.service: Succeeded. Mar 30 23:14:06 volumio systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Mar 30 23:14:06 volumio systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Mar 30 23:14:06 volumio systemd[1]: Stopping Music Player Daemon... Mar 30 23:14:06 volumio volumio[3953]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 30 23:14:06 volumio volumio[3953]: info: ___________ START PLUGINS ___________ Mar 30 23:14:06 volumio systemd[1]: mpd.service: Succeeded. Mar 30 23:14:06 volumio systemd[1]: Stopped Music Player Daemon. Mar 30 23:14:06 volumio volumio[3953]: info: ControllerMpd::onStart: Initializing MPD Mar 30 23:14:06 volumio volumio[3953]: info: Creating MPD Configuration file Mar 30 23:14:06 volumio systemd[1]: Starting Music Player Daemon... Mar 30 23:14:06 volumio sudo[4102]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 30 23:14:06 volumio sudo[4102]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 23:14:06 volumio volumio[3953]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 30 23:14:06 volumio volumio[3953]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 30 23:14:06 volumio volumio[3953]: info: [1711829646989] CoreMusicLibrary::Adding element Media Servers Mar 30 23:14:06 volumio volumio[3953]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 30 23:14:06 volumio sudo[4102]: pam_unix(sudo:session): session closed for user root Mar 30 23:14:07 volumio volumio[3953]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 23:14:07 volumio volumio[3953]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 30 23:14:07 volumio sudo[4105]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 30 23:14:07 volumio sudo[4105]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 23:14:07 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Mar 30 23:14:07 volumio systemd[1]: mpd.service: Succeeded. Mar 30 23:14:07 volumio systemd[1]: Stopped Music Player Daemon. Mar 30 23:14:07 volumio volumio[3953]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 30 23:14:07 volumio volumio[3953]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 23:14:07 volumio volumio[3953]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 30 23:14:07 volumio systemd[1]: Starting Music Player Daemon... Mar 30 23:14:07 volumio volumio[3953]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 30 23:14:07 volumio volumio[3953]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 30 23:14:07 volumio volumio[3953]: info: [1711829647170] CoreMusicLibrary::Adding element Last_100 Mar 30 23:14:07 volumio volumio[3953]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 30 23:14:07 volumio volumio[3953]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 30 23:14:07 volumio volumio[3953]: info: [1711829647175] CoreMusicLibrary::Adding element Webradio Mar 30 23:14:07 volumio volumio[3953]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 30 23:14:07 volumio volumio[3953]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 30 23:14:07 volumio volumio[3953]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 30 23:14:07 volumio volumio[3953]: info: [1711829647196] CoreMusicLibrary::Adding element Bandcamp Discover Mar 30 23:14:07 volumio volumio[3953]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 30 23:14:07 volumio volumio[3953]: Cannot find translation for source Bandcamp Discover Mar 30 23:14:07 volumio volumio[3953]: info: Adding Calm Radio to Browse Sources Mar 30 23:14:07 volumio volumio[3953]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 30 23:14:07 volumio volumio[3953]: info: [1711829647204] CoreMusicLibrary::Adding element Calm Radio Mar 30 23:14:07 volumio volumio[3953]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 30 23:14:07 volumio volumio[3953]: Cannot find translation for source Bandcamp Discover Mar 30 23:14:07 volumio volumio[3953]: Cannot find translation for source Calm Radio Mar 30 23:14:07 volumio volumio[3953]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 30 23:14:07 volumio volumio[3953]: info: [1711829647217] CoreMusicLibrary::Adding element Mixcloud Mar 30 23:14:07 volumio volumio[3953]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 30 23:14:07 volumio volumio[3953]: Cannot find translation for source Bandcamp Discover Mar 30 23:14:07 volumio volumio[3953]: Cannot find translation for source Calm Radio Mar 30 23:14:07 volumio volumio[3953]: Cannot find translation for source Mixcloud Mar 30 23:14:07 volumio volumio[3953]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 30 23:14:07 volumio volumio[3953]: info: [1711829647230] CoreMusicLibrary::Adding element SoundCloud Mar 30 23:14:07 volumio volumio[3953]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 30 23:14:07 volumio volumio[3953]: Cannot find translation for source Bandcamp Discover Mar 30 23:14:07 volumio volumio[3953]: Cannot find translation for source Calm Radio Mar 30 23:14:07 volumio volumio[3953]: Cannot find translation for source Mixcloud Mar 30 23:14:07 volumio volumio[3953]: Cannot find translation for source SoundCloud Mar 30 23:14:07 volumio volumio[3953]: info: Creating Spotify config file Mar 30 23:14:07 volumio volumio[3953]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 23:14:07 volumio volumio[3953]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 30 23:14:07 volumio volumio[3953]: info: [1711829647265] CoreMusicLibrary::Adding element YouTube2 Mar 30 23:14:07 volumio volumio[3953]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 30 23:14:07 volumio volumio[3953]: Cannot find translation for source Bandcamp Discover Mar 30 23:14:07 volumio volumio[3953]: Cannot find translation for source Calm Radio Mar 30 23:14:07 volumio volumio[3953]: Cannot find translation for source Mixcloud Mar 30 23:14:07 volumio volumio[3953]: Cannot find translation for source SoundCloud Mar 30 23:14:07 volumio volumio[3953]: Cannot find translation for source YouTube2 Mar 30 23:14:07 volumio volumio[3953]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 30 23:14:07 volumio volumio[3953]: info: [1711829647304] CoreMusicLibrary::Adding element Mother Earth Radio Mar 30 23:14:07 volumio volumio[3953]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 30 23:14:07 volumio volumio[3953]: Cannot find translation for source Bandcamp Discover Mar 30 23:14:07 volumio volumio[3953]: Cannot find translation for source Calm Radio Mar 30 23:14:07 volumio volumio[3953]: Cannot find translation for source Mixcloud Mar 30 23:14:07 volumio volumio[3953]: Cannot find translation for source SoundCloud Mar 30 23:14:07 volumio volumio[3953]: Cannot find translation for source YouTube2 Mar 30 23:14:07 volumio volumio[3953]: Cannot find translation for source Mother Earth Radio Mar 30 23:14:07 volumio sudo[4115]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Mar 30 23:14:07 volumio sudo[4115]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 23:14:07 volumio sudo[4115]: pam_unix(sudo:session): session closed for user root Mar 30 23:14:07 volumio volumio[3953]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 30 23:14:07 volumio volumio[3953]: info: [1711829647586] CoreMusicLibrary::Adding element LastFM Mar 30 23:14:07 volumio volumio[3953]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 30 23:14:07 volumio volumio[3953]: Cannot find translation for source Bandcamp Discover Mar 30 23:14:07 volumio volumio[3953]: Cannot find translation for source Calm Radio Mar 30 23:14:07 volumio volumio[3953]: Cannot find translation for source Mixcloud Mar 30 23:14:07 volumio volumio[3953]: Cannot find translation for source SoundCloud Mar 30 23:14:07 volumio volumio[3953]: Cannot find translation for source YouTube2 Mar 30 23:14:07 volumio volumio[3953]: Cannot find translation for source Mother Earth Radio Mar 30 23:14:07 volumio volumio[3953]: Cannot find translation for source LastFM Mar 30 23:14:07 volumio volumio[3953]: info: [LastFM] scrobbler initiated! Mar 30 23:14:07 volumio volumio[3953]: info: [LastFM] extended logging: false Mar 30 23:14:07 volumio volumio[3953]: info: [LastFM] try scrobble stream/radio plays: true Mar 30 23:14:07 volumio volumio[3953]: info: [LastFM] Left init routine Mar 30 23:14:07 volumio volumio[3953]: info: [LastFM] Socket already connected: false Mar 30 23:14:07 volumio volumio[3953]: info: Volumio Calling Home Mar 30 23:14:07 volumio sudo[4143]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/data/plugins/system_hardware/music_services_shield/moveallprocesses.sh Mar 30 23:14:07 volumio sudo[4143]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 23:14:07 volumio volumio[3953]: info: CoreStateMachine::setRepeat null single undefined Mar 30 23:14:07 volumio volumio[3953]: info: CoreStateMachine::pushState Mar 30 23:14:07 volumio volumio[3953]: info: CorePlayQueue::getTrack 0 Mar 30 23:14:07 volumio volumio[3953]: info: CoreCommandRouter::volumioPushState Mar 30 23:14:07 volumio volumio[3953]: info: CoreStateMachine::setRandom null Mar 30 23:14:07 volumio volumio[3953]: info: CoreStateMachine::pushState Mar 30 23:14:07 volumio volumio[3953]: info: CorePlayQueue::getTrack 0 Mar 30 23:14:07 volumio volumio[3953]: info: CoreCommandRouter::volumioPushState Mar 30 23:14:07 volumio volumio[3953]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 30 23:14:07 volumio volumio[3953]: Error: connect ENETUNREACH 130.211.19.189:80 - Local (0.0.0.0:0) Mar 30 23:14:07 volumio volumio[3953]: at internalConnect (net.js:934:16) Mar 30 23:14:07 volumio volumio[3953]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:452:18) Mar 30 23:14:07 volumio volumio[3953]: at GetAddrInfoReqWrap.emitLookup [as callback] (net.js:1077:9) Mar 30 23:14:07 volumio volumio[3953]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:73:8) { Mar 30 23:14:07 volumio volumio[3953]: errno: -101, Mar 30 23:14:07 volumio volumio[3953]: code: 'ENETUNREACH', Mar 30 23:14:07 volumio volumio[3953]: syscall: 'connect', Mar 30 23:14:07 volumio volumio[3953]: address: '130.211.19.189', Mar 30 23:14:07 volumio volumio[3953]: port: 80 Mar 30 23:14:07 volumio volumio[3953]: } Mar 30 23:14:07 volumio volumio[3953]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 30 23:14:08 volumio sudo[4143]: pam_unix(sudo:session): session closed for user root Mar 30 23:14:08 volumio sudo[4171]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-03-30 23:13 Mar 30 23:14:08 volumio sudo[4171]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 23:14:08 volumio sudo[4171]: pam_unix(sudo:session): session closed for user root Mar 30 23:14:09 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Mar 30 23:14:09 volumio sudo[4094]: pam_unix(sudo:session): session closed for user root Mar 30 23:14:09 volumio sudo[4105]: pam_unix(sudo:session): session closed for user root Mar 30 23:14:09 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Mar 30 23:14:09 volumio systemd[1]: Started dynamicswap service. Mar 30 23:14:09 volumio systemd[1]: dynamicswap.service: Succeeded. Mar 30 23:14:09 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Mar 30 23:14:09 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 13. Mar 30 23:14:09 volumio systemd[1]: Started dynamicswap service. Mar 30 23:14:09 volumio systemd[1]: Stopped Volumio Backend Module. Mar 30 23:14:09 volumio systemd[1]: Started Volumio Backend Module. Mar 30 23:14:09 volumio systemd[1]: dynamicswap.service: Succeeded. Mar 30 23:14:09 volumio mpd[4110]: Mar 30 23:14 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 30 23:14:09 volumio systemd[1]: Started Music Player Daemon. Mar 30 23:14:09 volumio systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Mar 30 23:14:09 volumio volumio-remote-updater[595]: [2024-03-30 23:14:09] [info] asio async_connect error: system:111 (Connection refused) Mar 30 23:14:09 volumio volumio-remote-updater[595]: [2024-03-30 23:14:09] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Mar 30 23:14:09 volumio volumio-remote-updater[595]: [2024-03-30 23:14:09] [error] handle_connect error: Underlying Transport Error Mar 30 23:14:10 volumio volumio[4199]: cset: --> shielding system active with Mar 30 23:14:10 volumio volumio[4199]: cset: "system" cpuset of CPUSPEC(0-2) with 73 tasks running Mar 30 23:14:10 volumio volumio[4199]: cset: "user" cpuset of CPUSPEC(3) with 3 tasks running Mar 30 23:14:10 volumio systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Mar 30 23:14:11 volumio volumio[4185]: info: ------------------------------------------- Mar 30 23:14:11 volumio volumio[4185]: info: ----- Volumio3 ---- Mar 30 23:14:11 volumio volumio[4185]: info: ------------------------------------------- Mar 30 23:14:11 volumio volumio[4185]: info: ----- System startup ---- Mar 30 23:14:11 volumio volumio[4185]: info: ------------------------------------------- Mar 30 23:14:12 volumio volumio[4185]: info: MYVOLUMIO Environment detected Mar 30 23:14:12 volumio volumio[4185]: info: Plugin folders cleanup Mar 30 23:14:12 volumio volumio[4185]: info: Scanning into folder /volumio/app/plugins/ Mar 30 23:14:12 volumio volumio[4185]: info: Scanning category audio_interface Mar 30 23:14:12 volumio volumio[4185]: info: Scanning category miscellanea Mar 30 23:14:12 volumio volumio[4185]: info: Scanning category music_service Mar 30 23:14:12 volumio volumio[4185]: info: Scanning category plugins.json Mar 30 23:14:12 volumio volumio[4185]: info: Scanning category system_controller Mar 30 23:14:12 volumio volumio[4185]: info: Scanning category user_interface Mar 30 23:14:12 volumio volumio[4185]: info: Scanning into folder /data/plugins/ Mar 30 23:14:12 volumio volumio[4185]: info: Scanning category music_service Mar 30 23:14:12 volumio volumio[4185]: info: Scanning category system_hardware Mar 30 23:14:12 volumio volumio[4185]: info: Scanning category user_interface Mar 30 23:14:12 volumio volumio[4185]: info: Plugin folders cleanup completed Mar 30 23:14:12 volumio volumio[4185]: info: ------------------------------------------- Mar 30 23:14:12 volumio volumio[4185]: info: ----- Core plugins startup ---- Mar 30 23:14:12 volumio volumio[4185]: info: ------------------------------------------- Mar 30 23:14:12 volumio volumio[4185]: info: Loading plugins from folder /volumio/app/plugins/ Mar 30 23:14:12 volumio volumio[4185]: info: Adding plugin upnp to MyMusic Plugins Mar 30 23:14:12 volumio volumio[4185]: info: Adding plugin airplay_emulation to MyMusic Plugins Mar 30 23:14:12 volumio volumio[4185]: info: Adding plugin upnp_browser to MyMusic Plugins Mar 30 23:14:12 volumio volumio[4185]: info: Loading plugins from folder /data/plugins/ Mar 30 23:14:12 volumio volumio[4185]: info: Loading plugin "system"... Mar 30 23:14:12 volumio volumio[4185]: info: Loading plugin "appearance"... Mar 30 23:14:13 volumio volumio[4185]: info: Loading plugin "network"... Mar 30 23:14:13 volumio volumio[4185]: info: Refreshing Cached IP Addresses Mar 30 23:14:13 volumio sudo[4228]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 30 23:14:13 volumio sudo[4228]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 23:14:13 volumio volumio[4185]: info: Loading plugin "services"... Mar 30 23:14:13 volumio sudo[4228]: pam_unix(sudo:session): session closed for user root Mar 30 23:14:13 volumio sudo[4230]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 30 23:14:13 volumio volumio[4185]: info: Loading plugin "alsa_controller"... Mar 30 23:14:13 volumio sudo[4230]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 23:14:13 volumio sudo[4234]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Mar 30 23:14:13 volumio sudo[4230]: pam_unix(sudo:session): session closed for user root Mar 30 23:14:13 volumio sudo[4234]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 23:14:13 volumio sudo[4234]: pam_unix(sudo:session): session closed for user root Mar 30 23:14:13 volumio volumio[4185]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 30 23:14:13 volumio volumio[4185]: info: Loading plugin "wizard"... Mar 30 23:14:13 volumio volumio[4185]: info: Loading plugin "networkfs"... Mar 30 23:14:13 volumio volumio[4185]: info: Starting Udev Watcher for removable devices Mar 30 23:14:13 volumio volumio[4185]: info: Ignoring mount for partition: boot Mar 30 23:14:13 volumio volumio[4185]: info: Ignoring mount for partition: volumio Mar 30 23:14:13 volumio volumio[4185]: info: Ignoring mount for partition: volumio_data Mar 30 23:14:13 volumio volumio[4185]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 30 23:14:13 volumio volumio[4185]: info: Loading plugin "volumio_command_line_client"... Mar 30 23:14:13 volumio volumio[4185]: info: Plugin upnp is not enabled Mar 30 23:14:13 volumio volumio[4185]: info: Loading plugin "my_music"... Mar 30 23:14:13 volumio volumio[4185]: info: Loading plugin "mpd"... Mar 30 23:14:14 volumio volumio[4185]: info: Loading plugin "upnp_browser"... Mar 30 23:14:14 volumio volumio[4185]: info: Loading plugin "alarm-clock"... Mar 30 23:14:14 volumio volumio[4185]: info: Loading plugin "airplay_emulation"... Mar 30 23:14:14 volumio volumio[4185]: info: Starting Shairport Sync Mar 30 23:14:14 volumio volumio[4185]: info: Loading plugin "last_100"... Mar 30 23:14:14 volumio volumio[4185]: info: Loading plugin "webradio"... Mar 30 23:14:14 volumio volumio[4185]: info: Loading plugin "i2s_dacs"... Mar 30 23:14:14 volumio volumio[4185]: info: I2S DAC not set, start Auto-detection Mar 30 23:14:14 volumio volumio[4185]: info: Loading plugin "volumiodiscovery"... Mar 30 23:14:14 volumio volumio[4185]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 30 23:14:14 volumio node[4185]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 30 23:14:14 volumio node[4185]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 30 23:14:14 volumio volumio[4185]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 30 23:14:14 volumio volumio[4185]: *** WARNING *** For more information see Mar 30 23:14:14 volumio volumio[4185]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 30 23:14:14 volumio volumio[4185]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 30 23:14:14 volumio volumio[4185]: *** WARNING *** For more information see Mar 30 23:14:14 volumio node[4185]: *** WARNING *** For more information see Mar 30 23:14:14 volumio node[4185]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 30 23:14:14 volumio node[4185]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 30 23:14:14 volumio node[4185]: *** WARNING *** For more information see Mar 30 23:14:14 volumio volumio[4185]: info: Applying required configuration parameters for plugin volumiodiscovery Mar 30 23:14:14 volumio volumio[4185]: info: Discovery: Started advertising with name: VOLUMIO Mar 30 23:14:14 volumio volumio[4185]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 30 23:14:14 volumio volumio[4185]: info: Loading plugin "bandcamp"... Mar 30 23:14:16 volumio volumio[4185]: info: Loading plugin "calmradio"... Mar 30 23:14:16 volumio volumio[4185]: info: Loading plugin "mixcloud"... Mar 30 23:14:17 volumio volumio[4185]: info: Loading plugin "soundcloud"... Mar 30 23:14:17 volumio volumio[4185]: info: Loading plugin "spop"... Mar 30 23:14:18 volumio volumio[4185]: info: Loading plugin "youtube2"... Mar 30 23:14:19 volumio volumio-remote-updater[595]: [2024-03-30 23:14:19] [connect] Successful connection Mar 30 23:14:20 volumio volumio[4185]: info: Loading plugin "outputs"... Mar 30 23:14:20 volumio volumio[4185]: info: Loading plugin "albumart"... Mar 30 23:14:20 volumio volumio[4185]: info: Plugin example_plugin is not enabled Mar 30 23:14:20 volumio volumio[4185]: info: Loading plugin "inputs"... Mar 30 23:14:20 volumio volumio[4185]: info: Loading plugin "updater_comm"... Mar 30 23:14:20 volumio volumio[4185]: info: Plugin mpdemulation is not enabled Mar 30 23:14:20 volumio volumio[4185]: info: Loading plugin "rest_api"... Mar 30 23:14:20 volumio volumio[4185]: info: Loading plugin "websocket"... Mar 30 23:14:20 volumio volumio[4185]: info: Loading plugin "RoonBridge"... Mar 30 23:14:20 volumio volumio[4185]: Forking 3 albumart workers Mar 30 23:14:20 volumio volumio[4185]: info: Applying required configuration parameters for plugin RoonBridge Mar 30 23:14:20 volumio volumio[4185]: info: Loading plugin "motherearthradio"... Mar 30 23:14:21 volumio volumio[4185]: info: Applying required configuration parameters for plugin motherearthradio Mar 30 23:14:21 volumio volumio[4185]: info: [1711829661248] [MotherEarth] API delay: 5 Mar 30 23:14:21 volumio volumio[4185]: info: Loading plugin "music_services_shield"... Mar 30 23:14:21 volumio volumio[4185]: Starting albumart workers Mar 30 23:14:21 volumio volumio[4185]: info: Applying required configuration parameters for plugin music_services_shield Mar 30 23:14:21 volumio volumio[4185]: info: Loading plugin "lastfm"... Mar 30 23:14:21 volumio volumio[4185]: Starting albumart workers Mar 30 23:14:22 volumio volumio[4185]: info: Loading i18n strings for locale en Mar 30 23:14:22 volumio volumio[4185]: Updating browse sources language Mar 30 23:14:22 volumio volumio[4185]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 30 23:14:22 volumio volumio[4185]: Starting albumart workers Mar 30 23:14:22 volumio volumio[4185]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 30 23:14:22 volumio volumio[4185]: info: CoreCommandRouter::initPlayerControls Mar 30 23:14:22 volumio volumio[4185]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 23:14:22 volumio volumio[4185]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 23:14:22 volumio volumio[4185]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 23:14:22 volumio volumio[4185]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 23:14:22 volumio volumio[4185]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 23:14:22 volumio volumio[4185]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 23:14:22 volumio volumio[4185]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 23:14:22 volumio volumio[4185]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 23:14:22 volumio volumio[4185]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 30 23:14:22 volumio volumio[4185]: Express server listening on port 3000 Mar 30 23:14:22 volumio volumio[4185]: [Metrics] WebUI: 12s 59.35ms Mar 30 23:14:22 volumio volumio[4185]: info: CoreStateMachine::resetVolumioState Mar 30 23:14:22 volumio volumio[4185]: info: CoreStateMachine::getcurrentVolume Mar 30 23:14:22 volumio volumio[4185]: info: CoreCommandRouter::volumioRetrievevolume Mar 30 23:14:22 volumio volumio[4185]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 30 23:14:22 volumio volumio[4185]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Mar 30 23:14:22 volumio volumio[4185]: info: Completed loading Core Plugins Mar 30 23:14:22 volumio volumio[4185]: info: Preparing to generate the ALSA configuration file Mar 30 23:14:23 volumio volumio[4185]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Mar 30 23:14:23 volumio volumio[4185]: wlan0 Interface doesn't support scanning. Mar 30 23:14:23 volumio volumio[4185]: info: Cannot use regular scanning, forcing with ap-force Mar 30 23:14:23 volumio sudo[4340]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Mar 30 23:14:23 volumio sudo[4340]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 23:14:23 volumio sudo[4340]: pam_unix(sudo:session): session closed for user root Mar 30 23:14:23 volumio volumio[4185]: command failed: No such device (-19) Mar 30 23:14:23 volumio volumio[4185]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Mar 30 23:14:23 volumio volumio[4185]: command failed: No such device (-19) Mar 30 23:14:23 volumio volumio[4185]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Mar 30 23:14:23 volumio volumio-remote-updater[595]: [2024-03-30 23:14:23] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1711829659 101 Mar 30 23:14:23 volumio volumio[4185]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1 Mar 30 23:14:23 volumio volumio[4185]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Mar 30 23:14:23 volumio volumio[4185]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 Mar 30 23:14:23 volumio volumio[4185]: info: Reloading queue from file Mar 30 23:14:23 volumio volumio[4185]: info: Asound.conf file unchanged, so no further update is needed Mar 30 23:14:23 volumio volumio[4185]: info: Output device has changed, restarting MPD Mar 30 23:14:23 volumio sudo[4359]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 30 23:14:23 volumio volumio[4185]: info: Output device has changed, restarting Shairport Sync Mar 30 23:14:23 volumio sudo[4359]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 23:14:23 volumio volumio[4185]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 23:14:23 volumio volumio[4185]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 30 23:14:23 volumio sudo[4359]: pam_unix(sudo:session): session closed for user root Mar 30 23:14:23 volumio sudo[4361]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 30 23:14:23 volumio sudo[4361]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 23:14:23 volumio systemd[1]: musicservicesshield.service: Succeeded. Mar 30 23:14:23 volumio systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Mar 30 23:14:23 volumio systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Mar 30 23:14:23 volumio systemd[1]: Stopping Music Player Daemon... Mar 30 23:14:23 volumio volumio[4185]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 30 23:14:23 volumio volumio[4185]: info: ___________ START PLUGINS ___________ Mar 30 23:14:23 volumio systemd[1]: mpd.service: Succeeded. Mar 30 23:14:23 volumio systemd[1]: Stopped Music Player Daemon. Mar 30 23:14:23 volumio volumio[4185]: info: ControllerMpd::onStart: Initializing MPD Mar 30 23:14:23 volumio volumio[4185]: info: Creating MPD Configuration file Mar 30 23:14:23 volumio systemd[1]: Starting Music Player Daemon... Mar 30 23:14:23 volumio volumio[4185]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 30 23:14:23 volumio volumio[4185]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 30 23:14:23 volumio volumio[4185]: info: [1711829663634] CoreMusicLibrary::Adding element Media Servers Mar 30 23:14:23 volumio volumio[4185]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 30 23:14:23 volumio sudo[4369]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 30 23:14:23 volumio sudo[4369]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 23:14:23 volumio sudo[4371]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 30 23:14:23 volumio sudo[4371]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 23:14:23 volumio volumio[4185]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 23:14:23 volumio volumio[4185]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 30 23:14:23 volumio sudo[4369]: pam_unix(sudo:session): session closed for user root Mar 30 23:14:23 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Mar 30 23:14:23 volumio systemd[1]: mpd.service: Succeeded. Mar 30 23:14:23 volumio systemd[1]: Stopped Music Player Daemon. Mar 30 23:14:23 volumio volumio[4185]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 30 23:14:23 volumio volumio[4185]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 23:14:23 volumio volumio[4185]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 30 23:14:23 volumio systemd[1]: Starting Music Player Daemon... Mar 30 23:14:23 volumio volumio[4185]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 30 23:14:23 volumio volumio[4185]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 30 23:14:23 volumio volumio[4185]: info: [1711829663818] CoreMusicLibrary::Adding element Last_100 Mar 30 23:14:23 volumio volumio[4185]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 30 23:14:23 volumio volumio[4185]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 30 23:14:23 volumio volumio[4185]: info: [1711829663823] CoreMusicLibrary::Adding element Webradio Mar 30 23:14:23 volumio volumio[4185]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 30 23:14:23 volumio volumio[4185]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 30 23:14:23 volumio volumio[4185]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 30 23:14:23 volumio volumio[4185]: info: [1711829663844] CoreMusicLibrary::Adding element Bandcamp Discover Mar 30 23:14:23 volumio volumio[4185]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 30 23:14:23 volumio volumio[4185]: Cannot find translation for source Bandcamp Discover Mar 30 23:14:23 volumio volumio[4185]: info: Adding Calm Radio to Browse Sources Mar 30 23:14:23 volumio volumio[4185]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 30 23:14:23 volumio volumio[4185]: info: [1711829663851] CoreMusicLibrary::Adding element Calm Radio Mar 30 23:14:23 volumio volumio[4185]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 30 23:14:23 volumio volumio[4185]: Cannot find translation for source Bandcamp Discover Mar 30 23:14:23 volumio volumio[4185]: Cannot find translation for source Calm Radio Mar 30 23:14:23 volumio volumio[4185]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 30 23:14:23 volumio volumio[4185]: info: [1711829663864] CoreMusicLibrary::Adding element Mixcloud Mar 30 23:14:23 volumio volumio[4185]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 30 23:14:23 volumio volumio[4185]: Cannot find translation for source Bandcamp Discover Mar 30 23:14:23 volumio volumio[4185]: Cannot find translation for source Calm Radio Mar 30 23:14:23 volumio volumio[4185]: Cannot find translation for source Mixcloud Mar 30 23:14:23 volumio volumio[4185]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 30 23:14:23 volumio volumio[4185]: info: [1711829663878] CoreMusicLibrary::Adding element SoundCloud Mar 30 23:14:23 volumio volumio[4185]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 30 23:14:23 volumio volumio[4185]: Cannot find translation for source Bandcamp Discover Mar 30 23:14:23 volumio volumio[4185]: Cannot find translation for source Calm Radio Mar 30 23:14:23 volumio volumio[4185]: Cannot find translation for source Mixcloud Mar 30 23:14:23 volumio volumio[4185]: Cannot find translation for source SoundCloud Mar 30 23:14:23 volumio volumio[4185]: info: Creating Spotify config file Mar 30 23:14:23 volumio volumio[4185]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 23:14:23 volumio volumio[4185]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 30 23:14:23 volumio volumio[4185]: info: [1711829663913] CoreMusicLibrary::Adding element YouTube2 Mar 30 23:14:23 volumio volumio[4185]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 30 23:14:23 volumio volumio[4185]: Cannot find translation for source Bandcamp Discover Mar 30 23:14:23 volumio volumio[4185]: Cannot find translation for source Calm Radio Mar 30 23:14:23 volumio volumio[4185]: Cannot find translation for source Mixcloud Mar 30 23:14:23 volumio volumio[4185]: Cannot find translation for source SoundCloud Mar 30 23:14:23 volumio volumio[4185]: Cannot find translation for source YouTube2 Mar 30 23:14:23 volumio volumio[4185]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 30 23:14:23 volumio volumio[4185]: info: [1711829663952] CoreMusicLibrary::Adding element Mother Earth Radio Mar 30 23:14:23 volumio volumio[4185]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 30 23:14:23 volumio volumio[4185]: Cannot find translation for source Bandcamp Discover Mar 30 23:14:23 volumio volumio[4185]: Cannot find translation for source Calm Radio Mar 30 23:14:23 volumio volumio[4185]: Cannot find translation for source Mixcloud Mar 30 23:14:23 volumio volumio[4185]: Cannot find translation for source SoundCloud Mar 30 23:14:23 volumio volumio[4185]: Cannot find translation for source YouTube2 Mar 30 23:14:23 volumio volumio[4185]: Cannot find translation for source Mother Earth Radio Mar 30 23:14:23 volumio sudo[4382]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Mar 30 23:14:23 volumio sudo[4382]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 23:14:24 volumio sudo[4382]: pam_unix(sudo:session): session closed for user root Mar 30 23:14:24 volumio volumio[4185]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 30 23:14:24 volumio volumio[4185]: info: [1711829664230] CoreMusicLibrary::Adding element LastFM Mar 30 23:14:24 volumio volumio[4185]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 30 23:14:24 volumio volumio[4185]: Cannot find translation for source Bandcamp Discover Mar 30 23:14:24 volumio volumio[4185]: Cannot find translation for source Calm Radio Mar 30 23:14:24 volumio volumio[4185]: Cannot find translation for source Mixcloud Mar 30 23:14:24 volumio volumio[4185]: Cannot find translation for source SoundCloud Mar 30 23:14:24 volumio volumio[4185]: Cannot find translation for source YouTube2 Mar 30 23:14:24 volumio volumio[4185]: Cannot find translation for source Mother Earth Radio Mar 30 23:14:24 volumio volumio[4185]: Cannot find translation for source LastFM Mar 30 23:14:24 volumio volumio[4185]: info: [LastFM] scrobbler initiated! Mar 30 23:14:24 volumio volumio[4185]: info: [LastFM] extended logging: false Mar 30 23:14:24 volumio volumio[4185]: info: [LastFM] try scrobble stream/radio plays: true Mar 30 23:14:24 volumio volumio[4185]: info: [LastFM] Left init routine Mar 30 23:14:24 volumio volumio[4185]: info: [LastFM] Socket already connected: false Mar 30 23:14:24 volumio volumio[4185]: info: Volumio Calling Home Mar 30 23:14:24 volumio sudo[4410]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/data/plugins/system_hardware/music_services_shield/moveallprocesses.sh Mar 30 23:14:24 volumio sudo[4410]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 23:14:24 volumio volumio[4185]: info: CoreStateMachine::setRepeat null single undefined Mar 30 23:14:24 volumio volumio[4185]: info: CoreStateMachine::pushState Mar 30 23:14:24 volumio volumio[4185]: info: CorePlayQueue::getTrack 0 Mar 30 23:14:24 volumio volumio[4185]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 30 23:14:24 volumio volumio[4185]: info: CoreCommandRouter::volumioPushState Mar 30 23:14:24 volumio volumio[4185]: info: CoreStateMachine::setRandom null Mar 30 23:14:24 volumio volumio[4185]: info: CoreStateMachine::pushState Mar 30 23:14:24 volumio volumio[4185]: info: CorePlayQueue::getTrack 0 Mar 30 23:14:24 volumio volumio[4185]: info: CoreCommandRouter::volumioPushState Mar 30 23:14:24 volumio volumio[4185]: info: VolumeController:: Volume=undefined Mute =false Mar 30 23:14:24 volumio volumio[4185]: info: CoreStateMachine::pushState Mar 30 23:14:24 volumio volumio[4185]: info: CorePlayQueue::getTrack 0 Mar 30 23:14:24 volumio volumio[4185]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 30 23:14:24 volumio volumio[4185]: info: CoreCommandRouter::volumioPushState Mar 30 23:14:24 volumio volumio[4185]: info: CoreStateMachine::updateTrackBlock Mar 30 23:14:24 volumio volumio[4185]: info: CorePlayQueue::getTrackBlock Mar 30 23:14:24 volumio volumio[4185]: info: CoreCommandRouter::volumioRetrievevolume Mar 30 23:14:24 volumio volumio[4185]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 30 23:14:24 volumio volumio[4185]: Error: connect ENETUNREACH 130.211.19.189:80 - Local (0.0.0.0:0) Mar 30 23:14:24 volumio volumio[4185]: at internalConnect (net.js:934:16) Mar 30 23:14:24 volumio volumio[4185]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:452:18) Mar 30 23:14:24 volumio volumio[4185]: at GetAddrInfoReqWrap.emitLookup [as callback] (net.js:1077:9) Mar 30 23:14:24 volumio volumio[4185]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:73:8) { Mar 30 23:14:24 volumio volumio[4185]: errno: -101, Mar 30 23:14:24 volumio volumio[4185]: code: 'ENETUNREACH', Mar 30 23:14:24 volumio volumio[4185]: syscall: 'connect', Mar 30 23:14:24 volumio volumio[4185]: address: '130.211.19.189', Mar 30 23:14:24 volumio volumio[4185]: port: 80 Mar 30 23:14:24 volumio volumio[4185]: } Mar 30 23:14:24 volumio volumio[4185]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 30 23:14:25 volumio sudo[4410]: pam_unix(sudo:session): session closed for user root Mar 30 23:14:25 volumio sudo[4439]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-03-30 23:13 Mar 30 23:14:25 volumio sudo[4439]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 23:14:25 volumio sudo[4439]: pam_unix(sudo:session): session closed for user root Mar 30 23:14:25 volumio volumio-remote-updater[595]: [2024-03-30 23:14:25] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Mar 30 23:14:25 volumio volumio-remote-updater[595]: [2024-03-30 23:14:25] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Mar 30 23:14:25 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Mar 30 23:14:25 volumio sudo[4371]: pam_unix(sudo:session): session closed for user root Mar 30 23:14:25 volumio sudo[4361]: pam_unix(sudo:session): session closed for user root Mar 30 23:14:25 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Mar 30 23:14:25 volumio systemd[1]: Started dynamicswap service. Mar 30 23:14:25 volumio systemd[1]: dynamicswap.service: Succeeded. Mar 30 23:14:26 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Mar 30 23:14:26 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 14. Mar 30 23:14:26 volumio systemd[1]: Stopped Volumio Backend Module. Mar 30 23:14:26 volumio systemd[1]: Started Volumio Backend Module. Mar 30 23:14:26 volumio systemd[1]: Started dynamicswap service. Mar 30 23:14:26 volumio systemd[1]: dynamicswap.service: Succeeded. Mar 30 23:14:26 volumio mpd[4377]: Mar 30 23:14 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 30 23:14:26 volumio systemd[1]: Started Music Player Daemon. Mar 30 23:14:26 volumio systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Mar 30 23:14:26 volumio volumio[4466]: cset: --> shielding system active with Mar 30 23:14:26 volumio volumio[4466]: cset: "system" cpuset of CPUSPEC(0-2) with 71 tasks running Mar 30 23:14:26 volumio volumio[4466]: cset: "user" cpuset of CPUSPEC(3) with 3 tasks running Mar 30 23:14:26 volumio systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Mar 30 23:14:28 volumio volumio[4452]: info: ------------------------------------------- Mar 30 23:14:28 volumio volumio[4452]: info: ----- Volumio3 ---- Mar 30 23:14:28 volumio volumio[4452]: info: ------------------------------------------- Mar 30 23:14:28 volumio volumio[4452]: info: ----- System startup ---- Mar 30 23:14:28 volumio volumio[4452]: info: ------------------------------------------- Mar 30 23:14:28 volumio volumio[4452]: info: MYVOLUMIO Environment detected Mar 30 23:14:29 volumio volumio[4452]: info: Plugin folders cleanup Mar 30 23:14:29 volumio volumio[4452]: info: Scanning into folder /volumio/app/plugins/ Mar 30 23:14:29 volumio volumio[4452]: info: Scanning category audio_interface Mar 30 23:14:29 volumio volumio[4452]: info: Scanning category miscellanea Mar 30 23:14:29 volumio volumio[4452]: info: Scanning category music_service Mar 30 23:14:29 volumio volumio[4452]: info: Scanning category plugins.json Mar 30 23:14:29 volumio volumio[4452]: info: Scanning category system_controller Mar 30 23:14:29 volumio volumio[4452]: info: Scanning category user_interface Mar 30 23:14:29 volumio volumio[4452]: info: Scanning into folder /data/plugins/ Mar 30 23:14:29 volumio volumio[4452]: info: Scanning category music_service Mar 30 23:14:29 volumio volumio[4452]: info: Scanning category system_hardware Mar 30 23:14:29 volumio volumio[4452]: info: Scanning category user_interface Mar 30 23:14:29 volumio volumio[4452]: info: Plugin folders cleanup completed Mar 30 23:14:29 volumio volumio[4452]: info: ------------------------------------------- Mar 30 23:14:29 volumio volumio[4452]: info: ----- Core plugins startup ---- Mar 30 23:14:29 volumio volumio[4452]: info: ------------------------------------------- Mar 30 23:14:29 volumio volumio[4452]: info: Loading plugins from folder /volumio/app/plugins/ Mar 30 23:14:29 volumio volumio[4452]: info: Adding plugin upnp to MyMusic Plugins Mar 30 23:14:29 volumio volumio[4452]: info: Adding plugin airplay_emulation to MyMusic Plugins Mar 30 23:14:29 volumio volumio[4452]: info: Adding plugin upnp_browser to MyMusic Plugins Mar 30 23:14:29 volumio volumio[4452]: info: Loading plugins from folder /data/plugins/ Mar 30 23:14:29 volumio volumio[4452]: info: Loading plugin "system"... Mar 30 23:14:29 volumio volumio[4452]: info: Loading plugin "appearance"... Mar 30 23:14:30 volumio volumio[4452]: info: Loading plugin "network"... Mar 30 23:14:30 volumio volumio[4452]: info: Refreshing Cached IP Addresses Mar 30 23:14:30 volumio sudo[4496]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 30 23:14:30 volumio sudo[4496]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 23:14:30 volumio volumio[4452]: info: Loading plugin "services"... Mar 30 23:14:30 volumio sudo[4496]: pam_unix(sudo:session): session closed for user root Mar 30 23:14:30 volumio sudo[4498]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 30 23:14:30 volumio sudo[4498]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 23:14:30 volumio volumio[4452]: info: Loading plugin "alsa_controller"... Mar 30 23:14:30 volumio sudo[4501]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Mar 30 23:14:30 volumio sudo[4501]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 23:14:30 volumio sudo[4498]: pam_unix(sudo:session): session closed for user root Mar 30 23:14:30 volumio sudo[4501]: pam_unix(sudo:session): session closed for user root Mar 30 23:14:30 volumio volumio[4452]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 30 23:14:30 volumio volumio[4452]: info: Loading plugin "wizard"... Mar 30 23:14:30 volumio volumio[4452]: info: Loading plugin "networkfs"... Mar 30 23:14:30 volumio volumio[4452]: info: Starting Udev Watcher for removable devices Mar 30 23:14:30 volumio volumio[4452]: info: Ignoring mount for partition: boot Mar 30 23:14:30 volumio volumio[4452]: info: Ignoring mount for partition: volumio Mar 30 23:14:30 volumio volumio[4452]: info: Ignoring mount for partition: volumio_data Mar 30 23:14:30 volumio volumio[4452]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 30 23:14:30 volumio volumio[4452]: info: Loading plugin "volumio_command_line_client"... Mar 30 23:14:30 volumio volumio[4452]: info: Plugin upnp is not enabled Mar 30 23:14:30 volumio volumio[4452]: info: Loading plugin "my_music"... Mar 30 23:14:30 volumio volumio[4452]: info: Loading plugin "mpd"... Mar 30 23:14:30 volumio volumio-remote-updater[595]: [2024-03-30 23:14:30] [connect] Successful connection Mar 30 23:14:30 volumio volumio[4452]: info: Loading plugin "upnp_browser"... Mar 30 23:14:31 volumio volumio[4452]: info: Loading plugin "alarm-clock"... Mar 30 23:14:31 volumio volumio[4452]: info: Loading plugin "airplay_emulation"... Mar 30 23:14:31 volumio volumio[4452]: info: Starting Shairport Sync Mar 30 23:14:31 volumio volumio[4452]: info: Loading plugin "last_100"... Mar 30 23:14:31 volumio volumio[4452]: info: Loading plugin "webradio"... Mar 30 23:14:31 volumio volumio[4452]: info: Loading plugin "i2s_dacs"... Mar 30 23:14:31 volumio volumio[4452]: info: I2S DAC not set, start Auto-detection Mar 30 23:14:31 volumio volumio[4452]: info: Loading plugin "volumiodiscovery"... Mar 30 23:14:31 volumio volumio[4452]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 30 23:14:31 volumio node[4452]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 30 23:14:31 volumio node[4452]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 30 23:14:31 volumio volumio[4452]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 30 23:14:31 volumio volumio[4452]: *** WARNING *** For more information see Mar 30 23:14:31 volumio volumio[4452]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 30 23:14:31 volumio volumio[4452]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 30 23:14:31 volumio volumio[4452]: *** WARNING *** For more information see Mar 30 23:14:31 volumio node[4452]: *** WARNING *** For more information see Mar 30 23:14:31 volumio node[4452]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 30 23:14:31 volumio node[4452]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 30 23:14:31 volumio node[4452]: *** WARNING *** For more information see Mar 30 23:14:31 volumio volumio[4452]: info: Applying required configuration parameters for plugin volumiodiscovery Mar 30 23:14:31 volumio volumio[4452]: info: Discovery: Started advertising with name: VOLUMIO Mar 30 23:14:31 volumio volumio[4452]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 30 23:14:31 volumio volumio[4452]: info: Loading plugin "bandcamp"... Mar 30 23:14:32 volumio volumio[4452]: info: Loading plugin "calmradio"... Mar 30 23:14:33 volumio volumio[4452]: info: Loading plugin "mixcloud"... Mar 30 23:14:34 volumio volumio[4452]: info: Loading plugin "soundcloud"... Mar 30 23:14:34 volumio volumio[4452]: info: Loading plugin "spop"... Mar 30 23:14:35 volumio volumio[4452]: info: Loading plugin "youtube2"... Mar 30 23:14:36 volumio volumio[4452]: info: Loading plugin "outputs"... Mar 30 23:14:36 volumio volumio[4452]: info: Loading plugin "albumart"... Mar 30 23:14:36 volumio volumio[4452]: info: Plugin example_plugin is not enabled Mar 30 23:14:36 volumio volumio[4452]: info: Loading plugin "inputs"... Mar 30 23:14:36 volumio volumio[4452]: info: Loading plugin "updater_comm"... Mar 30 23:14:37 volumio volumio[4452]: info: Plugin mpdemulation is not enabled Mar 30 23:14:37 volumio volumio[4452]: info: Loading plugin "rest_api"... Mar 30 23:14:37 volumio volumio[4452]: info: Loading plugin "websocket"... Mar 30 23:14:37 volumio volumio[4452]: info: Loading plugin "RoonBridge"... Mar 30 23:14:37 volumio volumio[4452]: Forking 3 albumart workers Mar 30 23:14:37 volumio volumio[4452]: info: Applying required configuration parameters for plugin RoonBridge Mar 30 23:14:37 volumio volumio[4452]: info: Loading plugin "motherearthradio"... Mar 30 23:14:37 volumio volumio[4452]: info: Applying required configuration parameters for plugin motherearthradio Mar 30 23:14:37 volumio volumio[4452]: info: [1711829677931] [MotherEarth] API delay: 5 Mar 30 23:14:37 volumio volumio[4452]: info: Loading plugin "music_services_shield"... Mar 30 23:14:38 volumio volumio[4452]: Starting albumart workers Mar 30 23:14:38 volumio volumio[4452]: Starting albumart workers Mar 30 23:14:38 volumio volumio[4452]: info: Applying required configuration parameters for plugin music_services_shield Mar 30 23:14:38 volumio volumio[4452]: info: Loading plugin "lastfm"... Mar 30 23:14:39 volumio volumio[4452]: Starting albumart workers Mar 30 23:14:39 volumio volumio[4452]: info: Loading i18n strings for locale en Mar 30 23:14:39 volumio volumio[4452]: Updating browse sources language Mar 30 23:14:39 volumio volumio[4452]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 30 23:14:39 volumio volumio[4452]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 30 23:14:39 volumio volumio[4452]: info: CoreCommandRouter::initPlayerControls Mar 30 23:14:39 volumio volumio[4452]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 23:14:39 volumio volumio[4452]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 23:14:39 volumio volumio[4452]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 23:14:39 volumio volumio[4452]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 23:14:39 volumio volumio[4452]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 23:14:39 volumio volumio[4452]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 23:14:39 volumio volumio[4452]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 23:14:39 volumio volumio[4452]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 23:14:39 volumio volumio[4452]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 30 23:14:39 volumio volumio[4452]: Express server listening on port 3000 Mar 30 23:14:39 volumio volumio[4452]: [Metrics] WebUI: 12s 156.20ms Mar 30 23:14:39 volumio volumio[4452]: info: CoreStateMachine::resetVolumioState Mar 30 23:14:39 volumio volumio[4452]: info: CoreStateMachine::getcurrentVolume Mar 30 23:14:39 volumio volumio[4452]: info: CoreCommandRouter::volumioRetrievevolume Mar 30 23:14:39 volumio volumio[4452]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 30 23:14:39 volumio volumio[4452]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Mar 30 23:14:39 volumio volumio[4452]: info: Completed loading Core Plugins Mar 30 23:14:39 volumio volumio[4452]: info: Preparing to generate the ALSA configuration file Mar 30 23:14:39 volumio volumio[4452]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Mar 30 23:14:39 volumio volumio[4452]: wlan0 Interface doesn't support scanning. Mar 30 23:14:39 volumio volumio[4452]: info: Cannot use regular scanning, forcing with ap-force Mar 30 23:14:39 volumio sudo[4574]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Mar 30 23:14:39 volumio sudo[4574]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 23:14:39 volumio sudo[4574]: pam_unix(sudo:session): session closed for user root Mar 30 23:14:39 volumio volumio[4452]: command failed: No such device (-19) Mar 30 23:14:39 volumio volumio[4452]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Mar 30 23:14:39 volumio volumio[4452]: command failed: No such device (-19) Mar 30 23:14:40 volumio volumio[4452]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Mar 30 23:14:40 volumio volumio[4452]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Mar 30 23:14:40 volumio volumio[4452]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 1 Mar 30 23:14:40 volumio volumio[4452]: info: Reloading queue from file Mar 30 23:14:40 volumio volumio[4452]: info: VolumeController:: Volume=undefined Mute =false Mar 30 23:14:40 volumio volumio[4452]: info: CoreStateMachine::pushState Mar 30 23:14:40 volumio volumio[4452]: info: CorePlayQueue::getTrack 0 Mar 30 23:14:40 volumio volumio[4452]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 30 23:14:40 volumio volumio[4452]: info: CoreCommandRouter::volumioPushState Mar 30 23:14:40 volumio volumio[4452]: info: CoreStateMachine::updateTrackBlock Mar 30 23:14:40 volumio volumio[4452]: info: CorePlayQueue::getTrackBlock Mar 30 23:14:40 volumio volumio[4452]: info: CoreCommandRouter::volumioRetrievevolume Mar 30 23:14:40 volumio volumio[4452]: info: Asound.conf file unchanged, so no further update is needed Mar 30 23:14:40 volumio volumio[4452]: info: Output device has changed, restarting MPD Mar 30 23:14:40 volumio sudo[4590]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 30 23:14:40 volumio volumio[4452]: info: Output device has changed, restarting Shairport Sync Mar 30 23:14:40 volumio sudo[4590]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 23:14:40 volumio volumio[4452]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 23:14:40 volumio volumio[4452]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 30 23:14:40 volumio sudo[4590]: pam_unix(sudo:session): session closed for user root Mar 30 23:14:40 volumio sudo[4592]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 30 23:14:40 volumio sudo[4592]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 23:14:40 volumio systemd[1]: musicservicesshield.service: Succeeded. Mar 30 23:14:40 volumio systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Mar 30 23:14:40 volumio systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Mar 30 23:14:40 volumio systemd[1]: Stopping Music Player Daemon... Mar 30 23:14:40 volumio volumio[4452]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 30 23:14:40 volumio systemd[1]: mpd.service: Succeeded. Mar 30 23:14:40 volumio volumio[4452]: info: ___________ START PLUGINS ___________ Mar 30 23:14:40 volumio systemd[1]: Stopped Music Player Daemon. Mar 30 23:14:40 volumio volumio[4452]: info: ControllerMpd::onStart: Initializing MPD Mar 30 23:14:40 volumio volumio[4452]: info: Creating MPD Configuration file Mar 30 23:14:40 volumio systemd[1]: Starting Music Player Daemon... Mar 30 23:14:40 volumio volumio[4452]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 30 23:14:40 volumio volumio[4452]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 30 23:14:40 volumio volumio[4452]: info: [1711829680508] CoreMusicLibrary::Adding element Media Servers Mar 30 23:14:40 volumio volumio[4452]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 30 23:14:40 volumio sudo[4600]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 30 23:14:40 volumio sudo[4602]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 30 23:14:40 volumio sudo[4602]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 23:14:40 volumio sudo[4600]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 23:14:40 volumio volumio[4452]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 23:14:40 volumio volumio[4452]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 30 23:14:40 volumio sudo[4600]: pam_unix(sudo:session): session closed for user root Mar 30 23:14:40 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Mar 30 23:14:40 volumio systemd[1]: mpd.service: Succeeded. Mar 30 23:14:40 volumio systemd[1]: Stopped Music Player Daemon. Mar 30 23:14:40 volumio systemd[1]: Starting Music Player Daemon... Mar 30 23:14:40 volumio volumio[4452]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 30 23:14:40 volumio volumio[4452]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 23:14:40 volumio volumio[4452]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 30 23:14:40 volumio volumio[4452]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 30 23:14:40 volumio volumio[4452]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 30 23:14:40 volumio volumio[4452]: info: [1711829680691] CoreMusicLibrary::Adding element Last_100 Mar 30 23:14:40 volumio volumio[4452]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 30 23:14:40 volumio volumio[4452]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 30 23:14:40 volumio volumio[4452]: info: [1711829680696] CoreMusicLibrary::Adding element Webradio Mar 30 23:14:40 volumio volumio[4452]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 30 23:14:40 volumio volumio[4452]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 30 23:14:40 volumio volumio[4452]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 30 23:14:40 volumio volumio[4452]: info: [1711829680717] CoreMusicLibrary::Adding element Bandcamp Discover Mar 30 23:14:40 volumio volumio[4452]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 30 23:14:40 volumio volumio[4452]: Cannot find translation for source Bandcamp Discover Mar 30 23:14:40 volumio volumio[4452]: info: Adding Calm Radio to Browse Sources Mar 30 23:14:40 volumio volumio[4452]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 30 23:14:40 volumio volumio[4452]: info: [1711829680724] CoreMusicLibrary::Adding element Calm Radio Mar 30 23:14:40 volumio volumio[4452]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 30 23:14:40 volumio volumio[4452]: Cannot find translation for source Bandcamp Discover Mar 30 23:14:40 volumio volumio[4452]: Cannot find translation for source Calm Radio Mar 30 23:14:40 volumio volumio[4452]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 30 23:14:40 volumio volumio[4452]: info: [1711829680740] CoreMusicLibrary::Adding element Mixcloud Mar 30 23:14:40 volumio volumio[4452]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 30 23:14:40 volumio volumio[4452]: Cannot find translation for source Bandcamp Discover Mar 30 23:14:40 volumio volumio[4452]: Cannot find translation for source Calm Radio Mar 30 23:14:40 volumio volumio[4452]: Cannot find translation for source Mixcloud Mar 30 23:14:40 volumio volumio[4452]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 30 23:14:40 volumio volumio[4452]: info: [1711829680756] CoreMusicLibrary::Adding element SoundCloud Mar 30 23:14:40 volumio volumio[4452]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 30 23:14:40 volumio volumio[4452]: Cannot find translation for source Bandcamp Discover Mar 30 23:14:40 volumio volumio[4452]: Cannot find translation for source Calm Radio Mar 30 23:14:40 volumio volumio[4452]: Cannot find translation for source Mixcloud Mar 30 23:14:40 volumio volumio[4452]: Cannot find translation for source SoundCloud Mar 30 23:14:40 volumio volumio[4452]: info: Creating Spotify config file Mar 30 23:14:40 volumio volumio[4452]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 23:14:40 volumio volumio[4452]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 30 23:14:40 volumio volumio[4452]: info: [1711829680793] CoreMusicLibrary::Adding element YouTube2 Mar 30 23:14:40 volumio volumio[4452]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 30 23:14:40 volumio volumio[4452]: Cannot find translation for source Bandcamp Discover Mar 30 23:14:40 volumio volumio[4452]: Cannot find translation for source Calm Radio Mar 30 23:14:40 volumio volumio[4452]: Cannot find translation for source Mixcloud Mar 30 23:14:40 volumio volumio[4452]: Cannot find translation for source SoundCloud Mar 30 23:14:40 volumio volumio[4452]: Cannot find translation for source YouTube2 Mar 30 23:14:40 volumio volumio[4452]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 30 23:14:40 volumio volumio[4452]: info: [1711829680832] CoreMusicLibrary::Adding element Mother Earth Radio Mar 30 23:14:40 volumio volumio[4452]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 30 23:14:40 volumio volumio[4452]: Cannot find translation for source Bandcamp Discover Mar 30 23:14:40 volumio volumio[4452]: Cannot find translation for source Calm Radio Mar 30 23:14:40 volumio volumio[4452]: Cannot find translation for source Mixcloud Mar 30 23:14:40 volumio volumio[4452]: Cannot find translation for source SoundCloud Mar 30 23:14:40 volumio volumio[4452]: Cannot find translation for source YouTube2 Mar 30 23:14:40 volumio volumio[4452]: Cannot find translation for source Mother Earth Radio Mar 30 23:14:40 volumio sudo[4613]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Mar 30 23:14:40 volumio sudo[4613]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 23:14:40 volumio sudo[4613]: pam_unix(sudo:session): session closed for user root Mar 30 23:14:41 volumio volumio[4452]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 30 23:14:41 volumio volumio[4452]: info: [1711829681108] CoreMusicLibrary::Adding element LastFM Mar 30 23:14:41 volumio volumio[4452]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 30 23:14:41 volumio volumio[4452]: Cannot find translation for source Bandcamp Discover Mar 30 23:14:41 volumio volumio[4452]: Cannot find translation for source Calm Radio Mar 30 23:14:41 volumio volumio[4452]: Cannot find translation for source Mixcloud Mar 30 23:14:41 volumio volumio[4452]: Cannot find translation for source SoundCloud Mar 30 23:14:41 volumio volumio[4452]: Cannot find translation for source YouTube2 Mar 30 23:14:41 volumio volumio[4452]: Cannot find translation for source Mother Earth Radio Mar 30 23:14:41 volumio volumio[4452]: Cannot find translation for source LastFM Mar 30 23:14:41 volumio volumio[4452]: info: [LastFM] scrobbler initiated! Mar 30 23:14:41 volumio volumio[4452]: info: [LastFM] extended logging: false Mar 30 23:14:41 volumio volumio[4452]: info: [LastFM] try scrobble stream/radio plays: true Mar 30 23:14:41 volumio volumio[4452]: info: [LastFM] Left init routine Mar 30 23:14:41 volumio volumio[4452]: info: [LastFM] Socket already connected: false Mar 30 23:14:41 volumio volumio[4452]: info: Volumio Calling Home Mar 30 23:14:41 volumio sudo[4641]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/data/plugins/system_hardware/music_services_shield/moveallprocesses.sh Mar 30 23:14:41 volumio sudo[4641]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 23:14:41 volumio volumio[4452]: info: CoreStateMachine::setRepeat null single undefined Mar 30 23:14:41 volumio volumio[4452]: info: CoreStateMachine::pushState Mar 30 23:14:41 volumio volumio[4452]: info: CorePlayQueue::getTrack 0 Mar 30 23:14:41 volumio volumio[4452]: info: CoreCommandRouter::volumioPushState Mar 30 23:14:41 volumio volumio[4452]: info: CoreStateMachine::setRandom null Mar 30 23:14:41 volumio volumio[4452]: info: CoreStateMachine::pushState Mar 30 23:14:41 volumio volumio[4452]: info: CorePlayQueue::getTrack 0 Mar 30 23:14:41 volumio volumio[4452]: info: CoreCommandRouter::volumioPushState Mar 30 23:14:41 volumio volumio[4452]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 30 23:14:41 volumio volumio[4452]: Error: connect ENETUNREACH 130.211.19.189:80 - Local (0.0.0.0:0) Mar 30 23:14:41 volumio volumio[4452]: at internalConnect (net.js:934:16) Mar 30 23:14:41 volumio volumio[4452]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:452:18) Mar 30 23:14:41 volumio volumio[4452]: at GetAddrInfoReqWrap.emitLookup [as callback] (net.js:1077:9) Mar 30 23:14:41 volumio volumio[4452]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:73:8) { Mar 30 23:14:41 volumio volumio[4452]: errno: -101, Mar 30 23:14:41 volumio volumio[4452]: code: 'ENETUNREACH', Mar 30 23:14:41 volumio volumio[4452]: syscall: 'connect', Mar 30 23:14:41 volumio volumio[4452]: address: '130.211.19.189', Mar 30 23:14:41 volumio volumio[4452]: port: 80 Mar 30 23:14:41 volumio volumio[4452]: } Mar 30 23:14:41 volumio volumio[4452]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 30 23:14:42 volumio sudo[4641]: pam_unix(sudo:session): session closed for user root Mar 30 23:14:42 volumio sudo[4669]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-03-30 23:13 Mar 30 23:14:42 volumio sudo[4669]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 23:14:42 volumio sudo[4669]: pam_unix(sudo:session): session closed for user root Mar 30 23:14:42 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Mar 30 23:14:42 volumio sudo[4602]: pam_unix(sudo:session): session closed for user root Mar 30 23:14:42 volumio sudo[4592]: pam_unix(sudo:session): session closed for user root Mar 30 23:14:42 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Mar 30 23:14:42 volumio systemd[1]: Started dynamicswap service. Mar 30 23:14:42 volumio systemd[1]: dynamicswap.service: Succeeded. Mar 30 23:14:42 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Mar 30 23:14:42 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 15. Mar 30 23:14:42 volumio systemd[1]: Stopped Volumio Backend Module. Mar 30 23:14:42 volumio systemd[1]: Started Volumio Backend Module. Mar 30 23:14:42 volumio systemd[1]: Started dynamicswap service. Mar 30 23:14:42 volumio systemd[1]: dynamicswap.service: Succeeded. Mar 30 23:14:43 volumio mpd[4606]: Mar 30 23:14 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 30 23:14:43 volumio systemd[1]: Started Music Player Daemon. Mar 30 23:14:43 volumio systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Mar 30 23:14:43 volumio volumio-remote-updater[595]: [2024-03-30 23:14:43] [info] asio async_connect error: system:111 (Connection refused) Mar 30 23:14:43 volumio volumio-remote-updater[595]: [2024-03-30 23:14:43] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Mar 30 23:14:43 volumio volumio-remote-updater[595]: [2024-03-30 23:14:43] [error] handle_connect error: Underlying Transport Error Mar 30 23:14:43 volumio volumio[4697]: cset: --> shielding system active with Mar 30 23:14:43 volumio volumio[4697]: cset: "system" cpuset of CPUSPEC(0-2) with 71 tasks running Mar 30 23:14:43 volumio volumio[4697]: cset: "user" cpuset of CPUSPEC(3) with 3 tasks running Mar 30 23:14:43 volumio systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Mar 30 23:14:44 volumio volumio[4682]: info: ------------------------------------------- Mar 30 23:14:44 volumio volumio[4682]: info: ----- Volumio3 ---- Mar 30 23:14:44 volumio volumio[4682]: info: ------------------------------------------- Mar 30 23:14:44 volumio volumio[4682]: info: ----- System startup ---- Mar 30 23:14:44 volumio volumio[4682]: info: ------------------------------------------- Mar 30 23:14:45 volumio volumio[4682]: info: MYVOLUMIO Environment detected Mar 30 23:14:45 volumio volumio[4682]: info: Plugin folders cleanup Mar 30 23:14:45 volumio volumio[4682]: info: Scanning into folder /volumio/app/plugins/ Mar 30 23:14:45 volumio volumio[4682]: info: Scanning category audio_interface Mar 30 23:14:45 volumio volumio[4682]: info: Scanning category miscellanea Mar 30 23:14:45 volumio volumio[4682]: info: Scanning category music_service Mar 30 23:14:45 volumio volumio[4682]: info: Scanning category plugins.json Mar 30 23:14:45 volumio volumio[4682]: info: Scanning category system_controller Mar 30 23:14:45 volumio volumio[4682]: info: Scanning category user_interface Mar 30 23:14:45 volumio volumio[4682]: info: Scanning into folder /data/plugins/ Mar 30 23:14:45 volumio volumio[4682]: info: Scanning category music_service Mar 30 23:14:45 volumio volumio[4682]: info: Scanning category system_hardware Mar 30 23:14:45 volumio volumio[4682]: info: Scanning category user_interface Mar 30 23:14:45 volumio volumio[4682]: info: Plugin folders cleanup completed Mar 30 23:14:45 volumio volumio[4682]: info: ------------------------------------------- Mar 30 23:14:45 volumio volumio[4682]: info: ----- Core plugins startup ---- Mar 30 23:14:45 volumio volumio[4682]: info: ------------------------------------------- Mar 30 23:14:45 volumio volumio[4682]: info: Loading plugins from folder /volumio/app/plugins/ Mar 30 23:14:45 volumio volumio[4682]: info: Adding plugin upnp to MyMusic Plugins Mar 30 23:14:45 volumio volumio[4682]: info: Adding plugin airplay_emulation to MyMusic Plugins Mar 30 23:14:45 volumio volumio[4682]: info: Adding plugin upnp_browser to MyMusic Plugins Mar 30 23:14:45 volumio volumio[4682]: info: Loading plugins from folder /data/plugins/ Mar 30 23:14:45 volumio volumio[4682]: info: Loading plugin "system"... Mar 30 23:14:45 volumio volumio[4682]: info: Loading plugin "appearance"... Mar 30 23:14:47 volumio volumio[4682]: info: Loading plugin "network"... Mar 30 23:14:47 volumio volumio[4682]: info: Refreshing Cached IP Addresses Mar 30 23:14:47 volumio sudo[4726]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 30 23:14:47 volumio sudo[4726]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 23:14:47 volumio volumio[4682]: info: Loading plugin "services"... Mar 30 23:14:47 volumio sudo[4726]: pam_unix(sudo:session): session closed for user root Mar 30 23:14:47 volumio sudo[4728]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 30 23:14:47 volumio volumio[4682]: info: Loading plugin "alsa_controller"... Mar 30 23:14:47 volumio sudo[4728]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 23:14:47 volumio sudo[4728]: pam_unix(sudo:session): session closed for user root Mar 30 23:14:47 volumio sudo[4732]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Mar 30 23:14:47 volumio sudo[4732]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 23:14:47 volumio sudo[4732]: pam_unix(sudo:session): session closed for user root Mar 30 23:14:47 volumio volumio[4682]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 30 23:14:47 volumio volumio[4682]: info: Loading plugin "wizard"... Mar 30 23:14:47 volumio volumio[4682]: info: Loading plugin "networkfs"... Mar 30 23:14:47 volumio volumio[4682]: info: Starting Udev Watcher for removable devices Mar 30 23:14:47 volumio volumio[4682]: info: Ignoring mount for partition: boot Mar 30 23:14:47 volumio volumio[4682]: info: Ignoring mount for partition: volumio Mar 30 23:14:47 volumio volumio[4682]: info: Ignoring mount for partition: volumio_data Mar 30 23:14:47 volumio volumio[4682]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 30 23:14:47 volumio volumio[4682]: info: Loading plugin "volumio_command_line_client"... Mar 30 23:14:47 volumio volumio[4682]: info: Plugin upnp is not enabled Mar 30 23:14:47 volumio volumio[4682]: info: Loading plugin "my_music"... Mar 30 23:14:47 volumio volumio[4682]: info: Loading plugin "mpd"... Mar 30 23:14:47 volumio volumio[4682]: info: Loading plugin "upnp_browser"... Mar 30 23:14:48 volumio volumio[4682]: info: Loading plugin "alarm-clock"... Mar 30 23:14:48 volumio volumio[4682]: info: Loading plugin "airplay_emulation"... Mar 30 23:14:48 volumio volumio[4682]: info: Starting Shairport Sync Mar 30 23:14:48 volumio volumio[4682]: info: Loading plugin "last_100"... Mar 30 23:14:48 volumio volumio[4682]: info: Loading plugin "webradio"... Mar 30 23:14:48 volumio volumio[4682]: info: Loading plugin "i2s_dacs"... Mar 30 23:14:48 volumio volumio[4682]: info: I2S DAC not set, start Auto-detection Mar 30 23:14:48 volumio volumio[4682]: info: Loading plugin "volumiodiscovery"... Mar 30 23:14:48 volumio volumio[4682]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 30 23:14:48 volumio volumio[4682]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 30 23:14:48 volumio node[4682]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 30 23:14:48 volumio volumio[4682]: *** WARNING *** For more information see Mar 30 23:14:48 volumio volumio[4682]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 30 23:14:48 volumio volumio[4682]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 30 23:14:48 volumio volumio[4682]: *** WARNING *** For more information see Mar 30 23:14:48 volumio node[4682]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 30 23:14:48 volumio node[4682]: *** WARNING *** For more information see Mar 30 23:14:48 volumio node[4682]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 30 23:14:48 volumio node[4682]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 30 23:14:48 volumio node[4682]: *** WARNING *** For more information see Mar 30 23:14:48 volumio volumio[4682]: info: Applying required configuration parameters for plugin volumiodiscovery Mar 30 23:14:48 volumio volumio[4682]: info: Discovery: Started advertising with name: VOLUMIO Mar 30 23:14:48 volumio volumio[4682]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 30 23:14:48 volumio volumio[4682]: info: Loading plugin "bandcamp"... Mar 30 23:14:49 volumio volumio[4682]: info: Loading plugin "calmradio"... Mar 30 23:14:50 volumio volumio[4682]: info: Loading plugin "mixcloud"... Mar 30 23:14:50 volumio volumio[4682]: info: Loading plugin "soundcloud"... Mar 30 23:14:51 volumio volumio[4682]: info: Loading plugin "spop"... Mar 30 23:14:52 volumio volumio[4682]: info: Loading plugin "youtube2"... Mar 30 23:14:53 volumio volumio-remote-updater[595]: [2024-03-30 23:14:53] [connect] Successful connection Mar 30 23:14:53 volumio volumio[4682]: info: Loading plugin "outputs"... Mar 30 23:14:53 volumio volumio[4682]: info: Loading plugin "albumart"... Mar 30 23:14:53 volumio volumio[4682]: info: Plugin example_plugin is not enabled Mar 30 23:14:53 volumio volumio[4682]: info: Loading plugin "inputs"... Mar 30 23:14:53 volumio volumio[4682]: info: Loading plugin "updater_comm"... Mar 30 23:14:53 volumio volumio[4682]: info: Plugin mpdemulation is not enabled Mar 30 23:14:53 volumio volumio[4682]: info: Loading plugin "rest_api"... Mar 30 23:14:53 volumio volumio[4682]: info: Loading plugin "websocket"... Mar 30 23:14:53 volumio volumio[4682]: info: Loading plugin "RoonBridge"... Mar 30 23:14:54 volumio volumio[4682]: Forking 3 albumart workers Mar 30 23:14:54 volumio volumio[4682]: info: Applying required configuration parameters for plugin RoonBridge Mar 30 23:14:54 volumio volumio[4682]: info: Loading plugin "motherearthradio"... Mar 30 23:14:54 volumio volumio[4682]: info: Applying required configuration parameters for plugin motherearthradio Mar 30 23:14:54 volumio volumio[4682]: info: [1711829694547] [MotherEarth] API delay: 5 Mar 30 23:14:54 volumio volumio[4682]: info: Loading plugin "music_services_shield"... Mar 30 23:14:54 volumio volumio[4682]: info: Applying required configuration parameters for plugin music_services_shield Mar 30 23:14:54 volumio volumio[4682]: info: Loading plugin "lastfm"... Mar 30 23:14:55 volumio volumio[4682]: Starting albumart workers Mar 30 23:14:55 volumio volumio[4682]: info: Loading i18n strings for locale en Mar 30 23:14:55 volumio volumio[4682]: Updating browse sources language Mar 30 23:14:55 volumio volumio[4682]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 30 23:14:55 volumio volumio[4682]: Starting albumart workers Mar 30 23:14:55 volumio ntpd[1022]: Soliciting pool server 162.159.200.1 Mar 30 23:14:55 volumio volumio[4682]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 30 23:14:55 volumio volumio[4682]: info: CoreCommandRouter::initPlayerControls Mar 30 23:14:55 volumio volumio[4682]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 23:14:55 volumio volumio[4682]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 23:14:55 volumio volumio[4682]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 23:14:55 volumio volumio[4682]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 23:14:55 volumio volumio[4682]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 23:14:55 volumio volumio[4682]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 23:14:55 volumio volumio[4682]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 23:14:55 volumio volumio[4682]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 23:14:56 volumio volumio[4682]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 30 23:14:56 volumio volumio[4682]: Express server listening on port 3000 Mar 30 23:14:56 volumio volumio[4682]: [Metrics] WebUI: 11s 753.66ms Mar 30 23:14:56 volumio volumio[4682]: info: CoreStateMachine::resetVolumioState Mar 30 23:14:56 volumio volumio[4682]: info: CoreStateMachine::getcurrentVolume Mar 30 23:14:56 volumio volumio[4682]: info: CoreCommandRouter::volumioRetrievevolume Mar 30 23:14:56 volumio volumio[4682]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 30 23:14:56 volumio volumio[4682]: Starting albumart workers Mar 30 23:14:56 volumio volumio[4682]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Mar 30 23:14:56 volumio volumio[4682]: info: Completed loading Core Plugins Mar 30 23:14:56 volumio volumio[4682]: info: Preparing to generate the ALSA configuration file Mar 30 23:14:56 volumio volumio[4682]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Mar 30 23:14:56 volumio volumio[4682]: wlan0 Interface doesn't support scanning. Mar 30 23:14:56 volumio volumio[4682]: info: Cannot use regular scanning, forcing with ap-force Mar 30 23:14:56 volumio sudo[4791]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Mar 30 23:14:56 volumio sudo[4791]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 23:14:56 volumio sudo[4791]: pam_unix(sudo:session): session closed for user root Mar 30 23:14:56 volumio volumio[4682]: command failed: No such device (-19) Mar 30 23:14:56 volumio volumio[4682]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Mar 30 23:14:56 volumio volumio[4682]: command failed: No such device (-19) Mar 30 23:14:56 volumio volumio[4682]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Mar 30 23:14:56 volumio volumio-remote-updater[595]: [2024-03-30 23:14:56] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1711829693 101 Mar 30 23:14:56 volumio volumio[4682]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1 Mar 30 23:14:56 volumio volumio[4682]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Mar 30 23:14:56 volumio volumio[4682]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 Mar 30 23:14:56 volumio volumio[4682]: info: Reloading queue from file Mar 30 23:14:56 volumio volumio[4682]: info: Asound.conf file unchanged, so no further update is needed Mar 30 23:14:56 volumio volumio[4682]: info: Output device has changed, restarting MPD Mar 30 23:14:56 volumio ntpd[1022]: Soliciting pool server 213.33.238.106 Mar 30 23:14:56 volumio sudo[4810]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 30 23:14:56 volumio volumio[4682]: info: Output device has changed, restarting Shairport Sync Mar 30 23:14:56 volumio sudo[4810]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 23:14:56 volumio volumio[4682]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 23:14:56 volumio volumio[4682]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 30 23:14:56 volumio sudo[4810]: pam_unix(sudo:session): session closed for user root Mar 30 23:14:56 volumio sudo[4813]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 30 23:14:56 volumio sudo[4813]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 23:14:56 volumio systemd[1]: musicservicesshield.service: Succeeded. Mar 30 23:14:56 volumio systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Mar 30 23:14:56 volumio systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Mar 30 23:14:56 volumio systemd[1]: Stopping Music Player Daemon... Mar 30 23:14:56 volumio volumio[4682]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 30 23:14:56 volumio volumio[4682]: info: ___________ START PLUGINS ___________ Mar 30 23:14:56 volumio systemd[1]: mpd.service: Succeeded. Mar 30 23:14:56 volumio volumio[4682]: info: ControllerMpd::onStart: Initializing MPD Mar 30 23:14:56 volumio volumio[4682]: info: Creating MPD Configuration file Mar 30 23:14:56 volumio systemd[1]: Stopped Music Player Daemon. Mar 30 23:14:56 volumio systemd[1]: Starting Music Player Daemon... Mar 30 23:14:56 volumio sudo[4828]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 30 23:14:56 volumio sudo[4828]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 23:14:56 volumio sudo[4828]: pam_unix(sudo:session): session closed for user root Mar 30 23:14:56 volumio volumio[4682]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 30 23:14:56 volumio volumio[4682]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 30 23:14:56 volumio volumio[4682]: info: [1711829696981] CoreMusicLibrary::Adding element Media Servers Mar 30 23:14:56 volumio volumio[4682]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 30 23:14:57 volumio volumio[4682]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 23:14:57 volumio volumio[4682]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 30 23:14:57 volumio sudo[4831]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 30 23:14:57 volumio sudo[4831]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 23:14:57 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Mar 30 23:14:57 volumio systemd[1]: mpd.service: Succeeded. Mar 30 23:14:57 volumio systemd[1]: Stopped Music Player Daemon. Mar 30 23:14:57 volumio volumio[4682]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 30 23:14:57 volumio volumio[4682]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 23:14:57 volumio volumio[4682]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 30 23:14:57 volumio systemd[1]: Starting Music Player Daemon... Mar 30 23:14:57 volumio volumio[4682]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 30 23:14:57 volumio volumio[4682]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 30 23:14:57 volumio volumio[4682]: info: [1711829697156] CoreMusicLibrary::Adding element Last_100 Mar 30 23:14:57 volumio volumio[4682]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 30 23:14:57 volumio volumio[4682]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 30 23:14:57 volumio volumio[4682]: info: [1711829697161] CoreMusicLibrary::Adding element Webradio Mar 30 23:14:57 volumio volumio[4682]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 30 23:14:57 volumio volumio[4682]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 30 23:14:57 volumio volumio[4682]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 30 23:14:57 volumio volumio[4682]: info: [1711829697183] CoreMusicLibrary::Adding element Bandcamp Discover Mar 30 23:14:57 volumio volumio[4682]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 30 23:14:57 volumio volumio[4682]: Cannot find translation for source Bandcamp Discover Mar 30 23:14:57 volumio volumio[4682]: info: Adding Calm Radio to Browse Sources Mar 30 23:14:57 volumio volumio[4682]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 30 23:14:57 volumio volumio[4682]: info: [1711829697191] CoreMusicLibrary::Adding element Calm Radio Mar 30 23:14:57 volumio volumio[4682]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 30 23:14:57 volumio volumio[4682]: Cannot find translation for source Bandcamp Discover Mar 30 23:14:57 volumio volumio[4682]: Cannot find translation for source Calm Radio Mar 30 23:14:57 volumio volumio[4682]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 30 23:14:57 volumio volumio[4682]: info: [1711829697204] CoreMusicLibrary::Adding element Mixcloud Mar 30 23:14:57 volumio volumio[4682]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 30 23:14:57 volumio volumio[4682]: Cannot find translation for source Bandcamp Discover Mar 30 23:14:57 volumio volumio[4682]: Cannot find translation for source Calm Radio Mar 30 23:14:57 volumio volumio[4682]: Cannot find translation for source Mixcloud Mar 30 23:14:57 volumio volumio[4682]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 30 23:14:57 volumio volumio[4682]: info: [1711829697218] CoreMusicLibrary::Adding element SoundCloud Mar 30 23:14:57 volumio volumio[4682]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 30 23:14:57 volumio volumio[4682]: Cannot find translation for source Bandcamp Discover Mar 30 23:14:57 volumio volumio[4682]: Cannot find translation for source Calm Radio Mar 30 23:14:57 volumio volumio[4682]: Cannot find translation for source Mixcloud Mar 30 23:14:57 volumio volumio[4682]: Cannot find translation for source SoundCloud Mar 30 23:14:57 volumio volumio[4682]: info: Creating Spotify config file Mar 30 23:14:57 volumio volumio[4682]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 23:14:57 volumio volumio[4682]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 30 23:14:57 volumio volumio[4682]: info: [1711829697255] CoreMusicLibrary::Adding element YouTube2 Mar 30 23:14:57 volumio volumio[4682]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 30 23:14:57 volumio volumio[4682]: Cannot find translation for source Bandcamp Discover Mar 30 23:14:57 volumio volumio[4682]: Cannot find translation for source Calm Radio Mar 30 23:14:57 volumio volumio[4682]: Cannot find translation for source Mixcloud Mar 30 23:14:57 volumio volumio[4682]: Cannot find translation for source SoundCloud Mar 30 23:14:57 volumio volumio[4682]: Cannot find translation for source YouTube2 Mar 30 23:14:57 volumio volumio[4682]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 30 23:14:57 volumio volumio[4682]: info: [1711829697295] CoreMusicLibrary::Adding element Mother Earth Radio Mar 30 23:14:57 volumio volumio[4682]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 30 23:14:57 volumio volumio[4682]: Cannot find translation for source Bandcamp Discover Mar 30 23:14:57 volumio volumio[4682]: Cannot find translation for source Calm Radio Mar 30 23:14:57 volumio volumio[4682]: Cannot find translation for source Mixcloud Mar 30 23:14:57 volumio volumio[4682]: Cannot find translation for source SoundCloud Mar 30 23:14:57 volumio volumio[4682]: Cannot find translation for source YouTube2 Mar 30 23:14:57 volumio volumio[4682]: Cannot find translation for source Mother Earth Radio Mar 30 23:14:57 volumio sudo[4841]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Mar 30 23:14:57 volumio sudo[4841]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 23:14:57 volumio sudo[4841]: pam_unix(sudo:session): session closed for user root Mar 30 23:14:57 volumio volumio[4682]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 30 23:14:57 volumio volumio[4682]: info: [1711829697574] CoreMusicLibrary::Adding element LastFM Mar 30 23:14:57 volumio volumio[4682]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 30 23:14:57 volumio volumio[4682]: Cannot find translation for source Bandcamp Discover Mar 30 23:14:57 volumio volumio[4682]: Cannot find translation for source Calm Radio Mar 30 23:14:57 volumio volumio[4682]: Cannot find translation for source Mixcloud Mar 30 23:14:57 volumio volumio[4682]: Cannot find translation for source SoundCloud Mar 30 23:14:57 volumio volumio[4682]: Cannot find translation for source YouTube2 Mar 30 23:14:57 volumio volumio[4682]: Cannot find translation for source Mother Earth Radio Mar 30 23:14:57 volumio volumio[4682]: Cannot find translation for source LastFM Mar 30 23:14:57 volumio volumio[4682]: info: [LastFM] scrobbler initiated! Mar 30 23:14:57 volumio volumio[4682]: info: [LastFM] extended logging: false Mar 30 23:14:57 volumio volumio[4682]: info: [LastFM] try scrobble stream/radio plays: true Mar 30 23:14:57 volumio volumio[4682]: info: [LastFM] Left init routine Mar 30 23:14:57 volumio volumio[4682]: info: [LastFM] Socket already connected: false Mar 30 23:14:57 volumio volumio[4682]: info: Volumio Calling Home Mar 30 23:14:57 volumio sudo[4869]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/data/plugins/system_hardware/music_services_shield/moveallprocesses.sh Mar 30 23:14:57 volumio sudo[4869]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 23:14:57 volumio volumio[4682]: info: CoreStateMachine::setRepeat null single undefined Mar 30 23:14:57 volumio volumio[4682]: info: CoreStateMachine::pushState Mar 30 23:14:57 volumio volumio[4682]: info: CorePlayQueue::getTrack 0 Mar 30 23:14:57 volumio volumio[4682]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 30 23:14:57 volumio volumio[4682]: info: CoreCommandRouter::volumioPushState Mar 30 23:14:57 volumio volumio[4682]: info: CoreStateMachine::setRandom null Mar 30 23:14:57 volumio volumio[4682]: info: CoreStateMachine::pushState Mar 30 23:14:57 volumio volumio[4682]: info: CorePlayQueue::getTrack 0 Mar 30 23:14:57 volumio volumio[4682]: info: CoreCommandRouter::volumioPushState Mar 30 23:14:57 volumio volumio[4682]: info: VolumeController:: Volume=undefined Mute =false Mar 30 23:14:57 volumio volumio[4682]: info: CoreStateMachine::pushState Mar 30 23:14:57 volumio volumio[4682]: info: CorePlayQueue::getTrack 0 Mar 30 23:14:57 volumio volumio[4682]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 30 23:14:57 volumio volumio[4682]: info: CoreCommandRouter::volumioPushState Mar 30 23:14:57 volumio volumio[4682]: info: CoreStateMachine::updateTrackBlock Mar 30 23:14:57 volumio volumio[4682]: info: CorePlayQueue::getTrackBlock Mar 30 23:14:57 volumio volumio[4682]: info: CoreCommandRouter::volumioRetrievevolume Mar 30 23:14:58 volumio volumio[4682]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 30 23:14:58 volumio volumio[4682]: Error: connect ENETUNREACH 130.211.19.189:80 - Local (0.0.0.0:0) Mar 30 23:14:58 volumio volumio[4682]: at internalConnect (net.js:934:16) Mar 30 23:14:58 volumio volumio[4682]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:452:18) Mar 30 23:14:58 volumio volumio[4682]: at GetAddrInfoReqWrap.emitLookup [as callback] (net.js:1077:9) Mar 30 23:14:58 volumio volumio[4682]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:73:8) { Mar 30 23:14:58 volumio volumio[4682]: errno: -101, Mar 30 23:14:58 volumio volumio[4682]: code: 'ENETUNREACH', Mar 30 23:14:58 volumio volumio[4682]: syscall: 'connect', Mar 30 23:14:58 volumio volumio[4682]: address: '130.211.19.189', Mar 30 23:14:58 volumio volumio[4682]: port: 80 Mar 30 23:14:58 volumio volumio[4682]: } Mar 30 23:14:58 volumio volumio[4682]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 30 23:14:58 volumio sudo[4869]: pam_unix(sudo:session): session closed for user root Mar 30 23:14:58 volumio ntpd[1022]: Soliciting pool server 188.225.9.167 Mar 30 23:14:58 volumio sudo[4898]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-03-30 23:13 Mar 30 23:14:58 volumio sudo[4898]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 23:14:59 volumio sudo[4898]: pam_unix(sudo:session): session closed for user root Mar 30 23:14:59 volumio volumio-remote-updater[595]: [2024-03-30 23:14:59] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Mar 30 23:14:59 volumio volumio-remote-updater[595]: [2024-03-30 23:14:59] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Mar 30 23:14:59 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Mar 30 23:14:59 volumio sudo[4813]: pam_unix(sudo:session): session closed for user root Mar 30 23:14:59 volumio sudo[4831]: pam_unix(sudo:session): session closed for user root Mar 30 23:14:59 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Mar 30 23:14:59 volumio systemd[1]: Started dynamicswap service. Mar 30 23:14:59 volumio systemd[1]: dynamicswap.service: Succeeded. Mar 30 23:14:59 volumio mpd[4836]: Mar 30 23:14 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 30 23:14:59 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Mar 30 23:14:59 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 16. Mar 30 23:14:59 volumio systemd[1]: Started Music Player Daemon. Mar 30 23:14:59 volumio systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Mar 30 23:14:59 volumio systemd[1]: Stopped Volumio Backend Module. Mar 30 23:14:59 volumio systemd[1]: Started Volumio Backend Module. Mar 30 23:14:59 volumio systemd[1]: Started dynamicswap service. Mar 30 23:14:59 volumio systemd[1]: dynamicswap.service: Succeeded. Mar 30 23:15:00 volumio volumio[4914]: cset: --> shielding system active with Mar 30 23:15:00 volumio volumio[4914]: cset: "system" cpuset of CPUSPEC(0-2) with 71 tasks running Mar 30 23:15:00 volumio volumio[4914]: cset: "user" cpuset of CPUSPEC(3) with 3 tasks running Mar 30 23:15:00 volumio systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Mar 30 23:15:00 volumio ntpd[1022]: Soliciting pool server 2a00:1390:5:722b:1337:c0de:4:11fe Mar 30 23:15:01 volumio volumio[4915]: info: ------------------------------------------- Mar 30 23:15:01 volumio volumio[4915]: info: ----- Volumio3 ---- Mar 30 23:15:01 volumio volumio[4915]: info: ------------------------------------------- Mar 30 23:15:01 volumio volumio[4915]: info: ----- System startup ---- Mar 30 23:15:01 volumio volumio[4915]: info: ------------------------------------------- Mar 30 23:15:02 volumio volumio[4915]: info: MYVOLUMIO Environment detected Mar 30 23:15:02 volumio volumio[4915]: info: Plugin folders cleanup Mar 30 23:15:02 volumio volumio[4915]: info: Scanning into folder /volumio/app/plugins/ Mar 30 23:15:02 volumio volumio[4915]: info: Scanning category audio_interface Mar 30 23:15:02 volumio volumio[4915]: info: Scanning category miscellanea Mar 30 23:15:02 volumio volumio[4915]: info: Scanning category music_service Mar 30 23:15:02 volumio volumio[4915]: info: Scanning category plugins.json Mar 30 23:15:02 volumio volumio[4915]: info: Scanning category system_controller Mar 30 23:15:02 volumio volumio[4915]: info: Scanning category user_interface Mar 30 23:15:02 volumio volumio[4915]: info: Scanning into folder /data/plugins/ Mar 30 23:15:02 volumio volumio[4915]: info: Scanning category music_service Mar 30 23:15:02 volumio volumio[4915]: info: Scanning category system_hardware Mar 30 23:15:02 volumio volumio[4915]: info: Scanning category user_interface Mar 30 23:15:02 volumio volumio[4915]: info: Plugin folders cleanup completed Mar 30 23:15:02 volumio volumio[4915]: info: ------------------------------------------- Mar 30 23:15:02 volumio volumio[4915]: info: ----- Core plugins startup ---- Mar 30 23:15:02 volumio volumio[4915]: info: ------------------------------------------- Mar 30 23:15:02 volumio volumio[4915]: info: Loading plugins from folder /volumio/app/plugins/ Mar 30 23:15:02 volumio volumio[4915]: info: Adding plugin upnp to MyMusic Plugins Mar 30 23:15:02 volumio volumio[4915]: info: Adding plugin airplay_emulation to MyMusic Plugins Mar 30 23:15:02 volumio volumio[4915]: info: Adding plugin upnp_browser to MyMusic Plugins Mar 30 23:15:02 volumio volumio[4915]: info: Loading plugins from folder /data/plugins/ Mar 30 23:15:02 volumio volumio[4915]: info: Loading plugin "system"... Mar 30 23:15:02 volumio volumio[4915]: info: Loading plugin "appearance"... Mar 30 23:15:03 volumio volumio[4915]: info: Loading plugin "network"... Mar 30 23:15:03 volumio volumio[4915]: info: Refreshing Cached IP Addresses Mar 30 23:15:03 volumio sudo[4956]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 30 23:15:03 volumio sudo[4956]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 23:15:03 volumio volumio[4915]: info: Loading plugin "services"... Mar 30 23:15:03 volumio sudo[4956]: pam_unix(sudo:session): session closed for user root Mar 30 23:15:03 volumio volumio[4915]: info: Loading plugin "alsa_controller"... Mar 30 23:15:03 volumio sudo[4958]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 30 23:15:03 volumio sudo[4958]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 23:15:03 volumio sudo[4958]: pam_unix(sudo:session): session closed for user root Mar 30 23:15:03 volumio sudo[4962]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Mar 30 23:15:03 volumio sudo[4962]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 23:15:03 volumio sudo[4962]: pam_unix(sudo:session): session closed for user root Mar 30 23:15:03 volumio volumio[4915]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 30 23:15:03 volumio volumio[4915]: info: Loading plugin "wizard"... Mar 30 23:15:03 volumio volumio[4915]: info: Loading plugin "networkfs"... Mar 30 23:15:04 volumio volumio[4915]: info: Starting Udev Watcher for removable devices Mar 30 23:15:04 volumio volumio[4915]: info: Ignoring mount for partition: boot Mar 30 23:15:04 volumio volumio[4915]: info: Ignoring mount for partition: volumio Mar 30 23:15:04 volumio volumio[4915]: info: Ignoring mount for partition: volumio_data Mar 30 23:15:04 volumio volumio[4915]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 30 23:15:04 volumio volumio[4915]: info: Loading plugin "volumio_command_line_client"... Mar 30 23:15:04 volumio volumio[4915]: info: Plugin upnp is not enabled Mar 30 23:15:04 volumio volumio[4915]: info: Loading plugin "my_music"... Mar 30 23:15:04 volumio volumio[4915]: info: Loading plugin "mpd"... Mar 30 23:15:04 volumio volumio-remote-updater[595]: [2024-03-30 23:15:04] [connect] Successful connection Mar 30 23:15:04 volumio volumio[4915]: info: Loading plugin "upnp_browser"... Mar 30 23:15:04 volumio volumio[4915]: info: Loading plugin "alarm-clock"... Mar 30 23:15:04 volumio volumio[4915]: info: Loading plugin "airplay_emulation"... Mar 30 23:15:04 volumio volumio[4915]: info: Starting Shairport Sync Mar 30 23:15:04 volumio volumio[4915]: info: Loading plugin "last_100"... Mar 30 23:15:04 volumio volumio[4915]: info: Loading plugin "webradio"... Mar 30 23:15:05 volumio volumio[4915]: info: Loading plugin "i2s_dacs"... Mar 30 23:15:05 volumio volumio[4915]: info: I2S DAC not set, start Auto-detection Mar 30 23:15:05 volumio volumio[4915]: info: Loading plugin "volumiodiscovery"... Mar 30 23:15:05 volumio volumio[4915]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 30 23:15:05 volumio node[4915]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 30 23:15:05 volumio node[4915]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 30 23:15:05 volumio volumio[4915]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 30 23:15:05 volumio volumio[4915]: *** WARNING *** For more information see Mar 30 23:15:05 volumio volumio[4915]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 30 23:15:05 volumio volumio[4915]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 30 23:15:05 volumio volumio[4915]: *** WARNING *** For more information see Mar 30 23:15:05 volumio node[4915]: *** WARNING *** For more information see Mar 30 23:15:05 volumio node[4915]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 30 23:15:05 volumio node[4915]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 30 23:15:05 volumio node[4915]: *** WARNING *** For more information see Mar 30 23:15:05 volumio volumio[4915]: info: Applying required configuration parameters for plugin volumiodiscovery Mar 30 23:15:05 volumio volumio[4915]: info: Discovery: Started advertising with name: VOLUMIO Mar 30 23:15:05 volumio volumio[4915]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 30 23:15:05 volumio volumio[4915]: info: Loading plugin "bandcamp"... Mar 30 23:15:06 volumio volumio[4915]: info: Loading plugin "calmradio"... Mar 30 23:15:07 volumio volumio[4915]: info: Loading plugin "mixcloud"... Mar 30 23:15:07 volumio volumio[4915]: info: Loading plugin "soundcloud"... Mar 30 23:15:08 volumio volumio[4915]: info: Loading plugin "spop"... Mar 30 23:15:09 volumio volumio[4915]: info: Loading plugin "youtube2"... Mar 30 23:15:10 volumio volumio[4915]: info: Loading plugin "outputs"... Mar 30 23:15:10 volumio volumio[4915]: info: Loading plugin "albumart"... Mar 30 23:15:10 volumio volumio[4915]: info: Plugin example_plugin is not enabled Mar 30 23:15:10 volumio volumio[4915]: info: Loading plugin "inputs"... Mar 30 23:15:10 volumio volumio[4915]: info: Loading plugin "updater_comm"... Mar 30 23:15:10 volumio volumio[4915]: info: Plugin mpdemulation is not enabled Mar 30 23:15:10 volumio volumio[4915]: info: Loading plugin "rest_api"... Mar 30 23:15:10 volumio volumio[4915]: info: Loading plugin "websocket"... Mar 30 23:15:10 volumio volumio[4915]: info: Loading plugin "RoonBridge"... Mar 30 23:15:10 volumio volumio[4915]: Forking 3 albumart workers Mar 30 23:15:10 volumio volumio[4915]: info: Applying required configuration parameters for plugin RoonBridge Mar 30 23:15:10 volumio volumio[4915]: info: Loading plugin "motherearthradio"... Mar 30 23:15:11 volumio volumio[4915]: info: Applying required configuration parameters for plugin motherearthradio Mar 30 23:15:11 volumio volumio[4915]: info: [1711829711372] [MotherEarth] API delay: 5 Mar 30 23:15:11 volumio volumio[4915]: info: Loading plugin "music_services_shield"... Mar 30 23:15:11 volumio volumio[4915]: info: Applying required configuration parameters for plugin music_services_shield Mar 30 23:15:11 volumio volumio[4915]: info: Loading plugin "lastfm"... Mar 30 23:15:12 volumio volumio[4915]: Starting albumart workers Mar 30 23:15:12 volumio volumio[4915]: Starting albumart workers Mar 30 23:15:12 volumio volumio[4915]: Starting albumart workers Mar 30 23:15:13 volumio volumio[4915]: info: Loading i18n strings for locale en Mar 30 23:15:13 volumio volumio[4915]: Updating browse sources language Mar 30 23:15:13 volumio volumio[4915]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 30 23:15:13 volumio volumio[4915]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 30 23:15:13 volumio volumio[4915]: info: CoreCommandRouter::initPlayerControls Mar 30 23:15:13 volumio volumio[4915]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 23:15:13 volumio volumio[4915]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 23:15:13 volumio volumio[4915]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 23:15:13 volumio volumio[4915]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 23:15:13 volumio volumio[4915]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 23:15:13 volumio volumio[4915]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 23:15:13 volumio volumio[4915]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 23:15:13 volumio volumio[4915]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 23:15:13 volumio volumio[4915]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 30 23:15:13 volumio volumio[4915]: Express server listening on port 3000 Mar 30 23:15:13 volumio volumio[4915]: [Metrics] WebUI: 12s 228.43ms Mar 30 23:15:13 volumio volumio[4915]: info: CoreStateMachine::resetVolumioState Mar 30 23:15:13 volumio volumio[4915]: info: CoreStateMachine::getcurrentVolume Mar 30 23:15:13 volumio volumio[4915]: info: CoreCommandRouter::volumioRetrievevolume Mar 30 23:15:13 volumio volumio[4915]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 30 23:15:13 volumio volumio[4915]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Mar 30 23:15:13 volumio volumio[4915]: info: Completed loading Core Plugins Mar 30 23:15:13 volumio volumio[4915]: info: Preparing to generate the ALSA configuration file Mar 30 23:15:13 volumio volumio[4915]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Mar 30 23:15:13 volumio volumio[4915]: wlan0 Interface doesn't support scanning. Mar 30 23:15:13 volumio volumio[4915]: info: Cannot use regular scanning, forcing with ap-force Mar 30 23:15:13 volumio sudo[5037]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Mar 30 23:15:13 volumio sudo[5037]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 23:15:13 volumio sudo[5037]: pam_unix(sudo:session): session closed for user root Mar 30 23:15:13 volumio volumio[4915]: command failed: No such device (-19) Mar 30 23:15:13 volumio volumio[4915]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Mar 30 23:15:13 volumio volumio[4915]: command failed: No such device (-19) Mar 30 23:15:13 volumio volumio[4915]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Mar 30 23:15:13 volumio volumio[4915]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Mar 30 23:15:13 volumio volumio[4915]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 1 Mar 30 23:15:13 volumio volumio[4915]: info: Reloading queue from file Mar 30 23:15:13 volumio volumio[4915]: info: VolumeController:: Volume=undefined Mute =false Mar 30 23:15:13 volumio volumio[4915]: info: CoreStateMachine::pushState Mar 30 23:15:13 volumio volumio[4915]: info: CorePlayQueue::getTrack 0 Mar 30 23:15:13 volumio volumio[4915]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 30 23:15:13 volumio volumio[4915]: info: CoreCommandRouter::volumioPushState Mar 30 23:15:13 volumio volumio[4915]: info: CoreStateMachine::updateTrackBlock Mar 30 23:15:13 volumio volumio[4915]: info: CorePlayQueue::getTrackBlock Mar 30 23:15:13 volumio volumio[4915]: info: CoreCommandRouter::volumioRetrievevolume Mar 30 23:15:13 volumio volumio[4915]: info: Asound.conf file unchanged, so no further update is needed Mar 30 23:15:13 volumio volumio[4915]: info: Output device has changed, restarting MPD Mar 30 23:15:13 volumio sudo[5049]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 30 23:15:13 volumio sudo[5049]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 23:15:13 volumio volumio[4915]: info: Output device has changed, restarting Shairport Sync Mar 30 23:15:13 volumio volumio[4915]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 23:15:13 volumio volumio[4915]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 30 23:15:13 volumio sudo[5049]: pam_unix(sudo:session): session closed for user root Mar 30 23:15:13 volumio sudo[5051]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 30 23:15:13 volumio sudo[5051]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 23:15:13 volumio systemd[1]: musicservicesshield.service: Succeeded. Mar 30 23:15:13 volumio systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Mar 30 23:15:13 volumio systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Mar 30 23:15:13 volumio systemd[1]: Stopping Music Player Daemon... Mar 30 23:15:13 volumio volumio[4915]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 30 23:15:13 volumio systemd[1]: mpd.service: Succeeded. Mar 30 23:15:13 volumio volumio[4915]: info: ___________ START PLUGINS ___________ Mar 30 23:15:13 volumio systemd[1]: Stopped Music Player Daemon. Mar 30 23:15:13 volumio volumio[4915]: info: ControllerMpd::onStart: Initializing MPD Mar 30 23:15:13 volumio volumio[4915]: info: Creating MPD Configuration file Mar 30 23:15:14 volumio systemd[1]: Starting Music Player Daemon... Mar 30 23:15:14 volumio sudo[5059]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 30 23:15:14 volumio sudo[5059]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 23:15:14 volumio sudo[5059]: pam_unix(sudo:session): session closed for user root Mar 30 23:15:14 volumio volumio[4915]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 30 23:15:14 volumio volumio[4915]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 30 23:15:14 volumio volumio[4915]: info: [1711829714035] CoreMusicLibrary::Adding element Media Servers Mar 30 23:15:14 volumio volumio[4915]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 30 23:15:14 volumio sudo[5062]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 30 23:15:14 volumio sudo[5062]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 23:15:14 volumio volumio[4915]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 23:15:14 volumio volumio[4915]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 30 23:15:14 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Mar 30 23:15:14 volumio systemd[1]: mpd.service: Succeeded. Mar 30 23:15:14 volumio systemd[1]: Stopped Music Player Daemon. Mar 30 23:15:14 volumio systemd[1]: Starting Music Player Daemon... Mar 30 23:15:14 volumio volumio[4915]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 30 23:15:14 volumio volumio[4915]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 23:15:14 volumio volumio[4915]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 30 23:15:14 volumio volumio[4915]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 30 23:15:14 volumio volumio[4915]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 30 23:15:14 volumio volumio[4915]: info: [1711829714209] CoreMusicLibrary::Adding element Last_100 Mar 30 23:15:14 volumio volumio[4915]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 30 23:15:14 volumio volumio[4915]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 30 23:15:14 volumio volumio[4915]: info: [1711829714214] CoreMusicLibrary::Adding element Webradio Mar 30 23:15:14 volumio volumio[4915]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 30 23:15:14 volumio volumio[4915]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 30 23:15:14 volumio volumio[4915]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 30 23:15:14 volumio volumio[4915]: info: [1711829714237] CoreMusicLibrary::Adding element Bandcamp Discover Mar 30 23:15:14 volumio volumio[4915]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 30 23:15:14 volumio volumio[4915]: Cannot find translation for source Bandcamp Discover Mar 30 23:15:14 volumio volumio[4915]: info: Adding Calm Radio to Browse Sources Mar 30 23:15:14 volumio volumio[4915]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 30 23:15:14 volumio volumio[4915]: info: [1711829714246] CoreMusicLibrary::Adding element Calm Radio Mar 30 23:15:14 volumio volumio[4915]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 30 23:15:14 volumio volumio[4915]: Cannot find translation for source Bandcamp Discover Mar 30 23:15:14 volumio volumio[4915]: Cannot find translation for source Calm Radio Mar 30 23:15:14 volumio volumio[4915]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 30 23:15:14 volumio volumio[4915]: info: [1711829714262] CoreMusicLibrary::Adding element Mixcloud Mar 30 23:15:14 volumio volumio[4915]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 30 23:15:14 volumio volumio[4915]: Cannot find translation for source Bandcamp Discover Mar 30 23:15:14 volumio volumio[4915]: Cannot find translation for source Calm Radio Mar 30 23:15:14 volumio volumio[4915]: Cannot find translation for source Mixcloud Mar 30 23:15:14 volumio volumio[4915]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 30 23:15:14 volumio volumio[4915]: info: [1711829714278] CoreMusicLibrary::Adding element SoundCloud Mar 30 23:15:14 volumio volumio[4915]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 30 23:15:14 volumio volumio[4915]: Cannot find translation for source Bandcamp Discover Mar 30 23:15:14 volumio volumio[4915]: Cannot find translation for source Calm Radio Mar 30 23:15:14 volumio volumio[4915]: Cannot find translation for source Mixcloud Mar 30 23:15:14 volumio volumio[4915]: Cannot find translation for source SoundCloud Mar 30 23:15:14 volumio volumio[4915]: info: Creating Spotify config file Mar 30 23:15:14 volumio volumio[4915]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 23:15:14 volumio volumio[4915]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 30 23:15:14 volumio volumio[4915]: info: [1711829714314] CoreMusicLibrary::Adding element YouTube2 Mar 30 23:15:14 volumio volumio[4915]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 30 23:15:14 volumio volumio[4915]: Cannot find translation for source Bandcamp Discover Mar 30 23:15:14 volumio volumio[4915]: Cannot find translation for source Calm Radio Mar 30 23:15:14 volumio volumio[4915]: Cannot find translation for source Mixcloud Mar 30 23:15:14 volumio volumio[4915]: Cannot find translation for source SoundCloud Mar 30 23:15:14 volumio volumio[4915]: Cannot find translation for source YouTube2 Mar 30 23:15:14 volumio volumio[4915]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 30 23:15:14 volumio volumio[4915]: info: [1711829714353] CoreMusicLibrary::Adding element Mother Earth Radio Mar 30 23:15:14 volumio volumio[4915]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 30 23:15:14 volumio volumio[4915]: Cannot find translation for source Bandcamp Discover Mar 30 23:15:14 volumio volumio[4915]: Cannot find translation for source Calm Radio Mar 30 23:15:14 volumio volumio[4915]: Cannot find translation for source Mixcloud Mar 30 23:15:14 volumio volumio[4915]: Cannot find translation for source SoundCloud Mar 30 23:15:14 volumio volumio[4915]: Cannot find translation for source YouTube2 Mar 30 23:15:14 volumio volumio[4915]: Cannot find translation for source Mother Earth Radio Mar 30 23:15:14 volumio sudo[5072]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Mar 30 23:15:14 volumio sudo[5072]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 23:15:14 volumio sudo[5072]: pam_unix(sudo:session): session closed for user root Mar 30 23:15:14 volumio volumio[4915]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 30 23:15:14 volumio volumio[4915]: info: [1711829714627] CoreMusicLibrary::Adding element LastFM Mar 30 23:15:14 volumio volumio[4915]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 30 23:15:14 volumio volumio[4915]: Cannot find translation for source Bandcamp Discover Mar 30 23:15:14 volumio volumio[4915]: Cannot find translation for source Calm Radio Mar 30 23:15:14 volumio volumio[4915]: Cannot find translation for source Mixcloud Mar 30 23:15:14 volumio volumio[4915]: Cannot find translation for source SoundCloud Mar 30 23:15:14 volumio volumio[4915]: Cannot find translation for source YouTube2 Mar 30 23:15:14 volumio volumio[4915]: Cannot find translation for source Mother Earth Radio Mar 30 23:15:14 volumio volumio[4915]: Cannot find translation for source LastFM Mar 30 23:15:14 volumio volumio[4915]: info: [LastFM] scrobbler initiated! Mar 30 23:15:14 volumio volumio[4915]: info: [LastFM] extended logging: false Mar 30 23:15:14 volumio volumio[4915]: info: [LastFM] try scrobble stream/radio plays: true Mar 30 23:15:14 volumio volumio[4915]: info: [LastFM] Left init routine Mar 30 23:15:14 volumio volumio[4915]: info: [LastFM] Socket already connected: false Mar 30 23:15:14 volumio volumio[4915]: info: Volumio Calling Home Mar 30 23:15:14 volumio sudo[5100]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/data/plugins/system_hardware/music_services_shield/moveallprocesses.sh Mar 30 23:15:14 volumio sudo[5100]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 23:15:14 volumio volumio[4915]: info: CoreStateMachine::setRepeat null single undefined Mar 30 23:15:14 volumio volumio[4915]: info: CoreStateMachine::pushState Mar 30 23:15:14 volumio volumio[4915]: info: CorePlayQueue::getTrack 0 Mar 30 23:15:14 volumio volumio[4915]: info: CoreCommandRouter::volumioPushState Mar 30 23:15:14 volumio volumio[4915]: info: CoreStateMachine::setRandom null Mar 30 23:15:14 volumio volumio[4915]: info: CoreStateMachine::pushState Mar 30 23:15:14 volumio volumio[4915]: info: CorePlayQueue::getTrack 0 Mar 30 23:15:14 volumio volumio[4915]: info: CoreCommandRouter::volumioPushState Mar 30 23:15:14 volumio volumio[4915]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 30 23:15:14 volumio volumio[4915]: Error: connect ENETUNREACH 130.211.19.189:80 - Local (0.0.0.0:0) Mar 30 23:15:14 volumio volumio[4915]: at internalConnect (net.js:934:16) Mar 30 23:15:14 volumio volumio[4915]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:452:18) Mar 30 23:15:14 volumio volumio[4915]: at GetAddrInfoReqWrap.emitLookup [as callback] (net.js:1077:9) Mar 30 23:15:14 volumio volumio[4915]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:73:8) { Mar 30 23:15:14 volumio volumio[4915]: errno: -101, Mar 30 23:15:14 volumio volumio[4915]: code: 'ENETUNREACH', Mar 30 23:15:14 volumio volumio[4915]: syscall: 'connect', Mar 30 23:15:14 volumio volumio[4915]: address: '130.211.19.189', Mar 30 23:15:14 volumio volumio[4915]: port: 80 Mar 30 23:15:14 volumio volumio[4915]: } Mar 30 23:15:14 volumio volumio[4915]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 30 23:15:15 volumio sudo[5100]: pam_unix(sudo:session): session closed for user root Mar 30 23:15:15 volumio sudo[5128]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-03-30 23:14 Mar 30 23:15:15 volumio sudo[5128]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 23:15:15 volumio sudo[5128]: pam_unix(sudo:session): session closed for user root Mar 30 23:15:16 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Mar 30 23:15:16 volumio sudo[5062]: pam_unix(sudo:session): session closed for user root Mar 30 23:15:16 volumio sudo[5051]: pam_unix(sudo:session): session closed for user root Mar 30 23:15:16 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Mar 30 23:15:16 volumio systemd[1]: Started dynamicswap service. Mar 30 23:15:16 volumio systemd[1]: dynamicswap.service: Succeeded. Mar 30 23:15:16 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Mar 30 23:15:16 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 17. Mar 30 23:15:16 volumio systemd[1]: Started dynamicswap service. Mar 30 23:15:16 volumio systemd[1]: Stopped Volumio Backend Module. Mar 30 23:15:16 volumio systemd[1]: Started Volumio Backend Module. Mar 30 23:15:16 volumio systemd[1]: dynamicswap.service: Succeeded. Mar 30 23:15:16 volumio mpd[5067]: Mar 30 23:15 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 30 23:15:16 volumio systemd[1]: Started Music Player Daemon. Mar 30 23:15:16 volumio systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Mar 30 23:15:16 volumio volumio-remote-updater[595]: [2024-03-30 23:15:16] [info] asio async_connect error: system:111 (Connection refused) Mar 30 23:15:16 volumio volumio-remote-updater[595]: [2024-03-30 23:15:16] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Mar 30 23:15:16 volumio volumio-remote-updater[595]: [2024-03-30 23:15:16] [error] handle_connect error: Underlying Transport Error Mar 30 23:15:17 volumio volumio[5156]: cset: --> shielding system active with Mar 30 23:15:17 volumio volumio[5156]: cset: "system" cpuset of CPUSPEC(0-2) with 71 tasks running Mar 30 23:15:17 volumio volumio[5156]: cset: "user" cpuset of CPUSPEC(3) with 3 tasks running Mar 30 23:15:17 volumio systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Mar 30 23:15:18 volumio volumio[5142]: info: ------------------------------------------- Mar 30 23:15:18 volumio volumio[5142]: info: ----- Volumio3 ---- Mar 30 23:15:18 volumio volumio[5142]: info: ------------------------------------------- Mar 30 23:15:18 volumio volumio[5142]: info: ----- System startup ---- Mar 30 23:15:18 volumio volumio[5142]: info: ------------------------------------------- Mar 30 23:15:19 volumio volumio[5142]: info: MYVOLUMIO Environment detected Mar 30 23:15:19 volumio volumio[5142]: info: Plugin folders cleanup Mar 30 23:15:19 volumio volumio[5142]: info: Scanning into folder /volumio/app/plugins/ Mar 30 23:15:19 volumio volumio[5142]: info: Scanning category audio_interface Mar 30 23:15:19 volumio volumio[5142]: info: Scanning category miscellanea Mar 30 23:15:19 volumio volumio[5142]: info: Scanning category music_service Mar 30 23:15:19 volumio volumio[5142]: info: Scanning category plugins.json Mar 30 23:15:19 volumio volumio[5142]: info: Scanning category system_controller Mar 30 23:15:19 volumio volumio[5142]: info: Scanning category user_interface Mar 30 23:15:19 volumio volumio[5142]: info: Scanning into folder /data/plugins/ Mar 30 23:15:19 volumio volumio[5142]: info: Scanning category music_service Mar 30 23:15:19 volumio volumio[5142]: info: Scanning category system_hardware Mar 30 23:15:19 volumio volumio[5142]: info: Scanning category user_interface Mar 30 23:15:19 volumio volumio[5142]: info: Plugin folders cleanup completed Mar 30 23:15:19 volumio volumio[5142]: info: ------------------------------------------- Mar 30 23:15:19 volumio volumio[5142]: info: ----- Core plugins startup ---- Mar 30 23:15:19 volumio volumio[5142]: info: ------------------------------------------- Mar 30 23:15:19 volumio volumio[5142]: info: Loading plugins from folder /volumio/app/plugins/ Mar 30 23:15:19 volumio volumio[5142]: info: Adding plugin upnp to MyMusic Plugins Mar 30 23:15:19 volumio volumio[5142]: info: Adding plugin airplay_emulation to MyMusic Plugins Mar 30 23:15:19 volumio volumio[5142]: info: Adding plugin upnp_browser to MyMusic Plugins Mar 30 23:15:19 volumio volumio[5142]: info: Loading plugins from folder /data/plugins/ Mar 30 23:15:19 volumio volumio[5142]: info: Loading plugin "system"... Mar 30 23:15:19 volumio volumio[5142]: info: Loading plugin "appearance"... Mar 30 23:15:20 volumio volumio[5142]: info: Loading plugin "network"... Mar 30 23:15:20 volumio volumio[5142]: info: Refreshing Cached IP Addresses Mar 30 23:15:20 volumio sudo[5224]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 30 23:15:20 volumio sudo[5224]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 23:15:20 volumio volumio[5142]: info: Loading plugin "services"... Mar 30 23:15:20 volumio sudo[5224]: pam_unix(sudo:session): session closed for user root Mar 30 23:15:20 volumio sudo[5226]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 30 23:15:20 volumio volumio[5142]: info: Loading plugin "alsa_controller"... Mar 30 23:15:20 volumio sudo[5226]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 23:15:20 volumio sudo[5226]: pam_unix(sudo:session): session closed for user root Mar 30 23:15:20 volumio sudo[5230]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Mar 30 23:15:20 volumio sudo[5230]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 23:15:20 volumio sudo[5230]: pam_unix(sudo:session): session closed for user root Mar 30 23:15:20 volumio volumio[5142]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 30 23:15:20 volumio volumio[5142]: info: Loading plugin "wizard"... Mar 30 23:15:20 volumio volumio[5142]: info: Loading plugin "networkfs"... Mar 30 23:15:20 volumio volumio[5142]: info: Starting Udev Watcher for removable devices Mar 30 23:15:20 volumio volumio[5142]: info: Ignoring mount for partition: boot Mar 30 23:15:20 volumio volumio[5142]: info: Ignoring mount for partition: volumio Mar 30 23:15:20 volumio volumio[5142]: info: Ignoring mount for partition: volumio_data Mar 30 23:15:20 volumio volumio[5142]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 30 23:15:20 volumio volumio[5142]: info: Loading plugin "volumio_command_line_client"... Mar 30 23:15:20 volumio volumio[5142]: info: Plugin upnp is not enabled Mar 30 23:15:20 volumio volumio[5142]: info: Loading plugin "my_music"... Mar 30 23:15:20 volumio volumio[5142]: info: Loading plugin "mpd"... Mar 30 23:15:21 volumio volumio[5142]: info: Loading plugin "upnp_browser"... Mar 30 23:15:21 volumio volumio[5142]: info: Loading plugin "alarm-clock"... Mar 30 23:15:21 volumio volumio[5142]: info: Loading plugin "airplay_emulation"... Mar 30 23:15:21 volumio volumio[5142]: info: Starting Shairport Sync Mar 30 23:15:21 volumio volumio[5142]: info: Loading plugin "last_100"... Mar 30 23:15:21 volumio volumio[5142]: info: Loading plugin "webradio"... Mar 30 23:15:21 volumio volumio[5142]: info: Loading plugin "i2s_dacs"... Mar 30 23:15:21 volumio volumio[5142]: info: I2S DAC not set, start Auto-detection Mar 30 23:15:21 volumio volumio[5142]: info: Loading plugin "volumiodiscovery"... Mar 30 23:15:21 volumio volumio[5142]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 30 23:15:21 volumio volumio[5142]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 30 23:15:21 volumio node[5142]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 30 23:15:21 volumio volumio[5142]: *** WARNING *** For more information see Mar 30 23:15:21 volumio volumio[5142]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 30 23:15:21 volumio volumio[5142]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 30 23:15:21 volumio volumio[5142]: *** WARNING *** For more information see Mar 30 23:15:21 volumio node[5142]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 30 23:15:21 volumio node[5142]: *** WARNING *** For more information see Mar 30 23:15:21 volumio node[5142]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 30 23:15:21 volumio node[5142]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 30 23:15:21 volumio node[5142]: *** WARNING *** For more information see Mar 30 23:15:21 volumio volumio[5142]: info: Applying required configuration parameters for plugin volumiodiscovery Mar 30 23:15:22 volumio volumio[5142]: info: Discovery: Started advertising with name: VOLUMIO Mar 30 23:15:22 volumio volumio[5142]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 30 23:15:22 volumio volumio[5142]: info: Loading plugin "bandcamp"... Mar 30 23:15:23 volumio volumio[5142]: info: Loading plugin "calmradio"... Mar 30 23:15:24 volumio volumio[5142]: info: Loading plugin "mixcloud"... Mar 30 23:15:24 volumio volumio[5142]: info: Loading plugin "soundcloud"... Mar 30 23:15:24 volumio volumio[5142]: info: Loading plugin "spop"... Mar 30 23:15:26 volumio volumio[5142]: info: Loading plugin "youtube2"... Mar 30 23:15:26 volumio volumio-remote-updater[595]: [2024-03-30 23:15:26] [connect] Successful connection Mar 30 23:15:27 volumio volumio[5142]: info: Loading plugin "outputs"... Mar 30 23:15:27 volumio volumio[5142]: info: Loading plugin "albumart"... Mar 30 23:15:27 volumio volumio[5142]: info: Plugin example_plugin is not enabled Mar 30 23:15:27 volumio volumio[5142]: info: Loading plugin "inputs"... Mar 30 23:15:27 volumio volumio[5142]: info: Loading plugin "updater_comm"... Mar 30 23:15:27 volumio volumio[5142]: info: Plugin mpdemulation is not enabled Mar 30 23:15:27 volumio volumio[5142]: info: Loading plugin "rest_api"... Mar 30 23:15:27 volumio volumio[5142]: info: Loading plugin "websocket"... Mar 30 23:15:27 volumio volumio[5142]: info: Loading plugin "RoonBridge"... Mar 30 23:15:27 volumio volumio[5142]: Forking 3 albumart workers Mar 30 23:15:27 volumio volumio[5142]: info: Applying required configuration parameters for plugin RoonBridge Mar 30 23:15:27 volumio volumio[5142]: info: Loading plugin "motherearthradio"... Mar 30 23:15:28 volumio volumio[5142]: info: Applying required configuration parameters for plugin motherearthradio Mar 30 23:15:28 volumio volumio[5142]: info: [1711829728289] [MotherEarth] API delay: 5 Mar 30 23:15:28 volumio volumio[5142]: info: Loading plugin "music_services_shield"... Mar 30 23:15:28 volumio volumio[5142]: Starting albumart workers Mar 30 23:15:28 volumio volumio[5142]: info: Applying required configuration parameters for plugin music_services_shield Mar 30 23:15:28 volumio volumio[5142]: info: Loading plugin "lastfm"... Mar 30 23:15:28 volumio volumio[5142]: Starting albumart workers Mar 30 23:15:29 volumio volumio[5142]: Starting albumart workers Mar 30 23:15:29 volumio volumio[5142]: info: Loading i18n strings for locale en Mar 30 23:15:29 volumio volumio[5142]: Updating browse sources language Mar 30 23:15:29 volumio volumio[5142]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 30 23:15:29 volumio volumio[5142]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 30 23:15:29 volumio volumio[5142]: info: CoreCommandRouter::initPlayerControls Mar 30 23:15:29 volumio volumio[5142]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 23:15:29 volumio volumio[5142]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 23:15:30 volumio volumio[5142]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 23:15:30 volumio volumio[5142]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 23:15:30 volumio volumio[5142]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 23:15:30 volumio volumio[5142]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 23:15:30 volumio volumio[5142]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 23:15:30 volumio volumio[5142]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 23:15:30 volumio volumio[5142]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 30 23:15:30 volumio volumio[5142]: Express server listening on port 3000 Mar 30 23:15:30 volumio volumio[5142]: [Metrics] WebUI: 12s 228.66ms Mar 30 23:15:30 volumio volumio[5142]: info: CoreStateMachine::resetVolumioState Mar 30 23:15:30 volumio volumio[5142]: info: CoreStateMachine::getcurrentVolume Mar 30 23:15:30 volumio volumio[5142]: info: CoreCommandRouter::volumioRetrievevolume Mar 30 23:15:30 volumio volumio[5142]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 30 23:15:30 volumio volumio[5142]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Mar 30 23:15:30 volumio volumio[5142]: info: Completed loading Core Plugins Mar 30 23:15:30 volumio volumio[5142]: info: Preparing to generate the ALSA configuration file Mar 30 23:15:30 volumio volumio[5142]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Mar 30 23:15:30 volumio volumio[5142]: wlan0 Interface doesn't support scanning. Mar 30 23:15:30 volumio volumio[5142]: info: Cannot use regular scanning, forcing with ap-force Mar 30 23:15:30 volumio sudo[5302]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Mar 30 23:15:30 volumio sudo[5302]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 23:15:30 volumio sudo[5302]: pam_unix(sudo:session): session closed for user root Mar 30 23:15:30 volumio volumio[5142]: command failed: No such device (-19) Mar 30 23:15:30 volumio volumio[5142]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Mar 30 23:15:30 volumio volumio[5142]: command failed: No such device (-19) Mar 30 23:15:30 volumio volumio[5142]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Mar 30 23:15:30 volumio volumio-remote-updater[595]: [2024-03-30 23:15:30] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1711829726 101 Mar 30 23:15:30 volumio volumio[5142]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1 Mar 30 23:15:30 volumio volumio[5142]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Mar 30 23:15:30 volumio volumio[5142]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 Mar 30 23:15:30 volumio volumio[5142]: info: Reloading queue from file Mar 30 23:15:30 volumio volumio[5142]: info: Asound.conf file unchanged, so no further update is needed Mar 30 23:15:30 volumio volumio[5142]: info: Output device has changed, restarting MPD Mar 30 23:15:30 volumio sudo[5317]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 30 23:15:30 volumio sudo[5317]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 23:15:30 volumio volumio[5142]: info: Output device has changed, restarting Shairport Sync Mar 30 23:15:30 volumio volumio[5142]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 23:15:30 volumio sudo[5317]: pam_unix(sudo:session): session closed for user root Mar 30 23:15:30 volumio volumio[5142]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 30 23:15:30 volumio sudo[5319]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 30 23:15:30 volumio sudo[5319]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 23:15:30 volumio systemd[1]: musicservicesshield.service: Succeeded. Mar 30 23:15:30 volumio systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Mar 30 23:15:30 volumio systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Mar 30 23:15:30 volumio systemd[1]: Stopping Music Player Daemon... Mar 30 23:15:30 volumio volumio[5142]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 30 23:15:30 volumio systemd[1]: mpd.service: Succeeded. Mar 30 23:15:30 volumio volumio[5142]: info: ___________ START PLUGINS ___________ Mar 30 23:15:30 volumio systemd[1]: Stopped Music Player Daemon. Mar 30 23:15:30 volumio volumio[5142]: info: ControllerMpd::onStart: Initializing MPD Mar 30 23:15:30 volumio volumio[5142]: info: Creating MPD Configuration file Mar 30 23:15:30 volumio systemd[1]: Starting Music Player Daemon... Mar 30 23:15:30 volumio volumio[5142]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 30 23:15:30 volumio volumio[5142]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 30 23:15:30 volumio volumio[5142]: info: [1711829730781] CoreMusicLibrary::Adding element Media Servers Mar 30 23:15:30 volumio volumio[5142]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 30 23:15:30 volumio sudo[5327]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 30 23:15:30 volumio sudo[5327]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 23:15:30 volumio sudo[5327]: pam_unix(sudo:session): session closed for user root Mar 30 23:15:30 volumio volumio[5142]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 23:15:30 volumio volumio[5142]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 30 23:15:30 volumio sudo[5331]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 30 23:15:30 volumio sudo[5331]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 23:15:30 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Mar 30 23:15:30 volumio systemd[1]: mpd.service: Succeeded. Mar 30 23:15:30 volumio systemd[1]: Stopped Music Player Daemon. Mar 30 23:15:30 volumio volumio[5142]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 30 23:15:30 volumio volumio[5142]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 23:15:30 volumio volumio[5142]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 30 23:15:30 volumio systemd[1]: Starting Music Player Daemon... Mar 30 23:15:30 volumio volumio[5142]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 30 23:15:30 volumio volumio[5142]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 30 23:15:30 volumio volumio[5142]: info: [1711829730967] CoreMusicLibrary::Adding element Last_100 Mar 30 23:15:30 volumio volumio[5142]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 30 23:15:30 volumio volumio[5142]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 30 23:15:30 volumio volumio[5142]: info: [1711829730972] CoreMusicLibrary::Adding element Webradio Mar 30 23:15:30 volumio volumio[5142]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 30 23:15:30 volumio volumio[5142]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 30 23:15:30 volumio volumio[5142]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 30 23:15:30 volumio volumio[5142]: info: [1711829730993] CoreMusicLibrary::Adding element Bandcamp Discover Mar 30 23:15:30 volumio volumio[5142]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 30 23:15:30 volumio volumio[5142]: Cannot find translation for source Bandcamp Discover Mar 30 23:15:30 volumio volumio[5142]: info: Adding Calm Radio to Browse Sources Mar 30 23:15:31 volumio volumio[5142]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 30 23:15:31 volumio volumio[5142]: info: [1711829731000] CoreMusicLibrary::Adding element Calm Radio Mar 30 23:15:31 volumio volumio[5142]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 30 23:15:31 volumio volumio[5142]: Cannot find translation for source Bandcamp Discover Mar 30 23:15:31 volumio volumio[5142]: Cannot find translation for source Calm Radio Mar 30 23:15:31 volumio volumio[5142]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 30 23:15:31 volumio volumio[5142]: info: [1711829731013] CoreMusicLibrary::Adding element Mixcloud Mar 30 23:15:31 volumio volumio[5142]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 30 23:15:31 volumio volumio[5142]: Cannot find translation for source Bandcamp Discover Mar 30 23:15:31 volumio volumio[5142]: Cannot find translation for source Calm Radio Mar 30 23:15:31 volumio volumio[5142]: Cannot find translation for source Mixcloud Mar 30 23:15:31 volumio volumio[5142]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 30 23:15:31 volumio volumio[5142]: info: [1711829731028] CoreMusicLibrary::Adding element SoundCloud Mar 30 23:15:31 volumio volumio[5142]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 30 23:15:31 volumio volumio[5142]: Cannot find translation for source Bandcamp Discover Mar 30 23:15:31 volumio volumio[5142]: Cannot find translation for source Calm Radio Mar 30 23:15:31 volumio volumio[5142]: Cannot find translation for source Mixcloud Mar 30 23:15:31 volumio volumio[5142]: Cannot find translation for source SoundCloud Mar 30 23:15:31 volumio volumio[5142]: info: Creating Spotify config file Mar 30 23:15:31 volumio volumio[5142]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 23:15:31 volumio volumio[5142]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 30 23:15:31 volumio volumio[5142]: info: [1711829731063] CoreMusicLibrary::Adding element YouTube2 Mar 30 23:15:31 volumio volumio[5142]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 30 23:15:31 volumio volumio[5142]: Cannot find translation for source Bandcamp Discover Mar 30 23:15:31 volumio volumio[5142]: Cannot find translation for source Calm Radio Mar 30 23:15:31 volumio volumio[5142]: Cannot find translation for source Mixcloud Mar 30 23:15:31 volumio volumio[5142]: Cannot find translation for source SoundCloud Mar 30 23:15:31 volumio volumio[5142]: Cannot find translation for source YouTube2 Mar 30 23:15:31 volumio volumio[5142]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 30 23:15:31 volumio volumio[5142]: info: [1711829731102] CoreMusicLibrary::Adding element Mother Earth Radio Mar 30 23:15:31 volumio volumio[5142]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 30 23:15:31 volumio volumio[5142]: Cannot find translation for source Bandcamp Discover Mar 30 23:15:31 volumio volumio[5142]: Cannot find translation for source Calm Radio Mar 30 23:15:31 volumio volumio[5142]: Cannot find translation for source Mixcloud Mar 30 23:15:31 volumio volumio[5142]: Cannot find translation for source SoundCloud Mar 30 23:15:31 volumio volumio[5142]: Cannot find translation for source YouTube2 Mar 30 23:15:31 volumio volumio[5142]: Cannot find translation for source Mother Earth Radio Mar 30 23:15:31 volumio sudo[5341]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Mar 30 23:15:31 volumio sudo[5341]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 23:15:31 volumio sudo[5341]: pam_unix(sudo:session): session closed for user root Mar 30 23:15:31 volumio volumio[5142]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 30 23:15:31 volumio volumio[5142]: info: [1711829731385] CoreMusicLibrary::Adding element LastFM Mar 30 23:15:31 volumio volumio[5142]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 30 23:15:31 volumio volumio[5142]: Cannot find translation for source Bandcamp Discover Mar 30 23:15:31 volumio volumio[5142]: Cannot find translation for source Calm Radio Mar 30 23:15:31 volumio volumio[5142]: Cannot find translation for source Mixcloud Mar 30 23:15:31 volumio volumio[5142]: Cannot find translation for source SoundCloud Mar 30 23:15:31 volumio volumio[5142]: Cannot find translation for source YouTube2 Mar 30 23:15:31 volumio volumio[5142]: Cannot find translation for source Mother Earth Radio Mar 30 23:15:31 volumio volumio[5142]: Cannot find translation for source LastFM Mar 30 23:15:31 volumio volumio[5142]: info: [LastFM] scrobbler initiated! Mar 30 23:15:31 volumio volumio[5142]: info: [LastFM] extended logging: false Mar 30 23:15:31 volumio volumio[5142]: info: [LastFM] try scrobble stream/radio plays: true Mar 30 23:15:31 volumio volumio[5142]: info: [LastFM] Left init routine Mar 30 23:15:31 volumio volumio[5142]: info: [LastFM] Socket already connected: false Mar 30 23:15:31 volumio volumio[5142]: info: Volumio Calling Home Mar 30 23:15:31 volumio sudo[5369]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/data/plugins/system_hardware/music_services_shield/moveallprocesses.sh Mar 30 23:15:31 volumio sudo[5369]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 23:15:31 volumio volumio[5142]: info: CoreStateMachine::setRepeat null single undefined Mar 30 23:15:31 volumio volumio[5142]: info: CoreStateMachine::pushState Mar 30 23:15:31 volumio volumio[5142]: info: CorePlayQueue::getTrack 0 Mar 30 23:15:31 volumio volumio[5142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 30 23:15:31 volumio volumio[5142]: info: CoreCommandRouter::volumioPushState Mar 30 23:15:31 volumio volumio[5142]: info: CoreStateMachine::setRandom null Mar 30 23:15:31 volumio volumio[5142]: info: CoreStateMachine::pushState Mar 30 23:15:31 volumio volumio[5142]: info: CorePlayQueue::getTrack 0 Mar 30 23:15:31 volumio volumio[5142]: info: CoreCommandRouter::volumioPushState Mar 30 23:15:31 volumio volumio[5142]: info: VolumeController:: Volume=undefined Mute =false Mar 30 23:15:31 volumio volumio[5142]: info: CoreStateMachine::pushState Mar 30 23:15:31 volumio volumio[5142]: info: CorePlayQueue::getTrack 0 Mar 30 23:15:31 volumio volumio[5142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 30 23:15:31 volumio volumio[5142]: info: CoreCommandRouter::volumioPushState Mar 30 23:15:31 volumio volumio[5142]: info: CoreStateMachine::updateTrackBlock Mar 30 23:15:31 volumio volumio[5142]: info: CorePlayQueue::getTrackBlock Mar 30 23:15:31 volumio volumio[5142]: info: CoreCommandRouter::volumioRetrievevolume Mar 30 23:15:31 volumio volumio[5142]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 30 23:15:31 volumio volumio[5142]: Error: connect ENETUNREACH 130.211.19.189:80 - Local (0.0.0.0:0) Mar 30 23:15:31 volumio volumio[5142]: at internalConnect (net.js:934:16) Mar 30 23:15:31 volumio volumio[5142]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:452:18) Mar 30 23:15:31 volumio volumio[5142]: at GetAddrInfoReqWrap.emitLookup [as callback] (net.js:1077:9) Mar 30 23:15:31 volumio volumio[5142]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:73:8) { Mar 30 23:15:31 volumio volumio[5142]: errno: -101, Mar 30 23:15:31 volumio volumio[5142]: code: 'ENETUNREACH', Mar 30 23:15:31 volumio volumio[5142]: syscall: 'connect', Mar 30 23:15:31 volumio volumio[5142]: address: '130.211.19.189', Mar 30 23:15:31 volumio volumio[5142]: port: 80 Mar 30 23:15:31 volumio volumio[5142]: } Mar 30 23:15:31 volumio volumio[5142]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 30 23:15:32 volumio sudo[5369]: pam_unix(sudo:session): session closed for user root Mar 30 23:15:32 volumio sudo[5407]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-03-30 23:14 Mar 30 23:15:32 volumio sudo[5407]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 23:15:32 volumio dhcpcd[648]: eth0: adding default route via 192.168.88.1 PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 24 Feb 2024 09:44:32 PM CET" VOLUMIO_VERSION="3.629" VOLUMIO_HARDWARE="nanopineo3-armbian" VOLUMIO_DEVICENAME="NanoPi Neo3" VOLUMIO_HASH="a46168a52783b80bcf164f007350514b"