-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Sat 2025-04-05 04:07:02 CEST. -- Apr 05 04:06:00 musicaluis volumio[1416]: info: Loading plugin "outputs"... Apr 05 04:06:00 musicaluis volumio[1416]: info: Loading plugin "albumart"... Apr 05 04:06:00 musicaluis volumio[1416]: info: Plugin example_plugin is not enabled Apr 05 04:06:00 musicaluis volumio[1416]: info: Loading plugin "inputs"... Apr 05 04:06:00 musicaluis volumio[1416]: info: Loading plugin "updater_comm"... Apr 05 04:06:00 musicaluis volumio[1416]: info: Plugin mpdemulation is not enabled Apr 05 04:06:00 musicaluis volumio[1416]: info: Loading plugin "rest_api"... Apr 05 04:06:00 musicaluis volumio[1416]: info: Loading plugin "websocket"... Apr 05 04:06:00 musicaluis volumio[1416]: info: Starting Socket.io Server version 2.3.0 Apr 05 04:06:00 musicaluis volumio[1416]: info: Loading plugin "fusiondsp"... Apr 05 04:06:00 musicaluis volumio[1416]: Forking 3 albumart workers Apr 05 04:06:00 musicaluis volumio[1416]: info: Applying required configuration parameters for plugin fusiondsp Apr 05 04:06:01 musicaluis volumio[1416]: info: Loading plugin "volusonic"... Apr 05 04:06:01 musicaluis volumio-remote-updater[566]: [2025-04-05 04:06:01] [connect] Successful connection Apr 05 04:06:01 musicaluis volumio[1416]: Starting albumart workers Apr 05 04:06:01 musicaluis volumio[1416]: Starting albumart workers Apr 05 04:06:01 musicaluis volumio[1416]: Starting albumart workers Apr 05 04:06:02 musicaluis volumio[1416]: info: Applying required configuration parameters for plugin volusonic Apr 05 04:06:02 musicaluis volumio[1416]: info: Loading plugin "lastfm"... Apr 05 04:06:02 musicaluis volumio[1416]: info: Loading i18n strings for locale en Apr 05 04:06:02 musicaluis volumio[1416]: info: Plugin volusonic has duplicated i18n key SAVE. It is ignored. Apr 05 04:06:02 musicaluis volumio[1416]: Updating browse sources language Apr 05 04:06:02 musicaluis volumio[1416]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 05 04:06:03 musicaluis volumio[1416]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 05 04:06:03 musicaluis volumio[1416]: info: CoreCommandRouter::initPlayerControls Apr 05 04:06:03 musicaluis volumio[1416]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 04:06:03 musicaluis volumio[1416]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 04:06:03 musicaluis volumio[1416]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 04:06:03 musicaluis volumio[1416]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 04:06:03 musicaluis volumio[1416]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 04:06:03 musicaluis volumio[1416]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 04:06:03 musicaluis volumio[1416]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 04:06:03 musicaluis volumio[1416]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 04:06:03 musicaluis volumio[1416]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 05 04:06:03 musicaluis volumio[1416]: Express server listening on port 3000 Apr 05 04:06:03 musicaluis volumio[1416]: [Metrics] WebUI: 7s 965.84ms Apr 05 04:06:03 musicaluis volumio[1416]: info: CoreStateMachine::resetVolumioState Apr 05 04:06:03 musicaluis volumio[1416]: info: CoreStateMachine::getcurrentVolume Apr 05 04:06:03 musicaluis volumio[1416]: info: CoreCommandRouter::volumioRetrievevolume Apr 05 04:06:03 musicaluis volumio[1416]: info: Volumio Network Manager: Network status updated: 0 Apr 05 04:06:03 musicaluis volumio-remote-updater[566]: [2025-04-05 04:06:03] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1743818761 101 Apr 05 04:06:03 musicaluis volumio[1416]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Apr 05 04:06:03 musicaluis volumio[1416]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Apr 05 04:06:03 musicaluis volumio[1416]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 05 04:06:03 musicaluis volumio[1416]: info: Reloading queue from file Apr 05 04:06:03 musicaluis volumio[1416]: info: VolumeController:: Volume=100 Mute =false Apr 05 04:06:03 musicaluis volumio[1416]: info: CoreStateMachine::pushState Apr 05 04:06:03 musicaluis volumio[1416]: info: CorePlayQueue::getTrack 0 Apr 05 04:06:03 musicaluis volumio[1416]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 04:06:03 musicaluis volumio[1416]: info: CoreCommandRouter::volumioPushState Apr 05 04:06:03 musicaluis volumio[1416]: info: CoreStateMachine::updateTrackBlock Apr 05 04:06:03 musicaluis volumio[1416]: info: CorePlayQueue::getTrackBlock Apr 05 04:06:03 musicaluis volumio[1416]: info: CoreCommandRouter::volumioRetrievevolume Apr 05 04:06:03 musicaluis volumio[1416]: info: CoreStateMachine::setRepeat null single undefined Apr 05 04:06:03 musicaluis volumio[1416]: info: CoreStateMachine::pushState Apr 05 04:06:03 musicaluis volumio[1416]: info: CorePlayQueue::getTrack 0 Apr 05 04:06:03 musicaluis volumio[1416]: info: CoreCommandRouter::volumioPushState Apr 05 04:06:03 musicaluis volumio[1416]: info: CoreStateMachine::setRandom null Apr 05 04:06:03 musicaluis volumio[1416]: info: CoreStateMachine::pushState Apr 05 04:06:03 musicaluis volumio[1416]: info: CorePlayQueue::getTrack 0 Apr 05 04:06:03 musicaluis volumio[1416]: info: CoreCommandRouter::volumioPushState Apr 05 04:06:03 musicaluis volumio[1416]: info: Setting Device type: Raspberry PI Apr 05 04:06:03 musicaluis volumio[1416]: info: Completed loading Core Plugins Apr 05 04:06:03 musicaluis volumio[1416]: info: Preparing to generate the ALSA configuration file Apr 05 04:06:03 musicaluis volumio[1416]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 05 04:06:03 musicaluis volumio[1416]: info: Reading ALSA contributions from plugins. Apr 05 04:06:03 musicaluis volumio[1416]: info: VolumeController:: Volume=100 Mute =false Apr 05 04:06:03 musicaluis volumio[1416]: info: CoreStateMachine::pushState Apr 05 04:06:03 musicaluis volumio[1416]: info: CorePlayQueue::getTrack 0 Apr 05 04:06:03 musicaluis volumio[1416]: info: CoreCommandRouter::volumioPushState Apr 05 04:06:03 musicaluis volumio[1416]: info: Asound.conf file unchanged, so no further update is needed Apr 05 04:06:03 musicaluis volumio[1416]: info: Output device has changed, restarting MPD Apr 05 04:06:03 musicaluis sudo[1570]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 05 04:06:03 musicaluis volumio[1416]: info: Output device has changed, restarting Shairport Sync Apr 05 04:06:03 musicaluis sudo[1570]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 05 04:06:03 musicaluis volumio[1416]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 04:06:03 musicaluis volumio[1416]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 05 04:06:03 musicaluis sudo[1570]: pam_unix(sudo:session): session closed for user root Apr 05 04:06:03 musicaluis sudo[1572]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 05 04:06:03 musicaluis sudo[1572]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 05 04:06:03 musicaluis systemd[1]: Stopping Music Player Daemon... Apr 05 04:06:03 musicaluis volumio[1416]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 05 04:06:03 musicaluis volumio[1416]: info: ___________ START PLUGINS ___________ Apr 05 04:06:03 musicaluis volumio[1416]: info: ControllerMpd::onStart: Initializing MPD Apr 05 04:06:03 musicaluis volumio[1416]: info: Creating MPD Configuration file Apr 05 04:06:03 musicaluis systemd[1]: mpd.service: Succeeded. Apr 05 04:06:03 musicaluis systemd[1]: Stopped Music Player Daemon. Apr 05 04:06:03 musicaluis sudo[1579]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 05 04:06:03 musicaluis sudo[1579]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 05 04:06:03 musicaluis volumio[1416]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 05 04:06:03 musicaluis volumio[1416]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 05 04:06:03 musicaluis volumio[1416]: info: [1743818763732] CoreMusicLibrary::Adding element Media Servers Apr 05 04:06:03 musicaluis volumio[1416]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 05 04:06:03 musicaluis volumio[1416]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 04:06:03 musicaluis volumio[1416]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 05 04:06:03 musicaluis systemd[1]: Starting Music Player Daemon... Apr 05 04:06:03 musicaluis sudo[1579]: pam_unix(sudo:session): session closed for user root Apr 05 04:06:03 musicaluis sudo[1582]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 05 04:06:03 musicaluis sudo[1582]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 05 04:06:03 musicaluis systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Apr 05 04:06:03 musicaluis systemd[1]: mpd.service: Succeeded. Apr 05 04:06:03 musicaluis systemd[1]: Stopped Music Player Daemon. Apr 05 04:06:03 musicaluis systemd[1]: Starting Music Player Daemon... Apr 05 04:06:03 musicaluis volumio[1416]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 05 04:06:03 musicaluis volumio[1416]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 04:06:03 musicaluis volumio[1416]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 05 04:06:03 musicaluis volumio[1416]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 05 04:06:03 musicaluis volumio[1416]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 05 04:06:03 musicaluis volumio[1416]: info: [1743818763874] CoreMusicLibrary::Adding element Last_100 Apr 05 04:06:03 musicaluis volumio[1416]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 05 04:06:03 musicaluis volumio[1416]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 05 04:06:03 musicaluis volumio[1416]: info: [1743818763879] CoreMusicLibrary::Adding element Webradio Apr 05 04:06:03 musicaluis volumio[1416]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 05 04:06:03 musicaluis volumio[1416]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 05 04:06:03 musicaluis volumio[1416]: info: Initializing BBC Radios Apr 05 04:06:03 musicaluis sudo[1588]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 05 04:06:03 musicaluis sudo[1588]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 05 04:06:03 musicaluis sudo[1588]: pam_unix(sudo:session): session closed for user root Apr 05 04:06:03 musicaluis volumio[1416]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 05 04:06:04 musicaluis volumio[1416]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 05 04:06:04 musicaluis volumio[1416]: info: AutoStart - onStart Apr 05 04:06:04 musicaluis volumio[1416]: info: [now-playing-config] Config is up to date. Apr 05 04:06:04 musicaluis volumio[1416]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 05 04:06:04 musicaluis volumio[1416]: info: Discovery: Getting this device information Apr 05 04:06:04 musicaluis volumio[1416]: info: CoreCommandRouter::volumioGetState Apr 05 04:06:04 musicaluis volumio[1416]: info: CorePlayQueue::getTrack 0 Apr 05 04:06:04 musicaluis volumio[1416]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 05 04:06:04 musicaluis volumio[1416]: info: Loading i18n strings for locale en Apr 05 04:06:04 musicaluis volumio[1416]: info: Plugin volusonic has duplicated i18n key SAVE. It is ignored. Apr 05 04:06:04 musicaluis volumio[1416]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Apr 05 04:06:04 musicaluis volumio[1416]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 04:06:04 musicaluis volumio[1416]: info: FusionDsp - mixtype--------------------- Hardware Apr 05 04:06:04 musicaluis volumio[1416]: info: Loading i18n strings for locale en Apr 05 04:06:04 musicaluis volumio[1416]: info: Plugin volusonic has duplicated i18n key SAVE. It is ignored. Apr 05 04:06:04 musicaluis volumio[1416]: Updating browse sources language Apr 05 04:06:04 musicaluis volumio[1416]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 05 04:06:04 musicaluis volumio[1416]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 05 04:06:04 musicaluis volumio[1416]: info: [1743818764063] CoreMusicLibrary::Adding element Volusonic Apr 05 04:06:04 musicaluis volumio[1416]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 05 04:06:04 musicaluis volumio[1416]: Cannot find translation for source Volusonic Apr 05 04:06:04 musicaluis volumio[1416]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 05 04:06:04 musicaluis volumio[1416]: info: [1743818764067] CoreMusicLibrary::Adding element LastFM Apr 05 04:06:04 musicaluis volumio[1416]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 05 04:06:04 musicaluis volumio[1416]: Cannot find translation for source Volusonic Apr 05 04:06:04 musicaluis volumio[1416]: Cannot find translation for source LastFM Apr 05 04:06:04 musicaluis volumio[1416]: info: [LastFM] scrobbler initiated! Apr 05 04:06:04 musicaluis volumio[1416]: info: [LastFM] extended logging: false Apr 05 04:06:04 musicaluis volumio[1416]: info: [LastFM] try scrobble stream/radio plays: true Apr 05 04:06:04 musicaluis volumio[1416]: info: [LastFM] Left init routine Apr 05 04:06:04 musicaluis volumio[1416]: info: [LastFM] Socket already connected: true Apr 05 04:06:04 musicaluis volumio[1416]: info: Volumio Calling Home Apr 05 04:06:04 musicaluis volumio[1416]: info: [now-playing-app] App is listening on port 4004. Apr 05 04:06:04 musicaluis volumio[1416]: info: Preparing to generate the ALSA configuration file Apr 05 04:06:04 musicaluis volumio[1416]: Unhandled rejection Error: No sockets available, cannot start. Apr 05 04:06:04 musicaluis volumio[1416]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Apr 05 04:06:04 musicaluis volumio[1416]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Apr 05 04:06:04 musicaluis volumio[1416]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Apr 05 04:06:04 musicaluis volumio[1416]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Apr 05 04:06:04 musicaluis volumio[1416]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Apr 05 04:06:04 musicaluis volumio[1416]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Apr 05 04:06:04 musicaluis volumio[1416]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Apr 05 04:06:04 musicaluis volumio[1416]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Apr 05 04:06:04 musicaluis volumio[1416]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Apr 05 04:06:04 musicaluis volumio[1416]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Apr 05 04:06:04 musicaluis volumio[1416]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Apr 05 04:06:04 musicaluis volumio[1416]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Apr 05 04:06:04 musicaluis volumio[1416]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Apr 05 04:06:04 musicaluis volumio[1416]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Apr 05 04:06:04 musicaluis volumio[1416]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Apr 05 04:06:04 musicaluis volumio[1416]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Apr 05 04:06:04 musicaluis volumio[1416]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 05 04:06:04 musicaluis volumio[1416]: Error: getaddrinfo EBUSY ws.audioscrobbler.com Apr 05 04:06:04 musicaluis volumio[1416]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:67:26) { Apr 05 04:06:04 musicaluis volumio[1416]: errno: -16, Apr 05 04:06:04 musicaluis volumio[1416]: code: 'EBUSY', Apr 05 04:06:04 musicaluis volumio[1416]: syscall: 'getaddrinfo', Apr 05 04:06:04 musicaluis volumio[1416]: hostname: 'ws.audioscrobbler.com' Apr 05 04:06:04 musicaluis volumio[1416]: } Apr 05 04:06:04 musicaluis volumio[1416]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 05 04:06:04 musicaluis volumio-time-update[593]: volumio-time-update-util: Fetching time from Volumio... Apr 05 04:06:04 musicaluis volumio-time-update[593]: volumio-time-update-util: Date not found in response Apr 05 04:06:04 musicaluis volumio-time-update[593]: volumio-time-update-util: Retrying in 5 seconds... Apr 05 04:06:04 musicaluis sudo[1623]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-05 04:05 Apr 05 04:06:04 musicaluis sudo[1623]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 05 04:06:05 musicaluis sudo[1623]: pam_unix(sudo:session): session closed for user root Apr 05 04:06:05 musicaluis volumio-remote-updater[566]: [2025-04-05 04:06:05] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Apr 05 04:06:05 musicaluis volumio-remote-updater[566]: [2025-04-05 04:06:05] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Apr 05 04:06:05 musicaluis systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 05 04:06:05 musicaluis sudo[1572]: pam_unix(sudo:session): session closed for user root Apr 05 04:06:05 musicaluis sudo[1582]: pam_unix(sudo:session): session closed for user root Apr 05 04:06:05 musicaluis systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 05 04:06:05 musicaluis systemd[1]: Started dynamicswap service. Apr 05 04:06:05 musicaluis systemd[1]: dynamicswap.service: Succeeded. Apr 05 04:06:05 musicaluis systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Apr 05 04:06:05 musicaluis systemd[1]: volumio.service: Scheduled restart job, restart counter is at 3. Apr 05 04:06:05 musicaluis systemd[1]: Stopped Volumio Backend Module. Apr 05 04:06:05 musicaluis systemd[1]: Started Volumio Backend Module. Apr 05 04:06:05 musicaluis systemd[1]: Started dynamicswap service. Apr 05 04:06:05 musicaluis systemd[1]: dynamicswap.service: Succeeded. Apr 05 04:06:05 musicaluis mpd[1596]: Apr 05 04:06 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 05 04:06:05 musicaluis systemd[1]: Started Music Player Daemon. Apr 05 04:06:06 musicaluis wpa_supplicant[892]: wlan0: CTRL-EVENT-SSID-REENABLED id=0 ssid="NyMyL" Apr 05 04:06:06 musicaluis wpa_supplicant[892]: wlan0: Trying to associate with SSID 'NyMyL' Apr 05 04:06:06 musicaluis wpa_supplicant[892]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Apr 05 04:06:06 musicaluis wpa_supplicant[892]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=0 ssid="NyMyL" auth_failures=2 duration=23 reason=CONN_FAILED Apr 05 04:06:07 musicaluis volumio[1636]: info: ------------------------------------------- Apr 05 04:06:07 musicaluis volumio[1636]: info: ----- Volumio3 ---- Apr 05 04:06:07 musicaluis volumio[1636]: info: ------------------------------------------- Apr 05 04:06:07 musicaluis volumio[1636]: info: ----- System startup ---- Apr 05 04:06:07 musicaluis volumio[1636]: info: ------------------------------------------- Apr 05 04:06:07 musicaluis ntpd[813]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Apr 05 04:06:07 musicaluis volumio[1636]: info: MYVOLUMIO Environment detected Apr 05 04:06:07 musicaluis volumio[1636]: info: Plugin folders cleanup Apr 05 04:06:07 musicaluis volumio[1636]: info: Scanning into folder /volumio/app/plugins/ Apr 05 04:06:07 musicaluis volumio[1636]: info: Scanning category audio_interface Apr 05 04:06:07 musicaluis volumio[1636]: info: Scanning category miscellanea Apr 05 04:06:07 musicaluis volumio[1636]: info: Scanning category music_service Apr 05 04:06:07 musicaluis volumio[1636]: info: Scanning category plugins.json Apr 05 04:06:07 musicaluis volumio[1636]: info: Scanning category system_controller Apr 05 04:06:07 musicaluis volumio[1636]: info: Scanning category user_interface Apr 05 04:06:07 musicaluis volumio[1636]: info: Scanning into folder /data/plugins/ Apr 05 04:06:07 musicaluis volumio[1636]: info: Scanning category audio_interface Apr 05 04:06:07 musicaluis volumio[1636]: info: Scanning category music_service Apr 05 04:06:07 musicaluis volumio[1636]: info: Scanning category system_controller Apr 05 04:06:07 musicaluis volumio[1636]: info: Scanning category user_interface Apr 05 04:06:07 musicaluis volumio[1636]: info: Plugin folders cleanup completed Apr 05 04:06:07 musicaluis volumio[1636]: info: ------------------------------------------- Apr 05 04:06:07 musicaluis volumio[1636]: info: ----- Core plugins startup ---- Apr 05 04:06:07 musicaluis volumio[1636]: info: ------------------------------------------- Apr 05 04:06:07 musicaluis volumio[1636]: info: Loading plugins from folder /volumio/app/plugins/ Apr 05 04:06:07 musicaluis volumio[1636]: info: Adding plugin upnp to MyMusic Plugins Apr 05 04:06:07 musicaluis volumio[1636]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 05 04:06:07 musicaluis volumio[1636]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 05 04:06:07 musicaluis volumio[1636]: info: Loading plugins from folder /data/plugins/ Apr 05 04:06:07 musicaluis volumio[1636]: info: Loading plugin "system"... Apr 05 04:06:07 musicaluis volumio[1636]: info: Loading plugin "appearance"... Apr 05 04:06:08 musicaluis ntpd[813]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Apr 05 04:06:08 musicaluis volumio[1636]: info: Loading plugin "network"... Apr 05 04:06:08 musicaluis volumio[1636]: info: Refreshing Cached IP Addresses Apr 05 04:06:09 musicaluis sudo[1666]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 05 04:06:09 musicaluis sudo[1666]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 05 04:06:09 musicaluis sudo[1668]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 05 04:06:09 musicaluis sudo[1668]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 05 04:06:09 musicaluis sudo[1666]: pam_unix(sudo:session): session closed for user root Apr 05 04:06:09 musicaluis sudo[1668]: pam_unix(sudo:session): session closed for user root Apr 05 04:06:09 musicaluis volumio[1636]: info: Loading plugin "services"... Apr 05 04:06:09 musicaluis volumio[1636]: info: Loading plugin "alsa_controller"... Apr 05 04:06:09 musicaluis sudo[1677]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 05 04:06:09 musicaluis sudo[1677]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 05 04:06:09 musicaluis volumio[1636]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 05 04:06:09 musicaluis volumio[1636]: info: Loading plugin "wizard"... Apr 05 04:06:09 musicaluis volumio[1636]: info: Loading plugin "networkfs"... Apr 05 04:06:09 musicaluis volumio[1636]: info: Starting Udev Watcher for removable devices Apr 05 04:06:09 musicaluis volumio[1636]: info: Ignoring mount for partition: boot Apr 05 04:06:09 musicaluis volumio[1636]: info: Ignoring mount for partition: volumio Apr 05 04:06:09 musicaluis volumio[1636]: info: Ignoring mount for partition: volumio_data Apr 05 04:06:09 musicaluis volumio[1636]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 05 04:06:09 musicaluis volumio[1636]: info: Loading plugin "volumio_command_line_client"... Apr 05 04:06:09 musicaluis volumio[1636]: info: Loading plugin "upnp"... Apr 05 04:06:09 musicaluis volumio[1636]: info: [1743818769242] Starting Upmpd Daemon Apr 05 04:06:09 musicaluis volumio[1636]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 05 04:06:09 musicaluis volumio[1636]: info: Loading plugin "my_music"... Apr 05 04:06:09 musicaluis volumio[1636]: info: Loading plugin "mpd"... Apr 05 04:06:09 musicaluis volumio[1636]: info: Loading plugin "upnp_browser"... Apr 05 04:06:09 musicaluis volumio-time-update[593]: volumio-time-update-util: Fetching time from Volumio... Apr 05 04:06:09 musicaluis sudo[1677]: pam_unix(sudo:session): session closed for user root Apr 05 04:06:09 musicaluis volumio-time-update[593]: volumio-time-update-util: Date not found in response Apr 05 04:06:09 musicaluis volumio-time-update[593]: volumio-time-update-util: Retrying in 5 seconds... Apr 05 04:06:09 musicaluis volumio[1636]: info: Loading plugin "alarm-clock"... Apr 05 04:06:10 musicaluis volumio[1636]: info: Loading plugin "airplay_emulation"... Apr 05 04:06:10 musicaluis volumio[1636]: info: Starting Shairport Sync Apr 05 04:06:10 musicaluis volumio[1636]: info: Loading plugin "last_100"... Apr 05 04:06:10 musicaluis volumio[1636]: info: Loading plugin "webradio"... Apr 05 04:06:10 musicaluis volumio[1636]: info: Loading plugin "i2s_dacs"... Apr 05 04:06:10 musicaluis volumio[1636]: info: Loading plugin "volumiodiscovery"... Apr 05 04:06:10 musicaluis volumio-remote-updater[566]: [2025-04-05 04:06:10] [connect] Successful connection Apr 05 04:06:10 musicaluis volumio[1636]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 05 04:06:10 musicaluis volumio[1636]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 05 04:06:10 musicaluis volumio[1636]: *** WARNING *** For more information see Apr 05 04:06:10 musicaluis node[1636]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 05 04:06:10 musicaluis volumio[1636]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 05 04:06:10 musicaluis volumio[1636]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 05 04:06:10 musicaluis volumio[1636]: *** WARNING *** For more information see Apr 05 04:06:10 musicaluis node[1636]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 05 04:06:10 musicaluis node[1636]: *** WARNING *** For more information see Apr 05 04:06:10 musicaluis node[1636]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 05 04:06:10 musicaluis node[1636]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 05 04:06:10 musicaluis node[1636]: *** WARNING *** For more information see Apr 05 04:06:10 musicaluis volumio[1636]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 05 04:06:10 musicaluis volumio[1636]: info: Discovery: Started advertising with name: MusicaLuis Apr 05 04:06:10 musicaluis volumio[1636]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 05 04:06:10 musicaluis volumio[1636]: info: Loading plugin "autostart"... Apr 05 04:06:10 musicaluis ntpd[813]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Apr 05 04:06:10 musicaluis volumio[1636]: info: Applying required configuration parameters for plugin autostart Apr 05 04:06:10 musicaluis volumio[1636]: info: AutoStart - onVolumioStart - read config.json Apr 05 04:06:10 musicaluis volumio[1636]: info: Loading plugin "now_playing"... Apr 05 04:06:11 musicaluis ntpd[813]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Apr 05 04:06:11 musicaluis volumio[1636]: info: Loading plugin "outputs"... Apr 05 04:06:11 musicaluis volumio[1636]: info: Loading plugin "albumart"... Apr 05 04:06:11 musicaluis volumio[1636]: info: Plugin example_plugin is not enabled Apr 05 04:06:11 musicaluis volumio[1636]: info: Loading plugin "inputs"... Apr 05 04:06:11 musicaluis volumio[1636]: info: Loading plugin "updater_comm"... Apr 05 04:06:11 musicaluis volumio[1636]: info: Plugin mpdemulation is not enabled Apr 05 04:06:11 musicaluis volumio[1636]: info: Loading plugin "rest_api"... Apr 05 04:06:11 musicaluis volumio[1636]: info: Loading plugin "websocket"... Apr 05 04:06:11 musicaluis volumio[1636]: info: Starting Socket.io Server version 2.3.0 Apr 05 04:06:11 musicaluis volumio[1636]: info: Loading plugin "fusiondsp"... Apr 05 04:06:12 musicaluis volumio[1636]: Forking 3 albumart workers Apr 05 04:06:12 musicaluis volumio[1636]: info: Applying required configuration parameters for plugin fusiondsp Apr 05 04:06:12 musicaluis volumio[1636]: info: Loading plugin "volusonic"... Apr 05 04:06:13 musicaluis volumio[1636]: Starting albumart workers Apr 05 04:06:13 musicaluis volumio[1636]: Starting albumart workers Apr 05 04:06:13 musicaluis volumio[1636]: Starting albumart workers Apr 05 04:06:13 musicaluis volumio[1636]: info: Applying required configuration parameters for plugin volusonic Apr 05 04:06:13 musicaluis volumio[1636]: info: Loading plugin "lastfm"... Apr 05 04:06:14 musicaluis volumio[1636]: info: Loading i18n strings for locale en Apr 05 04:06:14 musicaluis volumio[1636]: info: Plugin volusonic has duplicated i18n key SAVE. It is ignored. Apr 05 04:06:14 musicaluis volumio[1636]: Updating browse sources language Apr 05 04:06:14 musicaluis volumio[1636]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 05 04:06:14 musicaluis volumio[1636]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 05 04:06:14 musicaluis volumio[1636]: info: CoreCommandRouter::initPlayerControls Apr 05 04:06:14 musicaluis volumio[1636]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 04:06:14 musicaluis volumio[1636]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 04:06:14 musicaluis volumio[1636]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 04:06:14 musicaluis volumio[1636]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 04:06:14 musicaluis volumio[1636]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 04:06:14 musicaluis volumio[1636]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 04:06:14 musicaluis volumio[1636]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 04:06:14 musicaluis volumio[1636]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 04:06:14 musicaluis volumio[1636]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 05 04:06:14 musicaluis volumio[1636]: Express server listening on port 3000 Apr 05 04:06:14 musicaluis volumio[1636]: [Metrics] WebUI: 7s 897.32ms Apr 05 04:06:14 musicaluis volumio[1636]: info: CoreStateMachine::resetVolumioState Apr 05 04:06:14 musicaluis volumio[1636]: info: CoreStateMachine::getcurrentVolume Apr 05 04:06:14 musicaluis volumio[1636]: info: CoreCommandRouter::volumioRetrievevolume Apr 05 04:06:14 musicaluis volumio[1636]: info: Volumio Network Manager: Network status updated: 0 Apr 05 04:06:14 musicaluis volumio-remote-updater[566]: [2025-04-05 04:06:14] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1743818770 101 Apr 05 04:06:14 musicaluis volumio[1636]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Apr 05 04:06:14 musicaluis volumio[1636]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Apr 05 04:06:14 musicaluis volumio[1636]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 05 04:06:14 musicaluis volumio[1636]: info: Reloading queue from file Apr 05 04:06:14 musicaluis volumio[1636]: info: VolumeController:: Volume=100 Mute =false Apr 05 04:06:14 musicaluis volumio[1636]: info: CoreStateMachine::pushState Apr 05 04:06:14 musicaluis volumio[1636]: info: CorePlayQueue::getTrack 0 Apr 05 04:06:14 musicaluis volumio[1636]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 04:06:14 musicaluis volumio[1636]: info: CoreCommandRouter::volumioPushState Apr 05 04:06:14 musicaluis volumio[1636]: info: CoreStateMachine::updateTrackBlock Apr 05 04:06:14 musicaluis volumio[1636]: info: CorePlayQueue::getTrackBlock Apr 05 04:06:14 musicaluis volumio[1636]: info: CoreCommandRouter::volumioRetrievevolume Apr 05 04:06:14 musicaluis volumio[1636]: info: CoreStateMachine::setRepeat null single undefined Apr 05 04:06:14 musicaluis volumio[1636]: info: CoreStateMachine::pushState Apr 05 04:06:14 musicaluis volumio[1636]: info: CorePlayQueue::getTrack 0 Apr 05 04:06:14 musicaluis volumio[1636]: info: CoreCommandRouter::volumioPushState Apr 05 04:06:14 musicaluis volumio[1636]: info: CoreStateMachine::setRandom null Apr 05 04:06:14 musicaluis volumio[1636]: info: CoreStateMachine::pushState Apr 05 04:06:14 musicaluis volumio[1636]: info: CorePlayQueue::getTrack 0 Apr 05 04:06:14 musicaluis volumio[1636]: info: CoreCommandRouter::volumioPushState Apr 05 04:06:14 musicaluis volumio[1636]: info: Setting Device type: Raspberry PI Apr 05 04:06:14 musicaluis volumio-time-update[593]: volumio-time-update-util: Fetching time from Volumio... Apr 05 04:06:14 musicaluis volumio[1636]: info: Completed loading Core Plugins Apr 05 04:06:14 musicaluis volumio[1636]: info: Preparing to generate the ALSA configuration file Apr 05 04:06:14 musicaluis volumio-time-update[593]: volumio-time-update-util: Date not found in response Apr 05 04:06:14 musicaluis volumio-time-update[593]: volumio-time-update-util: Retrying in 5 seconds... Apr 05 04:06:14 musicaluis volumio[1636]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 05 04:06:14 musicaluis volumio[1636]: info: Reading ALSA contributions from plugins. Apr 05 04:06:14 musicaluis volumio[1636]: info: VolumeController:: Volume=100 Mute =false Apr 05 04:06:14 musicaluis volumio[1636]: info: CoreStateMachine::pushState Apr 05 04:06:14 musicaluis volumio[1636]: info: CorePlayQueue::getTrack 0 Apr 05 04:06:14 musicaluis volumio[1636]: info: CoreCommandRouter::volumioPushState Apr 05 04:06:14 musicaluis volumio[1636]: info: Asound.conf file unchanged, so no further update is needed Apr 05 04:06:14 musicaluis volumio[1636]: info: Output device has changed, restarting MPD Apr 05 04:06:14 musicaluis sudo[1769]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 05 04:06:14 musicaluis volumio[1636]: info: Output device has changed, restarting Shairport Sync Apr 05 04:06:14 musicaluis volumio[1636]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 04:06:14 musicaluis volumio[1636]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 05 04:06:14 musicaluis sudo[1769]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 05 04:06:15 musicaluis sudo[1769]: pam_unix(sudo:session): session closed for user root Apr 05 04:06:15 musicaluis sudo[1771]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 05 04:06:15 musicaluis sudo[1771]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 05 04:06:15 musicaluis systemd[1]: Stopping Music Player Daemon... Apr 05 04:06:15 musicaluis volumio[1636]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 05 04:06:15 musicaluis systemd[1]: mpd.service: Succeeded. Apr 05 04:06:15 musicaluis volumio[1636]: info: ___________ START PLUGINS ___________ Apr 05 04:06:15 musicaluis systemd[1]: Stopped Music Player Daemon. Apr 05 04:06:15 musicaluis volumio[1636]: info: ControllerMpd::onStart: Initializing MPD Apr 05 04:06:15 musicaluis volumio[1636]: info: Creating MPD Configuration file Apr 05 04:06:15 musicaluis sudo[1779]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 05 04:06:15 musicaluis volumio[1636]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 05 04:06:15 musicaluis volumio[1636]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 05 04:06:15 musicaluis volumio[1636]: info: [1743818775119] CoreMusicLibrary::Adding element Media Servers Apr 05 04:06:15 musicaluis volumio[1636]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 05 04:06:15 musicaluis systemd[1]: Starting Music Player Daemon... Apr 05 04:06:15 musicaluis sudo[1779]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 05 04:06:15 musicaluis sudo[1779]: pam_unix(sudo:session): session closed for user root Apr 05 04:06:15 musicaluis sudo[1781]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 05 04:06:15 musicaluis sudo[1781]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 05 04:06:15 musicaluis volumio[1636]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 04:06:15 musicaluis volumio[1636]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 05 04:06:15 musicaluis systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Apr 05 04:06:15 musicaluis systemd[1]: mpd.service: Succeeded. Apr 05 04:06:15 musicaluis systemd[1]: Stopped Music Player Daemon. Apr 05 04:06:15 musicaluis systemd[1]: Starting Music Player Daemon... Apr 05 04:06:15 musicaluis volumio[1636]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 05 04:06:15 musicaluis volumio[1636]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 04:06:15 musicaluis volumio[1636]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 05 04:06:15 musicaluis volumio[1636]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 05 04:06:15 musicaluis volumio[1636]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 05 04:06:15 musicaluis volumio[1636]: info: [1743818775250] CoreMusicLibrary::Adding element Last_100 Apr 05 04:06:15 musicaluis volumio[1636]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 05 04:06:15 musicaluis volumio[1636]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 05 04:06:15 musicaluis volumio[1636]: info: [1743818775254] CoreMusicLibrary::Adding element Webradio Apr 05 04:06:15 musicaluis volumio[1636]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 05 04:06:15 musicaluis volumio[1636]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 05 04:06:15 musicaluis volumio[1636]: info: Initializing BBC Radios Apr 05 04:06:15 musicaluis sudo[1787]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 05 04:06:15 musicaluis sudo[1787]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 05 04:06:15 musicaluis sudo[1787]: pam_unix(sudo:session): session closed for user root Apr 05 04:06:15 musicaluis volumio[1636]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 05 04:06:15 musicaluis volumio[1636]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 05 04:06:15 musicaluis volumio[1636]: info: AutoStart - onStart Apr 05 04:06:15 musicaluis volumio[1636]: info: [now-playing-config] Config is up to date. Apr 05 04:06:15 musicaluis volumio[1636]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 05 04:06:15 musicaluis volumio[1636]: info: Discovery: Getting this device information Apr 05 04:06:15 musicaluis volumio[1636]: info: CoreCommandRouter::volumioGetState Apr 05 04:06:15 musicaluis volumio[1636]: info: CorePlayQueue::getTrack 0 Apr 05 04:06:15 musicaluis volumio[1636]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 05 04:06:15 musicaluis volumio[1636]: info: Loading i18n strings for locale en Apr 05 04:06:15 musicaluis volumio[1636]: info: Plugin volusonic has duplicated i18n key SAVE. It is ignored. Apr 05 04:06:15 musicaluis volumio[1636]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Apr 05 04:06:15 musicaluis volumio[1636]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 04:06:15 musicaluis volumio[1636]: info: FusionDsp - mixtype--------------------- Hardware Apr 05 04:06:15 musicaluis volumio[1636]: info: Loading i18n strings for locale en Apr 05 04:06:15 musicaluis volumio[1636]: info: Plugin volusonic has duplicated i18n key SAVE. It is ignored. Apr 05 04:06:15 musicaluis volumio[1636]: Updating browse sources language Apr 05 04:06:15 musicaluis volumio[1636]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 05 04:06:15 musicaluis volumio[1636]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 05 04:06:15 musicaluis volumio[1636]: info: [1743818775451] CoreMusicLibrary::Adding element Volusonic Apr 05 04:06:15 musicaluis volumio[1636]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 05 04:06:15 musicaluis volumio[1636]: Cannot find translation for source Volusonic Apr 05 04:06:15 musicaluis volumio[1636]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 05 04:06:15 musicaluis volumio[1636]: info: [1743818775455] CoreMusicLibrary::Adding element LastFM Apr 05 04:06:15 musicaluis volumio[1636]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 05 04:06:15 musicaluis volumio[1636]: Cannot find translation for source Volusonic Apr 05 04:06:15 musicaluis volumio[1636]: Cannot find translation for source LastFM Apr 05 04:06:15 musicaluis volumio[1636]: info: [LastFM] scrobbler initiated! Apr 05 04:06:15 musicaluis volumio[1636]: info: [LastFM] extended logging: false Apr 05 04:06:15 musicaluis volumio[1636]: info: [LastFM] try scrobble stream/radio plays: true Apr 05 04:06:15 musicaluis volumio[1636]: info: [LastFM] Left init routine Apr 05 04:06:15 musicaluis volumio[1636]: info: [LastFM] Socket already connected: true Apr 05 04:06:15 musicaluis volumio[1636]: info: Volumio Calling Home Apr 05 04:06:15 musicaluis volumio[1636]: info: [now-playing-app] App is listening on port 4004. Apr 05 04:06:15 musicaluis volumio[1636]: info: Preparing to generate the ALSA configuration file Apr 05 04:06:15 musicaluis volumio[1636]: Unhandled rejection Error: No sockets available, cannot start. Apr 05 04:06:15 musicaluis volumio[1636]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Apr 05 04:06:15 musicaluis volumio[1636]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Apr 05 04:06:15 musicaluis volumio[1636]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Apr 05 04:06:15 musicaluis volumio[1636]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Apr 05 04:06:15 musicaluis volumio[1636]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Apr 05 04:06:15 musicaluis volumio[1636]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Apr 05 04:06:15 musicaluis volumio[1636]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Apr 05 04:06:15 musicaluis volumio[1636]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Apr 05 04:06:15 musicaluis volumio[1636]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Apr 05 04:06:15 musicaluis volumio[1636]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Apr 05 04:06:15 musicaluis volumio[1636]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Apr 05 04:06:15 musicaluis volumio[1636]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Apr 05 04:06:15 musicaluis volumio[1636]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Apr 05 04:06:15 musicaluis volumio[1636]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Apr 05 04:06:15 musicaluis volumio[1636]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Apr 05 04:06:15 musicaluis volumio[1636]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Apr 05 04:06:15 musicaluis volumio[1636]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 05 04:06:15 musicaluis volumio[1636]: Error: getaddrinfo EBUSY ws.audioscrobbler.com Apr 05 04:06:15 musicaluis volumio[1636]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:67:26) { Apr 05 04:06:15 musicaluis volumio[1636]: errno: -16, Apr 05 04:06:15 musicaluis volumio[1636]: code: 'EBUSY', Apr 05 04:06:15 musicaluis volumio[1636]: syscall: 'getaddrinfo', Apr 05 04:06:15 musicaluis volumio[1636]: hostname: 'ws.audioscrobbler.com' Apr 05 04:06:15 musicaluis volumio[1636]: } Apr 05 04:06:15 musicaluis volumio[1636]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 05 04:06:16 musicaluis sudo[1815]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-05 04:05 Apr 05 04:06:16 musicaluis sudo[1815]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 05 04:06:16 musicaluis sudo[1815]: pam_unix(sudo:session): session closed for user root Apr 05 04:06:16 musicaluis volumio-remote-updater[566]: [2025-04-05 04:06:16] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Apr 05 04:06:16 musicaluis volumio-remote-updater[566]: [2025-04-05 04:06:16] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Apr 05 04:06:16 musicaluis systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 05 04:06:16 musicaluis sudo[1771]: pam_unix(sudo:session): session closed for user root Apr 05 04:06:16 musicaluis sudo[1781]: pam_unix(sudo:session): session closed for user root Apr 05 04:06:16 musicaluis systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 05 04:06:16 musicaluis systemd[1]: Started dynamicswap service. Apr 05 04:06:16 musicaluis systemd[1]: dynamicswap.service: Succeeded. Apr 05 04:06:16 musicaluis systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Apr 05 04:06:16 musicaluis systemd[1]: volumio.service: Scheduled restart job, restart counter is at 4. Apr 05 04:06:16 musicaluis mpd[1798]: Apr 05 04:06 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 05 04:06:16 musicaluis systemd[1]: Started dynamicswap service. Apr 05 04:06:16 musicaluis systemd[1]: Stopped Volumio Backend Module. Apr 05 04:06:16 musicaluis systemd[1]: Started Volumio Backend Module. Apr 05 04:06:16 musicaluis systemd[1]: Started Music Player Daemon. Apr 05 04:06:16 musicaluis systemd[1]: dynamicswap.service: Succeeded. Apr 05 04:06:18 musicaluis volumio[1831]: info: ------------------------------------------- Apr 05 04:06:18 musicaluis volumio[1831]: info: ----- Volumio3 ---- Apr 05 04:06:18 musicaluis volumio[1831]: info: ------------------------------------------- Apr 05 04:06:18 musicaluis volumio[1831]: info: ----- System startup ---- Apr 05 04:06:18 musicaluis volumio[1831]: info: ------------------------------------------- Apr 05 04:06:19 musicaluis volumio[1831]: info: MYVOLUMIO Environment detected Apr 05 04:06:19 musicaluis volumio[1831]: info: Plugin folders cleanup Apr 05 04:06:19 musicaluis volumio[1831]: info: Scanning into folder /volumio/app/plugins/ Apr 05 04:06:19 musicaluis volumio[1831]: info: Scanning category audio_interface Apr 05 04:06:19 musicaluis volumio[1831]: info: Scanning category miscellanea Apr 05 04:06:19 musicaluis volumio[1831]: info: Scanning category music_service Apr 05 04:06:19 musicaluis volumio[1831]: info: Scanning category plugins.json Apr 05 04:06:19 musicaluis volumio[1831]: info: Scanning category system_controller Apr 05 04:06:19 musicaluis volumio[1831]: info: Scanning category user_interface Apr 05 04:06:19 musicaluis volumio[1831]: info: Scanning into folder /data/plugins/ Apr 05 04:06:19 musicaluis volumio[1831]: info: Scanning category audio_interface Apr 05 04:06:19 musicaluis volumio[1831]: info: Scanning category music_service Apr 05 04:06:19 musicaluis volumio[1831]: info: Scanning category system_controller Apr 05 04:06:19 musicaluis volumio[1831]: info: Scanning category user_interface Apr 05 04:06:19 musicaluis volumio[1831]: info: Plugin folders cleanup completed Apr 05 04:06:19 musicaluis volumio[1831]: info: ------------------------------------------- Apr 05 04:06:19 musicaluis volumio[1831]: info: ----- Core plugins startup ---- Apr 05 04:06:19 musicaluis volumio[1831]: info: ------------------------------------------- Apr 05 04:06:19 musicaluis volumio[1831]: info: Loading plugins from folder /volumio/app/plugins/ Apr 05 04:06:19 musicaluis volumio[1831]: info: Adding plugin upnp to MyMusic Plugins Apr 05 04:06:19 musicaluis volumio[1831]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 05 04:06:19 musicaluis volumio[1831]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 05 04:06:19 musicaluis volumio[1831]: info: Loading plugins from folder /data/plugins/ Apr 05 04:06:19 musicaluis volumio[1831]: info: Loading plugin "system"... Apr 05 04:06:19 musicaluis volumio[1831]: info: Loading plugin "appearance"... Apr 05 04:06:19 musicaluis volumio-time-update[593]: volumio-time-update-util: Fetching time from Volumio... Apr 05 04:06:19 musicaluis volumio-time-update[593]: volumio-time-update-util: Date not found in response Apr 05 04:06:19 musicaluis volumio-time-update[593]: volumio-time-update-util: Retrying in 5 seconds... Apr 05 04:06:20 musicaluis volumio[1831]: info: Loading plugin "network"... Apr 05 04:06:20 musicaluis volumio[1831]: info: Refreshing Cached IP Addresses Apr 05 04:06:20 musicaluis sudo[1862]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 05 04:06:20 musicaluis sudo[1862]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 05 04:06:20 musicaluis sudo[1862]: pam_unix(sudo:session): session closed for user root Apr 05 04:06:20 musicaluis sudo[1864]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 05 04:06:20 musicaluis sudo[1864]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 05 04:06:20 musicaluis sudo[1864]: pam_unix(sudo:session): session closed for user root Apr 05 04:06:20 musicaluis volumio[1831]: info: Loading plugin "services"... Apr 05 04:06:20 musicaluis volumio[1831]: info: Loading plugin "alsa_controller"... Apr 05 04:06:20 musicaluis sudo[1877]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 05 04:06:20 musicaluis sudo[1877]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 05 04:06:20 musicaluis volumio[1831]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 05 04:06:20 musicaluis volumio[1831]: info: Loading plugin "wizard"... Apr 05 04:06:20 musicaluis volumio[1831]: info: Loading plugin "networkfs"... Apr 05 04:06:20 musicaluis volumio[1831]: info: Starting Udev Watcher for removable devices Apr 05 04:06:20 musicaluis volumio[1831]: info: Ignoring mount for partition: boot Apr 05 04:06:20 musicaluis volumio[1831]: info: Ignoring mount for partition: volumio Apr 05 04:06:20 musicaluis volumio[1831]: info: Ignoring mount for partition: volumio_data Apr 05 04:06:20 musicaluis volumio[1831]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 05 04:06:20 musicaluis volumio[1831]: info: Loading plugin "volumio_command_line_client"... Apr 05 04:06:20 musicaluis volumio[1831]: info: Loading plugin "upnp"... Apr 05 04:06:20 musicaluis volumio[1831]: info: [1743818780828] Starting Upmpd Daemon Apr 05 04:06:20 musicaluis volumio[1831]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 05 04:06:20 musicaluis volumio[1831]: info: Loading plugin "my_music"... Apr 05 04:06:20 musicaluis volumio[1831]: info: Loading plugin "mpd"... Apr 05 04:06:21 musicaluis volumio[1831]: info: Loading plugin "upnp_browser"... Apr 05 04:06:21 musicaluis sudo[1877]: pam_unix(sudo:session): session closed for user root Apr 05 04:06:21 musicaluis volumio[1831]: info: Loading plugin "alarm-clock"... Apr 05 04:06:21 musicaluis volumio-remote-updater[566]: [2025-04-05 04:06:21] [connect] Successful connection Apr 05 04:06:21 musicaluis volumio[1831]: info: Loading plugin "airplay_emulation"... Apr 05 04:06:21 musicaluis volumio[1831]: info: Starting Shairport Sync Apr 05 04:06:21 musicaluis volumio[1831]: info: Loading plugin "last_100"... Apr 05 04:06:21 musicaluis volumio[1831]: info: Loading plugin "webradio"... Apr 05 04:06:21 musicaluis volumio[1831]: info: Loading plugin "i2s_dacs"... Apr 05 04:06:21 musicaluis volumio[1831]: info: Loading plugin "volumiodiscovery"... Apr 05 04:06:21 musicaluis volumio[1831]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 05 04:06:21 musicaluis volumio[1831]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 05 04:06:21 musicaluis volumio[1831]: *** WARNING *** For more information see Apr 05 04:06:21 musicaluis node[1831]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 05 04:06:21 musicaluis volumio[1831]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 05 04:06:21 musicaluis volumio[1831]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 05 04:06:21 musicaluis volumio[1831]: *** WARNING *** For more information see Apr 05 04:06:21 musicaluis node[1831]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 05 04:06:21 musicaluis node[1831]: *** WARNING *** For more information see Apr 05 04:06:21 musicaluis node[1831]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 05 04:06:21 musicaluis node[1831]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 05 04:06:21 musicaluis node[1831]: *** WARNING *** For more information see Apr 05 04:06:21 musicaluis volumio[1831]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 05 04:06:21 musicaluis volumio[1831]: info: Discovery: Started advertising with name: MusicaLuis Apr 05 04:06:21 musicaluis volumio[1831]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 05 04:06:21 musicaluis volumio[1831]: info: Loading plugin "autostart"... Apr 05 04:06:22 musicaluis volumio[1831]: info: Applying required configuration parameters for plugin autostart Apr 05 04:06:22 musicaluis volumio[1831]: info: AutoStart - onVolumioStart - read config.json Apr 05 04:06:22 musicaluis volumio[1831]: info: Loading plugin "now_playing"... Apr 05 04:06:23 musicaluis volumio[1831]: info: Loading plugin "outputs"... Apr 05 04:06:23 musicaluis volumio[1831]: info: Loading plugin "albumart"... Apr 05 04:06:23 musicaluis volumio[1831]: info: Plugin example_plugin is not enabled Apr 05 04:06:23 musicaluis volumio[1831]: info: Loading plugin "inputs"... Apr 05 04:06:23 musicaluis volumio[1831]: info: Loading plugin "updater_comm"... Apr 05 04:06:23 musicaluis volumio[1831]: info: Plugin mpdemulation is not enabled Apr 05 04:06:23 musicaluis volumio[1831]: info: Loading plugin "rest_api"... Apr 05 04:06:23 musicaluis volumio[1831]: info: Loading plugin "websocket"... Apr 05 04:06:23 musicaluis volumio[1831]: info: Starting Socket.io Server version 2.3.0 Apr 05 04:06:23 musicaluis volumio[1831]: info: Loading plugin "fusiondsp"... Apr 05 04:06:23 musicaluis volumio[1831]: Forking 3 albumart workers Apr 05 04:06:23 musicaluis volumio[1831]: info: Applying required configuration parameters for plugin fusiondsp Apr 05 04:06:24 musicaluis volumio[1831]: info: Loading plugin "volusonic"... Apr 05 04:06:24 musicaluis volumio[1831]: Starting albumart workers Apr 05 04:06:24 musicaluis volumio[1831]: Starting albumart workers Apr 05 04:06:24 musicaluis volumio-time-update[593]: volumio-time-update-util: Fetching time from Volumio... Apr 05 04:06:24 musicaluis volumio[1831]: Starting albumart workers Apr 05 04:06:24 musicaluis volumio-time-update[593]: volumio-time-update-util: Date not found in response Apr 05 04:06:24 musicaluis volumio-time-update[593]: volumio-time-update-util: Retrying in 5 seconds... Apr 05 04:06:25 musicaluis volumio[1831]: info: Applying required configuration parameters for plugin volusonic Apr 05 04:06:25 musicaluis volumio[1831]: info: Loading plugin "lastfm"... Apr 05 04:06:25 musicaluis volumio[1831]: info: Loading i18n strings for locale en Apr 05 04:06:25 musicaluis volumio[1831]: info: Plugin volusonic has duplicated i18n key SAVE. It is ignored. Apr 05 04:06:25 musicaluis volumio[1831]: Updating browse sources language Apr 05 04:06:25 musicaluis volumio[1831]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 05 04:06:26 musicaluis volumio[1831]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 05 04:06:26 musicaluis volumio[1831]: info: CoreCommandRouter::initPlayerControls Apr 05 04:06:26 musicaluis volumio[1831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 04:06:26 musicaluis volumio[1831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 04:06:26 musicaluis volumio[1831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 04:06:26 musicaluis volumio[1831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 04:06:26 musicaluis volumio[1831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 04:06:26 musicaluis volumio[1831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 04:06:26 musicaluis volumio[1831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 04:06:26 musicaluis volumio[1831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 04:06:26 musicaluis volumio[1831]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 05 04:06:26 musicaluis volumio[1831]: Express server listening on port 3000 Apr 05 04:06:26 musicaluis volumio[1831]: [Metrics] WebUI: 8s 18.10ms Apr 05 04:06:26 musicaluis volumio[1831]: info: CoreStateMachine::resetVolumioState Apr 05 04:06:26 musicaluis volumio[1831]: info: CoreStateMachine::getcurrentVolume Apr 05 04:06:26 musicaluis volumio[1831]: info: CoreCommandRouter::volumioRetrievevolume Apr 05 04:06:26 musicaluis volumio[1831]: info: Volumio Network Manager: Network status updated: 0 Apr 05 04:06:26 musicaluis volumio-remote-updater[566]: [2025-04-05 04:06:26] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1743818781 101 Apr 05 04:06:26 musicaluis volumio[1831]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Apr 05 04:06:26 musicaluis volumio[1831]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Apr 05 04:06:26 musicaluis volumio[1831]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 05 04:06:26 musicaluis volumio[1831]: info: Reloading queue from file Apr 05 04:06:26 musicaluis volumio[1831]: info: VolumeController:: Volume=100 Mute =false Apr 05 04:06:26 musicaluis volumio[1831]: info: CoreStateMachine::pushState Apr 05 04:06:26 musicaluis volumio[1831]: info: CorePlayQueue::getTrack 0 Apr 05 04:06:26 musicaluis volumio[1831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 04:06:26 musicaluis volumio[1831]: info: CoreCommandRouter::volumioPushState Apr 05 04:06:26 musicaluis volumio[1831]: info: CoreStateMachine::updateTrackBlock Apr 05 04:06:26 musicaluis volumio[1831]: info: CorePlayQueue::getTrackBlock Apr 05 04:06:26 musicaluis volumio[1831]: info: CoreCommandRouter::volumioRetrievevolume Apr 05 04:06:26 musicaluis volumio[1831]: info: CoreStateMachine::setRepeat null single undefined Apr 05 04:06:26 musicaluis volumio[1831]: info: CoreStateMachine::pushState Apr 05 04:06:26 musicaluis volumio[1831]: info: CorePlayQueue::getTrack 0 Apr 05 04:06:26 musicaluis volumio[1831]: info: CoreCommandRouter::volumioPushState Apr 05 04:06:26 musicaluis volumio[1831]: info: CoreStateMachine::setRandom null Apr 05 04:06:26 musicaluis volumio[1831]: info: CoreStateMachine::pushState Apr 05 04:06:26 musicaluis volumio[1831]: info: CorePlayQueue::getTrack 0 Apr 05 04:06:26 musicaluis volumio[1831]: info: CoreCommandRouter::volumioPushState Apr 05 04:06:26 musicaluis volumio[1831]: info: Setting Device type: Raspberry PI Apr 05 04:06:26 musicaluis volumio[1831]: info: Completed loading Core Plugins Apr 05 04:06:26 musicaluis volumio[1831]: info: Preparing to generate the ALSA configuration file Apr 05 04:06:26 musicaluis volumio[1831]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 05 04:06:26 musicaluis volumio[1831]: info: Reading ALSA contributions from plugins. Apr 05 04:06:26 musicaluis volumio[1831]: info: VolumeController:: Volume=100 Mute =false Apr 05 04:06:26 musicaluis volumio[1831]: info: CoreStateMachine::pushState Apr 05 04:06:26 musicaluis volumio[1831]: info: CorePlayQueue::getTrack 0 Apr 05 04:06:26 musicaluis volumio[1831]: info: CoreCommandRouter::volumioPushState Apr 05 04:06:26 musicaluis volumio[1831]: info: Asound.conf file unchanged, so no further update is needed Apr 05 04:06:26 musicaluis volumio[1831]: info: Output device has changed, restarting MPD Apr 05 04:06:26 musicaluis sudo[1963]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 05 04:06:26 musicaluis volumio[1831]: info: Output device has changed, restarting Shairport Sync Apr 05 04:06:26 musicaluis sudo[1963]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 05 04:06:26 musicaluis volumio[1831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 04:06:26 musicaluis volumio[1831]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 05 04:06:26 musicaluis sudo[1963]: pam_unix(sudo:session): session closed for user root Apr 05 04:06:26 musicaluis sudo[1965]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 05 04:06:26 musicaluis sudo[1965]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 05 04:06:26 musicaluis systemd[1]: Stopping Music Player Daemon... Apr 05 04:06:26 musicaluis volumio[1831]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 05 04:06:26 musicaluis volumio[1831]: info: ___________ START PLUGINS ___________ Apr 05 04:06:26 musicaluis systemd[1]: mpd.service: Succeeded. Apr 05 04:06:26 musicaluis systemd[1]: Stopped Music Player Daemon. Apr 05 04:06:26 musicaluis volumio[1831]: info: ControllerMpd::onStart: Initializing MPD Apr 05 04:06:26 musicaluis volumio[1831]: info: Creating MPD Configuration file Apr 05 04:06:26 musicaluis systemd[1]: Starting Music Player Daemon... Apr 05 04:06:26 musicaluis sudo[1973]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 05 04:06:26 musicaluis volumio[1831]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 05 04:06:26 musicaluis sudo[1973]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 05 04:06:26 musicaluis volumio[1831]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 05 04:06:26 musicaluis volumio[1831]: info: [1743818786780] CoreMusicLibrary::Adding element Media Servers Apr 05 04:06:26 musicaluis volumio[1831]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 05 04:06:26 musicaluis sudo[1973]: pam_unix(sudo:session): session closed for user root Apr 05 04:06:26 musicaluis sudo[1975]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 05 04:06:26 musicaluis sudo[1975]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 05 04:06:26 musicaluis volumio[1831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 04:06:26 musicaluis volumio[1831]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 05 04:06:26 musicaluis systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Apr 05 04:06:26 musicaluis systemd[1]: mpd.service: Succeeded. Apr 05 04:06:26 musicaluis systemd[1]: Stopped Music Player Daemon. Apr 05 04:06:26 musicaluis systemd[1]: Starting Music Player Daemon... Apr 05 04:06:26 musicaluis volumio[1831]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 05 04:06:26 musicaluis volumio[1831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 04:06:26 musicaluis volumio[1831]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 05 04:06:26 musicaluis volumio[1831]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 05 04:06:26 musicaluis volumio[1831]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 05 04:06:26 musicaluis volumio[1831]: info: [1743818786912] CoreMusicLibrary::Adding element Last_100 Apr 05 04:06:26 musicaluis volumio[1831]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 05 04:06:26 musicaluis volumio[1831]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 05 04:06:26 musicaluis volumio[1831]: info: [1743818786915] CoreMusicLibrary::Adding element Webradio Apr 05 04:06:26 musicaluis volumio[1831]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 05 04:06:26 musicaluis volumio[1831]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 05 04:06:26 musicaluis volumio[1831]: info: Initializing BBC Radios Apr 05 04:06:26 musicaluis sudo[1981]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 05 04:06:26 musicaluis sudo[1981]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 05 04:06:26 musicaluis sudo[1981]: pam_unix(sudo:session): session closed for user root Apr 05 04:06:26 musicaluis volumio[1831]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 05 04:06:27 musicaluis volumio[1831]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 05 04:06:27 musicaluis volumio[1831]: info: AutoStart - onStart Apr 05 04:06:27 musicaluis volumio[1831]: info: [now-playing-config] Config is up to date. Apr 05 04:06:27 musicaluis volumio[1831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 05 04:06:27 musicaluis volumio[1831]: info: Discovery: Getting this device information Apr 05 04:06:27 musicaluis volumio[1831]: info: CoreCommandRouter::volumioGetState Apr 05 04:06:27 musicaluis volumio[1831]: info: CorePlayQueue::getTrack 0 Apr 05 04:06:27 musicaluis volumio[1831]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 05 04:06:27 musicaluis volumio[1831]: info: Loading i18n strings for locale en Apr 05 04:06:27 musicaluis volumio[1831]: info: Plugin volusonic has duplicated i18n key SAVE. It is ignored. Apr 05 04:06:27 musicaluis volumio[1831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Apr 05 04:06:27 musicaluis volumio[1831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 04:06:27 musicaluis volumio[1831]: info: FusionDsp - mixtype--------------------- Hardware Apr 05 04:06:27 musicaluis volumio[1831]: info: Loading i18n strings for locale en Apr 05 04:06:27 musicaluis volumio[1831]: info: Plugin volusonic has duplicated i18n key SAVE. It is ignored. Apr 05 04:06:27 musicaluis volumio[1831]: Updating browse sources language Apr 05 04:06:27 musicaluis volumio[1831]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 05 04:06:27 musicaluis volumio[1831]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 05 04:06:27 musicaluis volumio[1831]: info: [1743818787104] CoreMusicLibrary::Adding element Volusonic Apr 05 04:06:27 musicaluis volumio[1831]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 05 04:06:27 musicaluis volumio[1831]: Cannot find translation for source Volusonic Apr 05 04:06:27 musicaluis volumio[1831]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 05 04:06:27 musicaluis volumio[1831]: info: [1743818787108] CoreMusicLibrary::Adding element LastFM Apr 05 04:06:27 musicaluis volumio[1831]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 05 04:06:27 musicaluis volumio[1831]: Cannot find translation for source Volusonic Apr 05 04:06:27 musicaluis volumio[1831]: Cannot find translation for source LastFM Apr 05 04:06:27 musicaluis volumio[1831]: info: [LastFM] scrobbler initiated! Apr 05 04:06:27 musicaluis volumio[1831]: info: [LastFM] extended logging: false Apr 05 04:06:27 musicaluis volumio[1831]: info: [LastFM] try scrobble stream/radio plays: true Apr 05 04:06:27 musicaluis volumio[1831]: info: [LastFM] Left init routine Apr 05 04:06:27 musicaluis volumio[1831]: info: [LastFM] Socket already connected: true Apr 05 04:06:27 musicaluis volumio[1831]: info: Volumio Calling Home Apr 05 04:06:27 musicaluis volumio[1831]: info: [now-playing-app] App is listening on port 4004. Apr 05 04:06:27 musicaluis volumio[1831]: info: Preparing to generate the ALSA configuration file Apr 05 04:06:27 musicaluis volumio[1831]: Unhandled rejection Error: No sockets available, cannot start. Apr 05 04:06:27 musicaluis volumio[1831]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Apr 05 04:06:27 musicaluis volumio[1831]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Apr 05 04:06:27 musicaluis volumio[1831]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Apr 05 04:06:27 musicaluis volumio[1831]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Apr 05 04:06:27 musicaluis volumio[1831]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Apr 05 04:06:27 musicaluis volumio[1831]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Apr 05 04:06:27 musicaluis volumio[1831]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Apr 05 04:06:27 musicaluis volumio[1831]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Apr 05 04:06:27 musicaluis volumio[1831]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Apr 05 04:06:27 musicaluis volumio[1831]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Apr 05 04:06:27 musicaluis volumio[1831]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Apr 05 04:06:27 musicaluis volumio[1831]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Apr 05 04:06:27 musicaluis volumio[1831]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Apr 05 04:06:27 musicaluis volumio[1831]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Apr 05 04:06:27 musicaluis volumio[1831]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Apr 05 04:06:27 musicaluis volumio[1831]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Apr 05 04:06:27 musicaluis volumio[1831]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 05 04:06:27 musicaluis volumio[1831]: Error: getaddrinfo EBUSY ws.audioscrobbler.com Apr 05 04:06:27 musicaluis volumio[1831]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:67:26) { Apr 05 04:06:27 musicaluis volumio[1831]: errno: -16, Apr 05 04:06:27 musicaluis volumio[1831]: code: 'EBUSY', Apr 05 04:06:27 musicaluis volumio[1831]: syscall: 'getaddrinfo', Apr 05 04:06:27 musicaluis volumio[1831]: hostname: 'ws.audioscrobbler.com' Apr 05 04:06:27 musicaluis volumio[1831]: } Apr 05 04:06:27 musicaluis volumio[1831]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 05 04:06:28 musicaluis sudo[2009]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-05 04:05 Apr 05 04:06:28 musicaluis sudo[2009]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 05 04:06:28 musicaluis sudo[2009]: pam_unix(sudo:session): session closed for user root Apr 05 04:06:28 musicaluis systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 05 04:06:28 musicaluis volumio-remote-updater[566]: [2025-04-05 04:06:28] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Apr 05 04:06:28 musicaluis volumio-remote-updater[566]: [2025-04-05 04:06:28] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Apr 05 04:06:28 musicaluis sudo[1965]: pam_unix(sudo:session): session closed for user root Apr 05 04:06:28 musicaluis sudo[1975]: pam_unix(sudo:session): session closed for user root Apr 05 04:06:28 musicaluis systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 05 04:06:28 musicaluis systemd[1]: Started dynamicswap service. Apr 05 04:06:28 musicaluis systemd[1]: dynamicswap.service: Succeeded. Apr 05 04:06:28 musicaluis mpd[1992]: Apr 05 04:06 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 05 04:06:28 musicaluis systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Apr 05 04:06:28 musicaluis systemd[1]: volumio.service: Scheduled restart job, restart counter is at 5. Apr 05 04:06:28 musicaluis systemd[1]: Started Music Player Daemon. Apr 05 04:06:28 musicaluis systemd[1]: Started dynamicswap service. Apr 05 04:06:28 musicaluis systemd[1]: Stopped Volumio Backend Module. Apr 05 04:06:28 musicaluis systemd[1]: Started Volumio Backend Module. Apr 05 04:06:28 musicaluis systemd[1]: dynamicswap.service: Succeeded. Apr 05 04:06:29 musicaluis wpa_supplicant[892]: wlan0: CTRL-EVENT-SSID-REENABLED id=0 ssid="NyMyL" Apr 05 04:06:29 musicaluis wpa_supplicant[892]: wlan0: Trying to associate with SSID 'NyMyL' Apr 05 04:06:29 musicaluis volumio-time-update[593]: volumio-time-update-util: Fetching time from Volumio... Apr 05 04:06:29 musicaluis volumio-time-update[593]: volumio-time-update-util: Date not found in response Apr 05 04:06:29 musicaluis volumio-time-update[593]: volumio-time-update-util: Retrying in 5 seconds... Apr 05 04:06:29 musicaluis wpa_supplicant[892]: wlan0: Associated with c8:9e:43:97:91:26 Apr 05 04:06:29 musicaluis wpa_supplicant[892]: wlan0: CTRL-EVENT-CONNECTED - Connection to c8:9e:43:97:91:26 completed [id=0 id_str=] Apr 05 04:06:29 musicaluis dhcpcd[908]: wlan0: carrier acquired Apr 05 04:06:29 musicaluis wpa_supplicant[892]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Apr 05 04:06:29 musicaluis dhcpcd[908]: wlan0: connected to Access Point `NyMyL' Apr 05 04:06:29 musicaluis dhcpcd[908]: DUID 00:01:00:01:2c:a6:bb:5a:b8:27:eb:2e:97:5b Apr 05 04:06:29 musicaluis dhcpcd[908]: wlan0: IAID eb:2e:97:5b Apr 05 04:06:29 musicaluis dhcpcd[908]: wlan0: adding address fe80::ba27:ebff:fe2e:975b Apr 05 04:06:29 musicaluis dhcpcd[908]: ipv6_addaddr1: Permission denied Apr 05 04:06:29 musicaluis dhcpcd[908]: wlan0: probing address 192.168.1.77/24 Apr 05 04:06:29 musicaluis wpa_supplicant[892]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=DE Apr 05 04:06:30 musicaluis systemd[1]: nmbd.service: Start operation timed out. Terminating. Apr 05 04:06:30 musicaluis systemd[1]: nmbd.service: Main process exited, code=killed, status=15/TERM Apr 05 04:06:30 musicaluis systemd[1]: nmbd.service: Failed with result 'timeout'. Apr 05 04:06:30 musicaluis systemd[1]: Failed to start Samba NMB Daemon. Apr 05 04:06:30 musicaluis systemd[1]: Starting Samba Winbind Daemon... Apr 05 04:06:30 musicaluis volumio[2025]: info: ------------------------------------------- Apr 05 04:06:30 musicaluis volumio[2025]: info: ----- Volumio3 ---- Apr 05 04:06:30 musicaluis volumio[2025]: info: ------------------------------------------- Apr 05 04:06:30 musicaluis volumio[2025]: info: ----- System startup ---- Apr 05 04:06:30 musicaluis volumio[2025]: info: ------------------------------------------- Apr 05 04:06:30 musicaluis winbindd[2043]: [2025/04/05 04:06:30.548588, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Apr 05 04:06:30 musicaluis winbindd[2043]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Apr 05 04:06:30 musicaluis winbindd[2043]: [2025/04/05 04:06:30.571936, 0] ../lib/util/become_daemon.c:138(daemon_ready) Apr 05 04:06:30 musicaluis winbindd[2043]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Apr 05 04:06:30 musicaluis systemd[1]: Started Samba Winbind Daemon. Apr 05 04:06:30 musicaluis systemd[1]: Starting Samba SMB Daemon... Apr 05 04:06:30 musicaluis dhcpcd[908]: wlan0: soliciting an IPv6 router Apr 05 04:06:31 musicaluis winbindd[2043]: [2025/04/05 04:06:31.152409, 0] ../source3/winbindd/wb_lookupsids.c:659(wb_lookupsids_recv) Apr 05 04:06:31 musicaluis winbindd[2043]: res_names->count = 3, expected 4 Apr 05 04:06:31 musicaluis smbd[2055]: [2025/04/05 04:06:31.180929, 0] ../lib/util/become_daemon.c:138(daemon_ready) Apr 05 04:06:31 musicaluis systemd[1]: Started Samba SMB Daemon. Apr 05 04:06:31 musicaluis smbd[2055]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Apr 05 04:06:31 musicaluis volumio[2025]: info: MYVOLUMIO Environment detected Apr 05 04:06:31 musicaluis volumio[2025]: info: Plugin folders cleanup Apr 05 04:06:31 musicaluis volumio[2025]: info: Scanning into folder /volumio/app/plugins/ Apr 05 04:06:31 musicaluis volumio[2025]: info: Scanning category audio_interface Apr 05 04:06:31 musicaluis volumio[2025]: info: Scanning category miscellanea Apr 05 04:06:31 musicaluis volumio[2025]: info: Scanning category music_service Apr 05 04:06:31 musicaluis volumio[2025]: info: Scanning category plugins.json Apr 05 04:06:31 musicaluis volumio[2025]: info: Scanning category system_controller Apr 05 04:06:31 musicaluis volumio[2025]: info: Scanning category user_interface Apr 05 04:06:31 musicaluis volumio[2025]: info: Scanning into folder /data/plugins/ Apr 05 04:06:31 musicaluis volumio[2025]: info: Scanning category audio_interface Apr 05 04:06:31 musicaluis volumio[2025]: info: Scanning category music_service Apr 05 04:06:31 musicaluis volumio[2025]: info: Scanning category system_controller Apr 05 04:06:31 musicaluis volumio[2025]: info: Scanning category user_interface Apr 05 04:06:31 musicaluis volumio[2025]: info: Plugin folders cleanup completed Apr 05 04:06:31 musicaluis volumio[2025]: info: ------------------------------------------- Apr 05 04:06:31 musicaluis volumio[2025]: info: ----- Core plugins startup ---- Apr 05 04:06:31 musicaluis volumio[2025]: info: ------------------------------------------- Apr 05 04:06:31 musicaluis volumio[2025]: info: Loading plugins from folder /volumio/app/plugins/ Apr 05 04:06:31 musicaluis volumio[2025]: info: Adding plugin upnp to MyMusic Plugins Apr 05 04:06:31 musicaluis volumio[2025]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 05 04:06:31 musicaluis volumio[2025]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 05 04:06:31 musicaluis volumio[2025]: info: Loading plugins from folder /data/plugins/ Apr 05 04:06:31 musicaluis volumio[2025]: info: Loading plugin "system"... Apr 05 04:06:31 musicaluis volumio[2025]: info: Loading plugin "appearance"... Apr 05 04:06:32 musicaluis volumio[2025]: info: Loading plugin "network"... Apr 05 04:06:32 musicaluis volumio[2025]: info: Refreshing Cached IP Addresses Apr 05 04:06:32 musicaluis sudo[2069]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 05 04:06:32 musicaluis sudo[2069]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 05 04:06:32 musicaluis sudo[2071]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 05 04:06:32 musicaluis sudo[2069]: pam_unix(sudo:session): session closed for user root Apr 05 04:06:32 musicaluis sudo[2071]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 05 04:06:32 musicaluis sudo[2071]: pam_unix(sudo:session): session closed for user root Apr 05 04:06:32 musicaluis volumio[2025]: info: Loading plugin "services"... Apr 05 04:06:32 musicaluis volumio[2025]: info: Loading plugin "alsa_controller"... Apr 05 04:06:32 musicaluis sudo[2080]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 05 04:06:32 musicaluis sudo[2080]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 05 04:06:32 musicaluis volumio[2025]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 05 04:06:32 musicaluis volumio[2025]: info: Loading plugin "wizard"... Apr 05 04:06:32 musicaluis volumio[2025]: info: Loading plugin "networkfs"... Apr 05 04:06:32 musicaluis volumio[2025]: info: Starting Udev Watcher for removable devices Apr 05 04:06:32 musicaluis volumio[2025]: info: Ignoring mount for partition: boot Apr 05 04:06:32 musicaluis volumio[2025]: info: Ignoring mount for partition: volumio Apr 05 04:06:32 musicaluis volumio[2025]: info: Ignoring mount for partition: volumio_data Apr 05 04:06:32 musicaluis volumio[2025]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 05 04:06:32 musicaluis volumio[2025]: info: Loading plugin "volumio_command_line_client"... Apr 05 04:06:32 musicaluis volumio[2025]: info: Loading plugin "upnp"... Apr 05 04:06:32 musicaluis volumio[2025]: info: [1743818792680] Starting Upmpd Daemon Apr 05 04:06:32 musicaluis volumio[2025]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 05 04:06:32 musicaluis volumio[2025]: info: Loading plugin "my_music"... Apr 05 04:06:32 musicaluis volumio[2025]: info: Loading plugin "mpd"... Apr 05 04:06:33 musicaluis volumio[2025]: info: Loading plugin "upnp_browser"... Apr 05 04:06:33 musicaluis volumio-remote-updater[566]: [2025-04-05 04:06:33] [connect] Successful connection Apr 05 04:06:33 musicaluis volumio[2025]: info: Loading plugin "alarm-clock"... Apr 05 04:06:33 musicaluis sudo[2080]: pam_unix(sudo:session): session closed for user root Apr 05 04:06:33 musicaluis volumio[2025]: info: Loading plugin "airplay_emulation"... Apr 05 04:06:33 musicaluis volumio[2025]: info: Starting Shairport Sync Apr 05 04:06:33 musicaluis volumio[2025]: info: Loading plugin "last_100"... Apr 05 04:06:33 musicaluis volumio[2025]: info: Loading plugin "webradio"... Apr 05 04:06:33 musicaluis volumio[2025]: info: Loading plugin "i2s_dacs"... Apr 05 04:06:33 musicaluis volumio[2025]: info: Loading plugin "volumiodiscovery"... Apr 05 04:06:33 musicaluis volumio[2025]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 05 04:06:33 musicaluis volumio[2025]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 05 04:06:33 musicaluis volumio[2025]: *** WARNING *** For more information see Apr 05 04:06:33 musicaluis node[2025]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 05 04:06:33 musicaluis volumio[2025]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 05 04:06:33 musicaluis volumio[2025]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 05 04:06:33 musicaluis volumio[2025]: *** WARNING *** For more information see Apr 05 04:06:33 musicaluis node[2025]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 05 04:06:33 musicaluis node[2025]: *** WARNING *** For more information see Apr 05 04:06:33 musicaluis node[2025]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 05 04:06:33 musicaluis node[2025]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 05 04:06:33 musicaluis node[2025]: *** WARNING *** For more information see Apr 05 04:06:33 musicaluis volumio[2025]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 05 04:06:33 musicaluis volumio[2025]: info: Discovery: Started advertising with name: MusicaLuis Apr 05 04:06:33 musicaluis volumio[2025]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 05 04:06:33 musicaluis volumio[2025]: info: Loading plugin "autostart"... Apr 05 04:06:34 musicaluis volumio[2025]: info: Applying required configuration parameters for plugin autostart Apr 05 04:06:34 musicaluis volumio[2025]: info: AutoStart - onVolumioStart - read config.json Apr 05 04:06:34 musicaluis volumio[2025]: info: Loading plugin "now_playing"... Apr 05 04:06:34 musicaluis dhcpcd[908]: wlan0: using static address 192.168.1.77/24 Apr 05 04:06:34 musicaluis avahi-daemon[579]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.77. Apr 05 04:06:34 musicaluis avahi-daemon[579]: New relevant interface wlan0.IPv4 for mDNS. Apr 05 04:06:34 musicaluis dhcpcd[908]: wlan0: adding route to 192.168.1.0/24 Apr 05 04:06:34 musicaluis avahi-daemon[579]: Registering new address record for 192.168.1.77 on wlan0.IPv4. Apr 05 04:06:34 musicaluis dhcpcd[908]: wlan0: adding default route via 192.168.1.1 Apr 05 04:06:34 musicaluis volumio-time-update[593]: volumio-time-update-util: Fetching time from Volumio... Apr 05 04:06:35 musicaluis volumio[2025]: info: Loading plugin "outputs"... Apr 05 04:06:35 musicaluis volumio[2025]: info: Loading plugin "albumart"... Apr 05 04:06:35 musicaluis volumio[2025]: info: Plugin example_plugin is not enabled Apr 05 04:06:35 musicaluis volumio[2025]: info: Loading plugin "inputs"... Apr 05 04:06:35 musicaluis volumio[2025]: info: Loading plugin "updater_comm"... Apr 05 04:06:35 musicaluis volumio[2025]: info: Plugin mpdemulation is not enabled Apr 05 04:06:35 musicaluis volumio[2025]: info: Loading plugin "rest_api"... Apr 05 04:06:35 musicaluis volumio[2025]: info: Loading plugin "websocket"... Apr 05 04:06:35 musicaluis volumio[2025]: info: Starting Socket.io Server version 2.3.0 Apr 05 04:06:35 musicaluis volumio[2025]: info: Loading plugin "fusiondsp"... Apr 05 04:06:35 musicaluis volumio[2025]: Forking 3 albumart workers Apr 05 04:06:35 musicaluis volumio[2025]: info: Applying required configuration parameters for plugin fusiondsp Apr 05 04:06:35 musicaluis volumio[2025]: info: Loading plugin "volusonic"... Apr 05 04:06:36 musicaluis ntpd[813]: Listen normally on 3 wlan0 192.168.1.77:123 Apr 05 04:06:36 musicaluis ntpd[813]: new interface(s) found: waking up resolver Apr 05 04:06:36 musicaluis volumio[2025]: Starting albumart workers Apr 05 04:06:36 musicaluis volumio[2025]: Starting albumart workers Apr 05 04:06:36 musicaluis volumio[2025]: Starting albumart workers Apr 05 04:06:37 musicaluis volumio[2025]: info: Applying required configuration parameters for plugin volusonic Apr 05 04:06:37 musicaluis volumio[2025]: info: Loading plugin "lastfm"... Apr 05 04:06:37 musicaluis volumio[2025]: info: Loading i18n strings for locale en Apr 05 04:06:37 musicaluis volumio[2025]: info: Plugin volusonic has duplicated i18n key SAVE. It is ignored. Apr 05 04:06:37 musicaluis volumio[2025]: Updating browse sources language Apr 05 04:06:37 musicaluis volumio[2025]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 05 04:06:37 musicaluis volumio[2025]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 05 04:06:37 musicaluis volumio[2025]: info: CoreCommandRouter::initPlayerControls Apr 05 04:06:37 musicaluis volumio[2025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 04:06:37 musicaluis volumio[2025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 04:06:37 musicaluis volumio[2025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 04:06:37 musicaluis volumio[2025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 04:06:37 musicaluis volumio[2025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 04:06:37 musicaluis volumio[2025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 04:06:37 musicaluis volumio[2025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 04:06:37 musicaluis volumio[2025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 04:06:37 musicaluis volumio[2025]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 05 04:06:37 musicaluis volumio[2025]: Express server listening on port 3000 Apr 05 04:06:37 musicaluis volumio[2025]: [Metrics] WebUI: 8s 118.21ms Apr 05 04:06:37 musicaluis volumio[2025]: info: CoreStateMachine::resetVolumioState Apr 05 04:06:37 musicaluis volumio[2025]: info: CoreStateMachine::getcurrentVolume Apr 05 04:06:37 musicaluis volumio[2025]: info: CoreCommandRouter::volumioRetrievevolume Apr 05 04:06:38 musicaluis volumio[2025]: info: Volumio Network Manager: Network status updated: 0 Apr 05 04:06:38 musicaluis volumio-remote-updater[566]: [2025-04-05 04:06:38] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1743818793 101 Apr 05 04:06:38 musicaluis volumio[2025]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Apr 05 04:06:38 musicaluis volumio[2025]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Apr 05 04:06:38 musicaluis volumio[2025]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 05 04:06:38 musicaluis volumio[2025]: info: Reloading queue from file Apr 05 04:06:38 musicaluis volumio[2025]: info: VolumeController:: Volume=100 Mute =false Apr 05 04:06:38 musicaluis volumio[2025]: info: CoreStateMachine::pushState Apr 05 04:06:38 musicaluis volumio[2025]: info: CorePlayQueue::getTrack 0 Apr 05 04:06:38 musicaluis volumio[2025]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 04:06:38 musicaluis volumio[2025]: info: CoreCommandRouter::volumioPushState Apr 05 04:06:38 musicaluis volumio[2025]: info: CoreStateMachine::updateTrackBlock Apr 05 04:06:38 musicaluis volumio[2025]: info: CorePlayQueue::getTrackBlock Apr 05 04:06:38 musicaluis volumio[2025]: info: CoreCommandRouter::volumioRetrievevolume Apr 05 04:06:38 musicaluis volumio[2025]: info: Setting Device type: Raspberry PI Apr 05 04:06:38 musicaluis volumio[2025]: info: CoreStateMachine::setRepeat null single undefined Apr 05 04:06:38 musicaluis volumio[2025]: info: CoreStateMachine::pushState Apr 05 04:06:38 musicaluis volumio[2025]: info: CorePlayQueue::getTrack 0 Apr 05 04:06:38 musicaluis volumio[2025]: info: CoreCommandRouter::volumioPushState Apr 05 04:06:38 musicaluis volumio[2025]: info: CoreStateMachine::setRandom null Apr 05 04:06:38 musicaluis volumio[2025]: info: CoreStateMachine::pushState Apr 05 04:06:38 musicaluis volumio[2025]: info: CorePlayQueue::getTrack 0 Apr 05 04:06:38 musicaluis volumio[2025]: info: CoreCommandRouter::volumioPushState Apr 05 04:06:38 musicaluis volumio[2025]: info: Completed loading Core Plugins Apr 05 04:06:38 musicaluis volumio[2025]: info: Preparing to generate the ALSA configuration file Apr 05 04:06:38 musicaluis volumio[2025]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 05 04:06:38 musicaluis volumio[2025]: info: Reading ALSA contributions from plugins. Apr 05 04:06:38 musicaluis volumio[2025]: info: VolumeController:: Volume=100 Mute =false Apr 05 04:06:38 musicaluis volumio[2025]: info: CoreStateMachine::pushState Apr 05 04:06:38 musicaluis volumio[2025]: info: CorePlayQueue::getTrack 0 Apr 05 04:06:38 musicaluis volumio[2025]: info: CoreCommandRouter::volumioPushState Apr 05 04:06:38 musicaluis volumio[2025]: info: Asound.conf file unchanged, so no further update is needed Apr 05 04:06:38 musicaluis volumio[2025]: info: Output device has changed, restarting MPD Apr 05 04:06:38 musicaluis volumio[2025]: info: Output device has changed, restarting Shairport Sync Apr 05 04:06:38 musicaluis sudo[2187]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 05 04:06:38 musicaluis volumio[2025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 04:06:38 musicaluis sudo[2187]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 05 04:06:38 musicaluis volumio[2025]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 05 04:06:38 musicaluis sudo[2187]: pam_unix(sudo:session): session closed for user root Apr 05 04:06:38 musicaluis sudo[2189]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 05 04:06:38 musicaluis sudo[2189]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 05 04:06:38 musicaluis systemd[1]: Stopping Music Player Daemon... Apr 05 04:06:38 musicaluis volumio[2025]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 05 04:06:38 musicaluis volumio[2025]: info: ___________ START PLUGINS ___________ Apr 05 04:06:38 musicaluis systemd[1]: mpd.service: Succeeded. Apr 05 04:06:38 musicaluis systemd[1]: Stopped Music Player Daemon. Apr 05 04:06:38 musicaluis volumio[2025]: info: ControllerMpd::onStart: Initializing MPD Apr 05 04:06:38 musicaluis volumio[2025]: info: Creating MPD Configuration file Apr 05 04:06:38 musicaluis sudo[2197]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 05 04:06:38 musicaluis sudo[2197]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 05 04:06:38 musicaluis volumio[2025]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 05 04:06:38 musicaluis volumio[2025]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 05 04:06:38 musicaluis volumio[2025]: info: [1743818798638] CoreMusicLibrary::Adding element Media Servers Apr 05 04:06:38 musicaluis volumio[2025]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 05 04:06:38 musicaluis volumio[2025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 04:06:38 musicaluis volumio[2025]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 05 04:06:38 musicaluis sudo[2197]: pam_unix(sudo:session): session closed for user root Apr 05 04:06:38 musicaluis systemd[1]: Starting Music Player Daemon... Apr 05 04:06:38 musicaluis sudo[2199]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 05 04:06:38 musicaluis sudo[2199]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 05 04:06:38 musicaluis systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Apr 05 04:06:38 musicaluis systemd[1]: mpd.service: Succeeded. Apr 05 04:06:38 musicaluis systemd[1]: Stopped Music Player Daemon. Apr 05 04:06:38 musicaluis systemd[1]: Starting Music Player Daemon... Apr 05 04:06:38 musicaluis volumio[2025]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 05 04:06:38 musicaluis volumio[2025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 04:06:38 musicaluis volumio[2025]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 05 04:06:38 musicaluis volumio[2025]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 05 04:06:38 musicaluis volumio[2025]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 05 04:06:38 musicaluis volumio[2025]: info: [1743818798772] CoreMusicLibrary::Adding element Last_100 Apr 05 04:06:38 musicaluis volumio[2025]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 05 04:06:38 musicaluis volumio[2025]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 05 04:06:38 musicaluis volumio[2025]: info: [1743818798776] CoreMusicLibrary::Adding element Webradio Apr 05 04:06:38 musicaluis volumio[2025]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 05 04:06:38 musicaluis volumio[2025]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 05 04:06:38 musicaluis volumio[2025]: info: Initializing BBC Radios Apr 05 04:06:38 musicaluis sudo[2205]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 05 04:06:38 musicaluis sudo[2205]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 05 04:06:38 musicaluis sudo[2205]: pam_unix(sudo:session): session closed for user root Apr 05 04:06:38 musicaluis volumio[2025]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 05 04:06:38 musicaluis volumio[2025]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 05 04:06:38 musicaluis volumio[2025]: info: AutoStart - onStart Apr 05 04:06:38 musicaluis volumio[2025]: info: [now-playing-config] Config is up to date. Apr 05 04:06:38 musicaluis volumio[2025]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 05 04:06:38 musicaluis volumio[2025]: info: Discovery: Getting this device information Apr 05 04:06:38 musicaluis volumio[2025]: info: CoreCommandRouter::volumioGetState Apr 05 04:06:38 musicaluis volumio[2025]: info: CorePlayQueue::getTrack 0 Apr 05 04:06:38 musicaluis volumio[2025]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 05 04:06:38 musicaluis volumio[2025]: info: Loading i18n strings for locale en Apr 05 04:06:38 musicaluis volumio[2025]: info: Plugin volusonic has duplicated i18n key SAVE. It is ignored. Apr 05 04:06:38 musicaluis volumio[2025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Apr 05 04:06:38 musicaluis volumio[2025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 04:06:38 musicaluis volumio[2025]: info: FusionDsp - mixtype--------------------- Hardware Apr 05 04:06:38 musicaluis volumio[2025]: info: Loading i18n strings for locale en Apr 05 04:06:38 musicaluis volumio[2025]: info: Plugin volusonic has duplicated i18n key SAVE. It is ignored. Apr 05 04:06:38 musicaluis volumio[2025]: Updating browse sources language Apr 05 04:06:38 musicaluis volumio[2025]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 05 04:06:38 musicaluis volumio[2025]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 05 04:06:38 musicaluis volumio[2025]: info: [1743818798969] CoreMusicLibrary::Adding element Volusonic Apr 05 04:06:38 musicaluis volumio[2025]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 05 04:06:38 musicaluis volumio[2025]: Cannot find translation for source Volusonic Apr 05 04:06:38 musicaluis volumio[2025]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 05 04:06:38 musicaluis volumio[2025]: info: [1743818798973] CoreMusicLibrary::Adding element LastFM Apr 05 04:06:38 musicaluis volumio[2025]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 05 04:06:38 musicaluis volumio[2025]: Cannot find translation for source Volusonic Apr 05 04:06:38 musicaluis volumio[2025]: Cannot find translation for source LastFM Apr 05 04:06:38 musicaluis volumio[2025]: info: [LastFM] scrobbler initiated! Apr 05 04:06:38 musicaluis volumio[2025]: info: [LastFM] extended logging: false Apr 05 04:06:38 musicaluis volumio[2025]: info: [LastFM] try scrobble stream/radio plays: true Apr 05 04:06:38 musicaluis volumio[2025]: info: [LastFM] Left init routine Apr 05 04:06:38 musicaluis volumio[2025]: info: [LastFM] Socket already connected: true Apr 05 04:06:38 musicaluis volumio[2025]: info: Volumio Calling Home Apr 05 04:06:39 musicaluis volumio[2025]: info: [now-playing-app] App is listening on port 4004. Apr 05 04:06:39 musicaluis volumio[2025]: info: Preparing to generate the ALSA configuration file Apr 05 04:06:39 musicaluis volumio[2025]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 05 04:06:39 musicaluis volumio[2025]: info: Reading ALSA contributions from plugins. Apr 05 04:06:39 musicaluis volumio[2025]: info: MPD Permissions set Apr 05 04:06:39 musicaluis volumio[2025]: info: MPD Permissions set Apr 05 04:06:39 musicaluis volumio[2025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 04:06:39 musicaluis volumio[2025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 04:06:39 musicaluis volumio[2025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 04:06:39 musicaluis volumio[2025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 04:06:39 musicaluis volumio[2025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 04:06:39 musicaluis volumio[2025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 04:06:39 musicaluis volumio[2025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 04:06:39 musicaluis volumio[2025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 04:06:39 musicaluis volumio[2025]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 05 04:06:39 musicaluis volumio[2025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 04:06:39 musicaluis volumio[2025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 04:06:39 musicaluis volumio[2025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 04:06:39 musicaluis volumio[2025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 04:06:39 musicaluis volumio[2025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 04:06:39 musicaluis volumio[2025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 04:06:39 musicaluis volumio[2025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 04:06:39 musicaluis volumio[2025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 04:06:39 musicaluis volumio[2025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 04:06:39 musicaluis volumio[2025]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 05 04:06:39 musicaluis volumio[2025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 04:06:39 musicaluis volumio[2025]: info: Starting Shairport Sync Apr 05 04:06:39 musicaluis volumio[2025]: info: Starting Shairport Sync Apr 05 04:06:39 musicaluis volumio[2025]: info: Starting Shairport Sync Apr 05 04:06:39 musicaluis sudo[2233]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 05 04:06:39 musicaluis sudo[2233]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 05 04:06:39 musicaluis sudo[2235]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 05 04:06:39 musicaluis sudo[2235]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 05 04:06:39 musicaluis volumio[2025]: info: Asound.conf file unchanged, so no further update is needed Apr 05 04:06:39 musicaluis volumio[2025]: info: Output device has changed, restarting MPD Apr 05 04:06:39 musicaluis systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 05 04:06:39 musicaluis systemd[1]: shairport-sync.service: Succeeded. Apr 05 04:06:39 musicaluis systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 05 04:06:39 musicaluis sudo[2239]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 05 04:06:39 musicaluis sudo[2239]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 05 04:06:39 musicaluis volumio[2025]: info: Output device has changed, restarting Shairport Sync Apr 05 04:06:39 musicaluis volumio[2025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 04:06:39 musicaluis volumio[2025]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 05 04:06:39 musicaluis systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 05 04:06:39 musicaluis sudo[2243]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 05 04:06:39 musicaluis sudo[2243]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 05 04:06:39 musicaluis sudo[2243]: pam_unix(sudo:session): session closed for user root Apr 05 04:06:39 musicaluis sudo[2233]: pam_unix(sudo:session): session closed for user root Apr 05 04:06:39 musicaluis sudo[2235]: pam_unix(sudo:session): session closed for user root Apr 05 04:06:39 musicaluis sudo[2245]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 05 04:06:39 musicaluis sudo[2245]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 05 04:06:39 musicaluis systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 05 04:06:39 musicaluis systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Apr 05 04:06:39 musicaluis systemd[1]: shairport-sync.service: Succeeded. Apr 05 04:06:39 musicaluis systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 05 04:06:39 musicaluis systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 05 04:06:39 musicaluis sudo[2239]: pam_unix(sudo:session): session closed for user root Apr 05 04:06:39 musicaluis systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Apr 05 04:06:39 musicaluis systemd[1]: mpd.service: Succeeded. Apr 05 04:06:39 musicaluis systemd[1]: Stopped Music Player Daemon. Apr 05 04:06:39 musicaluis volumio[2025]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 05 04:06:39 musicaluis systemd[1]: Starting Music Player Daemon... Apr 05 04:06:39 musicaluis volumio[2025]: info: MPD Permissions set Apr 05 04:06:39 musicaluis volumio[2025]: info: Shairport-Sync Started Apr 05 04:06:39 musicaluis volumio[2025]: Error adding Membership: Error: addMembership EINVAL Apr 05 04:06:39 musicaluis volumio[2025]: info: Shairport-Sync Started Apr 05 04:06:39 musicaluis volumio[2025]: info: Shairport-Sync Started Apr 05 04:06:39 musicaluis volumio[2025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 04:06:39 musicaluis volumio[2025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 04:06:39 musicaluis volumio[2025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 04:06:39 musicaluis volumio[2025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 04:06:39 musicaluis volumio[2025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 04:06:39 musicaluis volumio[2025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 04:06:39 musicaluis volumio[2025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 04:06:39 musicaluis volumio[2025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 04:06:39 musicaluis volumio[2025]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 05 04:06:39 musicaluis volumio[2025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 04:06:39 musicaluis volumio[2025]: info: Starting Shairport Sync Apr 05 04:06:39 musicaluis sudo[2260]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 05 04:06:39 musicaluis sudo[2260]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 05 04:06:39 musicaluis systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 05 04:06:39 musicaluis systemd[1]: shairport-sync.service: Succeeded. Apr 05 04:06:39 musicaluis systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 05 04:06:39 musicaluis sudo[2252]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 05 04:06:39 musicaluis sudo[2252]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 05 04:06:39 musicaluis sudo[2252]: pam_unix(sudo:session): session closed for user root Apr 05 04:06:39 musicaluis systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 05 04:06:39 musicaluis sudo[2260]: pam_unix(sudo:session): session closed for user root Apr 05 04:06:39 musicaluis volumio[2025]: info: Shairport-Sync Started Apr 05 04:06:40 musicaluis volumio[2025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 04:06:41 musicaluis volumio[2025]: info: FusionDsp - AAAAAAAAAAAAAA-> 44100 48000 88200 96000 176400 192000 384000 <-AAAAAAAAAAAAA Apr 05 04:06:41 musicaluis volumio[2025]: info: FusionDsp loaded Apr 05 04:06:41 musicaluis volumio[2025]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 05 04:06:41 musicaluis sudo[2276]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Apr 05 04:06:41 musicaluis sudo[2276]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 05 04:06:41 musicaluis volumio[2025]: info: FusionDsp - Reporting Fusion DSP Enabled Apr 05 04:06:41 musicaluis volumio[2025]: info: Adding Signal Path Element [object Object] Apr 05 04:06:41 musicaluis volumio[2025]: info: Adding fusiondspeq DSP Signal Path Element Apr 05 04:06:41 musicaluis volumio[2025]: info: FusionDsp - If filter freq >samplerate/2 then disable it Apr 05 04:06:41 musicaluis volumio[2025]: info: FusionDsp - crossfeed nc_11_30 Apr 05 04:06:41 musicaluis mpd[2264]: Apr 05 04:06 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 05 04:06:41 musicaluis systemd[1]: Started FusionDsp Daemon. Apr 05 04:06:41 musicaluis systemd[1]: Started Music Player Daemon. Apr 05 04:06:41 musicaluis sudo[2245]: pam_unix(sudo:session): session closed for user root Apr 05 04:06:41 musicaluis sudo[2199]: pam_unix(sudo:session): session closed for user root Apr 05 04:06:41 musicaluis sudo[2189]: pam_unix(sudo:session): session closed for user root Apr 05 04:06:41 musicaluis sudo[2276]: pam_unix(sudo:session): session closed for user root Apr 05 04:06:41 musicaluis volumio[2025]: info: Completed starting Core Plugins Apr 05 04:06:41 musicaluis volumio[2025]: info: ------------------------------------------- Apr 05 04:06:41 musicaluis volumio[2025]: info: ----- MyVolumio plugins startup ---- Apr 05 04:06:41 musicaluis volumio[2025]: info: ------------------------------------------- Apr 05 04:06:41 musicaluis volumio[2025]: info: [MyVolumio PluginManager] Fetching plans data.... Apr 05 04:06:41 musicaluis volumio[2025]: error: MPD error: The expression evaluated to a falsy value: Apr 05 04:06:41 musicaluis volumio[2025]: assert.ok(self.idling) Apr 05 04:06:41 musicaluis volumio[2025]: error: The expression evaluated to a falsy value: Apr 05 04:06:41 musicaluis volumio[2025]: assert.ok(self.idling) Apr 05 04:06:41 musicaluis volumio[2025]: error: MPD error: The expression evaluated to a falsy value: Apr 05 04:06:41 musicaluis volumio[2025]: assert.ok(self.idling) Apr 05 04:06:41 musicaluis volumio[2025]: error: The expression evaluated to a falsy value: Apr 05 04:06:41 musicaluis volumio[2025]: assert.ok(self.idling) Apr 05 04:06:41 musicaluis volumio[2025]: info: MPD running with PID2264 Apr 05 04:06:41 musicaluis volumio[2025]: ,establishing connection Apr 05 04:06:41 musicaluis volumio[2025]: error: updateQueue error: null Apr 05 04:06:41 musicaluis volumio[2025]: error: updateQueue error: null Apr 05 04:06:42 musicaluis volumio[2025]: info: FusionDsp - hrtf_conv_ll: Apr 05 04:06:42 musicaluis volumio[2025]: type: Conv Apr 05 04:06:42 musicaluis volumio[2025]: parameters: Apr 05 04:06:42 musicaluis volumio[2025]: type: Wav Apr 05 04:06:42 musicaluis volumio[2025]: filename: /data/plugins/audio_interface/fusiondsp/hrtf-filters/NC_11_30/NC_11_30_Left_$samplerate$.wav Apr 05 04:06:42 musicaluis volumio[2025]: channel: 0 Apr 05 04:06:42 musicaluis volumio[2025]: Apr 05 04:06:42 musicaluis volumio[2025]: hrtf_conv_lr: Apr 05 04:06:42 musicaluis volumio[2025]: type: Conv Apr 05 04:06:42 musicaluis volumio[2025]: parameters: Apr 05 04:06:42 musicaluis volumio[2025]: type: Wav Apr 05 04:06:42 musicaluis volumio[2025]: filename: /data/plugins/audio_interface/fusiondsp/hrtf-filters/NC_11_30/NC_11_30_Left_$samplerate$.wav Apr 05 04:06:42 musicaluis volumio[2025]: channel: 1 Apr 05 04:06:42 musicaluis volumio[2025]: Apr 05 04:06:42 musicaluis volumio[2025]: hrtf_conv_rl: Apr 05 04:06:42 musicaluis volumio[2025]: type: Conv Apr 05 04:06:42 musicaluis volumio[2025]: parameters: Apr 05 04:06:42 musicaluis volumio[2025]: type: Wav Apr 05 04:06:42 musicaluis volumio[2025]: filename: /data/plugins/audio_interface/fusiondsp/hrtf-filters/NC_11_30/NC_11_30_Right_$samplerate$.wav Apr 05 04:06:42 musicaluis volumio[2025]: channel: 0 Apr 05 04:06:42 musicaluis volumio[2025]: Apr 05 04:06:42 musicaluis volumio[2025]: hrtf_conv_rr: Apr 05 04:06:42 musicaluis volumio[2025]: type: Conv Apr 05 04:06:42 musicaluis volumio[2025]: parameters: Apr 05 04:06:42 musicaluis volumio[2025]: type: Wav Apr 05 04:06:42 musicaluis volumio[2025]: filename: /data/plugins/audio_interface/fusiondsp/hrtf-filters/NC_11_30/NC_11_30_Right_$samplerate$.wav Apr 05 04:06:42 musicaluis volumio[2025]: channel: 1 Apr 05 04:06:42 musicaluis volumio[2025]: Apr 05 04:06:42 musicaluis volumio[2025]: eq1: Apr 05 04:06:42 musicaluis volumio[2025]: type: Biquad Apr 05 04:06:42 musicaluis volumio[2025]: parameters: Apr 05 04:06:42 musicaluis volumio[2025]: type: Peaking Apr 05 04:06:42 musicaluis volumio[2025]: freq: 25 Apr 05 04:06:42 musicaluis volumio[2025]: q: 1.85 Apr 05 04:06:42 musicaluis volumio[2025]: gain: -0.5 Apr 05 04:06:42 musicaluis volumio[2025]: eq2: Apr 05 04:06:42 musicaluis volumio[2025]: type: Biquad Apr 05 04:06:42 musicaluis volumio[2025]: parameters: Apr 05 04:06:42 musicaluis volumio[2025]: type: Peaking Apr 05 04:06:42 musicaluis volumio[2025]: freq: 40 Apr 05 04:06:42 musicaluis volumio[2025]: q: 1.85 Apr 05 04:06:42 musicaluis volumio[2025]: gain: 1 Apr 05 04:06:42 musicaluis volumio[2025]: eq3: Apr 05 04:06:42 musicaluis volumio[2025]: type: Biquad Apr 05 04:06:42 musicaluis volumio[2025]: parameters: Apr 05 04:06:42 musicaluis volumio[2025]: type: Peaking Apr 05 04:06:42 musicaluis volumio[2025]: freq: 63 Apr 05 04:06:42 musicaluis volumio[2025]: q: 1.85 Apr 05 04:06:42 musicaluis volumio[2025]: gain: 1.5 Apr 05 04:06:42 musicaluis volumio[2025]: eq4: Apr 05 04:06:42 musicaluis volumio[2025]: type: Biquad Apr 05 04:06:42 musicaluis volumio[2025]: parameters: Apr 05 04:06:42 musicaluis volumio[2025]: type: Peaking Apr 05 04:06:42 musicaluis volumio[2025]: freq: 100 Apr 05 04:06:42 musicaluis volumio[2025]: q: 1.85 Apr 05 04:06:42 musicaluis volumio[2025]: gain: 1.5 Apr 05 04:06:42 musicaluis volumio[2025]: eq5: Apr 05 04:06:42 musicaluis volumio[2025]: type: Biquad Apr 05 04:06:42 musicaluis volumio[2025]: parameters: Apr 05 04:06:42 musicaluis volumio[2025]: type: Peaking Apr 05 04:06:42 musicaluis volumio[2025]: freq: 160 Apr 05 04:06:42 musicaluis volumio[2025]: q: 1.85 Apr 05 04:06:42 musicaluis volumio[2025]: gain: 1 Apr 05 04:06:42 musicaluis volumio[2025]: eq6: Apr 05 04:06:42 musicaluis volumio[2025]: type: Biquad Apr 05 04:06:42 musicaluis volumio[2025]: parameters: Apr 05 04:06:42 musicaluis volumio[2025]: type: Peaking Apr 05 04:06:42 musicaluis volumio[2025]: freq: 250 Apr 05 04:06:42 musicaluis volumio[2025]: q: 1.85 Apr 05 04:06:42 musicaluis volumio[2025]: gain: 0.5 Apr 05 04:06:42 musicaluis volumio[2025]: eq7: Apr 05 04:06:42 musicaluis volumio[2025]: type: Biquad Apr 05 04:06:42 musicaluis volumio[2025]: parameters: Apr 05 04:06:42 musicaluis volumio[2025]: type: Peaking Apr 05 04:06:42 musicaluis volumio[2025]: freq: 400 Apr 05 04:06:42 musicaluis volumio[2025]: q: 1.85 Apr 05 04:06:42 musicaluis volumio[2025]: gain: 0 Apr 05 04:06:42 musicaluis volumio[2025]: eq8: Apr 05 04:06:42 musicaluis volumio[2025]: type: Biquad Apr 05 04:06:42 musicaluis volumio[2025]: parameters: Apr 05 04:06:42 musicaluis volumio[2025]: type: Peaking Apr 05 04:06:42 musicaluis volumio[2025]: freq: 630 Apr 05 04:06:42 musicaluis volumio[2025]: q: 1.85 Apr 05 04:06:42 musicaluis volumio[2025]: gain: -0.5 Apr 05 04:06:42 musicaluis volumio[2025]: eq9: Apr 05 04:06:42 musicaluis volumio[2025]: type: Biquad Apr 05 04:06:42 musicaluis volumio[2025]: parameters: Apr 05 04:06:42 musicaluis volumio[2025]: type: Peaking Apr 05 04:06:42 musicaluis volumio[2025]: freq: 1000 Apr 05 04:06:42 musicaluis volumio[2025]: q: 1.85 Apr 05 04:06:42 musicaluis volumio[2025]: gain: 0.5 Apr 05 04:06:42 musicaluis volumio[2025]: eq10: Apr 05 04:06:42 musicaluis volumio[2025]: type: Biquad Apr 05 04:06:42 musicaluis volumio[2025]: parameters: Apr 05 04:06:42 musicaluis volumio[2025]: type: Peaking Apr 05 04:06:42 musicaluis volumio[2025]: freq: 1600 Apr 05 04:06:42 musicaluis volumio[2025]: q: 1.85 Apr 05 04:06:42 musicaluis volumio[2025]: gain: 0.5 Apr 05 04:06:42 musicaluis volumio[2025]: eq11: Apr 05 04:06:42 musicaluis volumio[2025]: type: Biquad Apr 05 04:06:42 musicaluis volumio[2025]: parameters: Apr 05 04:06:42 musicaluis volumio[2025]: type: Peaking Apr 05 04:06:42 musicaluis volumio[2025]: freq: 2500 Apr 05 04:06:42 musicaluis volumio[2025]: q: 1.85 Apr 05 04:06:42 musicaluis volumio[2025]: gain: 0.5 Apr 05 04:06:42 musicaluis volumio[2025]: eq12: Apr 05 04:06:42 musicaluis volumio[2025]: type: Biquad Apr 05 04:06:42 musicaluis volumio[2025]: parameters: Apr 05 04:06:42 musicaluis volumio[2025]: type: Peaking Apr 05 04:06:42 musicaluis volumio[2025]: freq: 4000 Apr 05 04:06:42 musicaluis volumio[2025]: q: 1.85 Apr 05 04:06:42 musicaluis volumio[2025]: gain: 1 Apr 05 04:06:42 musicaluis volumio[2025]: eq13: Apr 05 04:06:42 musicaluis volumio[2025]: type: Biquad Apr 05 04:06:42 musicaluis volumio[2025]: parameters: Apr 05 04:06:42 musicaluis volumio[2025]: type: Peaking Apr 05 04:06:42 musicaluis volumio[2025]: freq: 6300 Apr 05 04:06:42 musicaluis volumio[2025]: q: 1.85 Apr 05 04:06:42 musicaluis volumio[2025]: gain: 1.5 Apr 05 04:06:42 musicaluis volumio[2025]: eq14: Apr 05 04:06:42 musicaluis volumio[2025]: type: Biquad Apr 05 04:06:42 musicaluis volumio[2025]: parameters: Apr 05 04:06:42 musicaluis volumio[2025]: type: Peaking Apr 05 04:06:42 musicaluis volumio[2025]: freq: 10000 Apr 05 04:06:42 musicaluis volumio[2025]: q: 1.85 Apr 05 04:06:42 musicaluis volumio[2025]: gain: 2 Apr 05 04:06:42 musicaluis volumio[2025]: info: Volumio Network Manager: Network status updated: 2 Apr 05 04:06:42 musicaluis sudo[2298]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 05 04:06:42 musicaluis sudo[2298]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 05 04:06:42 musicaluis sudo[2298]: pam_unix(sudo:session): session closed for user root Apr 05 04:06:42 musicaluis sudo[2300]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 05 04:06:42 musicaluis sudo[2300]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 05 04:06:42 musicaluis sudo[2300]: pam_unix(sudo:session): session closed for user root Apr 05 04:06:42 musicaluis sudo[2305]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Apr 05 04:06:42 musicaluis sudo[2305]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 05 04:06:42 musicaluis systemd[1]: Started UPnP Renderer front-end to MPD. Apr 05 04:06:42 musicaluis sudo[2305]: pam_unix(sudo:session): session closed for user root Apr 05 04:06:42 musicaluis volumio[2025]: info: Upmpdcli Daemon Started Apr 05 04:06:43 musicaluis volumio[2307]: Generating RSA private key, 4096 bit long modulus (2 primes) Apr 05 04:06:46 musicaluis volumio[2025]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Apr 05 04:06:48 musicaluis volumio[2025]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 05 04:06:58 musicaluis volumio-time-update[593]: volumio-time-update-util: Date not found in response Apr 05 04:06:58 musicaluis volumio-time-update[593]: volumio-time-update-util: Retrying in 5 seconds... Apr 05 04:06:58 musicaluis volumio[2307]: ....................................................................................................................++++ Apr 05 04:06:58 musicaluis volumio[2025]: info: AutoStart - Plugin is starting Apr 05 04:06:58 musicaluis volumio[2025]: info: CoreCommandRouter::volumioGetQueue Apr 05 04:06:58 musicaluis volumio[2025]: info: CoreStateMachine::getQueue Apr 05 04:06:58 musicaluis volumio[2025]: info: CorePlayQueue::getQueue Apr 05 04:06:58 musicaluis volumio[2025]: info: AutoStart - start playing Apr 05 04:06:58 musicaluis volumio[2025]: info: AutoStart - start playing with no specific position Apr 05 04:06:58 musicaluis volumio[2025]: info: CoreCommandRouter::volumioPlay Apr 05 04:06:58 musicaluis volumio[2025]: info: CoreStateMachine::play index 0 Apr 05 04:06:58 musicaluis volumio[2025]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 05 04:06:58 musicaluis volumio[2025]: info: CoreStateMachine::stop Apr 05 04:06:58 musicaluis volumio[2025]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 05 04:06:58 musicaluis volumio[2025]: info: CoreStateMachine::play index undefined Apr 05 04:06:58 musicaluis volumio[2025]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 05 04:06:58 musicaluis volumio[2025]: info: CorePlayQueue::getTrack 0 Apr 05 04:06:58 musicaluis volumio[2025]: info: CoreStateMachine::startPlaybackTimer Apr 05 04:06:58 musicaluis volumio[2025]: info: CorePlayQueue::getTrack 0 Apr 05 04:06:58 musicaluis volumio[2025]: info: [1743818818872] ControllerVolusonic::clearAddPlayTrack Apr 05 04:06:58 musicaluis volumio[2025]: verbose: ControllerMpd::sendMpdCommand stop Apr 05 04:06:58 musicaluis volumio[2025]: info: sendMpdCommand stop took 3 milliseconds Apr 05 04:06:58 musicaluis volumio[2025]: verbose: ControllerMpd::sendMpdCommand clear Apr 05 04:06:58 musicaluis volumio[2025]: info: Apr 05 04:06:58 musicaluis volumio[2025]: ---------------------------- MPD announces system playlist update Apr 05 04:06:58 musicaluis volumio[2025]: info: Ignoring MPD Status Update Apr 05 04:06:58 musicaluis volumio[2025]: info: sendMpdCommand clear took 6 milliseconds Apr 05 04:06:58 musicaluis volumio[2025]: verbose: ControllerMpd::sendMpdCommand load "https://navidrome.nylarea.com/rest/stream.view?id=zxjXshvDgxseJYMrpBBwO3&format=raw&u=Luis&t=b0402b6fdf7a73c490823cdeb02af618&s=94mcu&v=1.10.2&f=json&c=volusonic" Apr 05 04:06:58 musicaluis volumio[2025]: info: Apr 05 04:06:58 musicaluis volumio[2025]: ---------------------------- MPD announces system playlist update Apr 05 04:06:58 musicaluis volumio[2025]: info: Ignoring MPD Status Update Apr 05 04:06:58 musicaluis volumio[2025]: info: Apr 05 04:06:58 musicaluis volumio[2025]: ---------------------------- MPD announces system playlist update Apr 05 04:06:58 musicaluis volumio[2025]: info: Ignoring MPD Status Update Apr 05 04:06:58 musicaluis volumio[2025]: info: Apr 05 04:06:58 musicaluis volumio[2025]: ---------------------------- MPD announces system playlist update Apr 05 04:06:58 musicaluis volumio[2025]: info: Ignoring MPD Status Update Apr 05 04:06:58 musicaluis volumio[2025]: error: updateQueue error: null Apr 05 04:06:58 musicaluis volumio[2025]: info: ------------------------------ 23ms Apr 05 04:07:01 musicaluis volumio[2025]: error: MyVolumio Plugin failed to start in a timely fashion Apr 05 04:07:01 musicaluis volumio[2025]: [Metrics] CommandRouter: 30s 755.15ms Apr 05 04:07:01 musicaluis volumio[2025]: info: CoreCommandRouter::volumiosetStartupVolume Apr 05 04:07:01 musicaluis volumio[2025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 04:07:01 musicaluis volumio[2025]: info: VolumeController:: Setting startup Volume 100 Apr 05 04:07:01 musicaluis volumio[2025]: info: VolumeController::SetAlsaVolume100 Apr 05 04:07:01 musicaluis volumio[2025]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 05 04:07:01 musicaluis volumio[2025]: info: CoreCommandRouter::Close All Modals sent Apr 05 04:07:01 musicaluis volumio[2025]: info: CoreStateMachine::pushState Apr 05 04:07:01 musicaluis volumio[2025]: info: CorePlayQueue::getTrack 0 Apr 05 04:07:01 musicaluis volumio[2025]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 04:07:01 musicaluis volumio[2025]: info: CoreCommandRouter::volumioPushState Apr 05 04:07:02 musicaluis volumio[2025]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 05 04:07:02 musicaluis volumio[2025]: Error: getaddrinfo ENOTFOUND ws.audioscrobbler.com Apr 05 04:07:02 musicaluis volumio[2025]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:67:26) { Apr 05 04:07:02 musicaluis volumio[2025]: errno: -3007, Apr 05 04:07:02 musicaluis volumio[2025]: code: 'ENOTFOUND', Apr 05 04:07:02 musicaluis volumio[2025]: syscall: 'getaddrinfo', Apr 05 04:07:02 musicaluis volumio[2025]: hostname: 'ws.audioscrobbler.com' Apr 05 04:07:02 musicaluis volumio[2025]: } Apr 05 04:07:02 musicaluis volumio[2025]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 05 04:07:02 musicaluis sudo[2409]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-05 04:06 Apr 05 04:07:02 musicaluis sudo[2409]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="e32c2c71ae78852844c70357816bdd871c155ab8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 31 Mar 2025 06:54:47 PM CEST" VOLUMIO_VERSION="3.799" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="436f3f84f6d0b4ffc344f0bc132a32e8"