-- Logs begin at Sun 2024-03-31 10:36:10 MSK, end at Mon 2024-04-01 21:01:29 MSK. -- Apr 01 21:00:00 volumio volumio[16183]: info: CoreStateMachine::setRepeat null single undefined Apr 01 21:00:00 volumio volumio[16183]: info: CoreStateMachine::pushState Apr 01 21:00:00 volumio volumio[16183]: info: CorePlayQueue::getTrack 0 Apr 01 21:00:00 volumio volumio[16183]: info: CoreCommandRouter::volumioPushState Apr 01 21:00:00 volumio volumio[16183]: info: CoreStateMachine::setRandom null Apr 01 21:00:00 volumio volumio[16183]: info: CoreStateMachine::pushState Apr 01 21:00:00 volumio volumio[16183]: info: CorePlayQueue::getTrack 0 Apr 01 21:00:00 volumio volumio[16183]: info: CoreCommandRouter::volumioPushState Apr 01 21:00:00 volumio volumio[16183]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 01 21:00:00 volumio volumio[16183]: Error: getaddrinfo ENOTFOUND ws.audioscrobbler.com Apr 01 21:00:00 volumio volumio[16183]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:71:26) { Apr 01 21:00:00 volumio volumio[16183]: errno: -3007, Apr 01 21:00:00 volumio volumio[16183]: code: 'ENOTFOUND', Apr 01 21:00:00 volumio volumio[16183]: syscall: 'getaddrinfo', Apr 01 21:00:00 volumio volumio[16183]: hostname: 'ws.audioscrobbler.com' Apr 01 21:00:00 volumio volumio[16183]: } Apr 01 21:00:00 volumio volumio[16183]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 01 21:00:01 volumio sudo[16370]: pam_unix(sudo:session): session closed for user root Apr 01 21:00:01 volumio sudo[16400]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-01 20:59 Apr 01 21:00:01 volumio sudo[16400]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 01 21:00:01 volumio sudo[16400]: pam_unix(sudo:session): session closed for user root Apr 01 21:00:01 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 01 21:00:01 volumio sudo[16332]: pam_unix(sudo:session): session closed for user root Apr 01 21:00:01 volumio sudo[16321]: pam_unix(sudo:session): session closed for user root Apr 01 21:00:01 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 01 21:00:01 volumio systemd[1]: Started dynamicswap service. Apr 01 21:00:01 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 01 21:00:01 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Apr 01 21:00:01 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 21. Apr 01 21:00:01 volumio systemd[1]: Started dynamicswap service. Apr 01 21:00:01 volumio systemd[1]: Stopped Volumio Backend Module. Apr 01 21:00:01 volumio systemd[1]: Started Volumio Backend Module. Apr 01 21:00:02 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 01 21:00:02 volumio mpd[16335]: Apr 01 21:00 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 01 21:00:02 volumio systemd[1]: Started Music Player Daemon. Apr 01 21:00:02 volumio systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Apr 01 21:00:02 volumio volumio[16429]: cset: --> shielding system active with Apr 01 21:00:02 volumio volumio[16429]: cset: "system" cpuset of CPUSPEC(0-2) with 88 tasks running Apr 01 21:00:02 volumio volumio[16429]: cset: "user" cpuset of CPUSPEC(3) with 3 tasks running Apr 01 21:00:02 volumio systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Apr 01 21:00:04 volumio volumio[16416]: info: ------------------------------------------- Apr 01 21:00:04 volumio volumio[16416]: info: ----- Volumio3 ---- Apr 01 21:00:04 volumio volumio[16416]: info: ------------------------------------------- Apr 01 21:00:04 volumio volumio[16416]: info: ----- System startup ---- Apr 01 21:00:04 volumio volumio[16416]: info: ------------------------------------------- Apr 01 21:00:05 volumio volumio-remote-updater[7589]: [2024-04-01 21:00:05] [connect] Successful connection Apr 01 21:00:05 volumio volumio[16416]: info: MYVOLUMIO Environment detected Apr 01 21:00:05 volumio volumio[16416]: info: Plugin folders cleanup Apr 01 21:00:05 volumio volumio[16416]: info: Scanning into folder /volumio/app/plugins/ Apr 01 21:00:05 volumio volumio[16416]: info: Scanning category audio_interface Apr 01 21:00:05 volumio volumio[16416]: info: Scanning category miscellanea Apr 01 21:00:05 volumio volumio[16416]: info: Scanning category music_service Apr 01 21:00:05 volumio volumio[16416]: info: Scanning category plugins.json Apr 01 21:00:05 volumio volumio[16416]: info: Scanning category system_controller Apr 01 21:00:05 volumio volumio[16416]: info: Scanning category user_interface Apr 01 21:00:05 volumio volumio[16416]: info: Scanning into folder /data/plugins/ Apr 01 21:00:05 volumio volumio[16416]: info: Scanning category music_service Apr 01 21:00:05 volumio volumio[16416]: info: Scanning category system_hardware Apr 01 21:00:05 volumio volumio[16416]: info: Scanning category user_interface Apr 01 21:00:05 volumio volumio[16416]: info: Plugin folders cleanup completed Apr 01 21:00:05 volumio volumio[16416]: info: ------------------------------------------- Apr 01 21:00:05 volumio volumio[16416]: info: ----- Core plugins startup ---- Apr 01 21:00:05 volumio volumio[16416]: info: ------------------------------------------- Apr 01 21:00:05 volumio volumio[16416]: info: Loading plugins from folder /volumio/app/plugins/ Apr 01 21:00:05 volumio volumio[16416]: info: Adding plugin upnp to MyMusic Plugins Apr 01 21:00:05 volumio volumio[16416]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 01 21:00:05 volumio volumio[16416]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 01 21:00:05 volumio volumio[16416]: info: Loading plugins from folder /data/plugins/ Apr 01 21:00:05 volumio volumio[16416]: info: Loading plugin "system"... Apr 01 21:00:05 volumio volumio[16416]: info: Loading plugin "appearance"... Apr 01 21:00:07 volumio volumio[16416]: info: Loading plugin "network"... Apr 01 21:00:07 volumio volumio[16416]: info: Refreshing Cached IP Addresses Apr 01 21:00:07 volumio sudo[16460]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 01 21:00:07 volumio sudo[16460]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 01 21:00:07 volumio volumio[16416]: info: Loading plugin "services"... Apr 01 21:00:07 volumio sudo[16462]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 01 21:00:07 volumio sudo[16462]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 01 21:00:07 volumio volumio[16416]: info: Loading plugin "alsa_controller"... Apr 01 21:00:07 volumio sudo[16460]: pam_unix(sudo:session): session closed for user root Apr 01 21:00:07 volumio sudo[16465]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 01 21:00:07 volumio sudo[16465]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 01 21:00:07 volumio sudo[16462]: pam_unix(sudo:session): session closed for user root Apr 01 21:00:07 volumio sudo[16465]: pam_unix(sudo:session): session closed for user root Apr 01 21:00:07 volumio volumio[16416]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 01 21:00:07 volumio volumio[16416]: info: Loading plugin "wizard"... Apr 01 21:00:07 volumio volumio[16416]: info: Loading plugin "networkfs"... Apr 01 21:00:07 volumio volumio[16416]: info: Starting Udev Watcher for removable devices Apr 01 21:00:07 volumio volumio[16416]: info: Ignoring mount for partition: boot Apr 01 21:00:07 volumio volumio[16416]: info: Ignoring mount for partition: volumio Apr 01 21:00:07 volumio volumio[16416]: info: Ignoring mount for partition: volumio_data Apr 01 21:00:07 volumio volumio[16416]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 01 21:00:07 volumio volumio[16416]: info: Loading plugin "volumio_command_line_client"... Apr 01 21:00:07 volumio volumio[16416]: info: Plugin upnp is not enabled Apr 01 21:00:07 volumio volumio[16416]: info: Loading plugin "my_music"... Apr 01 21:00:07 volumio volumio[16416]: info: Loading plugin "mpd"... Apr 01 21:00:08 volumio volumio[16416]: info: Loading plugin "upnp_browser"... Apr 01 21:00:08 volumio volumio[16416]: info: Loading plugin "alarm-clock"... Apr 01 21:00:08 volumio volumio[16416]: info: Loading plugin "airplay_emulation"... Apr 01 21:00:08 volumio volumio[16416]: info: Starting Shairport Sync Apr 01 21:00:08 volumio volumio[16416]: info: Loading plugin "last_100"... Apr 01 21:00:08 volumio volumio[16416]: info: Loading plugin "webradio"... Apr 01 21:00:09 volumio volumio[16416]: info: Loading plugin "i2s_dacs"... Apr 01 21:00:09 volumio volumio[16416]: info: I2S DAC not set, start Auto-detection Apr 01 21:00:09 volumio volumio[16416]: info: Loading plugin "volumiodiscovery"... Apr 01 21:00:09 volumio volumio[16416]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 01 21:00:09 volumio node[16416]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 01 21:00:09 volumio node[16416]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 01 21:00:09 volumio volumio[16416]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 01 21:00:09 volumio volumio[16416]: *** WARNING *** For more information see Apr 01 21:00:09 volumio volumio[16416]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 01 21:00:09 volumio volumio[16416]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 01 21:00:09 volumio node[16416]: *** WARNING *** For more information see Apr 01 21:00:09 volumio volumio[16416]: *** WARNING *** For more information see Apr 01 21:00:09 volumio node[16416]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 01 21:00:09 volumio node[16416]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 01 21:00:09 volumio node[16416]: *** WARNING *** For more information see Apr 01 21:00:09 volumio volumio[16416]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 01 21:00:09 volumio volumio[16416]: info: Discovery: Started advertising with name: VOLUMIO Apr 01 21:00:09 volumio volumio[16416]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 01 21:00:09 volumio volumio[16416]: info: Loading plugin "bandcamp"... Apr 01 21:00:10 volumio volumio[16416]: info: Loading plugin "calmradio"... Apr 01 21:00:11 volumio volumio[16416]: info: Loading plugin "mixcloud"... Apr 01 21:00:12 volumio volumio[16416]: info: Loading plugin "soundcloud"... Apr 01 21:00:12 volumio volumio[16416]: info: Loading plugin "spop"... Apr 01 21:00:13 volumio volumio[16416]: info: Loading plugin "youtube2"... Apr 01 21:00:15 volumio volumio[16416]: info: Loading plugin "outputs"... Apr 01 21:00:15 volumio volumio[16416]: info: Loading plugin "albumart"... Apr 01 21:00:15 volumio volumio[16416]: info: Plugin example_plugin is not enabled Apr 01 21:00:15 volumio volumio[16416]: info: Loading plugin "inputs"... Apr 01 21:00:15 volumio volumio[16416]: info: Loading plugin "updater_comm"... Apr 01 21:00:15 volumio volumio[16416]: info: Plugin mpdemulation is not enabled Apr 01 21:00:15 volumio volumio[16416]: info: Loading plugin "rest_api"... Apr 01 21:00:15 volumio volumio[16416]: info: Loading plugin "websocket"... Apr 01 21:00:15 volumio volumio[16416]: info: Loading plugin "RoonBridge"... Apr 01 21:00:15 volumio volumio[16416]: info: Applying required configuration parameters for plugin RoonBridge Apr 01 21:00:16 volumio volumio[16416]: info: Loading plugin "motherearthradio"... Apr 01 21:00:16 volumio volumio[16416]: Forking 3 albumart workers Apr 01 21:00:16 volumio volumio[16416]: info: Applying required configuration parameters for plugin motherearthradio Apr 01 21:00:16 volumio volumio[16416]: info: [1711994416605] [MotherEarth] API delay: 5 Apr 01 21:00:16 volumio volumio[16416]: info: Loading plugin "music_services_shield"... Apr 01 21:00:17 volumio volumio[16416]: info: Applying required configuration parameters for plugin music_services_shield Apr 01 21:00:17 volumio volumio[16416]: info: Loading plugin "lastfm"... Apr 01 21:00:17 volumio volumio[16416]: Starting albumart workers Apr 01 21:00:18 volumio volumio[16416]: Starting albumart workers Apr 01 21:00:18 volumio volumio[16416]: Starting albumart workers Apr 01 21:00:18 volumio volumio[16416]: info: Loading i18n strings for locale en Apr 01 21:00:18 volumio volumio[16416]: Updating browse sources language Apr 01 21:00:18 volumio volumio[16416]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 01 21:00:18 volumio volumio[16416]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 01 21:00:18 volumio volumio[16416]: info: CoreCommandRouter::initPlayerControls Apr 01 21:00:19 volumio volumio[16416]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 01 21:00:19 volumio volumio[16416]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 01 21:00:19 volumio volumio[16416]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 01 21:00:19 volumio volumio[16416]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 01 21:00:19 volumio volumio[16416]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 01 21:00:19 volumio volumio[16416]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 01 21:00:19 volumio volumio[16416]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 01 21:00:19 volumio volumio[16416]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 01 21:00:19 volumio volumio[16416]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 01 21:00:19 volumio volumio[16416]: Express server listening on port 3000 Apr 01 21:00:19 volumio volumio[16416]: [Metrics] WebUI: 15s 90.92ms Apr 01 21:00:19 volumio volumio[16416]: info: CoreStateMachine::resetVolumioState Apr 01 21:00:19 volumio volumio[16416]: info: CoreStateMachine::getcurrentVolume Apr 01 21:00:19 volumio volumio[16416]: info: CoreCommandRouter::volumioRetrievevolume Apr 01 21:00:19 volumio volumio[16416]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 01 21:00:19 volumio volumio[16416]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Apr 01 21:00:19 volumio volumio[16416]: info: Completed loading Core Plugins Apr 01 21:00:19 volumio volumio[16416]: info: Preparing to generate the ALSA configuration file Apr 01 21:00:19 volumio volumio[16416]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Apr 01 21:00:19 volumio volumio[16416]: wlan0 Interface doesn't support scanning. Apr 01 21:00:19 volumio volumio[16416]: info: Cannot use regular scanning, forcing with ap-force Apr 01 21:00:19 volumio sudo[16525]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Apr 01 21:00:19 volumio sudo[16525]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 01 21:00:19 volumio sudo[16525]: pam_unix(sudo:session): session closed for user root Apr 01 21:00:19 volumio volumio[16416]: command failed: No such device (-19) Apr 01 21:00:19 volumio volumio[16416]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Apr 01 21:00:19 volumio volumio[16416]: command failed: No such device (-19) Apr 01 21:00:19 volumio volumio[16416]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Apr 01 21:00:19 volumio volumio[16416]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Apr 01 21:00:19 volumio volumio[16416]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 1 Apr 01 21:00:19 volumio volumio[16416]: info: Reloading queue from file Apr 01 21:00:20 volumio volumio[16416]: info: VolumeController:: Volume=undefined Mute =false Apr 01 21:00:20 volumio volumio[16416]: info: CoreStateMachine::pushState Apr 01 21:00:20 volumio volumio[16416]: info: CorePlayQueue::getTrack 0 Apr 01 21:00:20 volumio volumio[16416]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 01 21:00:20 volumio volumio[16416]: info: CoreCommandRouter::volumioPushState Apr 01 21:00:20 volumio volumio[16416]: info: CoreStateMachine::updateTrackBlock Apr 01 21:00:20 volumio volumio[16416]: info: CorePlayQueue::getTrackBlock Apr 01 21:00:20 volumio volumio[16416]: info: CoreCommandRouter::volumioRetrievevolume Apr 01 21:00:20 volumio volumio[16416]: info: Asound.conf file unchanged, so no further update is needed Apr 01 21:00:20 volumio volumio[16416]: info: Output device has changed, restarting MPD Apr 01 21:00:20 volumio sudo[16552]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 01 21:00:20 volumio sudo[16552]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 01 21:00:20 volumio volumio[16416]: info: Output device has changed, restarting Shairport Sync Apr 01 21:00:20 volumio volumio[16416]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 01 21:00:20 volumio volumio[16416]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 01 21:00:20 volumio sudo[16552]: pam_unix(sudo:session): session closed for user root Apr 01 21:00:20 volumio sudo[16554]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 01 21:00:20 volumio sudo[16554]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 01 21:00:20 volumio systemd[1]: musicservicesshield.service: Succeeded. Apr 01 21:00:20 volumio systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Apr 01 21:00:20 volumio systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Apr 01 21:00:20 volumio systemd[1]: Stopping Music Player Daemon... Apr 01 21:00:20 volumio systemd[1]: mpd.service: Succeeded. Apr 01 21:00:20 volumio systemd[1]: Stopped Music Player Daemon. Apr 01 21:00:20 volumio volumio[16416]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 01 21:00:20 volumio volumio[16416]: info: ___________ START PLUGINS ___________ Apr 01 21:00:20 volumio volumio[16416]: info: ControllerMpd::onStart: Initializing MPD Apr 01 21:00:20 volumio volumio[16416]: info: Creating MPD Configuration file Apr 01 21:00:20 volumio systemd[1]: Starting Music Player Daemon... Apr 01 21:00:20 volumio sudo[16562]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 01 21:00:20 volumio sudo[16562]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 01 21:00:20 volumio volumio[16416]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 01 21:00:20 volumio volumio[16416]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 01 21:00:20 volumio sudo[16562]: pam_unix(sudo:session): session closed for user root Apr 01 21:00:20 volumio volumio[16416]: info: [1711994420406] CoreMusicLibrary::Adding element Media Servers Apr 01 21:00:20 volumio volumio[16416]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 01 21:00:20 volumio sudo[16565]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 01 21:00:20 volumio sudo[16565]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 01 21:00:20 volumio volumio-remote-updater[7589]: [2024-04-01 21:00:20] [connect] Successful connection Apr 01 21:00:20 volumio volumio[16416]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 01 21:00:20 volumio volumio[16416]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 01 21:00:20 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Apr 01 21:00:20 volumio systemd[1]: mpd.service: Succeeded. Apr 01 21:00:20 volumio systemd[1]: Stopped Music Player Daemon. Apr 01 21:00:20 volumio systemd[1]: Starting Music Player Daemon... Apr 01 21:00:20 volumio volumio[16416]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 01 21:00:20 volumio volumio[16416]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 01 21:00:20 volumio volumio[16416]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 01 21:00:20 volumio volumio[16416]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 01 21:00:20 volumio volumio[16416]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 01 21:00:20 volumio volumio[16416]: info: [1711994420693] CoreMusicLibrary::Adding element Last_100 Apr 01 21:00:20 volumio volumio[16416]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 01 21:00:20 volumio volumio[16416]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 01 21:00:20 volumio volumio[16416]: info: [1711994420699] CoreMusicLibrary::Adding element Webradio Apr 01 21:00:20 volumio volumio[16416]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 01 21:00:20 volumio volumio[16416]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 01 21:00:20 volumio volumio[16416]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 01 21:00:20 volumio volumio[16416]: info: [1711994420730] CoreMusicLibrary::Adding element Bandcamp Discover Apr 01 21:00:20 volumio volumio[16416]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 01 21:00:20 volumio volumio[16416]: Cannot find translation for source Bandcamp Discover Apr 01 21:00:20 volumio volumio[16416]: info: Adding Calm Radio to Browse Sources Apr 01 21:00:20 volumio volumio[16416]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 01 21:00:20 volumio volumio[16416]: info: [1711994420741] CoreMusicLibrary::Adding element Calm Radio Apr 01 21:00:20 volumio volumio[16416]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 01 21:00:20 volumio volumio[16416]: Cannot find translation for source Bandcamp Discover Apr 01 21:00:20 volumio volumio[16416]: Cannot find translation for source Calm Radio Apr 01 21:00:20 volumio volumio[16416]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 01 21:00:20 volumio volumio[16416]: info: [1711994420760] CoreMusicLibrary::Adding element Mixcloud Apr 01 21:00:20 volumio volumio[16416]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 01 21:00:20 volumio volumio[16416]: Cannot find translation for source Bandcamp Discover Apr 01 21:00:20 volumio volumio[16416]: Cannot find translation for source Calm Radio Apr 01 21:00:20 volumio volumio[16416]: Cannot find translation for source Mixcloud Apr 01 21:00:20 volumio volumio[16416]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 01 21:00:20 volumio volumio[16416]: info: [1711994420777] CoreMusicLibrary::Adding element SoundCloud Apr 01 21:00:20 volumio volumio[16416]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 01 21:00:20 volumio volumio[16416]: Cannot find translation for source Bandcamp Discover Apr 01 21:00:20 volumio volumio[16416]: Cannot find translation for source Calm Radio Apr 01 21:00:20 volumio volumio[16416]: Cannot find translation for source Mixcloud Apr 01 21:00:20 volumio volumio[16416]: Cannot find translation for source SoundCloud Apr 01 21:00:20 volumio volumio[16416]: info: Creating Spotify config file Apr 01 21:00:20 volumio volumio[16416]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 01 21:00:20 volumio volumio[16416]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 01 21:00:20 volumio volumio[16416]: info: [1711994420822] CoreMusicLibrary::Adding element YouTube2 Apr 01 21:00:20 volumio volumio[16416]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 01 21:00:20 volumio volumio[16416]: Cannot find translation for source Bandcamp Discover Apr 01 21:00:20 volumio volumio[16416]: Cannot find translation for source Calm Radio Apr 01 21:00:20 volumio volumio[16416]: Cannot find translation for source Mixcloud Apr 01 21:00:20 volumio volumio[16416]: Cannot find translation for source SoundCloud Apr 01 21:00:20 volumio volumio[16416]: Cannot find translation for source YouTube2 Apr 01 21:00:20 volumio volumio[16416]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 01 21:00:20 volumio volumio[16416]: info: [1711994420874] CoreMusicLibrary::Adding element Mother Earth Radio Apr 01 21:00:20 volumio volumio[16416]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 01 21:00:20 volumio volumio[16416]: Cannot find translation for source Bandcamp Discover Apr 01 21:00:20 volumio volumio[16416]: Cannot find translation for source Calm Radio Apr 01 21:00:20 volumio volumio[16416]: Cannot find translation for source Mixcloud Apr 01 21:00:20 volumio volumio[16416]: Cannot find translation for source SoundCloud Apr 01 21:00:20 volumio volumio[16416]: Cannot find translation for source YouTube2 Apr 01 21:00:20 volumio volumio[16416]: Cannot find translation for source Mother Earth Radio Apr 01 21:00:20 volumio sudo[16575]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Apr 01 21:00:20 volumio sudo[16575]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 01 21:00:20 volumio sudo[16575]: pam_unix(sudo:session): session closed for user root Apr 01 21:00:21 volumio volumio[16416]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 01 21:00:21 volumio volumio[16416]: info: [1711994421285] CoreMusicLibrary::Adding element LastFM Apr 01 21:00:21 volumio volumio[16416]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 01 21:00:21 volumio volumio[16416]: Cannot find translation for source Bandcamp Discover Apr 01 21:00:21 volumio volumio[16416]: Cannot find translation for source Calm Radio Apr 01 21:00:21 volumio volumio[16416]: Cannot find translation for source Mixcloud Apr 01 21:00:21 volumio volumio[16416]: Cannot find translation for source SoundCloud Apr 01 21:00:21 volumio volumio[16416]: Cannot find translation for source YouTube2 Apr 01 21:00:21 volumio volumio[16416]: Cannot find translation for source Mother Earth Radio Apr 01 21:00:21 volumio volumio[16416]: Cannot find translation for source LastFM Apr 01 21:00:21 volumio volumio[16416]: info: [LastFM] scrobbler initiated! Apr 01 21:00:21 volumio volumio[16416]: info: [LastFM] extended logging: false Apr 01 21:00:21 volumio volumio[16416]: info: [LastFM] try scrobble stream/radio plays: true Apr 01 21:00:21 volumio volumio[16416]: info: [LastFM] Left init routine Apr 01 21:00:21 volumio volumio[16416]: info: [LastFM] Socket already connected: false Apr 01 21:00:21 volumio volumio[16416]: info: Volumio Calling Home Apr 01 21:00:21 volumio sudo[16603]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/data/plugins/system_hardware/music_services_shield/moveallprocesses.sh Apr 01 21:00:21 volumio sudo[16603]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 01 21:00:21 volumio volumio[16416]: info: CoreStateMachine::setRepeat null single undefined Apr 01 21:00:21 volumio volumio[16416]: info: CoreStateMachine::pushState Apr 01 21:00:21 volumio volumio[16416]: info: CorePlayQueue::getTrack 0 Apr 01 21:00:21 volumio volumio[16416]: info: CoreCommandRouter::volumioPushState Apr 01 21:00:21 volumio volumio[16416]: info: CoreStateMachine::setRandom null Apr 01 21:00:21 volumio volumio[16416]: info: CoreStateMachine::pushState Apr 01 21:00:21 volumio volumio[16416]: info: CorePlayQueue::getTrack 0 Apr 01 21:00:21 volumio volumio[16416]: info: CoreCommandRouter::volumioPushState Apr 01 21:00:21 volumio volumio[16416]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 01 21:00:21 volumio volumio[16416]: Error: getaddrinfo ENOTFOUND ws.audioscrobbler.com Apr 01 21:00:21 volumio volumio[16416]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:71:26) { Apr 01 21:00:21 volumio volumio[16416]: errno: -3007, Apr 01 21:00:21 volumio volumio[16416]: code: 'ENOTFOUND', Apr 01 21:00:21 volumio volumio[16416]: syscall: 'getaddrinfo', Apr 01 21:00:21 volumio volumio[16416]: hostname: 'ws.audioscrobbler.com' Apr 01 21:00:21 volumio volumio[16416]: } Apr 01 21:00:21 volumio volumio[16416]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 01 21:00:22 volumio sudo[16603]: pam_unix(sudo:session): session closed for user root Apr 01 21:00:23 volumio sudo[16632]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-01 20:59 Apr 01 21:00:23 volumio sudo[16632]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 01 21:00:23 volumio sudo[16632]: pam_unix(sudo:session): session closed for user root Apr 01 21:00:23 volumio volumio-remote-updater[7589]: [2024-04-01 21:00:23] [info] asio async_read_at_least error: system:104 (Connection reset by peer) Apr 01 21:00:23 volumio volumio-remote-updater[7589]: [2024-04-01 21:00:23] [error] handle_read_http_response error: websocketpp.transport:2 (Underlying Transport Error) Apr 01 21:00:23 volumio volumio-remote-updater[7589]: [2024-04-01 21:00:23] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Apr 01 21:00:23 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 01 21:00:23 volumio sudo[16565]: pam_unix(sudo:session): session closed for user root Apr 01 21:00:23 volumio sudo[16554]: pam_unix(sudo:session): session closed for user root Apr 01 21:00:23 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 01 21:00:23 volumio systemd[1]: Started dynamicswap service. Apr 01 21:00:23 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 01 21:00:23 volumio mpd[16570]: Apr 01 21:00 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 01 21:00:23 volumio systemd[1]: Started Music Player Daemon. Apr 01 21:00:23 volumio systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Apr 01 21:00:23 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Apr 01 21:00:23 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 22. Apr 01 21:00:23 volumio systemd[1]: Stopped Volumio Backend Module. Apr 01 21:00:23 volumio systemd[1]: Started Volumio Backend Module. Apr 01 21:00:23 volumio systemd[1]: Started dynamicswap service. Apr 01 21:00:23 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 01 21:00:24 volumio volumio[16647]: cset: --> shielding system active with Apr 01 21:00:24 volumio volumio[16647]: cset: "system" cpuset of CPUSPEC(0-2) with 85 tasks running Apr 01 21:00:24 volumio volumio[16647]: cset: "user" cpuset of CPUSPEC(3) with 3 tasks running Apr 01 21:00:24 volumio systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Apr 01 21:00:26 volumio volumio[16650]: info: ------------------------------------------- Apr 01 21:00:26 volumio volumio[16650]: info: ----- Volumio3 ---- Apr 01 21:00:26 volumio volumio[16650]: info: ------------------------------------------- Apr 01 21:00:26 volumio volumio[16650]: info: ----- System startup ---- Apr 01 21:00:26 volumio volumio[16650]: info: ------------------------------------------- Apr 01 21:00:27 volumio volumio[16650]: info: MYVOLUMIO Environment detected Apr 01 21:00:27 volumio volumio[16650]: info: Plugin folders cleanup Apr 01 21:00:27 volumio volumio[16650]: info: Scanning into folder /volumio/app/plugins/ Apr 01 21:00:27 volumio volumio[16650]: info: Scanning category audio_interface Apr 01 21:00:27 volumio volumio[16650]: info: Scanning category miscellanea Apr 01 21:00:27 volumio volumio[16650]: info: Scanning category music_service Apr 01 21:00:27 volumio volumio[16650]: info: Scanning category plugins.json Apr 01 21:00:27 volumio volumio[16650]: info: Scanning category system_controller Apr 01 21:00:27 volumio volumio[16650]: info: Scanning category user_interface Apr 01 21:00:27 volumio volumio[16650]: info: Scanning into folder /data/plugins/ Apr 01 21:00:27 volumio volumio[16650]: info: Scanning category music_service Apr 01 21:00:27 volumio volumio[16650]: info: Scanning category system_hardware Apr 01 21:00:27 volumio volumio[16650]: info: Scanning category user_interface Apr 01 21:00:27 volumio volumio[16650]: info: Plugin folders cleanup completed Apr 01 21:00:27 volumio volumio[16650]: info: ------------------------------------------- Apr 01 21:00:27 volumio volumio[16650]: info: ----- Core plugins startup ---- Apr 01 21:00:27 volumio volumio[16650]: info: ------------------------------------------- Apr 01 21:00:27 volumio volumio[16650]: info: Loading plugins from folder /volumio/app/plugins/ Apr 01 21:00:27 volumio volumio[16650]: info: Adding plugin upnp to MyMusic Plugins Apr 01 21:00:27 volumio volumio[16650]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 01 21:00:27 volumio volumio[16650]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 01 21:00:27 volumio volumio[16650]: info: Loading plugins from folder /data/plugins/ Apr 01 21:00:27 volumio volumio[16650]: info: Loading plugin "system"... Apr 01 21:00:27 volumio volumio[16650]: info: Loading plugin "appearance"... Apr 01 21:00:28 volumio volumio[16650]: info: Loading plugin "network"... Apr 01 21:00:29 volumio volumio[16650]: info: Refreshing Cached IP Addresses Apr 01 21:00:29 volumio sudo[16729]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 01 21:00:29 volumio sudo[16729]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 01 21:00:29 volumio volumio[16650]: info: Loading plugin "services"... Apr 01 21:00:29 volumio sudo[16729]: pam_unix(sudo:session): session closed for user root Apr 01 21:00:29 volumio volumio[16650]: info: Loading plugin "alsa_controller"... Apr 01 21:00:29 volumio sudo[16731]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 01 21:00:29 volumio sudo[16731]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 01 21:00:29 volumio sudo[16734]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 01 21:00:29 volumio sudo[16734]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 01 21:00:29 volumio sudo[16731]: pam_unix(sudo:session): session closed for user root Apr 01 21:00:29 volumio sudo[16734]: pam_unix(sudo:session): session closed for user root Apr 01 21:00:29 volumio volumio[16650]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 01 21:00:29 volumio volumio[16650]: info: Loading plugin "wizard"... Apr 01 21:00:29 volumio volumio[16650]: info: Loading plugin "networkfs"... Apr 01 21:00:29 volumio volumio[16650]: info: Starting Udev Watcher for removable devices Apr 01 21:00:29 volumio volumio[16650]: info: Ignoring mount for partition: boot Apr 01 21:00:29 volumio volumio[16650]: info: Ignoring mount for partition: volumio Apr 01 21:00:29 volumio volumio[16650]: info: Ignoring mount for partition: volumio_data Apr 01 21:00:29 volumio volumio[16650]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 01 21:00:29 volumio volumio[16650]: info: Loading plugin "volumio_command_line_client"... Apr 01 21:00:29 volumio volumio[16650]: info: Plugin upnp is not enabled Apr 01 21:00:29 volumio volumio[16650]: info: Loading plugin "my_music"... Apr 01 21:00:29 volumio volumio[16650]: info: Loading plugin "mpd"... Apr 01 21:00:29 volumio volumio[16650]: info: Loading plugin "upnp_browser"... Apr 01 21:00:30 volumio volumio[16650]: info: Loading plugin "alarm-clock"... Apr 01 21:00:30 volumio volumio[16650]: info: Loading plugin "airplay_emulation"... Apr 01 21:00:30 volumio volumio[16650]: info: Starting Shairport Sync Apr 01 21:00:30 volumio volumio[16650]: info: Loading plugin "last_100"... Apr 01 21:00:30 volumio volumio[16650]: info: Loading plugin "webradio"... Apr 01 21:00:30 volumio volumio[16650]: info: Loading plugin "i2s_dacs"... Apr 01 21:00:30 volumio volumio[16650]: info: I2S DAC not set, start Auto-detection Apr 01 21:00:30 volumio volumio[16650]: info: Loading plugin "volumiodiscovery"... Apr 01 21:00:30 volumio volumio[16650]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 01 21:00:30 volumio volumio[16650]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 01 21:00:30 volumio node[16650]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 01 21:00:30 volumio volumio[16650]: *** WARNING *** For more information see Apr 01 21:00:30 volumio volumio[16650]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 01 21:00:30 volumio volumio[16650]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 01 21:00:30 volumio volumio[16650]: *** WARNING *** For more information see Apr 01 21:00:30 volumio node[16650]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 01 21:00:30 volumio node[16650]: *** WARNING *** For more information see Apr 01 21:00:30 volumio node[16650]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 01 21:00:30 volumio node[16650]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 01 21:00:30 volumio node[16650]: *** WARNING *** For more information see Apr 01 21:00:30 volumio volumio[16650]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 01 21:00:30 volumio volumio[16650]: info: Discovery: Started advertising with name: VOLUMIO Apr 01 21:00:30 volumio volumio[16650]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 01 21:00:30 volumio volumio[16650]: info: Loading plugin "bandcamp"... Apr 01 21:00:32 volumio volumio[16650]: info: Loading plugin "calmradio"... Apr 01 21:00:33 volumio volumio[16650]: info: Loading plugin "mixcloud"... Apr 01 21:00:33 volumio volumio-remote-updater[7589]: [2024-04-01 21:00:33] [connect] Successful connection Apr 01 21:00:33 volumio volumio[16650]: info: Loading plugin "soundcloud"... Apr 01 21:00:34 volumio volumio[16650]: info: Loading plugin "spop"... Apr 01 21:00:35 volumio volumio[16650]: info: Loading plugin "youtube2"... Apr 01 21:00:37 volumio volumio[16650]: info: Loading plugin "outputs"... Apr 01 21:00:37 volumio volumio[16650]: info: Loading plugin "albumart"... Apr 01 21:00:37 volumio volumio[16650]: info: Plugin example_plugin is not enabled Apr 01 21:00:37 volumio volumio[16650]: info: Loading plugin "inputs"... Apr 01 21:00:37 volumio volumio[16650]: info: Loading plugin "updater_comm"... Apr 01 21:00:37 volumio volumio[16650]: info: Plugin mpdemulation is not enabled Apr 01 21:00:37 volumio volumio[16650]: info: Loading plugin "rest_api"... Apr 01 21:00:37 volumio volumio[16650]: info: Loading plugin "websocket"... Apr 01 21:00:37 volumio volumio[16650]: info: Loading plugin "RoonBridge"... Apr 01 21:00:37 volumio volumio[16650]: info: Applying required configuration parameters for plugin RoonBridge Apr 01 21:00:37 volumio volumio[16650]: Forking 3 albumart workers Apr 01 21:00:37 volumio volumio[16650]: info: Loading plugin "motherearthradio"... Apr 01 21:00:38 volumio volumio[16650]: info: Applying required configuration parameters for plugin motherearthradio Apr 01 21:00:38 volumio volumio[16650]: info: [1711994438427] [MotherEarth] API delay: 5 Apr 01 21:00:38 volumio volumio[16650]: info: Loading plugin "music_services_shield"... Apr 01 21:00:39 volumio volumio[16650]: info: Applying required configuration parameters for plugin music_services_shield Apr 01 21:00:39 volumio volumio[16650]: info: Loading plugin "lastfm"... Apr 01 21:00:39 volumio volumio[16650]: Starting albumart workers Apr 01 21:00:39 volumio volumio[16650]: Starting albumart workers Apr 01 21:00:40 volumio volumio[16650]: Starting albumart workers Apr 01 21:00:40 volumio volumio[16650]: info: Loading i18n strings for locale en Apr 01 21:00:40 volumio volumio[16650]: Updating browse sources language Apr 01 21:00:40 volumio volumio[16650]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 01 21:00:41 volumio volumio[16650]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 01 21:00:41 volumio volumio[16650]: info: CoreCommandRouter::initPlayerControls Apr 01 21:00:41 volumio volumio[16650]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 01 21:00:41 volumio volumio[16650]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 01 21:00:41 volumio volumio[16650]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 01 21:00:41 volumio volumio[16650]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 01 21:00:41 volumio volumio[16650]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 01 21:00:41 volumio volumio[16650]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 01 21:00:41 volumio volumio[16650]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 01 21:00:41 volumio volumio[16650]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 01 21:00:41 volumio volumio[16650]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 01 21:00:41 volumio volumio[16650]: Express server listening on port 3000 Apr 01 21:00:41 volumio volumio[16650]: [Metrics] WebUI: 15s 482.32ms Apr 01 21:00:41 volumio volumio[16650]: info: CoreStateMachine::resetVolumioState Apr 01 21:00:41 volumio volumio[16650]: info: CoreStateMachine::getcurrentVolume Apr 01 21:00:41 volumio volumio[16650]: info: CoreCommandRouter::volumioRetrievevolume Apr 01 21:00:41 volumio volumio[16650]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 01 21:00:41 volumio volumio[16650]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Apr 01 21:00:41 volumio volumio[16650]: info: Completed loading Core Plugins Apr 01 21:00:41 volumio volumio[16650]: info: Preparing to generate the ALSA configuration file Apr 01 21:00:41 volumio volumio[16650]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Apr 01 21:00:41 volumio volumio[16650]: wlan0 Interface doesn't support scanning. Apr 01 21:00:41 volumio volumio[16650]: info: Cannot use regular scanning, forcing with ap-force Apr 01 21:00:41 volumio sudo[16813]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Apr 01 21:00:41 volumio sudo[16813]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 01 21:00:41 volumio sudo[16813]: pam_unix(sudo:session): session closed for user root Apr 01 21:00:41 volumio volumio[16650]: command failed: No such device (-19) Apr 01 21:00:41 volumio volumio[16650]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Apr 01 21:00:41 volumio volumio[16650]: command failed: No such device (-19) Apr 01 21:00:41 volumio volumio[16650]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Apr 01 21:00:41 volumio volumio[16650]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Apr 01 21:00:41 volumio volumio[16650]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 1 Apr 01 21:00:41 volumio volumio[16650]: info: Reloading queue from file Apr 01 21:00:41 volumio volumio[16650]: info: VolumeController:: Volume=undefined Mute =false Apr 01 21:00:41 volumio volumio[16650]: info: CoreStateMachine::pushState Apr 01 21:00:41 volumio volumio[16650]: info: CorePlayQueue::getTrack 0 Apr 01 21:00:41 volumio volumio[16650]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 01 21:00:41 volumio volumio[16650]: info: CoreCommandRouter::volumioPushState Apr 01 21:00:41 volumio volumio[16650]: info: CoreStateMachine::updateTrackBlock Apr 01 21:00:41 volumio volumio[16650]: info: CorePlayQueue::getTrackBlock Apr 01 21:00:41 volumio volumio[16650]: info: CoreCommandRouter::volumioRetrievevolume Apr 01 21:00:41 volumio volumio[16650]: info: Asound.conf file unchanged, so no further update is needed Apr 01 21:00:41 volumio volumio[16650]: info: Output device has changed, restarting MPD Apr 01 21:00:42 volumio sudo[16824]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 01 21:00:42 volumio sudo[16824]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 01 21:00:42 volumio volumio[16650]: info: Output device has changed, restarting Shairport Sync Apr 01 21:00:42 volumio volumio[16650]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 01 21:00:42 volumio volumio[16650]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 01 21:00:42 volumio sudo[16824]: pam_unix(sudo:session): session closed for user root Apr 01 21:00:42 volumio sudo[16827]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 01 21:00:42 volumio sudo[16827]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 01 21:00:42 volumio systemd[1]: musicservicesshield.service: Succeeded. Apr 01 21:00:42 volumio systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Apr 01 21:00:42 volumio systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Apr 01 21:00:42 volumio systemd[1]: Stopping Music Player Daemon... Apr 01 21:00:42 volumio volumio[16650]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 01 21:00:42 volumio volumio[16650]: info: ___________ START PLUGINS ___________ Apr 01 21:00:42 volumio volumio[16650]: info: ControllerMpd::onStart: Initializing MPD Apr 01 21:00:42 volumio volumio[16650]: info: Creating MPD Configuration file Apr 01 21:00:42 volumio systemd[1]: mpd.service: Succeeded. Apr 01 21:00:42 volumio systemd[1]: Stopped Music Player Daemon. Apr 01 21:00:42 volumio systemd[1]: Starting Music Player Daemon... Apr 01 21:00:42 volumio sudo[16834]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 01 21:00:42 volumio sudo[16834]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 01 21:00:42 volumio volumio[16650]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 01 21:00:42 volumio volumio[16650]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 01 21:00:42 volumio volumio[16650]: info: [1711994442270] CoreMusicLibrary::Adding element Media Servers Apr 01 21:00:42 volumio volumio[16650]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 01 21:00:42 volumio sudo[16834]: pam_unix(sudo:session): session closed for user root Apr 01 21:00:42 volumio volumio[16650]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 01 21:00:42 volumio volumio[16650]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 01 21:00:42 volumio sudo[16837]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 01 21:00:42 volumio sudo[16837]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 01 21:00:42 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Apr 01 21:00:42 volumio systemd[1]: mpd.service: Succeeded. Apr 01 21:00:42 volumio systemd[1]: Stopped Music Player Daemon. Apr 01 21:00:42 volumio volumio[16650]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 01 21:00:42 volumio volumio[16650]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 01 21:00:42 volumio volumio[16650]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 01 21:00:42 volumio systemd[1]: Starting Music Player Daemon... Apr 01 21:00:42 volumio volumio[16650]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 01 21:00:42 volumio volumio[16650]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 01 21:00:42 volumio volumio[16650]: info: [1711994442538] CoreMusicLibrary::Adding element Last_100 Apr 01 21:00:42 volumio volumio[16650]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 01 21:00:42 volumio volumio[16650]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 01 21:00:42 volumio volumio[16650]: info: [1711994442544] CoreMusicLibrary::Adding element Webradio Apr 01 21:00:42 volumio volumio[16650]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 01 21:00:42 volumio volumio[16650]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 01 21:00:42 volumio volumio[16650]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 01 21:00:42 volumio volumio[16650]: info: [1711994442574] CoreMusicLibrary::Adding element Bandcamp Discover Apr 01 21:00:42 volumio volumio[16650]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 01 21:00:42 volumio volumio[16650]: Cannot find translation for source Bandcamp Discover Apr 01 21:00:42 volumio volumio[16650]: info: Adding Calm Radio to Browse Sources Apr 01 21:00:42 volumio volumio[16650]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 01 21:00:42 volumio volumio[16650]: info: [1711994442583] CoreMusicLibrary::Adding element Calm Radio Apr 01 21:00:42 volumio volumio[16650]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 01 21:00:42 volumio volumio[16650]: Cannot find translation for source Bandcamp Discover Apr 01 21:00:42 volumio volumio[16650]: Cannot find translation for source Calm Radio Apr 01 21:00:42 volumio volumio[16650]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 01 21:00:42 volumio volumio[16650]: info: [1711994442600] CoreMusicLibrary::Adding element Mixcloud Apr 01 21:00:42 volumio volumio[16650]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 01 21:00:42 volumio volumio[16650]: Cannot find translation for source Bandcamp Discover Apr 01 21:00:42 volumio volumio[16650]: Cannot find translation for source Calm Radio Apr 01 21:00:42 volumio volumio[16650]: Cannot find translation for source Mixcloud Apr 01 21:00:42 volumio volumio[16650]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 01 21:00:42 volumio volumio[16650]: info: [1711994442617] CoreMusicLibrary::Adding element SoundCloud Apr 01 21:00:42 volumio volumio[16650]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 01 21:00:42 volumio volumio[16650]: Cannot find translation for source Bandcamp Discover Apr 01 21:00:42 volumio volumio[16650]: Cannot find translation for source Calm Radio Apr 01 21:00:42 volumio volumio[16650]: Cannot find translation for source Mixcloud Apr 01 21:00:42 volumio volumio[16650]: Cannot find translation for source SoundCloud Apr 01 21:00:42 volumio volumio[16650]: info: Creating Spotify config file Apr 01 21:00:42 volumio volumio[16650]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 01 21:00:42 volumio volumio[16650]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 01 21:00:42 volumio volumio[16650]: info: [1711994442660] CoreMusicLibrary::Adding element YouTube2 Apr 01 21:00:42 volumio volumio[16650]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 01 21:00:42 volumio volumio[16650]: Cannot find translation for source Bandcamp Discover Apr 01 21:00:42 volumio volumio[16650]: Cannot find translation for source Calm Radio Apr 01 21:00:42 volumio volumio[16650]: Cannot find translation for source Mixcloud Apr 01 21:00:42 volumio volumio[16650]: Cannot find translation for source SoundCloud Apr 01 21:00:42 volumio volumio[16650]: Cannot find translation for source YouTube2 Apr 01 21:00:42 volumio volumio[16650]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 01 21:00:42 volumio volumio[16650]: info: [1711994442711] CoreMusicLibrary::Adding element Mother Earth Radio Apr 01 21:00:42 volumio volumio[16650]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 01 21:00:42 volumio volumio[16650]: Cannot find translation for source Bandcamp Discover Apr 01 21:00:42 volumio volumio[16650]: Cannot find translation for source Calm Radio Apr 01 21:00:42 volumio volumio[16650]: Cannot find translation for source Mixcloud Apr 01 21:00:42 volumio volumio[16650]: Cannot find translation for source SoundCloud Apr 01 21:00:42 volumio volumio[16650]: Cannot find translation for source YouTube2 Apr 01 21:00:42 volumio volumio[16650]: Cannot find translation for source Mother Earth Radio Apr 01 21:00:42 volumio sudo[16847]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Apr 01 21:00:42 volumio sudo[16847]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 01 21:00:42 volumio sudo[16847]: pam_unix(sudo:session): session closed for user root Apr 01 21:00:43 volumio volumio[16650]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 01 21:00:43 volumio volumio[16650]: info: [1711994443085] CoreMusicLibrary::Adding element LastFM Apr 01 21:00:43 volumio volumio[16650]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 01 21:00:43 volumio volumio[16650]: Cannot find translation for source Bandcamp Discover Apr 01 21:00:43 volumio volumio[16650]: Cannot find translation for source Calm Radio Apr 01 21:00:43 volumio volumio[16650]: Cannot find translation for source Mixcloud Apr 01 21:00:43 volumio volumio[16650]: Cannot find translation for source SoundCloud Apr 01 21:00:43 volumio volumio[16650]: Cannot find translation for source YouTube2 Apr 01 21:00:43 volumio volumio[16650]: Cannot find translation for source Mother Earth Radio Apr 01 21:00:43 volumio volumio[16650]: Cannot find translation for source LastFM Apr 01 21:00:43 volumio volumio[16650]: info: [LastFM] scrobbler initiated! Apr 01 21:00:43 volumio volumio[16650]: info: [LastFM] extended logging: false Apr 01 21:00:43 volumio volumio[16650]: info: [LastFM] try scrobble stream/radio plays: true Apr 01 21:00:43 volumio volumio[16650]: info: [LastFM] Left init routine Apr 01 21:00:43 volumio volumio[16650]: info: [LastFM] Socket already connected: false Apr 01 21:00:43 volumio volumio[16650]: info: Volumio Calling Home Apr 01 21:00:43 volumio sudo[16875]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/data/plugins/system_hardware/music_services_shield/moveallprocesses.sh Apr 01 21:00:43 volumio sudo[16875]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 01 21:00:43 volumio volumio[16650]: info: CoreStateMachine::setRepeat null single undefined Apr 01 21:00:43 volumio volumio[16650]: info: CoreStateMachine::pushState Apr 01 21:00:43 volumio volumio[16650]: info: CorePlayQueue::getTrack 0 Apr 01 21:00:43 volumio volumio[16650]: info: CoreCommandRouter::volumioPushState Apr 01 21:00:43 volumio volumio[16650]: info: CoreStateMachine::setRandom null Apr 01 21:00:43 volumio volumio[16650]: info: CoreStateMachine::pushState Apr 01 21:00:43 volumio volumio[16650]: info: CorePlayQueue::getTrack 0 Apr 01 21:00:43 volumio volumio[16650]: info: CoreCommandRouter::volumioPushState Apr 01 21:00:43 volumio volumio[16650]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 01 21:00:43 volumio volumio[16650]: Error: getaddrinfo ENOTFOUND ws.audioscrobbler.com Apr 01 21:00:43 volumio volumio[16650]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:71:26) { Apr 01 21:00:43 volumio volumio[16650]: errno: -3007, Apr 01 21:00:43 volumio volumio[16650]: code: 'ENOTFOUND', Apr 01 21:00:43 volumio volumio[16650]: syscall: 'getaddrinfo', Apr 01 21:00:43 volumio volumio[16650]: hostname: 'ws.audioscrobbler.com' Apr 01 21:00:43 volumio volumio[16650]: } Apr 01 21:00:43 volumio volumio[16650]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 01 21:00:44 volumio sudo[16875]: pam_unix(sudo:session): session closed for user root Apr 01 21:00:44 volumio sudo[16904]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-01 20:59 Apr 01 21:00:44 volumio sudo[16904]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 01 21:00:44 volumio sudo[16904]: pam_unix(sudo:session): session closed for user root Apr 01 21:00:45 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 01 21:00:45 volumio sudo[16827]: pam_unix(sudo:session): session closed for user root Apr 01 21:00:45 volumio sudo[16837]: pam_unix(sudo:session): session closed for user root Apr 01 21:00:45 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 01 21:00:45 volumio systemd[1]: Started dynamicswap service. Apr 01 21:00:45 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 01 21:00:45 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Apr 01 21:00:45 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 23. Apr 01 21:00:45 volumio systemd[1]: Stopped Volumio Backend Module. Apr 01 21:00:45 volumio systemd[1]: Started Volumio Backend Module. Apr 01 21:00:45 volumio systemd[1]: Started dynamicswap service. Apr 01 21:00:45 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 01 21:00:45 volumio mpd[16842]: Apr 01 21:00 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 01 21:00:45 volumio systemd[1]: Started Music Player Daemon. Apr 01 21:00:46 volumio systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Apr 01 21:00:46 volumio volumio[16933]: cset: --> shielding system active with Apr 01 21:00:46 volumio volumio[16933]: cset: "system" cpuset of CPUSPEC(0-2) with 88 tasks running Apr 01 21:00:46 volumio volumio[16933]: cset: "user" cpuset of CPUSPEC(3) with 3 tasks running Apr 01 21:00:46 volumio systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Apr 01 21:00:48 volumio volumio[16918]: info: ------------------------------------------- Apr 01 21:00:48 volumio volumio[16918]: info: ----- Volumio3 ---- Apr 01 21:00:48 volumio volumio[16918]: info: ------------------------------------------- Apr 01 21:00:48 volumio volumio[16918]: info: ----- System startup ---- Apr 01 21:00:48 volumio volumio[16918]: info: ------------------------------------------- Apr 01 21:00:48 volumio volumio-remote-updater[7589]: [2024-04-01 21:00:48] [connect] Successful connection Apr 01 21:00:49 volumio volumio[16918]: info: MYVOLUMIO Environment detected Apr 01 21:00:49 volumio volumio[16918]: info: Plugin folders cleanup Apr 01 21:00:49 volumio volumio[16918]: info: Scanning into folder /volumio/app/plugins/ Apr 01 21:00:49 volumio volumio[16918]: info: Scanning category audio_interface Apr 01 21:00:49 volumio volumio[16918]: info: Scanning category miscellanea Apr 01 21:00:49 volumio volumio[16918]: info: Scanning category music_service Apr 01 21:00:49 volumio volumio[16918]: info: Scanning category plugins.json Apr 01 21:00:49 volumio volumio[16918]: info: Scanning category system_controller Apr 01 21:00:49 volumio volumio[16918]: info: Scanning category user_interface Apr 01 21:00:49 volumio volumio[16918]: info: Scanning into folder /data/plugins/ Apr 01 21:00:49 volumio volumio[16918]: info: Scanning category music_service Apr 01 21:00:49 volumio volumio[16918]: info: Scanning category system_hardware Apr 01 21:00:49 volumio volumio[16918]: info: Scanning category user_interface Apr 01 21:00:49 volumio volumio[16918]: info: Plugin folders cleanup completed Apr 01 21:00:49 volumio volumio[16918]: info: ------------------------------------------- Apr 01 21:00:49 volumio volumio[16918]: info: ----- Core plugins startup ---- Apr 01 21:00:49 volumio volumio[16918]: info: ------------------------------------------- Apr 01 21:00:49 volumio volumio[16918]: info: Loading plugins from folder /volumio/app/plugins/ Apr 01 21:00:49 volumio volumio[16918]: info: Adding plugin upnp to MyMusic Plugins Apr 01 21:00:49 volumio volumio[16918]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 01 21:00:49 volumio volumio[16918]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 01 21:00:49 volumio volumio[16918]: info: Loading plugins from folder /data/plugins/ Apr 01 21:00:49 volumio volumio[16918]: info: Loading plugin "system"... Apr 01 21:00:49 volumio volumio[16918]: info: Loading plugin "appearance"... Apr 01 21:00:50 volumio ntpd[13838]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Apr 01 21:00:50 volumio volumio[16918]: info: Loading plugin "network"... Apr 01 21:00:50 volumio volumio[16918]: info: Refreshing Cached IP Addresses Apr 01 21:00:50 volumio sudo[16962]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 01 21:00:50 volumio sudo[16962]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 01 21:00:50 volumio sudo[16962]: pam_unix(sudo:session): session closed for user root Apr 01 21:00:50 volumio sudo[16964]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 01 21:00:50 volumio volumio[16918]: info: Loading plugin "services"... Apr 01 21:00:50 volumio sudo[16964]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 01 21:00:51 volumio volumio[16918]: info: Loading plugin "alsa_controller"... Apr 01 21:00:51 volumio sudo[16964]: pam_unix(sudo:session): session closed for user root Apr 01 21:00:51 volumio sudo[16968]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 01 21:00:51 volumio sudo[16968]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 01 21:00:51 volumio sudo[16968]: pam_unix(sudo:session): session closed for user root Apr 01 21:00:51 volumio volumio[16918]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 01 21:00:51 volumio volumio[16918]: info: Loading plugin "wizard"... Apr 01 21:00:51 volumio volumio[16918]: info: Loading plugin "networkfs"... Apr 01 21:00:51 volumio volumio[16918]: info: Starting Udev Watcher for removable devices Apr 01 21:00:51 volumio volumio[16918]: info: Ignoring mount for partition: boot Apr 01 21:00:51 volumio volumio[16918]: info: Ignoring mount for partition: volumio Apr 01 21:00:51 volumio volumio[16918]: info: Ignoring mount for partition: volumio_data Apr 01 21:00:51 volumio volumio[16918]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 01 21:00:51 volumio volumio[16918]: info: Loading plugin "volumio_command_line_client"... Apr 01 21:00:51 volumio volumio[16918]: info: Plugin upnp is not enabled Apr 01 21:00:51 volumio volumio[16918]: info: Loading plugin "my_music"... Apr 01 21:00:51 volumio volumio[16918]: info: Loading plugin "mpd"... Apr 01 21:00:51 volumio volumio[16918]: info: Loading plugin "upnp_browser"... Apr 01 21:00:52 volumio volumio[16918]: info: Loading plugin "alarm-clock"... Apr 01 21:00:52 volumio volumio[16918]: info: Loading plugin "airplay_emulation"... Apr 01 21:00:52 volumio volumio[16918]: info: Starting Shairport Sync Apr 01 21:00:52 volumio volumio[16918]: info: Loading plugin "last_100"... Apr 01 21:00:52 volumio volumio[16918]: info: Loading plugin "webradio"... Apr 01 21:00:52 volumio volumio[16918]: info: Loading plugin "i2s_dacs"... Apr 01 21:00:52 volumio volumio[16918]: info: I2S DAC not set, start Auto-detection Apr 01 21:00:52 volumio volumio[16918]: info: Loading plugin "volumiodiscovery"... Apr 01 21:00:52 volumio volumio[16918]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 01 21:00:52 volumio node[16918]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 01 21:00:52 volumio node[16918]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 01 21:00:52 volumio volumio[16918]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 01 21:00:52 volumio volumio[16918]: *** WARNING *** For more information see Apr 01 21:00:52 volumio volumio[16918]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 01 21:00:52 volumio volumio[16918]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 01 21:00:52 volumio volumio[16918]: *** WARNING *** For more information see Apr 01 21:00:52 volumio node[16918]: *** WARNING *** For more information see Apr 01 21:00:52 volumio node[16918]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 01 21:00:52 volumio node[16918]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 01 21:00:52 volumio node[16918]: *** WARNING *** For more information see Apr 01 21:00:52 volumio volumio[16918]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 01 21:00:52 volumio volumio[16918]: info: Discovery: Started advertising with name: VOLUMIO Apr 01 21:00:52 volumio volumio[16918]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 01 21:00:52 volumio volumio[16918]: info: Loading plugin "bandcamp"... Apr 01 21:00:53 volumio ntpd[13838]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Apr 01 21:00:53 volumio ntpd[13838]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Apr 01 21:00:54 volumio volumio[16918]: info: Loading plugin "calmradio"... Apr 01 21:00:54 volumio ntpd[13838]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Apr 01 21:00:55 volumio volumio[16918]: info: Loading plugin "mixcloud"... Apr 01 21:00:55 volumio volumio[16918]: info: Loading plugin "soundcloud"... Apr 01 21:00:56 volumio volumio[16918]: info: Loading plugin "spop"... Apr 01 21:00:57 volumio volumio[16918]: info: Loading plugin "youtube2"... Apr 01 21:00:59 volumio volumio[16918]: info: Loading plugin "outputs"... Apr 01 21:00:59 volumio volumio[16918]: info: Loading plugin "albumart"... Apr 01 21:00:59 volumio volumio[16918]: info: Plugin example_plugin is not enabled Apr 01 21:00:59 volumio volumio[16918]: info: Loading plugin "inputs"... Apr 01 21:00:59 volumio volumio[16918]: info: Loading plugin "updater_comm"... Apr 01 21:00:59 volumio volumio[16918]: info: Plugin mpdemulation is not enabled Apr 01 21:00:59 volumio volumio[16918]: info: Loading plugin "rest_api"... Apr 01 21:00:59 volumio volumio[16918]: info: Loading plugin "websocket"... Apr 01 21:00:59 volumio volumio[16918]: info: Loading plugin "RoonBridge"... Apr 01 21:00:59 volumio volumio[16918]: Forking 3 albumart workers Apr 01 21:00:59 volumio volumio[16918]: info: Applying required configuration parameters for plugin RoonBridge Apr 01 21:00:59 volumio volumio[16918]: info: Loading plugin "motherearthradio"... Apr 01 21:01:00 volumio volumio[16918]: info: Applying required configuration parameters for plugin motherearthradio Apr 01 21:01:00 volumio volumio[16918]: info: [1711994460755] [MotherEarth] API delay: 5 Apr 01 21:01:00 volumio volumio[16918]: info: Loading plugin "music_services_shield"... Apr 01 21:01:01 volumio volumio[16918]: Starting albumart workers Apr 01 21:01:01 volumio volumio[16918]: Starting albumart workers Apr 01 21:01:01 volumio volumio[16918]: info: Applying required configuration parameters for plugin music_services_shield Apr 01 21:01:01 volumio volumio[16918]: info: Loading plugin "lastfm"... Apr 01 21:01:02 volumio volumio[16918]: Starting albumart workers Apr 01 21:01:02 volumio volumio[16918]: info: Loading i18n strings for locale en Apr 01 21:01:02 volumio volumio[16918]: Updating browse sources language Apr 01 21:01:02 volumio volumio[16918]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 01 21:01:03 volumio volumio[16918]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 01 21:01:03 volumio volumio[16918]: info: CoreCommandRouter::initPlayerControls Apr 01 21:01:03 volumio volumio[16918]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 01 21:01:03 volumio volumio[16918]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 01 21:01:03 volumio volumio[16918]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 01 21:01:03 volumio volumio[16918]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 01 21:01:03 volumio volumio[16918]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 01 21:01:03 volumio volumio[16918]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 01 21:01:03 volumio volumio[16918]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 01 21:01:03 volumio volumio[16918]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 01 21:01:03 volumio volumio[16918]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 01 21:01:03 volumio volumio[16918]: Express server listening on port 3000 Apr 01 21:01:03 volumio volumio[16918]: [Metrics] WebUI: 15s 728.49ms Apr 01 21:01:03 volumio volumio[16918]: info: CoreStateMachine::resetVolumioState Apr 01 21:01:03 volumio volumio[16918]: info: CoreStateMachine::getcurrentVolume Apr 01 21:01:03 volumio volumio[16918]: info: CoreCommandRouter::volumioRetrievevolume Apr 01 21:01:03 volumio volumio[16918]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 01 21:01:03 volumio volumio[16918]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Apr 01 21:01:03 volumio volumio[16918]: info: Completed loading Core Plugins Apr 01 21:01:03 volumio volumio[16918]: info: Preparing to generate the ALSA configuration file Apr 01 21:01:03 volumio volumio-remote-updater[7589]: [2024-04-01 21:01:03] [connect] Successful connection Apr 01 21:01:03 volumio volumio[16918]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Apr 01 21:01:03 volumio volumio[16918]: wlan0 Interface doesn't support scanning. Apr 01 21:01:03 volumio volumio[16918]: info: Cannot use regular scanning, forcing with ap-force Apr 01 21:01:03 volumio sudo[17047]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Apr 01 21:01:03 volumio sudo[17047]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 01 21:01:03 volumio sudo[17047]: pam_unix(sudo:session): session closed for user root Apr 01 21:01:03 volumio volumio[16918]: command failed: No such device (-19) Apr 01 21:01:03 volumio volumio[16918]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Apr 01 21:01:03 volumio volumio[16918]: command failed: No such device (-19) Apr 01 21:01:03 volumio volumio[16918]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Apr 01 21:01:03 volumio volumio[16918]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Apr 01 21:01:03 volumio volumio[16918]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 1 Apr 01 21:01:03 volumio volumio-remote-updater[7589]: [2024-04-01 21:01:03] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1711994463 101 Apr 01 21:01:03 volumio volumio[16918]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 2 Apr 01 21:01:04 volumio volumio[16918]: info: Reloading queue from file Apr 01 21:01:04 volumio volumio[16918]: info: VolumeController:: Volume=undefined Mute =false Apr 01 21:01:04 volumio volumio[16918]: info: CoreStateMachine::pushState Apr 01 21:01:04 volumio volumio[16918]: info: CorePlayQueue::getTrack 0 Apr 01 21:01:04 volumio volumio[16918]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 01 21:01:04 volumio volumio[16918]: info: CoreCommandRouter::volumioPushState Apr 01 21:01:04 volumio volumio[16918]: info: CoreStateMachine::updateTrackBlock Apr 01 21:01:04 volumio volumio[16918]: info: CorePlayQueue::getTrackBlock Apr 01 21:01:04 volumio volumio[16918]: info: CoreCommandRouter::volumioRetrievevolume Apr 01 21:01:04 volumio volumio[16918]: info: Asound.conf file unchanged, so no further update is needed Apr 01 21:01:04 volumio volumio[16918]: info: Output device has changed, restarting MPD Apr 01 21:01:04 volumio sudo[17060]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 01 21:01:04 volumio sudo[17060]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 01 21:01:04 volumio volumio[16918]: info: Output device has changed, restarting Shairport Sync Apr 01 21:01:04 volumio volumio[16918]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 01 21:01:04 volumio volumio[16918]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 01 21:01:04 volumio sudo[17060]: pam_unix(sudo:session): session closed for user root Apr 01 21:01:04 volumio sudo[17063]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 01 21:01:04 volumio sudo[17063]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 01 21:01:04 volumio systemd[1]: musicservicesshield.service: Succeeded. Apr 01 21:01:04 volumio systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Apr 01 21:01:04 volumio systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Apr 01 21:01:04 volumio systemd[1]: Stopping Music Player Daemon... Apr 01 21:01:04 volumio systemd[1]: mpd.service: Succeeded. Apr 01 21:01:04 volumio volumio[16918]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 01 21:01:04 volumio systemd[1]: Stopped Music Player Daemon. Apr 01 21:01:04 volumio volumio[16918]: info: ___________ START PLUGINS ___________ Apr 01 21:01:04 volumio volumio[16918]: info: ControllerMpd::onStart: Initializing MPD Apr 01 21:01:04 volumio volumio[16918]: info: Creating MPD Configuration file Apr 01 21:01:04 volumio systemd[1]: Starting Music Player Daemon... Apr 01 21:01:04 volumio sudo[17070]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 01 21:01:04 volumio sudo[17070]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 01 21:01:04 volumio volumio[16918]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 01 21:01:04 volumio sudo[17070]: pam_unix(sudo:session): session closed for user root Apr 01 21:01:04 volumio volumio[16918]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 01 21:01:04 volumio volumio[16918]: info: [1711994464428] CoreMusicLibrary::Adding element Media Servers Apr 01 21:01:04 volumio volumio[16918]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 01 21:01:04 volumio volumio[16918]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 01 21:01:04 volumio volumio[16918]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 01 21:01:04 volumio sudo[17073]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 01 21:01:04 volumio sudo[17073]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 01 21:01:04 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Apr 01 21:01:04 volumio systemd[1]: mpd.service: Succeeded. Apr 01 21:01:04 volumio systemd[1]: Stopped Music Player Daemon. Apr 01 21:01:04 volumio systemd[1]: Starting Music Player Daemon... Apr 01 21:01:04 volumio volumio[16918]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 01 21:01:04 volumio volumio[16918]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 01 21:01:04 volumio volumio[16918]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 01 21:01:04 volumio volumio[16918]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 01 21:01:04 volumio volumio[16918]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 01 21:01:04 volumio volumio[16918]: info: [1711994464707] CoreMusicLibrary::Adding element Last_100 Apr 01 21:01:04 volumio volumio[16918]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 01 21:01:04 volumio volumio[16918]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 01 21:01:04 volumio volumio[16918]: info: [1711994464714] CoreMusicLibrary::Adding element Webradio Apr 01 21:01:04 volumio volumio[16918]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 01 21:01:04 volumio volumio[16918]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 01 21:01:04 volumio volumio[16918]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 01 21:01:04 volumio volumio[16918]: info: [1711994464751] CoreMusicLibrary::Adding element Bandcamp Discover Apr 01 21:01:04 volumio volumio[16918]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 01 21:01:04 volumio volumio[16918]: Cannot find translation for source Bandcamp Discover Apr 01 21:01:04 volumio volumio[16918]: info: Adding Calm Radio to Browse Sources Apr 01 21:01:04 volumio volumio[16918]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 01 21:01:04 volumio volumio[16918]: info: [1711994464762] CoreMusicLibrary::Adding element Calm Radio Apr 01 21:01:04 volumio volumio[16918]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 01 21:01:04 volumio volumio[16918]: Cannot find translation for source Bandcamp Discover Apr 01 21:01:04 volumio volumio[16918]: Cannot find translation for source Calm Radio Apr 01 21:01:04 volumio volumio[16918]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 01 21:01:04 volumio volumio[16918]: info: [1711994464789] CoreMusicLibrary::Adding element Mixcloud Apr 01 21:01:04 volumio volumio[16918]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 01 21:01:04 volumio volumio[16918]: Cannot find translation for source Bandcamp Discover Apr 01 21:01:04 volumio volumio[16918]: Cannot find translation for source Calm Radio Apr 01 21:01:04 volumio volumio[16918]: Cannot find translation for source Mixcloud Apr 01 21:01:04 volumio volumio[16918]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 01 21:01:04 volumio volumio[16918]: info: [1711994464814] CoreMusicLibrary::Adding element SoundCloud Apr 01 21:01:04 volumio volumio[16918]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 01 21:01:04 volumio volumio[16918]: Cannot find translation for source Bandcamp Discover Apr 01 21:01:04 volumio volumio[16918]: Cannot find translation for source Calm Radio Apr 01 21:01:04 volumio volumio[16918]: Cannot find translation for source Mixcloud Apr 01 21:01:04 volumio volumio[16918]: Cannot find translation for source SoundCloud Apr 01 21:01:04 volumio volumio[16918]: info: Creating Spotify config file Apr 01 21:01:04 volumio volumio[16918]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 01 21:01:04 volumio volumio[16918]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 01 21:01:04 volumio volumio[16918]: info: [1711994464869] CoreMusicLibrary::Adding element YouTube2 Apr 01 21:01:04 volumio volumio[16918]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 01 21:01:04 volumio volumio[16918]: Cannot find translation for source Bandcamp Discover Apr 01 21:01:04 volumio volumio[16918]: Cannot find translation for source Calm Radio Apr 01 21:01:04 volumio volumio[16918]: Cannot find translation for source Mixcloud Apr 01 21:01:04 volumio volumio[16918]: Cannot find translation for source SoundCloud Apr 01 21:01:04 volumio volumio[16918]: Cannot find translation for source YouTube2 Apr 01 21:01:04 volumio volumio[16918]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 01 21:01:04 volumio volumio[16918]: info: [1711994464923] CoreMusicLibrary::Adding element Mother Earth Radio Apr 01 21:01:04 volumio volumio[16918]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 01 21:01:04 volumio volumio[16918]: Cannot find translation for source Bandcamp Discover Apr 01 21:01:04 volumio volumio[16918]: Cannot find translation for source Calm Radio Apr 01 21:01:04 volumio volumio[16918]: Cannot find translation for source Mixcloud Apr 01 21:01:04 volumio volumio[16918]: Cannot find translation for source SoundCloud Apr 01 21:01:04 volumio volumio[16918]: Cannot find translation for source YouTube2 Apr 01 21:01:04 volumio volumio[16918]: Cannot find translation for source Mother Earth Radio Apr 01 21:01:04 volumio sudo[17083]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Apr 01 21:01:04 volumio sudo[17083]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 01 21:01:04 volumio sudo[17083]: pam_unix(sudo:session): session closed for user root Apr 01 21:01:05 volumio volumio[16918]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 01 21:01:05 volumio volumio[16918]: info: [1711994465374] CoreMusicLibrary::Adding element LastFM Apr 01 21:01:05 volumio volumio[16918]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 01 21:01:05 volumio volumio[16918]: Cannot find translation for source Bandcamp Discover Apr 01 21:01:05 volumio volumio[16918]: Cannot find translation for source Calm Radio Apr 01 21:01:05 volumio volumio[16918]: Cannot find translation for source Mixcloud Apr 01 21:01:05 volumio volumio[16918]: Cannot find translation for source SoundCloud Apr 01 21:01:05 volumio volumio[16918]: Cannot find translation for source YouTube2 Apr 01 21:01:05 volumio volumio[16918]: Cannot find translation for source Mother Earth Radio Apr 01 21:01:05 volumio volumio[16918]: Cannot find translation for source LastFM Apr 01 21:01:05 volumio volumio[16918]: info: [LastFM] scrobbler initiated! Apr 01 21:01:05 volumio volumio[16918]: info: [LastFM] extended logging: false Apr 01 21:01:05 volumio volumio[16918]: info: [LastFM] try scrobble stream/radio plays: true Apr 01 21:01:05 volumio volumio[16918]: info: [LastFM] Left init routine Apr 01 21:01:05 volumio volumio[16918]: info: [LastFM] Socket already connected: false Apr 01 21:01:05 volumio volumio[16918]: info: Volumio Calling Home Apr 01 21:01:05 volumio sudo[17111]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/data/plugins/system_hardware/music_services_shield/moveallprocesses.sh Apr 01 21:01:05 volumio sudo[17111]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 01 21:01:05 volumio volumio[16918]: info: CoreStateMachine::setRepeat null single undefined Apr 01 21:01:05 volumio volumio[16918]: info: CoreStateMachine::pushState Apr 01 21:01:05 volumio volumio[16918]: info: CorePlayQueue::getTrack 0 Apr 01 21:01:05 volumio volumio[16918]: info: CoreCommandRouter::volumioPushState Apr 01 21:01:05 volumio volumio[16918]: info: CoreStateMachine::setRandom null Apr 01 21:01:05 volumio volumio[16918]: info: CoreStateMachine::pushState Apr 01 21:01:05 volumio volumio[16918]: info: CorePlayQueue::getTrack 0 Apr 01 21:01:05 volumio volumio[16918]: info: CoreCommandRouter::volumioPushState Apr 01 21:01:05 volumio volumio[16918]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 01 21:01:05 volumio volumio[16918]: Error: getaddrinfo ENOTFOUND ws.audioscrobbler.com Apr 01 21:01:05 volumio volumio[16918]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:71:26) { Apr 01 21:01:05 volumio volumio[16918]: errno: -3007, Apr 01 21:01:05 volumio volumio[16918]: code: 'ENOTFOUND', Apr 01 21:01:05 volumio volumio[16918]: syscall: 'getaddrinfo', Apr 01 21:01:05 volumio volumio[16918]: hostname: 'ws.audioscrobbler.com' Apr 01 21:01:05 volumio volumio[16918]: } Apr 01 21:01:05 volumio volumio[16918]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 01 21:01:06 volumio sudo[17111]: pam_unix(sudo:session): session closed for user root Apr 01 21:01:07 volumio sudo[17140]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-01 21:00 Apr 01 21:01:07 volumio sudo[17140]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 01 21:01:07 volumio sudo[17140]: pam_unix(sudo:session): session closed for user root Apr 01 21:01:07 volumio volumio-remote-updater[7589]: [2024-04-01 21:01:07] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Apr 01 21:01:07 volumio volumio-remote-updater[7589]: [2024-04-01 21:01:07] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Apr 01 21:01:07 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 01 21:01:07 volumio sudo[17063]: pam_unix(sudo:session): session closed for user root Apr 01 21:01:07 volumio sudo[17073]: pam_unix(sudo:session): session closed for user root Apr 01 21:01:07 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 01 21:01:07 volumio systemd[1]: Started dynamicswap service. Apr 01 21:01:07 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 01 21:01:07 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Apr 01 21:01:07 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 24. Apr 01 21:01:07 volumio mpd[17078]: Apr 01 21:01 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 01 21:01:07 volumio systemd[1]: Started dynamicswap service. Apr 01 21:01:07 volumio systemd[1]: Stopped Volumio Backend Module. Apr 01 21:01:07 volumio systemd[1]: Started Volumio Backend Module. Apr 01 21:01:07 volumio systemd[1]: Started Music Player Daemon. Apr 01 21:01:07 volumio systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Apr 01 21:01:07 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 01 21:01:08 volumio volumio[17162]: cset: --> shielding system active with Apr 01 21:01:08 volumio volumio[17162]: cset: "system" cpuset of CPUSPEC(0-2) with 87 tasks running Apr 01 21:01:08 volumio volumio[17162]: cset: "user" cpuset of CPUSPEC(3) with 3 tasks running Apr 01 21:01:08 volumio systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Apr 01 21:01:10 volumio volumio[17156]: info: ------------------------------------------- Apr 01 21:01:10 volumio volumio[17156]: info: ----- Volumio3 ---- Apr 01 21:01:10 volumio volumio[17156]: info: ------------------------------------------- Apr 01 21:01:10 volumio volumio[17156]: info: ----- System startup ---- Apr 01 21:01:10 volumio volumio[17156]: info: ------------------------------------------- Apr 01 21:01:11 volumio volumio[17156]: info: MYVOLUMIO Environment detected Apr 01 21:01:11 volumio volumio[17156]: info: Plugin folders cleanup Apr 01 21:01:11 volumio volumio[17156]: info: Scanning into folder /volumio/app/plugins/ Apr 01 21:01:11 volumio volumio[17156]: info: Scanning category audio_interface Apr 01 21:01:11 volumio volumio[17156]: info: Scanning category miscellanea Apr 01 21:01:11 volumio volumio[17156]: info: Scanning category music_service Apr 01 21:01:11 volumio volumio[17156]: info: Scanning category plugins.json Apr 01 21:01:11 volumio volumio[17156]: info: Scanning category system_controller Apr 01 21:01:11 volumio volumio[17156]: info: Scanning category user_interface Apr 01 21:01:11 volumio volumio[17156]: info: Scanning into folder /data/plugins/ Apr 01 21:01:11 volumio volumio[17156]: info: Scanning category music_service Apr 01 21:01:11 volumio volumio[17156]: info: Scanning category system_hardware Apr 01 21:01:11 volumio volumio[17156]: info: Scanning category user_interface Apr 01 21:01:11 volumio volumio[17156]: info: Plugin folders cleanup completed Apr 01 21:01:11 volumio volumio[17156]: info: ------------------------------------------- Apr 01 21:01:11 volumio volumio[17156]: info: ----- Core plugins startup ---- Apr 01 21:01:11 volumio volumio[17156]: info: ------------------------------------------- Apr 01 21:01:11 volumio volumio[17156]: info: Loading plugins from folder /volumio/app/plugins/ Apr 01 21:01:11 volumio volumio[17156]: info: Adding plugin upnp to MyMusic Plugins Apr 01 21:01:11 volumio volumio[17156]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 01 21:01:11 volumio volumio[17156]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 01 21:01:11 volumio volumio[17156]: info: Loading plugins from folder /data/plugins/ Apr 01 21:01:11 volumio volumio[17156]: info: Loading plugin "system"... Apr 01 21:01:11 volumio volumio[17156]: info: Loading plugin "appearance"... Apr 01 21:01:12 volumio volumio-remote-updater[7589]: [2024-04-01 21:01:12] [connect] Successful connection Apr 01 21:01:12 volumio volumio[17156]: info: Loading plugin "network"... Apr 01 21:01:12 volumio volumio[17156]: info: Refreshing Cached IP Addresses Apr 01 21:01:12 volumio volumio[17156]: info: Loading plugin "services"... Apr 01 21:01:12 volumio sudo[17199]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 01 21:01:12 volumio sudo[17197]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 01 21:01:12 volumio volumio[17156]: info: Loading plugin "alsa_controller"... Apr 01 21:01:12 volumio sudo[17197]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 01 21:01:12 volumio sudo[17199]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 01 21:01:12 volumio sudo[17197]: pam_unix(sudo:session): session closed for user root Apr 01 21:01:12 volumio sudo[17203]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 01 21:01:12 volumio sudo[17203]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 01 21:01:12 volumio sudo[17199]: pam_unix(sudo:session): session closed for user root Apr 01 21:01:13 volumio sudo[17203]: pam_unix(sudo:session): session closed for user root Apr 01 21:01:13 volumio volumio[17156]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 01 21:01:13 volumio volumio[17156]: info: Loading plugin "wizard"... Apr 01 21:01:13 volumio volumio[17156]: info: Loading plugin "networkfs"... Apr 01 21:01:13 volumio volumio[17156]: info: Starting Udev Watcher for removable devices Apr 01 21:01:13 volumio volumio[17156]: info: Ignoring mount for partition: boot Apr 01 21:01:13 volumio volumio[17156]: info: Ignoring mount for partition: volumio Apr 01 21:01:13 volumio volumio[17156]: info: Ignoring mount for partition: volumio_data Apr 01 21:01:13 volumio volumio[17156]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 01 21:01:13 volumio volumio[17156]: info: Loading plugin "volumio_command_line_client"... Apr 01 21:01:13 volumio volumio[17156]: info: Plugin upnp is not enabled Apr 01 21:01:13 volumio volumio[17156]: info: Loading plugin "my_music"... Apr 01 21:01:13 volumio volumio[17156]: info: Loading plugin "mpd"... Apr 01 21:01:13 volumio volumio[17156]: info: Loading plugin "upnp_browser"... Apr 01 21:01:14 volumio volumio[17156]: info: Loading plugin "alarm-clock"... Apr 01 21:01:14 volumio volumio[17156]: info: Loading plugin "airplay_emulation"... Apr 01 21:01:14 volumio volumio[17156]: info: Starting Shairport Sync Apr 01 21:01:14 volumio volumio[17156]: info: Loading plugin "last_100"... Apr 01 21:01:14 volumio volumio[17156]: info: Loading plugin "webradio"... Apr 01 21:01:14 volumio volumio[17156]: info: Loading plugin "i2s_dacs"... Apr 01 21:01:14 volumio volumio[17156]: info: I2S DAC not set, start Auto-detection Apr 01 21:01:14 volumio volumio[17156]: info: Loading plugin "volumiodiscovery"... Apr 01 21:01:14 volumio volumio[17156]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 01 21:01:14 volumio volumio[17156]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 01 21:01:14 volumio node[17156]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 01 21:01:14 volumio volumio[17156]: *** WARNING *** For more information see Apr 01 21:01:14 volumio volumio[17156]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 01 21:01:14 volumio volumio[17156]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 01 21:01:14 volumio volumio[17156]: *** WARNING *** For more information see Apr 01 21:01:14 volumio node[17156]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 01 21:01:14 volumio node[17156]: *** WARNING *** For more information see Apr 01 21:01:14 volumio node[17156]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 01 21:01:14 volumio node[17156]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 01 21:01:14 volumio node[17156]: *** WARNING *** For more information see Apr 01 21:01:14 volumio volumio[17156]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 01 21:01:14 volumio volumio[17156]: info: Discovery: Started advertising with name: VOLUMIO Apr 01 21:01:14 volumio volumio[17156]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 01 21:01:14 volumio volumio[17156]: info: Loading plugin "bandcamp"... Apr 01 21:01:15 volumio volumio[17156]: info: Loading plugin "calmradio"... Apr 01 21:01:16 volumio volumio[17156]: info: Loading plugin "mixcloud"... Apr 01 21:01:17 volumio volumio[17156]: info: Loading plugin "soundcloud"... Apr 01 21:01:18 volumio volumio[17156]: info: Loading plugin "spop"... Apr 01 21:01:19 volumio volumio[17156]: info: Loading plugin "youtube2"... Apr 01 21:01:20 volumio volumio[17156]: info: Loading plugin "outputs"... Apr 01 21:01:20 volumio volumio[17156]: info: Loading plugin "albumart"... Apr 01 21:01:20 volumio volumio[17156]: info: Plugin example_plugin is not enabled Apr 01 21:01:20 volumio volumio[17156]: info: Loading plugin "inputs"... Apr 01 21:01:20 volumio volumio[17156]: info: Loading plugin "updater_comm"... Apr 01 21:01:21 volumio volumio[17156]: info: Plugin mpdemulation is not enabled Apr 01 21:01:21 volumio volumio[17156]: info: Loading plugin "rest_api"... Apr 01 21:01:21 volumio volumio[17156]: info: Loading plugin "websocket"... Apr 01 21:01:21 volumio volumio[17156]: info: Loading plugin "RoonBridge"... Apr 01 21:01:21 volumio volumio[17156]: Forking 3 albumart workers Apr 01 21:01:21 volumio volumio[17156]: info: Applying required configuration parameters for plugin RoonBridge Apr 01 21:01:21 volumio volumio[17156]: info: Loading plugin "motherearthradio"... Apr 01 21:01:22 volumio volumio[17156]: info: Applying required configuration parameters for plugin motherearthradio Apr 01 21:01:22 volumio volumio[17156]: info: [1711994482230] [MotherEarth] API delay: 5 Apr 01 21:01:22 volumio volumio[17156]: info: Loading plugin "music_services_shield"... Apr 01 21:01:22 volumio volumio[17156]: info: Applying required configuration parameters for plugin music_services_shield Apr 01 21:01:22 volumio volumio[17156]: info: Loading plugin "lastfm"... Apr 01 21:01:23 volumio volumio[17156]: Starting albumart workers Apr 01 21:01:23 volumio volumio[17156]: Starting albumart workers Apr 01 21:01:23 volumio volumio[17156]: info: Loading i18n strings for locale en Apr 01 21:01:23 volumio volumio[17156]: Updating browse sources language Apr 01 21:01:23 volumio volumio[17156]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 01 21:01:24 volumio volumio[17156]: Starting albumart workers Apr 01 21:01:24 volumio volumio[17156]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 01 21:01:24 volumio volumio[17156]: info: CoreCommandRouter::initPlayerControls Apr 01 21:01:24 volumio volumio[17156]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 01 21:01:24 volumio volumio[17156]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 01 21:01:24 volumio volumio[17156]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 01 21:01:24 volumio volumio[17156]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 01 21:01:24 volumio volumio[17156]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 01 21:01:24 volumio volumio[17156]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 01 21:01:24 volumio volumio[17156]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 01 21:01:24 volumio volumio[17156]: Express server listening on port 3000 Apr 01 21:01:24 volumio volumio[17156]: [Metrics] WebUI: 14s 808.14ms Apr 01 21:01:24 volumio volumio[17156]: info: CoreStateMachine::resetVolumioState Apr 01 21:01:24 volumio volumio[17156]: info: CoreStateMachine::getcurrentVolume Apr 01 21:01:24 volumio volumio[17156]: info: CoreCommandRouter::volumioRetrievevolume Apr 01 21:01:24 volumio volumio[17156]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 01 21:01:24 volumio volumio[17156]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Apr 01 21:01:24 volumio volumio[17156]: info: Completed loading Core Plugins Apr 01 21:01:24 volumio volumio[17156]: info: Preparing to generate the ALSA configuration file Apr 01 21:01:25 volumio volumio-remote-updater[7589]: [2024-04-01 21:01:25] [connect] Successful connection Apr 01 21:01:25 volumio volumio[17156]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Apr 01 21:01:25 volumio volumio[17156]: wlan0 Interface doesn't support scanning. Apr 01 21:01:25 volumio volumio[17156]: info: Cannot use regular scanning, forcing with ap-force Apr 01 21:01:25 volumio sudo[17264]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Apr 01 21:01:25 volumio sudo[17264]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 01 21:01:25 volumio sudo[17264]: pam_unix(sudo:session): session closed for user root Apr 01 21:01:25 volumio volumio[17156]: command failed: No such device (-19) Apr 01 21:01:25 volumio volumio[17156]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Apr 01 21:01:25 volumio volumio[17156]: command failed: No such device (-19) Apr 01 21:01:25 volumio volumio[17156]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Apr 01 21:01:25 volumio volumio[17156]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Apr 01 21:01:25 volumio volumio[17156]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 1 Apr 01 21:01:25 volumio volumio-remote-updater[7589]: [2024-04-01 21:01:25] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1711994485 101 Apr 01 21:01:25 volumio volumio[17156]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 2 Apr 01 21:01:25 volumio volumio[17156]: info: Reloading queue from file Apr 01 21:01:25 volumio volumio[17156]: info: VolumeController:: Volume=undefined Mute =false Apr 01 21:01:25 volumio volumio[17156]: info: CoreStateMachine::pushState Apr 01 21:01:25 volumio volumio[17156]: info: CorePlayQueue::getTrack 0 Apr 01 21:01:25 volumio volumio[17156]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 01 21:01:25 volumio volumio[17156]: info: CoreCommandRouter::volumioPushState Apr 01 21:01:25 volumio volumio[17156]: info: CoreStateMachine::updateTrackBlock Apr 01 21:01:25 volumio volumio[17156]: info: CorePlayQueue::getTrackBlock Apr 01 21:01:25 volumio volumio[17156]: info: CoreCommandRouter::volumioRetrievevolume Apr 01 21:01:25 volumio volumio[17156]: info: Asound.conf file unchanged, so no further update is needed Apr 01 21:01:25 volumio volumio[17156]: info: Output device has changed, restarting MPD Apr 01 21:01:25 volumio sudo[17291]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 01 21:01:25 volumio sudo[17291]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 01 21:01:25 volumio volumio[17156]: info: Output device has changed, restarting Shairport Sync Apr 01 21:01:25 volumio volumio[17156]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 01 21:01:25 volumio volumio[17156]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 01 21:01:25 volumio sudo[17291]: pam_unix(sudo:session): session closed for user root Apr 01 21:01:25 volumio sudo[17294]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 01 21:01:25 volumio sudo[17294]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 01 21:01:25 volumio systemd[1]: musicservicesshield.service: Succeeded. Apr 01 21:01:25 volumio systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Apr 01 21:01:25 volumio systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Apr 01 21:01:25 volumio systemd[1]: Stopping Music Player Daemon... Apr 01 21:01:25 volumio volumio[17156]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 01 21:01:25 volumio volumio[17156]: info: ___________ START PLUGINS ___________ Apr 01 21:01:25 volumio systemd[1]: mpd.service: Succeeded. Apr 01 21:01:25 volumio volumio[17156]: info: ControllerMpd::onStart: Initializing MPD Apr 01 21:01:25 volumio volumio[17156]: info: Creating MPD Configuration file Apr 01 21:01:25 volumio systemd[1]: Stopped Music Player Daemon. Apr 01 21:01:26 volumio systemd[1]: Starting Music Player Daemon... Apr 01 21:01:26 volumio volumio[17156]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 01 21:01:26 volumio volumio[17156]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 01 21:01:26 volumio volumio[17156]: info: [1711994486097] CoreMusicLibrary::Adding element Media Servers Apr 01 21:01:26 volumio volumio[17156]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 01 21:01:26 volumio sudo[17305]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 01 21:01:26 volumio sudo[17305]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 01 21:01:26 volumio sudo[17305]: pam_unix(sudo:session): session closed for user root Apr 01 21:01:26 volumio volumio[17156]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 01 21:01:26 volumio volumio[17156]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 01 21:01:26 volumio sudo[17310]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 01 21:01:26 volumio sudo[17310]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 01 21:01:26 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Apr 01 21:01:26 volumio systemd[1]: mpd.service: Succeeded. Apr 01 21:01:26 volumio systemd[1]: Stopped Music Player Daemon. Apr 01 21:01:26 volumio volumio[17156]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 01 21:01:26 volumio volumio[17156]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 01 21:01:26 volumio volumio[17156]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 01 21:01:26 volumio systemd[1]: Starting Music Player Daemon... Apr 01 21:01:26 volumio volumio[17156]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 01 21:01:26 volumio volumio[17156]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 01 21:01:26 volumio volumio[17156]: info: [1711994486500] CoreMusicLibrary::Adding element Last_100 Apr 01 21:01:26 volumio volumio[17156]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 01 21:01:26 volumio volumio[17156]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 01 21:01:26 volumio volumio[17156]: info: [1711994486508] CoreMusicLibrary::Adding element Webradio Apr 01 21:01:26 volumio volumio[17156]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 01 21:01:26 volumio volumio[17156]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 01 21:01:26 volumio volumio[17156]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 01 21:01:26 volumio volumio[17156]: info: [1711994486544] CoreMusicLibrary::Adding element Bandcamp Discover Apr 01 21:01:26 volumio volumio[17156]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 01 21:01:26 volumio volumio[17156]: Cannot find translation for source Bandcamp Discover Apr 01 21:01:26 volumio volumio[17156]: info: Adding Calm Radio to Browse Sources Apr 01 21:01:26 volumio volumio[17156]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 01 21:01:26 volumio volumio[17156]: info: [1711994486557] CoreMusicLibrary::Adding element Calm Radio Apr 01 21:01:26 volumio volumio[17156]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 01 21:01:26 volumio volumio[17156]: Cannot find translation for source Bandcamp Discover Apr 01 21:01:26 volumio volumio[17156]: Cannot find translation for source Calm Radio Apr 01 21:01:26 volumio volumio[17156]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 01 21:01:26 volumio volumio[17156]: info: [1711994486583] CoreMusicLibrary::Adding element Mixcloud Apr 01 21:01:26 volumio volumio[17156]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 01 21:01:26 volumio volumio[17156]: Cannot find translation for source Bandcamp Discover Apr 01 21:01:26 volumio volumio[17156]: Cannot find translation for source Calm Radio Apr 01 21:01:26 volumio volumio[17156]: Cannot find translation for source Mixcloud Apr 01 21:01:26 volumio volumio[17156]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 01 21:01:26 volumio volumio[17156]: info: [1711994486606] CoreMusicLibrary::Adding element SoundCloud Apr 01 21:01:26 volumio volumio[17156]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 01 21:01:26 volumio volumio[17156]: Cannot find translation for source Bandcamp Discover Apr 01 21:01:26 volumio volumio[17156]: Cannot find translation for source Calm Radio Apr 01 21:01:26 volumio volumio[17156]: Cannot find translation for source Mixcloud Apr 01 21:01:26 volumio volumio[17156]: Cannot find translation for source SoundCloud Apr 01 21:01:26 volumio volumio[17156]: info: Creating Spotify config file Apr 01 21:01:26 volumio volumio[17156]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 01 21:01:26 volumio volumio[17156]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 01 21:01:26 volumio volumio[17156]: info: [1711994486660] CoreMusicLibrary::Adding element YouTube2 Apr 01 21:01:26 volumio volumio[17156]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 01 21:01:26 volumio volumio[17156]: Cannot find translation for source Bandcamp Discover Apr 01 21:01:26 volumio volumio[17156]: Cannot find translation for source Calm Radio Apr 01 21:01:26 volumio volumio[17156]: Cannot find translation for source Mixcloud Apr 01 21:01:26 volumio volumio[17156]: Cannot find translation for source SoundCloud Apr 01 21:01:26 volumio volumio[17156]: Cannot find translation for source YouTube2 Apr 01 21:01:26 volumio volumio[17156]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 01 21:01:26 volumio volumio[17156]: info: [1711994486723] CoreMusicLibrary::Adding element Mother Earth Radio Apr 01 21:01:26 volumio volumio[17156]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 01 21:01:26 volumio volumio[17156]: Cannot find translation for source Bandcamp Discover Apr 01 21:01:26 volumio volumio[17156]: Cannot find translation for source Calm Radio Apr 01 21:01:26 volumio volumio[17156]: Cannot find translation for source Mixcloud Apr 01 21:01:26 volumio volumio[17156]: Cannot find translation for source SoundCloud Apr 01 21:01:26 volumio volumio[17156]: Cannot find translation for source YouTube2 Apr 01 21:01:26 volumio volumio[17156]: Cannot find translation for source Mother Earth Radio Apr 01 21:01:26 volumio sudo[17352]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Apr 01 21:01:26 volumio sudo[17352]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 01 21:01:26 volumio sudo[17352]: pam_unix(sudo:session): session closed for user root Apr 01 21:01:27 volumio volumio[17156]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 01 21:01:27 volumio volumio[17156]: info: [1711994487133] CoreMusicLibrary::Adding element LastFM Apr 01 21:01:27 volumio volumio[17156]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 01 21:01:27 volumio volumio[17156]: Cannot find translation for source Bandcamp Discover Apr 01 21:01:27 volumio volumio[17156]: Cannot find translation for source Calm Radio Apr 01 21:01:27 volumio volumio[17156]: Cannot find translation for source Mixcloud Apr 01 21:01:27 volumio volumio[17156]: Cannot find translation for source SoundCloud Apr 01 21:01:27 volumio volumio[17156]: Cannot find translation for source YouTube2 Apr 01 21:01:27 volumio volumio[17156]: Cannot find translation for source Mother Earth Radio Apr 01 21:01:27 volumio volumio[17156]: Cannot find translation for source LastFM Apr 01 21:01:27 volumio volumio[17156]: info: [LastFM] scrobbler initiated! Apr 01 21:01:27 volumio volumio[17156]: info: [LastFM] extended logging: false Apr 01 21:01:27 volumio volumio[17156]: info: [LastFM] try scrobble stream/radio plays: true Apr 01 21:01:27 volumio volumio[17156]: info: [LastFM] Left init routine Apr 01 21:01:27 volumio volumio[17156]: info: [LastFM] Socket already connected: false Apr 01 21:01:27 volumio volumio[17156]: info: Volumio Calling Home Apr 01 21:01:27 volumio sudo[17380]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/data/plugins/system_hardware/music_services_shield/moveallprocesses.sh Apr 01 21:01:27 volumio sudo[17380]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 01 21:01:27 volumio volumio[17156]: info: CoreStateMachine::setRepeat null single undefined Apr 01 21:01:27 volumio volumio[17156]: info: CoreStateMachine::pushState Apr 01 21:01:27 volumio volumio[17156]: info: CorePlayQueue::getTrack 0 Apr 01 21:01:27 volumio volumio[17156]: info: CoreCommandRouter::volumioPushState Apr 01 21:01:27 volumio volumio[17156]: info: CoreStateMachine::setRandom null Apr 01 21:01:27 volumio volumio[17156]: info: CoreStateMachine::pushState Apr 01 21:01:27 volumio volumio[17156]: info: CorePlayQueue::getTrack 0 Apr 01 21:01:27 volumio volumio[17156]: info: CoreCommandRouter::volumioPushState Apr 01 21:01:27 volumio volumio[17156]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 01 21:01:27 volumio volumio[17156]: Error: getaddrinfo ENOTFOUND ws.audioscrobbler.com Apr 01 21:01:27 volumio volumio[17156]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:71:26) { Apr 01 21:01:27 volumio volumio[17156]: errno: -3007, Apr 01 21:01:27 volumio volumio[17156]: code: 'ENOTFOUND', Apr 01 21:01:27 volumio volumio[17156]: syscall: 'getaddrinfo', Apr 01 21:01:27 volumio volumio[17156]: hostname: 'ws.audioscrobbler.com' Apr 01 21:01:27 volumio volumio[17156]: } Apr 01 21:01:27 volumio volumio[17156]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 01 21:01:28 volumio sudo[17380]: pam_unix(sudo:session): session closed for user root Apr 01 21:01:28 volumio ntpd[13838]: ntpd exiting on signal 15 (Terminated) Apr 01 21:01:28 volumio systemd[1]: Stopping Network Time Service... Apr 01 21:01:28 volumio systemd[1]: ntp.service: Succeeded. Apr 01 21:01:28 volumio systemd[1]: Stopped Network Time Service. Apr 01 21:01:28 volumio systemd[1]: Starting Network Time Service... Apr 01 21:01:28 volumio systemd[1]: ntp.service: Control process exited, code=killed, status=15/TERM Apr 01 21:01:29 volumio systemd[1]: ntp.service: Succeeded. Apr 01 21:01:29 volumio systemd[1]: Stopped Network Time Service. Apr 01 21:01:29 volumio systemd[1]: Starting Network Time Service... Apr 01 21:01:29 volumio ntpd[17475]: ntpd 4.2.8p12@1.3728-o (1): Starting Apr 01 21:01:29 volumio ntpd[17475]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Apr 01 21:01:29 volumio ntpd[17481]: proto: precision = 2.333 usec (-19) Apr 01 21:01:29 volumio ntpd[17481]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Apr 01 21:01:29 volumio ntpd[17481]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Apr 01 21:01:29 volumio ntpd[17481]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 461 days ago Apr 01 21:01:29 volumio systemd[1]: Started Network Time Service. Apr 01 21:01:29 volumio ntpd[17481]: Listen and drop on 0 v6wildcard [::]:123 Apr 01 21:01:29 volumio ntpd[17481]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Apr 01 21:01:29 volumio ntpd[17481]: Listen normally on 2 lo 127.0.0.1:123 Apr 01 21:01:29 volumio ntpd[17481]: Listen normally on 3 eth0 192.168.88.22:123 Apr 01 21:01:29 volumio ntpd[17481]: Listening on routing socket on fd #20 for interface updates Apr 01 21:01:29 volumio ntpd[17481]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Apr 01 21:01:29 volumio ntpd[17481]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Apr 01 21:01:29 volumio sudo[17484]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-01 21:00 Apr 01 21:01:29 volumio sudo[17484]: pam_unix(sudo:session): session opened for user root by (uid=0) 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"