-- Logs begin at Fri 2024-12-20 04:13:42 CET, end at Fri 2024-12-20 04:42:50 CET. -- Dec 20 04:41:00 musicaluis volumio[23072]: info: Loading plugin "outputs"... Dec 20 04:41:00 musicaluis volumio[23072]: info: Loading plugin "albumart"... Dec 20 04:41:00 musicaluis volumio[23072]: info: Plugin example_plugin is not enabled Dec 20 04:41:00 musicaluis volumio[23072]: info: Loading plugin "inputs"... Dec 20 04:41:00 musicaluis volumio[23072]: info: Loading plugin "updater_comm"... Dec 20 04:41:00 musicaluis volumio[23072]: info: Plugin mpdemulation is not enabled Dec 20 04:41:00 musicaluis volumio[23072]: info: Loading plugin "rest_api"... Dec 20 04:41:00 musicaluis volumio[23072]: info: Loading plugin "websocket"... Dec 20 04:41:00 musicaluis volumio[23072]: info: Starting Socket.io Server version 2.3.0 Dec 20 04:41:00 musicaluis volumio[23072]: info: Loading plugin "fusiondsp"... Dec 20 04:41:00 musicaluis volumio[23072]: Forking 3 albumart workers Dec 20 04:41:00 musicaluis volumio[23072]: info: Applying required configuration parameters for plugin fusiondsp Dec 20 04:41:01 musicaluis volumio[23072]: info: Loading plugin "volusonic"... Dec 20 04:41:01 musicaluis volumio[23072]: Starting albumart workers Dec 20 04:41:01 musicaluis volumio[23072]: Starting albumart workers Dec 20 04:41:01 musicaluis volumio[23072]: Starting albumart workers Dec 20 04:41:02 musicaluis volumio[23072]: info: Applying required configuration parameters for plugin volusonic Dec 20 04:41:02 musicaluis volumio[23072]: info: Loading plugin "lastfm"... Dec 20 04:41:02 musicaluis volumio[23072]: info: Loading i18n strings for locale en Dec 20 04:41:02 musicaluis volumio[23072]: info: Plugin volusonic has duplicated i18n key SAVE. It is ignored. Dec 20 04:41:02 musicaluis volumio[23072]: Updating browse sources language Dec 20 04:41:02 musicaluis volumio[23072]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 04:41:02 musicaluis volumio[23072]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 20 04:41:02 musicaluis volumio[23072]: info: CoreCommandRouter::initPlayerControls Dec 20 04:41:02 musicaluis volumio[23072]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:41:02 musicaluis volumio[23072]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:41:02 musicaluis volumio[23072]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:41:02 musicaluis volumio[23072]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:41:02 musicaluis volumio[23072]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:41:02 musicaluis volumio[23072]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:41:02 musicaluis volumio[23072]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:41:02 musicaluis volumio[23072]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:41:02 musicaluis volumio[23072]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 20 04:41:02 musicaluis volumio[23072]: Express server listening on port 3000 Dec 20 04:41:02 musicaluis volumio[23072]: [Metrics] WebUI: 7s 826.63ms Dec 20 04:41:02 musicaluis volumio[23072]: info: CoreStateMachine::resetVolumioState Dec 20 04:41:02 musicaluis volumio[23072]: info: CoreStateMachine::getcurrentVolume Dec 20 04:41:03 musicaluis volumio[23072]: info: CoreCommandRouter::volumioRetrievevolume Dec 20 04:41:03 musicaluis volumio[23072]: info: Volumio Network Manager: Network status updated: 0 Dec 20 04:41:03 musicaluis volumio-remote-updater[578]: [2024-12-20 04:41:03] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1734666058 101 Dec 20 04:41:03 musicaluis volumio[23072]: 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 Dec 20 04:41:03 musicaluis volumio[23072]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Dec 20 04:41:03 musicaluis volumio[23072]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 04:41:03 musicaluis volumio[23072]: info: Reloading queue from file Dec 20 04:41:03 musicaluis volumio[23072]: info: VolumeController:: Volume=100 Mute =false Dec 20 04:41:03 musicaluis volumio[23072]: info: CoreStateMachine::pushState Dec 20 04:41:03 musicaluis volumio[23072]: info: CorePlayQueue::getTrack 0 Dec 20 04:41:03 musicaluis volumio[23072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 20 04:41:03 musicaluis volumio[23072]: info: CoreCommandRouter::volumioPushState Dec 20 04:41:03 musicaluis volumio[23072]: info: CoreStateMachine::updateTrackBlock Dec 20 04:41:03 musicaluis volumio[23072]: info: CorePlayQueue::getTrackBlock Dec 20 04:41:03 musicaluis volumio[23072]: info: CoreCommandRouter::volumioRetrievevolume Dec 20 04:41:03 musicaluis volumio[23072]: info: Setting Device type: Raspberry PI Dec 20 04:41:03 musicaluis volumio[23072]: info: CoreStateMachine::setRepeat null single undefined Dec 20 04:41:03 musicaluis volumio[23072]: info: CoreStateMachine::pushState Dec 20 04:41:03 musicaluis volumio[23072]: info: CorePlayQueue::getTrack 0 Dec 20 04:41:03 musicaluis volumio[23072]: info: CoreCommandRouter::volumioPushState Dec 20 04:41:03 musicaluis volumio[23072]: info: CoreStateMachine::setRandom null Dec 20 04:41:03 musicaluis volumio[23072]: info: CoreStateMachine::pushState Dec 20 04:41:03 musicaluis volumio[23072]: info: CorePlayQueue::getTrack 0 Dec 20 04:41:03 musicaluis volumio[23072]: info: CoreCommandRouter::volumioPushState Dec 20 04:41:03 musicaluis volumio[23072]: info: Completed loading Core Plugins Dec 20 04:41:03 musicaluis volumio[23072]: info: Preparing to generate the ALSA configuration file Dec 20 04:41:03 musicaluis volumio[23072]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Dec 20 04:41:03 musicaluis volumio[23072]: info: Reading ALSA contributions from plugins. Dec 20 04:41:03 musicaluis volumio[23072]: info: VolumeController:: Volume=100 Mute =false Dec 20 04:41:03 musicaluis volumio[23072]: info: CoreStateMachine::pushState Dec 20 04:41:03 musicaluis volumio[23072]: info: CorePlayQueue::getTrack 0 Dec 20 04:41:03 musicaluis volumio[23072]: info: CoreCommandRouter::volumioPushState Dec 20 04:41:03 musicaluis volumio[23072]: info: Asound.conf file unchanged, so no further update is needed Dec 20 04:41:03 musicaluis volumio[23072]: info: Output device has changed, restarting MPD Dec 20 04:41:03 musicaluis sudo[23204]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 20 04:41:03 musicaluis volumio[23072]: info: Output device has changed, restarting Shairport Sync Dec 20 04:41:03 musicaluis sudo[23204]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 04:41:03 musicaluis volumio[23072]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:41:03 musicaluis volumio[23072]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 04:41:03 musicaluis sudo[23204]: pam_unix(sudo:session): session closed for user root Dec 20 04:41:03 musicaluis sudo[23206]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 20 04:41:03 musicaluis sudo[23206]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 04:41:03 musicaluis systemd[1]: Stopping Music Player Daemon... Dec 20 04:41:03 musicaluis volumio[23072]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 20 04:41:03 musicaluis volumio[23072]: info: ___________ START PLUGINS ___________ Dec 20 04:41:03 musicaluis systemd[1]: mpd.service: Succeeded. Dec 20 04:41:03 musicaluis systemd[1]: Stopped Music Player Daemon. Dec 20 04:41:03 musicaluis volumio[23072]: info: ControllerMpd::onStart: Initializing MPD Dec 20 04:41:03 musicaluis volumio[23072]: info: Creating MPD Configuration file Dec 20 04:41:03 musicaluis sudo[23214]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 20 04:41:03 musicaluis sudo[23214]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 04:41:03 musicaluis systemd[1]: Starting Music Player Daemon... Dec 20 04:41:03 musicaluis volumio[23072]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 20 04:41:03 musicaluis volumio[23072]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 20 04:41:03 musicaluis volumio[23072]: info: [1734666063630] CoreMusicLibrary::Adding element Media Servers Dec 20 04:41:03 musicaluis volumio[23072]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 04:41:03 musicaluis sudo[23214]: pam_unix(sudo:session): session closed for user root Dec 20 04:41:03 musicaluis sudo[23216]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 20 04:41:03 musicaluis sudo[23216]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 04:41:03 musicaluis volumio[23072]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:41:03 musicaluis volumio[23072]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 04:41:03 musicaluis systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Dec 20 04:41:03 musicaluis systemd[1]: mpd.service: Succeeded. Dec 20 04:41:03 musicaluis systemd[1]: Stopped Music Player Daemon. Dec 20 04:41:03 musicaluis systemd[1]: Starting Music Player Daemon... Dec 20 04:41:03 musicaluis volumio[23072]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 20 04:41:03 musicaluis volumio[23072]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:41:03 musicaluis volumio[23072]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 04:41:03 musicaluis volumio[23072]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 20 04:41:03 musicaluis volumio[23072]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 20 04:41:03 musicaluis volumio[23072]: info: [1734666063774] CoreMusicLibrary::Adding element Last_100 Dec 20 04:41:03 musicaluis volumio[23072]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 04:41:03 musicaluis volumio[23072]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 20 04:41:03 musicaluis volumio[23072]: info: [1734666063777] CoreMusicLibrary::Adding element Webradio Dec 20 04:41:03 musicaluis volumio[23072]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 04:41:03 musicaluis volumio[23072]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 20 04:41:03 musicaluis volumio[23072]: info: Initializing BBC Radios Dec 20 04:41:03 musicaluis sudo[23222]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 20 04:41:03 musicaluis sudo[23222]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 04:41:03 musicaluis sudo[23222]: pam_unix(sudo:session): session closed for user root Dec 20 04:41:03 musicaluis volumio[23072]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 20 04:41:03 musicaluis volumio[23072]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 04:41:03 musicaluis volumio[23072]: info: AutoStart - onStart Dec 20 04:41:03 musicaluis volumio[23072]: info: [now-playing-config] Config is up to date. Dec 20 04:41:03 musicaluis volumio[23072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 20 04:41:03 musicaluis volumio[23072]: info: Discovery: Getting this device information Dec 20 04:41:03 musicaluis volumio[23072]: info: CoreCommandRouter::volumioGetState Dec 20 04:41:03 musicaluis volumio[23072]: info: CorePlayQueue::getTrack 0 Dec 20 04:41:03 musicaluis volumio[23072]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 20 04:41:03 musicaluis volumio[23072]: info: Loading i18n strings for locale en Dec 20 04:41:03 musicaluis volumio[23072]: info: Plugin volusonic has duplicated i18n key SAVE. It is ignored. Dec 20 04:41:03 musicaluis volumio[23072]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Dec 20 04:41:03 musicaluis volumio[23072]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:41:03 musicaluis volumio[23072]: info: FusionDsp - mixtype--------------------- Hardware Dec 20 04:41:03 musicaluis volumio[23072]: info: Loading i18n strings for locale en Dec 20 04:41:03 musicaluis volumio[23072]: info: Plugin volusonic has duplicated i18n key SAVE. It is ignored. Dec 20 04:41:03 musicaluis volumio[23072]: Updating browse sources language Dec 20 04:41:03 musicaluis volumio[23072]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 04:41:03 musicaluis volumio[23072]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 20 04:41:03 musicaluis volumio[23072]: info: [1734666063975] CoreMusicLibrary::Adding element Volusonic Dec 20 04:41:03 musicaluis volumio[23072]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 04:41:03 musicaluis volumio[23072]: Cannot find translation for source Volusonic Dec 20 04:41:03 musicaluis volumio[23072]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 20 04:41:03 musicaluis volumio[23072]: info: [1734666063978] CoreMusicLibrary::Adding element LastFM Dec 20 04:41:03 musicaluis volumio[23072]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 04:41:03 musicaluis volumio[23072]: Cannot find translation for source Volusonic Dec 20 04:41:03 musicaluis volumio[23072]: Cannot find translation for source LastFM Dec 20 04:41:03 musicaluis volumio[23072]: info: [LastFM] scrobbler initiated! Dec 20 04:41:03 musicaluis volumio[23072]: info: [LastFM] extended logging: false Dec 20 04:41:03 musicaluis volumio[23072]: info: [LastFM] try scrobble stream/radio plays: true Dec 20 04:41:03 musicaluis volumio[23072]: info: [LastFM] Left init routine Dec 20 04:41:03 musicaluis volumio[23072]: info: [LastFM] Socket already connected: true Dec 20 04:41:03 musicaluis volumio[23072]: info: Volumio Calling Home Dec 20 04:41:04 musicaluis volumio[23072]: info: [now-playing-app] App is listening on port 4004. Dec 20 04:41:04 musicaluis volumio[23072]: info: Preparing to generate the ALSA configuration file Dec 20 04:41:04 musicaluis volumio[23072]: Unhandled rejection Error: No sockets available, cannot start. Dec 20 04:41:04 musicaluis volumio[23072]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Dec 20 04:41:04 musicaluis volumio[23072]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Dec 20 04:41:04 musicaluis volumio[23072]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Dec 20 04:41:04 musicaluis volumio[23072]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Dec 20 04:41:04 musicaluis volumio[23072]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Dec 20 04:41:04 musicaluis volumio[23072]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Dec 20 04:41:04 musicaluis volumio[23072]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Dec 20 04:41:04 musicaluis volumio[23072]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Dec 20 04:41:04 musicaluis volumio[23072]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Dec 20 04:41:04 musicaluis volumio[23072]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Dec 20 04:41:04 musicaluis volumio[23072]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Dec 20 04:41:04 musicaluis volumio[23072]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Dec 20 04:41:04 musicaluis volumio[23072]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Dec 20 04:41:04 musicaluis volumio[23072]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Dec 20 04:41:04 musicaluis volumio[23072]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Dec 20 04:41:04 musicaluis volumio[23072]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Dec 20 04:41:04 musicaluis volumio[23072]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 20 04:41:04 musicaluis volumio[23072]: Error: getaddrinfo EBUSY ws.audioscrobbler.com Dec 20 04:41:04 musicaluis volumio[23072]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:67:26) { Dec 20 04:41:04 musicaluis volumio[23072]: errno: -16, Dec 20 04:41:04 musicaluis volumio[23072]: code: 'EBUSY', Dec 20 04:41:04 musicaluis volumio[23072]: syscall: 'getaddrinfo', Dec 20 04:41:04 musicaluis volumio[23072]: hostname: 'ws.audioscrobbler.com' Dec 20 04:41:04 musicaluis volumio[23072]: } Dec 20 04:41:04 musicaluis volumio[23072]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 20 04:41:04 musicaluis volumio-time-update[568]: volumio-time-update-util: Fetching time from Volumio... Dec 20 04:41:04 musicaluis volumio-time-update[568]: volumio-time-update-util: Date not found in response Dec 20 04:41:04 musicaluis volumio-time-update[568]: volumio-time-update-util: Retrying in 5 seconds... Dec 20 04:41:04 musicaluis sudo[23257]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-12-20 04:40 Dec 20 04:41:04 musicaluis sudo[23257]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 04:41:05 musicaluis sudo[23257]: pam_unix(sudo:session): session closed for user root Dec 20 04:41:05 musicaluis volumio-remote-updater[578]: [2024-12-20 04:41:05] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Dec 20 04:41:05 musicaluis volumio-remote-updater[578]: [2024-12-20 04:41:05] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Dec 20 04:41:05 musicaluis systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Dec 20 04:41:05 musicaluis sudo[23216]: pam_unix(sudo:session): session closed for user root Dec 20 04:41:05 musicaluis sudo[23206]: pam_unix(sudo:session): session closed for user root Dec 20 04:41:05 musicaluis systemd[1]: volumio.service: Failed with result 'exit-code'. Dec 20 04:41:05 musicaluis systemd[1]: Started dynamicswap service. Dec 20 04:41:05 musicaluis systemd[1]: dynamicswap.service: Succeeded. Dec 20 04:41:05 musicaluis mpd[23233]: Dec 20 04:41 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 20 04:41:05 musicaluis systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Dec 20 04:41:05 musicaluis systemd[1]: volumio.service: Scheduled restart job, restart counter is at 268. Dec 20 04:41:05 musicaluis systemd[1]: Started Music Player Daemon. Dec 20 04:41:05 musicaluis systemd[1]: Stopped Volumio Backend Module. Dec 20 04:41:05 musicaluis systemd[1]: Started Volumio Backend Module. Dec 20 04:41:05 musicaluis systemd[1]: Started dynamicswap service. Dec 20 04:41:05 musicaluis systemd[1]: dynamicswap.service: Succeeded. Dec 20 04:41:07 musicaluis volumio[23272]: info: ------------------------------------------- Dec 20 04:41:07 musicaluis volumio[23272]: info: ----- Volumio3 ---- Dec 20 04:41:07 musicaluis volumio[23272]: info: ------------------------------------------- Dec 20 04:41:07 musicaluis volumio[23272]: info: ----- System startup ---- Dec 20 04:41:07 musicaluis volumio[23272]: info: ------------------------------------------- Dec 20 04:41:08 musicaluis volumio[23272]: info: MYVOLUMIO Environment detected Dec 20 04:41:08 musicaluis volumio[23272]: info: Plugin folders cleanup Dec 20 04:41:08 musicaluis volumio[23272]: info: Scanning into folder /volumio/app/plugins/ Dec 20 04:41:08 musicaluis volumio[23272]: info: Scanning category audio_interface Dec 20 04:41:08 musicaluis volumio[23272]: info: Scanning category miscellanea Dec 20 04:41:08 musicaluis volumio[23272]: info: Scanning category music_service Dec 20 04:41:08 musicaluis volumio[23272]: info: Scanning category plugins.json Dec 20 04:41:08 musicaluis volumio[23272]: info: Scanning category system_controller Dec 20 04:41:08 musicaluis volumio[23272]: info: Scanning category user_interface Dec 20 04:41:08 musicaluis volumio[23272]: info: Scanning into folder /data/plugins/ Dec 20 04:41:08 musicaluis volumio[23272]: info: Scanning category audio_interface Dec 20 04:41:08 musicaluis volumio[23272]: info: Scanning category music_service Dec 20 04:41:08 musicaluis volumio[23272]: info: Scanning category system_controller Dec 20 04:41:08 musicaluis volumio[23272]: info: Scanning category user_interface Dec 20 04:41:08 musicaluis volumio[23272]: info: Plugin folders cleanup completed Dec 20 04:41:08 musicaluis volumio[23272]: info: ------------------------------------------- Dec 20 04:41:08 musicaluis volumio[23272]: info: ----- Core plugins startup ---- Dec 20 04:41:08 musicaluis volumio[23272]: info: ------------------------------------------- Dec 20 04:41:08 musicaluis volumio[23272]: info: Loading plugins from folder /volumio/app/plugins/ Dec 20 04:41:08 musicaluis volumio[23272]: info: Adding plugin upnp to MyMusic Plugins Dec 20 04:41:08 musicaluis volumio[23272]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 20 04:41:08 musicaluis volumio[23272]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 20 04:41:08 musicaluis volumio[23272]: info: Loading plugins from folder /data/plugins/ Dec 20 04:41:08 musicaluis volumio[23272]: info: Loading plugin "system"... Dec 20 04:41:08 musicaluis volumio[23272]: info: Loading plugin "appearance"... Dec 20 04:41:09 musicaluis volumio[23272]: info: Loading plugin "network"... Dec 20 04:41:09 musicaluis volumio[23272]: info: Refreshing Cached IP Addresses Dec 20 04:41:09 musicaluis sudo[23300]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 20 04:41:09 musicaluis sudo[23300]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 04:41:09 musicaluis sudo[23302]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 20 04:41:09 musicaluis sudo[23302]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 04:41:09 musicaluis sudo[23302]: pam_unix(sudo:session): session closed for user root Dec 20 04:41:09 musicaluis sudo[23300]: pam_unix(sudo:session): session closed for user root Dec 20 04:41:09 musicaluis volumio[23272]: info: Loading plugin "services"... Dec 20 04:41:09 musicaluis volumio[23272]: info: Loading plugin "alsa_controller"... Dec 20 04:41:09 musicaluis sudo[23311]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 20 04:41:09 musicaluis sudo[23311]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 04:41:09 musicaluis volumio[23272]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 20 04:41:09 musicaluis volumio[23272]: info: Loading plugin "wizard"... Dec 20 04:41:09 musicaluis volumio[23272]: info: Loading plugin "networkfs"... Dec 20 04:41:09 musicaluis volumio[23272]: info: Starting Udev Watcher for removable devices Dec 20 04:41:09 musicaluis volumio[23272]: info: Ignoring mount for partition: boot Dec 20 04:41:09 musicaluis volumio[23272]: info: Ignoring mount for partition: volumio Dec 20 04:41:09 musicaluis volumio[23272]: info: Ignoring mount for partition: volumio_data Dec 20 04:41:09 musicaluis volumio[23272]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 20 04:41:09 musicaluis volumio[23272]: info: Loading plugin "volumio_command_line_client"... Dec 20 04:41:09 musicaluis volumio[23272]: info: Loading plugin "upnp"... Dec 20 04:41:09 musicaluis volumio[23272]: info: [1734666069499] Starting Upmpd Daemon Dec 20 04:41:09 musicaluis volumio[23272]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 20 04:41:09 musicaluis volumio[23272]: info: Loading plugin "my_music"... Dec 20 04:41:09 musicaluis volumio[23272]: info: Loading plugin "mpd"... Dec 20 04:41:09 musicaluis volumio-time-update[568]: volumio-time-update-util: Fetching time from Volumio... Dec 20 04:41:09 musicaluis volumio-time-update[568]: volumio-time-update-util: Date not found in response Dec 20 04:41:09 musicaluis volumio-time-update[568]: volumio-time-update-util: Retrying in 5 seconds... Dec 20 04:41:09 musicaluis volumio[23272]: info: Loading plugin "upnp_browser"... Dec 20 04:41:10 musicaluis volumio[23272]: info: Loading plugin "alarm-clock"... Dec 20 04:41:10 musicaluis volumio-remote-updater[578]: [2024-12-20 04:41:10] [connect] Successful connection Dec 20 04:41:10 musicaluis sudo[23311]: pam_unix(sudo:session): session closed for user root Dec 20 04:41:10 musicaluis volumio[23272]: info: Loading plugin "airplay_emulation"... Dec 20 04:41:10 musicaluis volumio[23272]: info: Starting Shairport Sync Dec 20 04:41:10 musicaluis volumio[23272]: info: Loading plugin "last_100"... Dec 20 04:41:10 musicaluis volumio[23272]: info: Loading plugin "webradio"... Dec 20 04:41:10 musicaluis volumio[23272]: info: Loading plugin "i2s_dacs"... Dec 20 04:41:10 musicaluis volumio[23272]: info: Loading plugin "volumiodiscovery"... Dec 20 04:41:10 musicaluis volumio[23272]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 20 04:41:10 musicaluis volumio[23272]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 20 04:41:10 musicaluis volumio[23272]: *** WARNING *** For more information see Dec 20 04:41:10 musicaluis node[23272]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 20 04:41:10 musicaluis volumio[23272]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 20 04:41:10 musicaluis volumio[23272]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 20 04:41:10 musicaluis volumio[23272]: *** WARNING *** For more information see Dec 20 04:41:10 musicaluis node[23272]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 20 04:41:10 musicaluis node[23272]: *** WARNING *** For more information see Dec 20 04:41:10 musicaluis node[23272]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 20 04:41:10 musicaluis node[23272]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 20 04:41:10 musicaluis node[23272]: *** WARNING *** For more information see Dec 20 04:41:10 musicaluis volumio[23272]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 20 04:41:10 musicaluis volumio[23272]: info: Discovery: Started advertising with name: MusicaLuis Dec 20 04:41:10 musicaluis volumio[23272]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 20 04:41:10 musicaluis volumio[23272]: info: Loading plugin "autostart"... Dec 20 04:41:10 musicaluis volumio[23272]: info: Applying required configuration parameters for plugin autostart Dec 20 04:41:10 musicaluis volumio[23272]: info: AutoStart - onVolumioStart - read config.json Dec 20 04:41:10 musicaluis volumio[23272]: info: Loading plugin "now_playing"... Dec 20 04:41:11 musicaluis volumio[23272]: info: Loading plugin "outputs"... Dec 20 04:41:11 musicaluis volumio[23272]: info: Loading plugin "albumart"... Dec 20 04:41:11 musicaluis volumio[23272]: info: Plugin example_plugin is not enabled Dec 20 04:41:11 musicaluis volumio[23272]: info: Loading plugin "inputs"... Dec 20 04:41:11 musicaluis volumio[23272]: info: Loading plugin "updater_comm"... Dec 20 04:41:12 musicaluis volumio[23272]: info: Plugin mpdemulation is not enabled Dec 20 04:41:12 musicaluis volumio[23272]: info: Loading plugin "rest_api"... Dec 20 04:41:12 musicaluis volumio[23272]: info: Loading plugin "websocket"... Dec 20 04:41:12 musicaluis volumio[23272]: info: Starting Socket.io Server version 2.3.0 Dec 20 04:41:12 musicaluis volumio[23272]: info: Loading plugin "fusiondsp"... Dec 20 04:41:12 musicaluis volumio[23272]: Forking 3 albumart workers Dec 20 04:41:12 musicaluis volumio[23272]: info: Applying required configuration parameters for plugin fusiondsp Dec 20 04:41:12 musicaluis volumio[23272]: info: Loading plugin "volusonic"... Dec 20 04:41:13 musicaluis volumio[23272]: Starting albumart workers Dec 20 04:41:13 musicaluis volumio[23272]: Starting albumart workers Dec 20 04:41:13 musicaluis volumio[23272]: Starting albumart workers Dec 20 04:41:13 musicaluis volumio[23272]: info: Applying required configuration parameters for plugin volusonic Dec 20 04:41:13 musicaluis volumio[23272]: info: Loading plugin "lastfm"... Dec 20 04:41:14 musicaluis volumio[23272]: info: Loading i18n strings for locale en Dec 20 04:41:14 musicaluis volumio[23272]: info: Plugin volusonic has duplicated i18n key SAVE. It is ignored. Dec 20 04:41:14 musicaluis volumio[23272]: Updating browse sources language Dec 20 04:41:14 musicaluis volumio[23272]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 04:41:14 musicaluis volumio-time-update[568]: volumio-time-update-util: Fetching time from Volumio... Dec 20 04:41:14 musicaluis volumio-time-update[568]: volumio-time-update-util: Date not found in response Dec 20 04:41:14 musicaluis volumio-time-update[568]: volumio-time-update-util: Retrying in 5 seconds... Dec 20 04:41:14 musicaluis volumio[23272]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 20 04:41:14 musicaluis volumio[23272]: info: CoreCommandRouter::initPlayerControls Dec 20 04:41:14 musicaluis volumio[23272]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:41:14 musicaluis volumio[23272]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:41:14 musicaluis volumio[23272]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:41:14 musicaluis volumio[23272]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:41:14 musicaluis volumio[23272]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:41:14 musicaluis volumio[23272]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:41:14 musicaluis volumio[23272]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:41:14 musicaluis volumio[23272]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:41:14 musicaluis volumio[23272]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 20 04:41:14 musicaluis volumio[23272]: Express server listening on port 3000 Dec 20 04:41:14 musicaluis volumio[23272]: [Metrics] WebUI: 7s 958.80ms Dec 20 04:41:14 musicaluis volumio[23272]: info: CoreStateMachine::resetVolumioState Dec 20 04:41:14 musicaluis volumio[23272]: info: CoreStateMachine::getcurrentVolume Dec 20 04:41:14 musicaluis volumio[23272]: info: CoreCommandRouter::volumioRetrievevolume Dec 20 04:41:14 musicaluis volumio[23272]: info: Volumio Network Manager: Network status updated: 0 Dec 20 04:41:14 musicaluis volumio-remote-updater[578]: [2024-12-20 04:41:14] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1734666070 101 Dec 20 04:41:14 musicaluis volumio[23272]: 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 Dec 20 04:41:14 musicaluis volumio[23272]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Dec 20 04:41:14 musicaluis volumio[23272]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 04:41:14 musicaluis volumio[23272]: info: Reloading queue from file Dec 20 04:41:15 musicaluis volumio[23272]: info: VolumeController:: Volume=100 Mute =false Dec 20 04:41:15 musicaluis volumio[23272]: info: CoreStateMachine::pushState Dec 20 04:41:15 musicaluis volumio[23272]: info: CorePlayQueue::getTrack 0 Dec 20 04:41:15 musicaluis volumio[23272]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 20 04:41:15 musicaluis volumio[23272]: info: CoreCommandRouter::volumioPushState Dec 20 04:41:15 musicaluis volumio[23272]: info: CoreStateMachine::updateTrackBlock Dec 20 04:41:15 musicaluis volumio[23272]: info: CorePlayQueue::getTrackBlock Dec 20 04:41:15 musicaluis volumio[23272]: info: CoreCommandRouter::volumioRetrievevolume Dec 20 04:41:15 musicaluis volumio[23272]: info: CoreStateMachine::setRepeat null single undefined Dec 20 04:41:15 musicaluis volumio[23272]: info: CoreStateMachine::pushState Dec 20 04:41:15 musicaluis volumio[23272]: info: CorePlayQueue::getTrack 0 Dec 20 04:41:15 musicaluis volumio[23272]: info: CoreCommandRouter::volumioPushState Dec 20 04:41:15 musicaluis volumio[23272]: info: CoreStateMachine::setRandom null Dec 20 04:41:15 musicaluis volumio[23272]: info: CoreStateMachine::pushState Dec 20 04:41:15 musicaluis volumio[23272]: info: CorePlayQueue::getTrack 0 Dec 20 04:41:15 musicaluis volumio[23272]: info: CoreCommandRouter::volumioPushState Dec 20 04:41:15 musicaluis volumio[23272]: info: Setting Device type: Raspberry PI Dec 20 04:41:15 musicaluis volumio[23272]: info: Completed loading Core Plugins Dec 20 04:41:15 musicaluis volumio[23272]: info: Preparing to generate the ALSA configuration file Dec 20 04:41:15 musicaluis volumio[23272]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Dec 20 04:41:15 musicaluis volumio[23272]: info: Reading ALSA contributions from plugins. Dec 20 04:41:15 musicaluis volumio[23272]: info: VolumeController:: Volume=100 Mute =false Dec 20 04:41:15 musicaluis volumio[23272]: info: CoreStateMachine::pushState Dec 20 04:41:15 musicaluis volumio[23272]: info: CorePlayQueue::getTrack 0 Dec 20 04:41:15 musicaluis volumio[23272]: info: CoreCommandRouter::volumioPushState Dec 20 04:41:15 musicaluis volumio[23272]: info: Asound.conf file unchanged, so no further update is needed Dec 20 04:41:15 musicaluis volumio[23272]: info: Output device has changed, restarting MPD Dec 20 04:41:15 musicaluis sudo[23404]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 20 04:41:15 musicaluis volumio[23272]: info: Output device has changed, restarting Shairport Sync Dec 20 04:41:15 musicaluis sudo[23404]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 04:41:15 musicaluis volumio[23272]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:41:15 musicaluis volumio[23272]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 04:41:15 musicaluis sudo[23404]: pam_unix(sudo:session): session closed for user root Dec 20 04:41:15 musicaluis sudo[23406]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 20 04:41:15 musicaluis sudo[23406]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 04:41:15 musicaluis systemd[1]: Stopping Music Player Daemon... Dec 20 04:41:15 musicaluis volumio[23272]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 20 04:41:15 musicaluis volumio[23272]: info: ___________ START PLUGINS ___________ Dec 20 04:41:15 musicaluis systemd[1]: mpd.service: Succeeded. Dec 20 04:41:15 musicaluis systemd[1]: Stopped Music Player Daemon. Dec 20 04:41:15 musicaluis volumio[23272]: info: ControllerMpd::onStart: Initializing MPD Dec 20 04:41:15 musicaluis volumio[23272]: info: Creating MPD Configuration file Dec 20 04:41:15 musicaluis sudo[23414]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 20 04:41:15 musicaluis sudo[23414]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 04:41:15 musicaluis systemd[1]: Starting Music Player Daemon... Dec 20 04:41:15 musicaluis volumio[23272]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 20 04:41:15 musicaluis volumio[23272]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 20 04:41:15 musicaluis volumio[23272]: info: [1734666075409] CoreMusicLibrary::Adding element Media Servers Dec 20 04:41:15 musicaluis volumio[23272]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 04:41:15 musicaluis sudo[23414]: pam_unix(sudo:session): session closed for user root Dec 20 04:41:15 musicaluis sudo[23416]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 20 04:41:15 musicaluis sudo[23416]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 04:41:15 musicaluis volumio[23272]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:41:15 musicaluis volumio[23272]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 04:41:15 musicaluis systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Dec 20 04:41:15 musicaluis systemd[1]: mpd.service: Succeeded. Dec 20 04:41:15 musicaluis systemd[1]: Stopped Music Player Daemon. Dec 20 04:41:15 musicaluis systemd[1]: Starting Music Player Daemon... Dec 20 04:41:15 musicaluis volumio[23272]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 20 04:41:15 musicaluis volumio[23272]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:41:15 musicaluis volumio[23272]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 04:41:15 musicaluis volumio[23272]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 20 04:41:15 musicaluis volumio[23272]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 20 04:41:15 musicaluis volumio[23272]: info: [1734666075542] CoreMusicLibrary::Adding element Last_100 Dec 20 04:41:15 musicaluis volumio[23272]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 04:41:15 musicaluis volumio[23272]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 20 04:41:15 musicaluis volumio[23272]: info: [1734666075545] CoreMusicLibrary::Adding element Webradio Dec 20 04:41:15 musicaluis volumio[23272]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 04:41:15 musicaluis volumio[23272]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 20 04:41:15 musicaluis volumio[23272]: info: Initializing BBC Radios Dec 20 04:41:15 musicaluis sudo[23422]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 20 04:41:15 musicaluis sudo[23422]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 04:41:15 musicaluis sudo[23422]: pam_unix(sudo:session): session closed for user root Dec 20 04:41:15 musicaluis volumio[23272]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 20 04:41:15 musicaluis volumio[23272]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 04:41:15 musicaluis volumio[23272]: info: AutoStart - onStart Dec 20 04:41:15 musicaluis volumio[23272]: info: [now-playing-config] Config is up to date. Dec 20 04:41:15 musicaluis volumio[23272]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 20 04:41:15 musicaluis volumio[23272]: info: Discovery: Getting this device information Dec 20 04:41:15 musicaluis volumio[23272]: info: CoreCommandRouter::volumioGetState Dec 20 04:41:15 musicaluis volumio[23272]: info: CorePlayQueue::getTrack 0 Dec 20 04:41:15 musicaluis volumio[23272]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 20 04:41:15 musicaluis volumio[23272]: info: Loading i18n strings for locale en Dec 20 04:41:15 musicaluis volumio[23272]: info: Plugin volusonic has duplicated i18n key SAVE. It is ignored. Dec 20 04:41:15 musicaluis volumio[23272]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Dec 20 04:41:15 musicaluis volumio[23272]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:41:15 musicaluis volumio[23272]: info: FusionDsp - mixtype--------------------- Hardware Dec 20 04:41:15 musicaluis volumio[23272]: info: Loading i18n strings for locale en Dec 20 04:41:15 musicaluis volumio[23272]: info: Plugin volusonic has duplicated i18n key SAVE. It is ignored. Dec 20 04:41:15 musicaluis volumio[23272]: Updating browse sources language Dec 20 04:41:15 musicaluis volumio[23272]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 04:41:15 musicaluis volumio[23272]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 20 04:41:15 musicaluis volumio[23272]: info: [1734666075739] CoreMusicLibrary::Adding element Volusonic Dec 20 04:41:15 musicaluis volumio[23272]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 04:41:15 musicaluis volumio[23272]: Cannot find translation for source Volusonic Dec 20 04:41:15 musicaluis volumio[23272]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 20 04:41:15 musicaluis volumio[23272]: info: [1734666075743] CoreMusicLibrary::Adding element LastFM Dec 20 04:41:15 musicaluis volumio[23272]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 04:41:15 musicaluis volumio[23272]: Cannot find translation for source Volusonic Dec 20 04:41:15 musicaluis volumio[23272]: Cannot find translation for source LastFM Dec 20 04:41:15 musicaluis volumio[23272]: info: [LastFM] scrobbler initiated! Dec 20 04:41:15 musicaluis volumio[23272]: info: [LastFM] extended logging: false Dec 20 04:41:15 musicaluis volumio[23272]: info: [LastFM] try scrobble stream/radio plays: true Dec 20 04:41:15 musicaluis volumio[23272]: info: [LastFM] Left init routine Dec 20 04:41:15 musicaluis volumio[23272]: info: [LastFM] Socket already connected: true Dec 20 04:41:15 musicaluis volumio[23272]: info: Volumio Calling Home Dec 20 04:41:15 musicaluis volumio[23272]: info: [now-playing-app] App is listening on port 4004. Dec 20 04:41:15 musicaluis volumio[23272]: info: Preparing to generate the ALSA configuration file Dec 20 04:41:16 musicaluis volumio[23272]: Unhandled rejection Error: No sockets available, cannot start. Dec 20 04:41:16 musicaluis volumio[23272]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Dec 20 04:41:16 musicaluis volumio[23272]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Dec 20 04:41:16 musicaluis volumio[23272]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Dec 20 04:41:16 musicaluis volumio[23272]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Dec 20 04:41:16 musicaluis volumio[23272]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Dec 20 04:41:16 musicaluis volumio[23272]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Dec 20 04:41:16 musicaluis volumio[23272]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Dec 20 04:41:16 musicaluis volumio[23272]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Dec 20 04:41:16 musicaluis volumio[23272]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Dec 20 04:41:16 musicaluis volumio[23272]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Dec 20 04:41:16 musicaluis volumio[23272]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Dec 20 04:41:16 musicaluis volumio[23272]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Dec 20 04:41:16 musicaluis volumio[23272]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Dec 20 04:41:16 musicaluis volumio[23272]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Dec 20 04:41:16 musicaluis volumio[23272]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Dec 20 04:41:16 musicaluis volumio[23272]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Dec 20 04:41:16 musicaluis volumio[23272]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 20 04:41:16 musicaluis volumio[23272]: Error: getaddrinfo EBUSY ws.audioscrobbler.com Dec 20 04:41:16 musicaluis volumio[23272]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:67:26) { Dec 20 04:41:16 musicaluis volumio[23272]: errno: -16, Dec 20 04:41:16 musicaluis volumio[23272]: code: 'EBUSY', Dec 20 04:41:16 musicaluis volumio[23272]: syscall: 'getaddrinfo', Dec 20 04:41:16 musicaluis volumio[23272]: hostname: 'ws.audioscrobbler.com' Dec 20 04:41:16 musicaluis volumio[23272]: } Dec 20 04:41:16 musicaluis volumio[23272]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 20 04:41:16 musicaluis sudo[23452]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-12-20 04:40 Dec 20 04:41:16 musicaluis sudo[23452]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 04:41:16 musicaluis sudo[23452]: pam_unix(sudo:session): session closed for user root Dec 20 04:41:17 musicaluis volumio-remote-updater[578]: [2024-12-20 04:41:17] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Dec 20 04:41:17 musicaluis systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Dec 20 04:41:17 musicaluis volumio-remote-updater[578]: [2024-12-20 04:41:17] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Dec 20 04:41:17 musicaluis sudo[23416]: pam_unix(sudo:session): session closed for user root Dec 20 04:41:17 musicaluis sudo[23406]: pam_unix(sudo:session): session closed for user root Dec 20 04:41:17 musicaluis systemd[1]: volumio.service: Failed with result 'exit-code'. Dec 20 04:41:17 musicaluis systemd[1]: Started dynamicswap service. Dec 20 04:41:17 musicaluis systemd[1]: dynamicswap.service: Succeeded. Dec 20 04:41:17 musicaluis mpd[23435]: Dec 20 04:41 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 20 04:41:17 musicaluis systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Dec 20 04:41:17 musicaluis systemd[1]: volumio.service: Scheduled restart job, restart counter is at 269. Dec 20 04:41:17 musicaluis systemd[1]: Started Music Player Daemon. Dec 20 04:41:17 musicaluis systemd[1]: Stopped Volumio Backend Module. Dec 20 04:41:17 musicaluis systemd[1]: Started Volumio Backend Module. Dec 20 04:41:17 musicaluis systemd[1]: Started dynamicswap service. Dec 20 04:41:17 musicaluis systemd[1]: dynamicswap.service: Succeeded. Dec 20 04:41:19 musicaluis volumio[23467]: info: ------------------------------------------- Dec 20 04:41:19 musicaluis volumio[23467]: info: ----- Volumio3 ---- Dec 20 04:41:19 musicaluis volumio[23467]: info: ------------------------------------------- Dec 20 04:41:19 musicaluis volumio[23467]: info: ----- System startup ---- Dec 20 04:41:19 musicaluis volumio[23467]: info: ------------------------------------------- Dec 20 04:41:19 musicaluis volumio-time-update[568]: volumio-time-update-util: Fetching time from Volumio... Dec 20 04:41:19 musicaluis volumio-time-update[568]: volumio-time-update-util: Date not found in response Dec 20 04:41:19 musicaluis volumio-time-update[568]: volumio-time-update-util: Retrying in 5 seconds... Dec 20 04:41:19 musicaluis volumio[23467]: info: MYVOLUMIO Environment detected Dec 20 04:41:19 musicaluis volumio[23467]: info: Plugin folders cleanup Dec 20 04:41:19 musicaluis volumio[23467]: info: Scanning into folder /volumio/app/plugins/ Dec 20 04:41:19 musicaluis volumio[23467]: info: Scanning category audio_interface Dec 20 04:41:19 musicaluis volumio[23467]: info: Scanning category miscellanea Dec 20 04:41:19 musicaluis volumio[23467]: info: Scanning category music_service Dec 20 04:41:19 musicaluis volumio[23467]: info: Scanning category plugins.json Dec 20 04:41:19 musicaluis volumio[23467]: info: Scanning category system_controller Dec 20 04:41:19 musicaluis volumio[23467]: info: Scanning category user_interface Dec 20 04:41:19 musicaluis volumio[23467]: info: Scanning into folder /data/plugins/ Dec 20 04:41:19 musicaluis volumio[23467]: info: Scanning category audio_interface Dec 20 04:41:19 musicaluis volumio[23467]: info: Scanning category music_service Dec 20 04:41:19 musicaluis volumio[23467]: info: Scanning category system_controller Dec 20 04:41:19 musicaluis volumio[23467]: info: Scanning category user_interface Dec 20 04:41:19 musicaluis volumio[23467]: info: Plugin folders cleanup completed Dec 20 04:41:19 musicaluis volumio[23467]: info: ------------------------------------------- Dec 20 04:41:19 musicaluis volumio[23467]: info: ----- Core plugins startup ---- Dec 20 04:41:19 musicaluis volumio[23467]: info: ------------------------------------------- Dec 20 04:41:19 musicaluis volumio[23467]: info: Loading plugins from folder /volumio/app/plugins/ Dec 20 04:41:19 musicaluis volumio[23467]: info: Adding plugin upnp to MyMusic Plugins Dec 20 04:41:19 musicaluis volumio[23467]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 20 04:41:19 musicaluis volumio[23467]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 20 04:41:19 musicaluis volumio[23467]: info: Loading plugins from folder /data/plugins/ Dec 20 04:41:20 musicaluis volumio[23467]: info: Loading plugin "system"... Dec 20 04:41:20 musicaluis volumio[23467]: info: Loading plugin "appearance"... Dec 20 04:41:21 musicaluis volumio[23467]: info: Loading plugin "network"... Dec 20 04:41:21 musicaluis volumio[23467]: info: Refreshing Cached IP Addresses Dec 20 04:41:21 musicaluis sudo[23500]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 20 04:41:21 musicaluis sudo[23500]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 04:41:21 musicaluis sudo[23500]: pam_unix(sudo:session): session closed for user root Dec 20 04:41:21 musicaluis sudo[23502]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 20 04:41:21 musicaluis sudo[23502]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 04:41:21 musicaluis sudo[23502]: pam_unix(sudo:session): session closed for user root Dec 20 04:41:21 musicaluis volumio[23467]: info: Loading plugin "services"... Dec 20 04:41:21 musicaluis volumio[23467]: info: Loading plugin "alsa_controller"... Dec 20 04:41:21 musicaluis sudo[23515]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 20 04:41:21 musicaluis sudo[23515]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 04:41:21 musicaluis volumio[23467]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 20 04:41:21 musicaluis volumio[23467]: info: Loading plugin "wizard"... Dec 20 04:41:21 musicaluis volumio[23467]: info: Loading plugin "networkfs"... Dec 20 04:41:21 musicaluis volumio[23467]: info: Starting Udev Watcher for removable devices Dec 20 04:41:21 musicaluis volumio[23467]: info: Ignoring mount for partition: boot Dec 20 04:41:21 musicaluis volumio[23467]: info: Ignoring mount for partition: volumio Dec 20 04:41:21 musicaluis volumio[23467]: info: Ignoring mount for partition: volumio_data Dec 20 04:41:21 musicaluis volumio[23467]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 20 04:41:21 musicaluis volumio[23467]: info: Loading plugin "volumio_command_line_client"... Dec 20 04:41:21 musicaluis volumio[23467]: info: Loading plugin "upnp"... Dec 20 04:41:21 musicaluis volumio[23467]: info: [1734666081338] Starting Upmpd Daemon Dec 20 04:41:21 musicaluis volumio[23467]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 20 04:41:21 musicaluis volumio[23467]: info: Loading plugin "my_music"... Dec 20 04:41:21 musicaluis volumio[23467]: info: Loading plugin "mpd"... Dec 20 04:41:21 musicaluis volumio[23467]: info: Loading plugin "upnp_browser"... Dec 20 04:41:21 musicaluis volumio[23467]: info: Loading plugin "alarm-clock"... Dec 20 04:41:22 musicaluis volumio-remote-updater[578]: [2024-12-20 04:41:22] [connect] Successful connection Dec 20 04:41:22 musicaluis sudo[23515]: pam_unix(sudo:session): session closed for user root Dec 20 04:41:22 musicaluis volumio[23467]: info: Loading plugin "airplay_emulation"... Dec 20 04:41:22 musicaluis volumio[23467]: info: Starting Shairport Sync Dec 20 04:41:22 musicaluis volumio[23467]: info: Loading plugin "last_100"... Dec 20 04:41:22 musicaluis volumio[23467]: info: Loading plugin "webradio"... Dec 20 04:41:22 musicaluis volumio[23467]: info: Loading plugin "i2s_dacs"... Dec 20 04:41:22 musicaluis volumio[23467]: info: Loading plugin "volumiodiscovery"... Dec 20 04:41:22 musicaluis volumio[23467]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 20 04:41:22 musicaluis volumio[23467]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 20 04:41:22 musicaluis volumio[23467]: *** WARNING *** For more information see Dec 20 04:41:22 musicaluis node[23467]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 20 04:41:22 musicaluis volumio[23467]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 20 04:41:22 musicaluis volumio[23467]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 20 04:41:22 musicaluis volumio[23467]: *** WARNING *** For more information see Dec 20 04:41:22 musicaluis node[23467]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 20 04:41:22 musicaluis node[23467]: *** WARNING *** For more information see Dec 20 04:41:22 musicaluis node[23467]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 20 04:41:22 musicaluis node[23467]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 20 04:41:22 musicaluis node[23467]: *** WARNING *** For more information see Dec 20 04:41:22 musicaluis volumio[23467]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 20 04:41:22 musicaluis volumio[23467]: info: Discovery: Started advertising with name: MusicaLuis Dec 20 04:41:22 musicaluis volumio[23467]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 20 04:41:22 musicaluis volumio[23467]: info: Loading plugin "autostart"... Dec 20 04:41:22 musicaluis volumio[23467]: info: Applying required configuration parameters for plugin autostart Dec 20 04:41:22 musicaluis volumio[23467]: info: AutoStart - onVolumioStart - read config.json Dec 20 04:41:22 musicaluis volumio[23467]: info: Loading plugin "now_playing"... Dec 20 04:41:23 musicaluis volumio[23467]: info: Loading plugin "outputs"... Dec 20 04:41:23 musicaluis volumio[23467]: info: Loading plugin "albumart"... Dec 20 04:41:23 musicaluis volumio[23467]: info: Plugin example_plugin is not enabled Dec 20 04:41:23 musicaluis volumio[23467]: info: Loading plugin "inputs"... Dec 20 04:41:23 musicaluis volumio[23467]: info: Loading plugin "updater_comm"... Dec 20 04:41:23 musicaluis volumio[23467]: info: Plugin mpdemulation is not enabled Dec 20 04:41:23 musicaluis volumio[23467]: info: Loading plugin "rest_api"... Dec 20 04:41:24 musicaluis volumio[23467]: info: Loading plugin "websocket"... Dec 20 04:41:24 musicaluis volumio[23467]: info: Starting Socket.io Server version 2.3.0 Dec 20 04:41:24 musicaluis volumio[23467]: info: Loading plugin "fusiondsp"... Dec 20 04:41:24 musicaluis volumio[23467]: Forking 3 albumart workers Dec 20 04:41:24 musicaluis volumio[23467]: info: Applying required configuration parameters for plugin fusiondsp Dec 20 04:41:24 musicaluis volumio[23467]: info: Loading plugin "volusonic"... Dec 20 04:41:24 musicaluis volumio-time-update[568]: volumio-time-update-util: Fetching time from Volumio... Dec 20 04:41:24 musicaluis volumio-time-update[568]: volumio-time-update-util: Date not found in response Dec 20 04:41:24 musicaluis volumio-time-update[568]: volumio-time-update-util: Retrying in 5 seconds... Dec 20 04:41:25 musicaluis volumio[23467]: Starting albumart workers Dec 20 04:41:25 musicaluis volumio[23467]: Starting albumart workers Dec 20 04:41:25 musicaluis volumio[23467]: Starting albumart workers Dec 20 04:41:25 musicaluis volumio[23467]: info: Applying required configuration parameters for plugin volusonic Dec 20 04:41:25 musicaluis volumio[23467]: info: Loading plugin "lastfm"... Dec 20 04:41:26 musicaluis volumio[23467]: info: Loading i18n strings for locale en Dec 20 04:41:26 musicaluis volumio[23467]: info: Plugin volusonic has duplicated i18n key SAVE. It is ignored. Dec 20 04:41:26 musicaluis volumio[23467]: Updating browse sources language Dec 20 04:41:26 musicaluis volumio[23467]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 04:41:26 musicaluis volumio[23467]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 20 04:41:26 musicaluis volumio[23467]: info: CoreCommandRouter::initPlayerControls Dec 20 04:41:26 musicaluis volumio[23467]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:41:26 musicaluis volumio[23467]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:41:26 musicaluis volumio[23467]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:41:26 musicaluis volumio[23467]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:41:26 musicaluis volumio[23467]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:41:26 musicaluis volumio[23467]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:41:26 musicaluis volumio[23467]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:41:26 musicaluis volumio[23467]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:41:26 musicaluis volumio[23467]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 20 04:41:26 musicaluis volumio[23467]: Express server listening on port 3000 Dec 20 04:41:26 musicaluis volumio[23467]: [Metrics] WebUI: 8s 116.33ms Dec 20 04:41:26 musicaluis volumio[23467]: info: CoreStateMachine::resetVolumioState Dec 20 04:41:26 musicaluis volumio[23467]: info: CoreStateMachine::getcurrentVolume Dec 20 04:41:26 musicaluis volumio[23467]: info: CoreCommandRouter::volumioRetrievevolume Dec 20 04:41:26 musicaluis volumio[23467]: info: Volumio Network Manager: Network status updated: 0 Dec 20 04:41:26 musicaluis volumio-remote-updater[578]: [2024-12-20 04:41:26] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1734666082 101 Dec 20 04:41:26 musicaluis volumio[23467]: 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 Dec 20 04:41:26 musicaluis volumio[23467]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Dec 20 04:41:26 musicaluis volumio[23467]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 04:41:26 musicaluis volumio[23467]: info: Reloading queue from file Dec 20 04:41:27 musicaluis volumio[23467]: info: VolumeController:: Volume=100 Mute =false Dec 20 04:41:27 musicaluis volumio[23467]: info: CoreStateMachine::pushState Dec 20 04:41:27 musicaluis volumio[23467]: info: CorePlayQueue::getTrack 0 Dec 20 04:41:27 musicaluis volumio[23467]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 20 04:41:27 musicaluis volumio[23467]: info: CoreCommandRouter::volumioPushState Dec 20 04:41:27 musicaluis volumio[23467]: info: CoreStateMachine::updateTrackBlock Dec 20 04:41:27 musicaluis volumio[23467]: info: CorePlayQueue::getTrackBlock Dec 20 04:41:27 musicaluis volumio[23467]: info: CoreCommandRouter::volumioRetrievevolume Dec 20 04:41:27 musicaluis volumio[23467]: info: CoreStateMachine::setRepeat null single undefined Dec 20 04:41:27 musicaluis volumio[23467]: info: CoreStateMachine::pushState Dec 20 04:41:27 musicaluis volumio[23467]: info: CorePlayQueue::getTrack 0 Dec 20 04:41:27 musicaluis volumio[23467]: info: CoreCommandRouter::volumioPushState Dec 20 04:41:27 musicaluis volumio[23467]: info: CoreStateMachine::setRandom null Dec 20 04:41:27 musicaluis volumio[23467]: info: CoreStateMachine::pushState Dec 20 04:41:27 musicaluis volumio[23467]: info: CorePlayQueue::getTrack 0 Dec 20 04:41:27 musicaluis volumio[23467]: info: CoreCommandRouter::volumioPushState Dec 20 04:41:27 musicaluis volumio[23467]: info: Setting Device type: Raspberry PI Dec 20 04:41:27 musicaluis volumio[23467]: info: Completed loading Core Plugins Dec 20 04:41:27 musicaluis volumio[23467]: info: Preparing to generate the ALSA configuration file Dec 20 04:41:27 musicaluis volumio[23467]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Dec 20 04:41:27 musicaluis volumio[23467]: info: Reading ALSA contributions from plugins. Dec 20 04:41:27 musicaluis volumio[23467]: info: VolumeController:: Volume=100 Mute =false Dec 20 04:41:27 musicaluis volumio[23467]: info: CoreStateMachine::pushState Dec 20 04:41:27 musicaluis volumio[23467]: info: CorePlayQueue::getTrack 0 Dec 20 04:41:27 musicaluis volumio[23467]: info: CoreCommandRouter::volumioPushState Dec 20 04:41:27 musicaluis volumio[23467]: info: Asound.conf file unchanged, so no further update is needed Dec 20 04:41:27 musicaluis volumio[23467]: info: Output device has changed, restarting MPD Dec 20 04:41:27 musicaluis sudo[23601]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 20 04:41:27 musicaluis volumio[23467]: info: Output device has changed, restarting Shairport Sync Dec 20 04:41:27 musicaluis sudo[23601]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 04:41:27 musicaluis volumio[23467]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:41:27 musicaluis volumio[23467]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 04:41:27 musicaluis sudo[23601]: pam_unix(sudo:session): session closed for user root Dec 20 04:41:27 musicaluis sudo[23603]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 20 04:41:27 musicaluis sudo[23603]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 04:41:27 musicaluis systemd[1]: Stopping Music Player Daemon... Dec 20 04:41:27 musicaluis volumio[23467]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 20 04:41:27 musicaluis volumio[23467]: info: ___________ START PLUGINS ___________ Dec 20 04:41:27 musicaluis systemd[1]: mpd.service: Succeeded. Dec 20 04:41:27 musicaluis systemd[1]: Stopped Music Player Daemon. Dec 20 04:41:27 musicaluis volumio[23467]: info: ControllerMpd::onStart: Initializing MPD Dec 20 04:41:27 musicaluis volumio[23467]: info: Creating MPD Configuration file Dec 20 04:41:27 musicaluis sudo[23611]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 20 04:41:27 musicaluis sudo[23611]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 04:41:27 musicaluis volumio[23467]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 20 04:41:27 musicaluis volumio[23467]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 20 04:41:27 musicaluis volumio[23467]: info: [1734666087392] CoreMusicLibrary::Adding element Media Servers Dec 20 04:41:27 musicaluis volumio[23467]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 04:41:27 musicaluis volumio[23467]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:41:27 musicaluis volumio[23467]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 04:41:27 musicaluis systemd[1]: Starting Music Player Daemon... Dec 20 04:41:27 musicaluis sudo[23611]: pam_unix(sudo:session): session closed for user root Dec 20 04:41:27 musicaluis sudo[23613]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 20 04:41:27 musicaluis sudo[23613]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 04:41:27 musicaluis systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Dec 20 04:41:27 musicaluis systemd[1]: mpd.service: Succeeded. Dec 20 04:41:27 musicaluis systemd[1]: Stopped Music Player Daemon. Dec 20 04:41:27 musicaluis volumio[23467]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 20 04:41:27 musicaluis volumio[23467]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:41:27 musicaluis volumio[23467]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 04:41:27 musicaluis systemd[1]: Starting Music Player Daemon... Dec 20 04:41:27 musicaluis volumio[23467]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 20 04:41:27 musicaluis volumio[23467]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 20 04:41:27 musicaluis volumio[23467]: info: [1734666087527] CoreMusicLibrary::Adding element Last_100 Dec 20 04:41:27 musicaluis volumio[23467]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 04:41:27 musicaluis volumio[23467]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 20 04:41:27 musicaluis volumio[23467]: info: [1734666087532] CoreMusicLibrary::Adding element Webradio Dec 20 04:41:27 musicaluis volumio[23467]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 04:41:27 musicaluis volumio[23467]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 20 04:41:27 musicaluis volumio[23467]: info: Initializing BBC Radios Dec 20 04:41:27 musicaluis sudo[23619]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 20 04:41:27 musicaluis sudo[23619]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 04:41:27 musicaluis volumio[23467]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 20 04:41:27 musicaluis volumio[23467]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 04:41:27 musicaluis sudo[23619]: pam_unix(sudo:session): session closed for user root Dec 20 04:41:27 musicaluis volumio[23467]: info: AutoStart - onStart Dec 20 04:41:27 musicaluis volumio[23467]: info: [now-playing-config] Config is up to date. Dec 20 04:41:27 musicaluis volumio[23467]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 20 04:41:27 musicaluis volumio[23467]: info: Discovery: Getting this device information Dec 20 04:41:27 musicaluis volumio[23467]: info: CoreCommandRouter::volumioGetState Dec 20 04:41:27 musicaluis volumio[23467]: info: CorePlayQueue::getTrack 0 Dec 20 04:41:27 musicaluis volumio[23467]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 20 04:41:27 musicaluis volumio[23467]: info: Loading i18n strings for locale en Dec 20 04:41:27 musicaluis volumio[23467]: info: Plugin volusonic has duplicated i18n key SAVE. It is ignored. Dec 20 04:41:27 musicaluis volumio[23467]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Dec 20 04:41:27 musicaluis volumio[23467]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:41:27 musicaluis volumio[23467]: info: FusionDsp - mixtype--------------------- Hardware Dec 20 04:41:27 musicaluis volumio[23467]: info: Loading i18n strings for locale en Dec 20 04:41:27 musicaluis volumio[23467]: info: Plugin volusonic has duplicated i18n key SAVE. It is ignored. Dec 20 04:41:27 musicaluis volumio[23467]: Updating browse sources language Dec 20 04:41:27 musicaluis volumio[23467]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 04:41:27 musicaluis volumio[23467]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 20 04:41:27 musicaluis volumio[23467]: info: [1734666087757] CoreMusicLibrary::Adding element Volusonic Dec 20 04:41:27 musicaluis volumio[23467]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 04:41:27 musicaluis volumio[23467]: Cannot find translation for source Volusonic Dec 20 04:41:27 musicaluis volumio[23467]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 20 04:41:27 musicaluis volumio[23467]: info: [1734666087762] CoreMusicLibrary::Adding element LastFM Dec 20 04:41:27 musicaluis volumio[23467]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 04:41:27 musicaluis volumio[23467]: Cannot find translation for source Volusonic Dec 20 04:41:27 musicaluis volumio[23467]: Cannot find translation for source LastFM Dec 20 04:41:27 musicaluis volumio[23467]: info: [LastFM] scrobbler initiated! Dec 20 04:41:27 musicaluis volumio[23467]: info: [LastFM] extended logging: false Dec 20 04:41:27 musicaluis volumio[23467]: info: [LastFM] try scrobble stream/radio plays: true Dec 20 04:41:27 musicaluis volumio[23467]: info: [LastFM] Left init routine Dec 20 04:41:27 musicaluis volumio[23467]: info: [LastFM] Socket already connected: true Dec 20 04:41:27 musicaluis volumio[23467]: info: Volumio Calling Home Dec 20 04:41:27 musicaluis volumio[23467]: info: [now-playing-app] App is listening on port 4004. Dec 20 04:41:27 musicaluis volumio[23467]: info: Preparing to generate the ALSA configuration file Dec 20 04:41:28 musicaluis volumio[23467]: Unhandled rejection Error: No sockets available, cannot start. Dec 20 04:41:28 musicaluis volumio[23467]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Dec 20 04:41:28 musicaluis volumio[23467]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Dec 20 04:41:28 musicaluis volumio[23467]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Dec 20 04:41:28 musicaluis volumio[23467]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Dec 20 04:41:28 musicaluis volumio[23467]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Dec 20 04:41:28 musicaluis volumio[23467]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Dec 20 04:41:28 musicaluis volumio[23467]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Dec 20 04:41:28 musicaluis volumio[23467]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Dec 20 04:41:28 musicaluis volumio[23467]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Dec 20 04:41:28 musicaluis volumio[23467]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Dec 20 04:41:28 musicaluis volumio[23467]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Dec 20 04:41:28 musicaluis volumio[23467]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Dec 20 04:41:28 musicaluis volumio[23467]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Dec 20 04:41:28 musicaluis volumio[23467]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Dec 20 04:41:28 musicaluis volumio[23467]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Dec 20 04:41:28 musicaluis volumio[23467]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Dec 20 04:41:28 musicaluis volumio[23467]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 20 04:41:28 musicaluis volumio[23467]: Error: getaddrinfo EBUSY ws.audioscrobbler.com Dec 20 04:41:28 musicaluis volumio[23467]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:67:26) { Dec 20 04:41:28 musicaluis volumio[23467]: errno: -16, Dec 20 04:41:28 musicaluis volumio[23467]: code: 'EBUSY', Dec 20 04:41:28 musicaluis volumio[23467]: syscall: 'getaddrinfo', Dec 20 04:41:28 musicaluis volumio[23467]: hostname: 'ws.audioscrobbler.com' Dec 20 04:41:28 musicaluis volumio[23467]: } Dec 20 04:41:28 musicaluis volumio[23467]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 20 04:41:28 musicaluis sudo[23685]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-12-20 04:40 Dec 20 04:41:28 musicaluis sudo[23685]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 04:41:28 musicaluis sudo[23685]: pam_unix(sudo:session): session closed for user root Dec 20 04:41:29 musicaluis volumio-remote-updater[578]: [2024-12-20 04:41:29] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Dec 20 04:41:29 musicaluis volumio-remote-updater[578]: [2024-12-20 04:41:29] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Dec 20 04:41:29 musicaluis systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Dec 20 04:41:29 musicaluis sudo[23613]: pam_unix(sudo:session): session closed for user root Dec 20 04:41:29 musicaluis sudo[23603]: pam_unix(sudo:session): session closed for user root Dec 20 04:41:29 musicaluis systemd[1]: volumio.service: Failed with result 'exit-code'. Dec 20 04:41:29 musicaluis systemd[1]: Started dynamicswap service. Dec 20 04:41:29 musicaluis systemd[1]: dynamicswap.service: Succeeded. Dec 20 04:41:29 musicaluis mpd[23671]: Dec 20 04:41 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 20 04:41:29 musicaluis systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Dec 20 04:41:29 musicaluis systemd[1]: volumio.service: Scheduled restart job, restart counter is at 270. Dec 20 04:41:29 musicaluis systemd[1]: Started Music Player Daemon. Dec 20 04:41:29 musicaluis systemd[1]: Started dynamicswap service. Dec 20 04:41:29 musicaluis systemd[1]: Stopped Volumio Backend Module. Dec 20 04:41:29 musicaluis systemd[1]: Started Volumio Backend Module. Dec 20 04:41:29 musicaluis systemd[1]: dynamicswap.service: Succeeded. Dec 20 04:41:29 musicaluis volumio-time-update[568]: volumio-time-update-util: Fetching time from Volumio... Dec 20 04:41:29 musicaluis volumio-time-update[568]: volumio-time-update-util: Date not found in response Dec 20 04:41:29 musicaluis volumio-time-update[568]: volumio-time-update-util: Retrying in 5 seconds... Dec 20 04:41:31 musicaluis volumio[23701]: info: ------------------------------------------- Dec 20 04:41:31 musicaluis volumio[23701]: info: ----- Volumio3 ---- Dec 20 04:41:31 musicaluis volumio[23701]: info: ------------------------------------------- Dec 20 04:41:31 musicaluis volumio[23701]: info: ----- System startup ---- Dec 20 04:41:31 musicaluis volumio[23701]: info: ------------------------------------------- Dec 20 04:41:31 musicaluis volumio[23701]: info: MYVOLUMIO Environment detected Dec 20 04:41:31 musicaluis volumio[23701]: info: Plugin folders cleanup Dec 20 04:41:31 musicaluis volumio[23701]: info: Scanning into folder /volumio/app/plugins/ Dec 20 04:41:31 musicaluis volumio[23701]: info: Scanning category audio_interface Dec 20 04:41:31 musicaluis volumio[23701]: info: Scanning category miscellanea Dec 20 04:41:31 musicaluis volumio[23701]: info: Scanning category music_service Dec 20 04:41:31 musicaluis volumio[23701]: info: Scanning category plugins.json Dec 20 04:41:31 musicaluis volumio[23701]: info: Scanning category system_controller Dec 20 04:41:31 musicaluis volumio[23701]: info: Scanning category user_interface Dec 20 04:41:31 musicaluis volumio[23701]: info: Scanning into folder /data/plugins/ Dec 20 04:41:31 musicaluis volumio[23701]: info: Scanning category audio_interface Dec 20 04:41:32 musicaluis volumio[23701]: info: Scanning category music_service Dec 20 04:41:32 musicaluis volumio[23701]: info: Scanning category system_controller Dec 20 04:41:32 musicaluis volumio[23701]: info: Scanning category user_interface Dec 20 04:41:32 musicaluis volumio[23701]: info: Plugin folders cleanup completed Dec 20 04:41:32 musicaluis volumio[23701]: info: ------------------------------------------- Dec 20 04:41:32 musicaluis volumio[23701]: info: ----- Core plugins startup ---- Dec 20 04:41:32 musicaluis volumio[23701]: info: ------------------------------------------- Dec 20 04:41:32 musicaluis volumio[23701]: info: Loading plugins from folder /volumio/app/plugins/ Dec 20 04:41:32 musicaluis volumio[23701]: info: Adding plugin upnp to MyMusic Plugins Dec 20 04:41:32 musicaluis volumio[23701]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 20 04:41:32 musicaluis volumio[23701]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 20 04:41:32 musicaluis volumio[23701]: info: Loading plugins from folder /data/plugins/ Dec 20 04:41:32 musicaluis volumio[23701]: info: Loading plugin "system"... Dec 20 04:41:32 musicaluis volumio[23701]: info: Loading plugin "appearance"... Dec 20 04:41:33 musicaluis volumio[23701]: info: Loading plugin "network"... Dec 20 04:41:33 musicaluis volumio[23701]: info: Refreshing Cached IP Addresses Dec 20 04:41:33 musicaluis sudo[23732]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 20 04:41:33 musicaluis sudo[23732]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 04:41:33 musicaluis sudo[23732]: pam_unix(sudo:session): session closed for user root Dec 20 04:41:33 musicaluis sudo[23734]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 20 04:41:33 musicaluis sudo[23734]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 04:41:33 musicaluis sudo[23734]: pam_unix(sudo:session): session closed for user root Dec 20 04:41:33 musicaluis volumio[23701]: info: Loading plugin "services"... Dec 20 04:41:33 musicaluis volumio[23701]: info: Loading plugin "alsa_controller"... Dec 20 04:41:33 musicaluis sudo[23743]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 20 04:41:33 musicaluis sudo[23743]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 04:41:33 musicaluis volumio[23701]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 20 04:41:33 musicaluis volumio[23701]: info: Loading plugin "wizard"... Dec 20 04:41:33 musicaluis volumio[23701]: info: Loading plugin "networkfs"... Dec 20 04:41:33 musicaluis volumio[23701]: info: Starting Udev Watcher for removable devices Dec 20 04:41:33 musicaluis volumio[23701]: info: Ignoring mount for partition: boot Dec 20 04:41:33 musicaluis volumio[23701]: info: Ignoring mount for partition: volumio Dec 20 04:41:33 musicaluis volumio[23701]: info: Ignoring mount for partition: volumio_data Dec 20 04:41:33 musicaluis volumio[23701]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 20 04:41:33 musicaluis volumio[23701]: info: Loading plugin "volumio_command_line_client"... Dec 20 04:41:33 musicaluis volumio[23701]: info: Loading plugin "upnp"... Dec 20 04:41:33 musicaluis volumio[23701]: info: [1734666093339] Starting Upmpd Daemon Dec 20 04:41:33 musicaluis volumio[23701]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 20 04:41:33 musicaluis volumio[23701]: info: Loading plugin "my_music"... Dec 20 04:41:33 musicaluis volumio[23701]: info: Loading plugin "mpd"... Dec 20 04:41:33 musicaluis volumio[23701]: info: Loading plugin "upnp_browser"... Dec 20 04:41:33 musicaluis volumio[23701]: info: Loading plugin "alarm-clock"... Dec 20 04:41:34 musicaluis sudo[23743]: pam_unix(sudo:session): session closed for user root Dec 20 04:41:34 musicaluis volumio-remote-updater[578]: [2024-12-20 04:41:34] [connect] Successful connection Dec 20 04:41:34 musicaluis volumio[23701]: info: Loading plugin "airplay_emulation"... Dec 20 04:41:34 musicaluis volumio[23701]: info: Starting Shairport Sync Dec 20 04:41:34 musicaluis volumio[23701]: info: Loading plugin "last_100"... Dec 20 04:41:34 musicaluis volumio[23701]: info: Loading plugin "webradio"... Dec 20 04:41:34 musicaluis volumio[23701]: info: Loading plugin "i2s_dacs"... Dec 20 04:41:34 musicaluis volumio[23701]: info: Loading plugin "volumiodiscovery"... Dec 20 04:41:34 musicaluis volumio[23701]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 20 04:41:34 musicaluis volumio[23701]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 20 04:41:34 musicaluis volumio[23701]: *** WARNING *** For more information see Dec 20 04:41:34 musicaluis node[23701]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 20 04:41:34 musicaluis volumio[23701]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 20 04:41:34 musicaluis volumio[23701]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 20 04:41:34 musicaluis volumio[23701]: *** WARNING *** For more information see Dec 20 04:41:34 musicaluis node[23701]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 20 04:41:34 musicaluis node[23701]: *** WARNING *** For more information see Dec 20 04:41:34 musicaluis node[23701]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 20 04:41:34 musicaluis node[23701]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 20 04:41:34 musicaluis node[23701]: *** WARNING *** For more information see Dec 20 04:41:34 musicaluis volumio[23701]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 20 04:41:34 musicaluis volumio[23701]: info: Discovery: Started advertising with name: MusicaLuis Dec 20 04:41:34 musicaluis volumio[23701]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 20 04:41:34 musicaluis volumio[23701]: info: Loading plugin "autostart"... Dec 20 04:41:34 musicaluis volumio[23701]: info: Applying required configuration parameters for plugin autostart Dec 20 04:41:34 musicaluis volumio[23701]: info: AutoStart - onVolumioStart - read config.json Dec 20 04:41:34 musicaluis volumio[23701]: info: Loading plugin "now_playing"... Dec 20 04:41:34 musicaluis volumio-time-update[568]: volumio-time-update-util: Fetching time from Volumio... Dec 20 04:41:34 musicaluis volumio-time-update[568]: volumio-time-update-util: Date not found in response Dec 20 04:41:34 musicaluis volumio-time-update[568]: volumio-time-update-util: Retrying in 5 seconds... Dec 20 04:41:35 musicaluis volumio[23701]: info: Loading plugin "outputs"... Dec 20 04:41:35 musicaluis volumio[23701]: info: Loading plugin "albumart"... Dec 20 04:41:35 musicaluis volumio[23701]: info: Plugin example_plugin is not enabled Dec 20 04:41:35 musicaluis volumio[23701]: info: Loading plugin "inputs"... Dec 20 04:41:35 musicaluis volumio[23701]: info: Loading plugin "updater_comm"... Dec 20 04:41:35 musicaluis volumio[23701]: info: Plugin mpdemulation is not enabled Dec 20 04:41:35 musicaluis volumio[23701]: info: Loading plugin "rest_api"... Dec 20 04:41:35 musicaluis volumio[23701]: info: Loading plugin "websocket"... Dec 20 04:41:36 musicaluis volumio[23701]: info: Starting Socket.io Server version 2.3.0 Dec 20 04:41:36 musicaluis volumio[23701]: info: Loading plugin "fusiondsp"... Dec 20 04:41:36 musicaluis volumio[23701]: Forking 3 albumart workers Dec 20 04:41:36 musicaluis volumio[23701]: info: Applying required configuration parameters for plugin fusiondsp Dec 20 04:41:36 musicaluis volumio[23701]: info: Loading plugin "volusonic"... Dec 20 04:41:37 musicaluis volumio[23701]: Starting albumart workers Dec 20 04:41:37 musicaluis volumio[23701]: Starting albumart workers Dec 20 04:41:37 musicaluis volumio[23701]: Starting albumart workers Dec 20 04:41:37 musicaluis volumio[23701]: info: Applying required configuration parameters for plugin volusonic Dec 20 04:41:37 musicaluis volumio[23701]: info: Loading plugin "lastfm"... Dec 20 04:41:38 musicaluis volumio[23701]: info: Loading i18n strings for locale en Dec 20 04:41:38 musicaluis volumio[23701]: info: Plugin volusonic has duplicated i18n key SAVE. It is ignored. Dec 20 04:41:38 musicaluis volumio[23701]: Updating browse sources language Dec 20 04:41:38 musicaluis volumio[23701]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 04:41:38 musicaluis volumio[23701]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 20 04:41:38 musicaluis volumio[23701]: info: CoreCommandRouter::initPlayerControls Dec 20 04:41:38 musicaluis volumio[23701]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:41:38 musicaluis volumio[23701]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:41:38 musicaluis volumio[23701]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:41:38 musicaluis volumio[23701]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:41:38 musicaluis volumio[23701]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:41:38 musicaluis volumio[23701]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:41:38 musicaluis volumio[23701]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:41:38 musicaluis volumio[23701]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:41:38 musicaluis volumio[23701]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 20 04:41:38 musicaluis volumio[23701]: Express server listening on port 3000 Dec 20 04:41:38 musicaluis volumio[23701]: [Metrics] WebUI: 7s 943.72ms Dec 20 04:41:38 musicaluis volumio[23701]: info: CoreStateMachine::resetVolumioState Dec 20 04:41:38 musicaluis volumio[23701]: info: CoreStateMachine::getcurrentVolume Dec 20 04:41:38 musicaluis volumio[23701]: info: CoreCommandRouter::volumioRetrievevolume Dec 20 04:41:38 musicaluis volumio[23701]: info: Volumio Network Manager: Network status updated: 0 Dec 20 04:41:38 musicaluis volumio-remote-updater[578]: [2024-12-20 04:41:38] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1734666094 101 Dec 20 04:41:38 musicaluis volumio[23701]: 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 Dec 20 04:41:38 musicaluis volumio[23701]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Dec 20 04:41:38 musicaluis volumio[23701]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 04:41:38 musicaluis volumio[23701]: info: Reloading queue from file Dec 20 04:41:38 musicaluis volumio[23701]: info: VolumeController:: Volume=100 Mute =false Dec 20 04:41:38 musicaluis volumio[23701]: info: CoreStateMachine::pushState Dec 20 04:41:38 musicaluis volumio[23701]: info: CorePlayQueue::getTrack 0 Dec 20 04:41:38 musicaluis volumio[23701]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 20 04:41:38 musicaluis volumio[23701]: info: CoreCommandRouter::volumioPushState Dec 20 04:41:38 musicaluis volumio[23701]: info: CoreStateMachine::updateTrackBlock Dec 20 04:41:38 musicaluis volumio[23701]: info: CorePlayQueue::getTrackBlock Dec 20 04:41:38 musicaluis volumio[23701]: info: CoreCommandRouter::volumioRetrievevolume Dec 20 04:41:38 musicaluis volumio[23701]: info: Setting Device type: Raspberry PI Dec 20 04:41:38 musicaluis volumio[23701]: info: CoreStateMachine::setRepeat null single undefined Dec 20 04:41:38 musicaluis volumio[23701]: info: CoreStateMachine::pushState Dec 20 04:41:38 musicaluis volumio[23701]: info: CorePlayQueue::getTrack 0 Dec 20 04:41:38 musicaluis volumio[23701]: info: CoreCommandRouter::volumioPushState Dec 20 04:41:38 musicaluis volumio[23701]: info: CoreStateMachine::setRandom null Dec 20 04:41:38 musicaluis volumio[23701]: info: CoreStateMachine::pushState Dec 20 04:41:38 musicaluis volumio[23701]: info: CorePlayQueue::getTrack 0 Dec 20 04:41:38 musicaluis volumio[23701]: info: CoreCommandRouter::volumioPushState Dec 20 04:41:38 musicaluis volumio[23701]: info: Completed loading Core Plugins Dec 20 04:41:38 musicaluis volumio[23701]: info: Preparing to generate the ALSA configuration file Dec 20 04:41:38 musicaluis volumio[23701]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Dec 20 04:41:38 musicaluis volumio[23701]: info: Reading ALSA contributions from plugins. Dec 20 04:41:39 musicaluis volumio[23701]: info: VolumeController:: Volume=100 Mute =false Dec 20 04:41:39 musicaluis volumio[23701]: info: CoreStateMachine::pushState Dec 20 04:41:39 musicaluis volumio[23701]: info: CorePlayQueue::getTrack 0 Dec 20 04:41:39 musicaluis volumio[23701]: info: CoreCommandRouter::volumioPushState Dec 20 04:41:39 musicaluis volumio[23701]: info: Asound.conf file unchanged, so no further update is needed Dec 20 04:41:39 musicaluis volumio[23701]: info: Output device has changed, restarting MPD Dec 20 04:41:39 musicaluis sudo[23833]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 20 04:41:39 musicaluis volumio[23701]: info: Output device has changed, restarting Shairport Sync Dec 20 04:41:39 musicaluis sudo[23833]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 04:41:39 musicaluis volumio[23701]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:41:39 musicaluis volumio[23701]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 04:41:39 musicaluis sudo[23833]: pam_unix(sudo:session): session closed for user root Dec 20 04:41:39 musicaluis sudo[23835]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 20 04:41:39 musicaluis sudo[23835]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 04:41:39 musicaluis systemd[1]: Stopping Music Player Daemon... Dec 20 04:41:39 musicaluis volumio[23701]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 20 04:41:39 musicaluis volumio[23701]: info: ___________ START PLUGINS ___________ Dec 20 04:41:39 musicaluis systemd[1]: mpd.service: Succeeded. Dec 20 04:41:39 musicaluis systemd[1]: Stopped Music Player Daemon. Dec 20 04:41:39 musicaluis volumio[23701]: info: ControllerMpd::onStart: Initializing MPD Dec 20 04:41:39 musicaluis volumio[23701]: info: Creating MPD Configuration file Dec 20 04:41:39 musicaluis sudo[23843]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 20 04:41:39 musicaluis volumio[23701]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 20 04:41:39 musicaluis volumio[23701]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 20 04:41:39 musicaluis volumio[23701]: info: [1734666099245] CoreMusicLibrary::Adding element Media Servers Dec 20 04:41:39 musicaluis volumio[23701]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 04:41:39 musicaluis volumio[23701]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:41:39 musicaluis volumio[23701]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 04:41:39 musicaluis systemd[1]: Starting Music Player Daemon... Dec 20 04:41:39 musicaluis sudo[23843]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 04:41:39 musicaluis sudo[23843]: pam_unix(sudo:session): session closed for user root Dec 20 04:41:39 musicaluis sudo[23845]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 20 04:41:39 musicaluis sudo[23845]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 04:41:39 musicaluis systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Dec 20 04:41:39 musicaluis systemd[1]: mpd.service: Succeeded. Dec 20 04:41:39 musicaluis systemd[1]: Stopped Music Player Daemon. Dec 20 04:41:39 musicaluis systemd[1]: Starting Music Player Daemon... Dec 20 04:41:39 musicaluis volumio[23701]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 20 04:41:39 musicaluis volumio[23701]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:41:39 musicaluis volumio[23701]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 04:41:39 musicaluis volumio[23701]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 20 04:41:39 musicaluis volumio[23701]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 20 04:41:39 musicaluis volumio[23701]: info: [1734666099376] CoreMusicLibrary::Adding element Last_100 Dec 20 04:41:39 musicaluis volumio[23701]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 04:41:39 musicaluis volumio[23701]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 20 04:41:39 musicaluis volumio[23701]: info: [1734666099379] CoreMusicLibrary::Adding element Webradio Dec 20 04:41:39 musicaluis volumio[23701]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 04:41:39 musicaluis volumio[23701]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 20 04:41:39 musicaluis volumio[23701]: info: Initializing BBC Radios Dec 20 04:41:39 musicaluis sudo[23851]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 20 04:41:39 musicaluis sudo[23851]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 04:41:39 musicaluis sudo[23851]: pam_unix(sudo:session): session closed for user root Dec 20 04:41:39 musicaluis volumio[23701]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 20 04:41:39 musicaluis volumio[23701]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 04:41:39 musicaluis volumio[23701]: info: AutoStart - onStart Dec 20 04:41:39 musicaluis volumio[23701]: info: [now-playing-config] Config is up to date. Dec 20 04:41:39 musicaluis volumio[23701]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 20 04:41:39 musicaluis volumio[23701]: info: Discovery: Getting this device information Dec 20 04:41:39 musicaluis volumio[23701]: info: CoreCommandRouter::volumioGetState Dec 20 04:41:39 musicaluis volumio[23701]: info: CorePlayQueue::getTrack 0 Dec 20 04:41:39 musicaluis volumio[23701]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 20 04:41:39 musicaluis volumio[23701]: info: Loading i18n strings for locale en Dec 20 04:41:39 musicaluis volumio[23701]: info: Plugin volusonic has duplicated i18n key SAVE. It is ignored. Dec 20 04:41:39 musicaluis volumio[23701]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Dec 20 04:41:39 musicaluis volumio[23701]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:41:39 musicaluis volumio[23701]: info: FusionDsp - mixtype--------------------- Hardware Dec 20 04:41:39 musicaluis volumio[23701]: info: Loading i18n strings for locale en Dec 20 04:41:39 musicaluis volumio[23701]: info: Plugin volusonic has duplicated i18n key SAVE. It is ignored. Dec 20 04:41:39 musicaluis volumio[23701]: Updating browse sources language Dec 20 04:41:39 musicaluis volumio[23701]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 04:41:39 musicaluis volumio[23701]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 20 04:41:39 musicaluis volumio[23701]: info: [1734666099560] CoreMusicLibrary::Adding element Volusonic Dec 20 04:41:39 musicaluis volumio[23701]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 04:41:39 musicaluis volumio[23701]: Cannot find translation for source Volusonic Dec 20 04:41:39 musicaluis volumio[23701]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 20 04:41:39 musicaluis volumio[23701]: info: [1734666099567] CoreMusicLibrary::Adding element LastFM Dec 20 04:41:39 musicaluis volumio[23701]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 04:41:39 musicaluis volumio[23701]: Cannot find translation for source Volusonic Dec 20 04:41:39 musicaluis volumio[23701]: Cannot find translation for source LastFM Dec 20 04:41:39 musicaluis volumio[23701]: info: [LastFM] scrobbler initiated! Dec 20 04:41:39 musicaluis volumio[23701]: info: [LastFM] extended logging: false Dec 20 04:41:39 musicaluis volumio[23701]: info: [LastFM] try scrobble stream/radio plays: true Dec 20 04:41:39 musicaluis volumio[23701]: info: [LastFM] Left init routine Dec 20 04:41:39 musicaluis volumio[23701]: info: [LastFM] Socket already connected: true Dec 20 04:41:39 musicaluis volumio[23701]: info: Volumio Calling Home Dec 20 04:41:39 musicaluis volumio[23701]: info: [now-playing-app] App is listening on port 4004. Dec 20 04:41:39 musicaluis volumio[23701]: info: Preparing to generate the ALSA configuration file Dec 20 04:41:39 musicaluis volumio[23701]: Unhandled rejection Error: No sockets available, cannot start. Dec 20 04:41:39 musicaluis volumio[23701]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Dec 20 04:41:39 musicaluis volumio[23701]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Dec 20 04:41:39 musicaluis volumio[23701]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Dec 20 04:41:39 musicaluis volumio[23701]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Dec 20 04:41:39 musicaluis volumio[23701]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Dec 20 04:41:39 musicaluis volumio[23701]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Dec 20 04:41:39 musicaluis volumio[23701]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Dec 20 04:41:39 musicaluis volumio[23701]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Dec 20 04:41:39 musicaluis volumio[23701]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Dec 20 04:41:39 musicaluis volumio[23701]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Dec 20 04:41:39 musicaluis volumio[23701]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Dec 20 04:41:39 musicaluis volumio[23701]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Dec 20 04:41:39 musicaluis volumio[23701]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Dec 20 04:41:39 musicaluis volumio[23701]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Dec 20 04:41:39 musicaluis volumio[23701]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Dec 20 04:41:39 musicaluis volumio[23701]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Dec 20 04:41:39 musicaluis volumio[23701]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 20 04:41:39 musicaluis volumio-time-update[568]: volumio-time-update-util: Fetching time from Volumio... Dec 20 04:41:39 musicaluis volumio[23701]: Error: getaddrinfo EBUSY ws.audioscrobbler.com Dec 20 04:41:39 musicaluis volumio[23701]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:67:26) { Dec 20 04:41:39 musicaluis volumio[23701]: errno: -16, Dec 20 04:41:39 musicaluis volumio[23701]: code: 'EBUSY', Dec 20 04:41:39 musicaluis volumio[23701]: syscall: 'getaddrinfo', Dec 20 04:41:39 musicaluis volumio[23701]: hostname: 'ws.audioscrobbler.com' Dec 20 04:41:39 musicaluis volumio[23701]: } Dec 20 04:41:39 musicaluis volumio[23701]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 20 04:41:39 musicaluis volumio-time-update[568]: volumio-time-update-util: Date not found in response Dec 20 04:41:39 musicaluis volumio-time-update[568]: volumio-time-update-util: Retrying in 5 seconds... Dec 20 04:41:40 musicaluis sudo[23884]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-12-20 04:40 Dec 20 04:41:40 musicaluis sudo[23884]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 04:41:40 musicaluis sudo[23884]: pam_unix(sudo:session): session closed for user root Dec 20 04:41:40 musicaluis volumio-remote-updater[578]: [2024-12-20 04:41:40] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Dec 20 04:41:40 musicaluis volumio-remote-updater[578]: [2024-12-20 04:41:40] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Dec 20 04:41:40 musicaluis sudo[23835]: pam_unix(sudo:session): session closed for user root Dec 20 04:41:40 musicaluis systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Dec 20 04:41:40 musicaluis sudo[23845]: pam_unix(sudo:session): session closed for user root Dec 20 04:41:40 musicaluis systemd[1]: volumio.service: Failed with result 'exit-code'. Dec 20 04:41:40 musicaluis systemd[1]: Started dynamicswap service. Dec 20 04:41:40 musicaluis systemd[1]: dynamicswap.service: Succeeded. Dec 20 04:41:41 musicaluis systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Dec 20 04:41:41 musicaluis systemd[1]: volumio.service: Scheduled restart job, restart counter is at 271. Dec 20 04:41:41 musicaluis systemd[1]: Started dynamicswap service. Dec 20 04:41:41 musicaluis systemd[1]: Stopped Volumio Backend Module. Dec 20 04:41:41 musicaluis systemd[1]: Started Volumio Backend Module. Dec 20 04:41:41 musicaluis systemd[1]: dynamicswap.service: Succeeded. Dec 20 04:41:41 musicaluis mpd[23863]: Dec 20 04:41 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 20 04:41:41 musicaluis systemd[1]: Started Music Player Daemon. Dec 20 04:41:42 musicaluis volumio[23898]: info: ------------------------------------------- Dec 20 04:41:42 musicaluis volumio[23898]: info: ----- Volumio3 ---- Dec 20 04:41:42 musicaluis volumio[23898]: info: ------------------------------------------- Dec 20 04:41:42 musicaluis volumio[23898]: info: ----- System startup ---- Dec 20 04:41:42 musicaluis volumio[23898]: info: ------------------------------------------- Dec 20 04:41:43 musicaluis volumio[23898]: info: MYVOLUMIO Environment detected Dec 20 04:41:43 musicaluis volumio[23898]: info: Plugin folders cleanup Dec 20 04:41:43 musicaluis volumio[23898]: info: Scanning into folder /volumio/app/plugins/ Dec 20 04:41:43 musicaluis volumio[23898]: info: Scanning category audio_interface Dec 20 04:41:43 musicaluis volumio[23898]: info: Scanning category miscellanea Dec 20 04:41:43 musicaluis volumio[23898]: info: Scanning category music_service Dec 20 04:41:43 musicaluis volumio[23898]: info: Scanning category plugins.json Dec 20 04:41:43 musicaluis volumio[23898]: info: Scanning category system_controller Dec 20 04:41:43 musicaluis volumio[23898]: info: Scanning category user_interface Dec 20 04:41:43 musicaluis volumio[23898]: info: Scanning into folder /data/plugins/ Dec 20 04:41:43 musicaluis volumio[23898]: info: Scanning category audio_interface Dec 20 04:41:43 musicaluis volumio[23898]: info: Scanning category music_service Dec 20 04:41:43 musicaluis volumio[23898]: info: Scanning category system_controller Dec 20 04:41:43 musicaluis volumio[23898]: info: Scanning category user_interface Dec 20 04:41:43 musicaluis volumio[23898]: info: Plugin folders cleanup completed Dec 20 04:41:43 musicaluis volumio[23898]: info: ------------------------------------------- Dec 20 04:41:43 musicaluis volumio[23898]: info: ----- Core plugins startup ---- Dec 20 04:41:43 musicaluis volumio[23898]: info: ------------------------------------------- Dec 20 04:41:43 musicaluis volumio[23898]: info: Loading plugins from folder /volumio/app/plugins/ Dec 20 04:41:43 musicaluis volumio[23898]: info: Adding plugin upnp to MyMusic Plugins Dec 20 04:41:43 musicaluis volumio[23898]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 20 04:41:43 musicaluis volumio[23898]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 20 04:41:43 musicaluis volumio[23898]: info: Loading plugins from folder /data/plugins/ Dec 20 04:41:43 musicaluis volumio[23898]: info: Loading plugin "system"... Dec 20 04:41:43 musicaluis volumio[23898]: info: Loading plugin "appearance"... Dec 20 04:41:44 musicaluis volumio[23898]: info: Loading plugin "network"... Dec 20 04:41:44 musicaluis volumio[23898]: info: Refreshing Cached IP Addresses Dec 20 04:41:44 musicaluis sudo[23926]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 20 04:41:44 musicaluis sudo[23926]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 04:41:44 musicaluis sudo[23926]: pam_unix(sudo:session): session closed for user root Dec 20 04:41:44 musicaluis sudo[23928]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 20 04:41:44 musicaluis sudo[23928]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 04:41:44 musicaluis sudo[23928]: pam_unix(sudo:session): session closed for user root Dec 20 04:41:44 musicaluis volumio[23898]: info: Loading plugin "services"... Dec 20 04:41:44 musicaluis volumio[23898]: info: Loading plugin "alsa_controller"... Dec 20 04:41:44 musicaluis sudo[23937]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 20 04:41:44 musicaluis volumio-time-update[568]: volumio-time-update-util: Fetching time from Volumio... Dec 20 04:41:44 musicaluis sudo[23937]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 04:41:44 musicaluis volumio-time-update[568]: volumio-time-update-util: Date not found in response Dec 20 04:41:44 musicaluis volumio-time-update[568]: volumio-time-update-util: Retrying in 5 seconds... Dec 20 04:41:45 musicaluis volumio[23898]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 20 04:41:45 musicaluis volumio[23898]: info: Loading plugin "wizard"... Dec 20 04:41:45 musicaluis volumio[23898]: info: Loading plugin "networkfs"... Dec 20 04:41:45 musicaluis volumio[23898]: info: Starting Udev Watcher for removable devices Dec 20 04:41:45 musicaluis volumio[23898]: info: Ignoring mount for partition: boot Dec 20 04:41:45 musicaluis volumio[23898]: info: Ignoring mount for partition: volumio Dec 20 04:41:45 musicaluis volumio[23898]: info: Ignoring mount for partition: volumio_data Dec 20 04:41:45 musicaluis volumio[23898]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 20 04:41:45 musicaluis volumio[23898]: info: Loading plugin "volumio_command_line_client"... Dec 20 04:41:45 musicaluis ntpd[765]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Dec 20 04:41:45 musicaluis volumio[23898]: info: Loading plugin "upnp"... Dec 20 04:41:45 musicaluis volumio[23898]: info: [1734666105098] Starting Upmpd Daemon Dec 20 04:41:45 musicaluis volumio[23898]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 20 04:41:45 musicaluis volumio[23898]: info: Loading plugin "my_music"... Dec 20 04:41:45 musicaluis volumio[23898]: info: Loading plugin "mpd"... Dec 20 04:41:45 musicaluis volumio[23898]: info: Loading plugin "upnp_browser"... Dec 20 04:41:45 musicaluis volumio[23898]: info: Loading plugin "alarm-clock"... Dec 20 04:41:45 musicaluis volumio-remote-updater[578]: [2024-12-20 04:41:45] [connect] Successful connection Dec 20 04:41:45 musicaluis sudo[23937]: pam_unix(sudo:session): session closed for user root Dec 20 04:41:45 musicaluis volumio[23898]: info: Loading plugin "airplay_emulation"... Dec 20 04:41:45 musicaluis volumio[23898]: info: Starting Shairport Sync Dec 20 04:41:45 musicaluis volumio[23898]: info: Loading plugin "last_100"... Dec 20 04:41:45 musicaluis volumio[23898]: info: Loading plugin "webradio"... Dec 20 04:41:46 musicaluis volumio[23898]: info: Loading plugin "i2s_dacs"... Dec 20 04:41:46 musicaluis volumio[23898]: info: Loading plugin "volumiodiscovery"... Dec 20 04:41:46 musicaluis volumio[23898]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 20 04:41:46 musicaluis volumio[23898]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 20 04:41:46 musicaluis volumio[23898]: *** WARNING *** For more information see Dec 20 04:41:46 musicaluis node[23898]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 20 04:41:46 musicaluis volumio[23898]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 20 04:41:46 musicaluis volumio[23898]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 20 04:41:46 musicaluis volumio[23898]: *** WARNING *** For more information see Dec 20 04:41:46 musicaluis node[23898]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 20 04:41:46 musicaluis node[23898]: *** WARNING *** For more information see Dec 20 04:41:46 musicaluis node[23898]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 20 04:41:46 musicaluis node[23898]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 20 04:41:46 musicaluis node[23898]: *** WARNING *** For more information see Dec 20 04:41:46 musicaluis volumio[23898]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 20 04:41:46 musicaluis volumio[23898]: info: Discovery: Started advertising with name: MusicaLuis Dec 20 04:41:46 musicaluis volumio[23898]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 20 04:41:46 musicaluis volumio[23898]: info: Loading plugin "autostart"... Dec 20 04:41:46 musicaluis volumio[23898]: info: Applying required configuration parameters for plugin autostart Dec 20 04:41:46 musicaluis volumio[23898]: info: AutoStart - onVolumioStart - read config.json Dec 20 04:41:46 musicaluis volumio[23898]: info: Loading plugin "now_playing"... Dec 20 04:41:47 musicaluis volumio[23898]: info: Loading plugin "outputs"... Dec 20 04:41:47 musicaluis volumio[23898]: info: Loading plugin "albumart"... Dec 20 04:41:47 musicaluis volumio[23898]: info: Plugin example_plugin is not enabled Dec 20 04:41:47 musicaluis volumio[23898]: info: Loading plugin "inputs"... Dec 20 04:41:47 musicaluis volumio[23898]: info: Loading plugin "updater_comm"... Dec 20 04:41:47 musicaluis volumio[23898]: info: Plugin mpdemulation is not enabled Dec 20 04:41:47 musicaluis volumio[23898]: info: Loading plugin "rest_api"... Dec 20 04:41:47 musicaluis volumio[23898]: info: Loading plugin "websocket"... Dec 20 04:41:47 musicaluis volumio[23898]: info: Starting Socket.io Server version 2.3.0 Dec 20 04:41:47 musicaluis volumio[23898]: info: Loading plugin "fusiondsp"... Dec 20 04:41:47 musicaluis volumio[23898]: Forking 3 albumart workers Dec 20 04:41:48 musicaluis volumio[23898]: info: Applying required configuration parameters for plugin fusiondsp Dec 20 04:41:48 musicaluis volumio[23898]: info: Loading plugin "volusonic"... Dec 20 04:41:48 musicaluis volumio[23898]: Starting albumart workers Dec 20 04:41:49 musicaluis volumio[23898]: Starting albumart workers Dec 20 04:41:49 musicaluis volumio[23898]: Starting albumart workers Dec 20 04:41:49 musicaluis volumio[23898]: info: Applying required configuration parameters for plugin volusonic Dec 20 04:41:49 musicaluis volumio[23898]: info: Loading plugin "lastfm"... Dec 20 04:41:49 musicaluis volumio-time-update[568]: volumio-time-update-util: Fetching time from Volumio... Dec 20 04:41:49 musicaluis volumio-time-update[568]: volumio-time-update-util: Date not found in response Dec 20 04:41:49 musicaluis volumio-time-update[568]: volumio-time-update-util: Retrying in 5 seconds... Dec 20 04:41:50 musicaluis volumio[23898]: info: Loading i18n strings for locale en Dec 20 04:41:50 musicaluis volumio[23898]: info: Plugin volusonic has duplicated i18n key SAVE. It is ignored. Dec 20 04:41:50 musicaluis volumio[23898]: Updating browse sources language Dec 20 04:41:50 musicaluis volumio[23898]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 04:41:50 musicaluis volumio[23898]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 20 04:41:50 musicaluis volumio[23898]: info: CoreCommandRouter::initPlayerControls Dec 20 04:41:50 musicaluis volumio[23898]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:41:50 musicaluis volumio[23898]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:41:50 musicaluis volumio[23898]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:41:50 musicaluis volumio[23898]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:41:50 musicaluis volumio[23898]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:41:50 musicaluis volumio[23898]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:41:50 musicaluis volumio[23898]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:41:50 musicaluis volumio[23898]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:41:50 musicaluis volumio[23898]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 20 04:41:50 musicaluis volumio[23898]: Express server listening on port 3000 Dec 20 04:41:50 musicaluis volumio[23898]: [Metrics] WebUI: 7s 925.57ms Dec 20 04:41:50 musicaluis volumio[23898]: info: CoreStateMachine::resetVolumioState Dec 20 04:41:50 musicaluis volumio[23898]: info: CoreStateMachine::getcurrentVolume Dec 20 04:41:50 musicaluis volumio[23898]: info: CoreCommandRouter::volumioRetrievevolume Dec 20 04:41:50 musicaluis volumio[23898]: info: Volumio Network Manager: Network status updated: 0 Dec 20 04:41:50 musicaluis volumio-remote-updater[578]: [2024-12-20 04:41:50] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1734666105 101 Dec 20 04:41:50 musicaluis volumio[23898]: 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 Dec 20 04:41:50 musicaluis volumio[23898]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Dec 20 04:41:50 musicaluis volumio[23898]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 04:41:50 musicaluis volumio[23898]: info: Reloading queue from file Dec 20 04:41:50 musicaluis volumio[23898]: info: VolumeController:: Volume=100 Mute =false Dec 20 04:41:50 musicaluis volumio[23898]: info: CoreStateMachine::pushState Dec 20 04:41:50 musicaluis volumio[23898]: info: CorePlayQueue::getTrack 0 Dec 20 04:41:50 musicaluis volumio[23898]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 20 04:41:50 musicaluis volumio[23898]: info: CoreCommandRouter::volumioPushState Dec 20 04:41:50 musicaluis volumio[23898]: info: CoreStateMachine::updateTrackBlock Dec 20 04:41:50 musicaluis volumio[23898]: info: CorePlayQueue::getTrackBlock Dec 20 04:41:50 musicaluis volumio[23898]: info: CoreCommandRouter::volumioRetrievevolume Dec 20 04:41:50 musicaluis volumio[23898]: info: CoreStateMachine::setRepeat null single undefined Dec 20 04:41:50 musicaluis volumio[23898]: info: CoreStateMachine::pushState Dec 20 04:41:50 musicaluis volumio[23898]: info: CorePlayQueue::getTrack 0 Dec 20 04:41:50 musicaluis volumio[23898]: info: CoreCommandRouter::volumioPushState Dec 20 04:41:50 musicaluis volumio[23898]: info: CoreStateMachine::setRandom null Dec 20 04:41:50 musicaluis volumio[23898]: info: CoreStateMachine::pushState Dec 20 04:41:50 musicaluis volumio[23898]: info: CorePlayQueue::getTrack 0 Dec 20 04:41:50 musicaluis volumio[23898]: info: CoreCommandRouter::volumioPushState Dec 20 04:41:50 musicaluis volumio[23898]: info: Setting Device type: Raspberry PI Dec 20 04:41:50 musicaluis volumio[23898]: info: Completed loading Core Plugins Dec 20 04:41:50 musicaluis volumio[23898]: info: Preparing to generate the ALSA configuration file Dec 20 04:41:50 musicaluis volumio[23898]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Dec 20 04:41:50 musicaluis volumio[23898]: info: Reading ALSA contributions from plugins. Dec 20 04:41:50 musicaluis volumio[23898]: info: VolumeController:: Volume=100 Mute =false Dec 20 04:41:50 musicaluis volumio[23898]: info: CoreStateMachine::pushState Dec 20 04:41:50 musicaluis volumio[23898]: info: CorePlayQueue::getTrack 0 Dec 20 04:41:50 musicaluis volumio[23898]: info: CoreCommandRouter::volumioPushState Dec 20 04:41:50 musicaluis volumio[23898]: info: Asound.conf file unchanged, so no further update is needed Dec 20 04:41:50 musicaluis volumio[23898]: info: Output device has changed, restarting MPD Dec 20 04:41:50 musicaluis sudo[24032]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 20 04:41:50 musicaluis volumio[23898]: info: Output device has changed, restarting Shairport Sync Dec 20 04:41:50 musicaluis sudo[24032]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 04:41:50 musicaluis volumio[23898]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:41:50 musicaluis volumio[23898]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 04:41:50 musicaluis sudo[24032]: pam_unix(sudo:session): session closed for user root Dec 20 04:41:50 musicaluis sudo[24034]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 20 04:41:50 musicaluis sudo[24034]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 04:41:50 musicaluis systemd[1]: Stopping Music Player Daemon... Dec 20 04:41:50 musicaluis volumio[23898]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 20 04:41:50 musicaluis volumio[23898]: info: ___________ START PLUGINS ___________ Dec 20 04:41:50 musicaluis systemd[1]: mpd.service: Succeeded. Dec 20 04:41:50 musicaluis volumio[23898]: info: ControllerMpd::onStart: Initializing MPD Dec 20 04:41:50 musicaluis systemd[1]: Stopped Music Player Daemon. Dec 20 04:41:50 musicaluis volumio[23898]: info: Creating MPD Configuration file Dec 20 04:41:50 musicaluis sudo[24042]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 20 04:41:50 musicaluis sudo[24042]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 04:41:50 musicaluis systemd[1]: Starting Music Player Daemon... Dec 20 04:41:50 musicaluis volumio[23898]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 20 04:41:50 musicaluis volumio[23898]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 20 04:41:50 musicaluis volumio[23898]: info: [1734666110970] CoreMusicLibrary::Adding element Media Servers Dec 20 04:41:50 musicaluis volumio[23898]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 04:41:50 musicaluis sudo[24042]: pam_unix(sudo:session): session closed for user root Dec 20 04:41:50 musicaluis sudo[24044]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 20 04:41:50 musicaluis volumio[23898]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:41:50 musicaluis volumio[23898]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 04:41:50 musicaluis sudo[24044]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 04:41:51 musicaluis systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Dec 20 04:41:51 musicaluis systemd[1]: mpd.service: Succeeded. Dec 20 04:41:51 musicaluis systemd[1]: Stopped Music Player Daemon. Dec 20 04:41:51 musicaluis systemd[1]: Starting Music Player Daemon... Dec 20 04:41:51 musicaluis volumio[23898]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 20 04:41:51 musicaluis volumio[23898]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:41:51 musicaluis volumio[23898]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 04:41:51 musicaluis volumio[23898]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 20 04:41:51 musicaluis volumio[23898]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 20 04:41:51 musicaluis volumio[23898]: info: [1734666111104] CoreMusicLibrary::Adding element Last_100 Dec 20 04:41:51 musicaluis volumio[23898]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 04:41:51 musicaluis volumio[23898]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 20 04:41:51 musicaluis volumio[23898]: info: [1734666111107] CoreMusicLibrary::Adding element Webradio Dec 20 04:41:51 musicaluis volumio[23898]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 04:41:51 musicaluis volumio[23898]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 20 04:41:51 musicaluis volumio[23898]: info: Initializing BBC Radios Dec 20 04:41:51 musicaluis sudo[24050]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 20 04:41:51 musicaluis sudo[24050]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 04:41:51 musicaluis sudo[24050]: pam_unix(sudo:session): session closed for user root Dec 20 04:41:51 musicaluis volumio[23898]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 20 04:41:51 musicaluis volumio[23898]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 04:41:51 musicaluis volumio[23898]: info: AutoStart - onStart Dec 20 04:41:51 musicaluis volumio[23898]: info: [now-playing-config] Config is up to date. Dec 20 04:41:51 musicaluis volumio[23898]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 20 04:41:51 musicaluis volumio[23898]: info: Discovery: Getting this device information Dec 20 04:41:51 musicaluis volumio[23898]: info: CoreCommandRouter::volumioGetState Dec 20 04:41:51 musicaluis volumio[23898]: info: CorePlayQueue::getTrack 0 Dec 20 04:41:51 musicaluis volumio[23898]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 20 04:41:51 musicaluis volumio[23898]: info: Loading i18n strings for locale en Dec 20 04:41:51 musicaluis volumio[23898]: info: Plugin volusonic has duplicated i18n key SAVE. It is ignored. Dec 20 04:41:51 musicaluis volumio[23898]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Dec 20 04:41:51 musicaluis volumio[23898]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:41:51 musicaluis volumio[23898]: info: FusionDsp - mixtype--------------------- Hardware Dec 20 04:41:51 musicaluis volumio[23898]: info: Loading i18n strings for locale en Dec 20 04:41:51 musicaluis volumio[23898]: info: Plugin volusonic has duplicated i18n key SAVE. It is ignored. Dec 20 04:41:51 musicaluis volumio[23898]: Updating browse sources language Dec 20 04:41:51 musicaluis volumio[23898]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 04:41:51 musicaluis volumio[23898]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 20 04:41:51 musicaluis volumio[23898]: info: [1734666111302] CoreMusicLibrary::Adding element Volusonic Dec 20 04:41:51 musicaluis volumio[23898]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 04:41:51 musicaluis volumio[23898]: Cannot find translation for source Volusonic Dec 20 04:41:51 musicaluis volumio[23898]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 20 04:41:51 musicaluis volumio[23898]: info: [1734666111306] CoreMusicLibrary::Adding element LastFM Dec 20 04:41:51 musicaluis volumio[23898]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 04:41:51 musicaluis volumio[23898]: Cannot find translation for source Volusonic Dec 20 04:41:51 musicaluis volumio[23898]: Cannot find translation for source LastFM Dec 20 04:41:51 musicaluis volumio[23898]: info: [LastFM] scrobbler initiated! Dec 20 04:41:51 musicaluis volumio[23898]: info: [LastFM] extended logging: false Dec 20 04:41:51 musicaluis volumio[23898]: info: [LastFM] try scrobble stream/radio plays: true Dec 20 04:41:51 musicaluis volumio[23898]: info: [LastFM] Left init routine Dec 20 04:41:51 musicaluis volumio[23898]: info: [LastFM] Socket already connected: true Dec 20 04:41:51 musicaluis volumio[23898]: info: Volumio Calling Home Dec 20 04:41:51 musicaluis volumio[23898]: info: [now-playing-app] App is listening on port 4004. Dec 20 04:41:51 musicaluis volumio[23898]: info: Preparing to generate the ALSA configuration file Dec 20 04:41:51 musicaluis volumio[23898]: Unhandled rejection Error: No sockets available, cannot start. Dec 20 04:41:51 musicaluis volumio[23898]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Dec 20 04:41:51 musicaluis volumio[23898]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Dec 20 04:41:51 musicaluis volumio[23898]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Dec 20 04:41:51 musicaluis volumio[23898]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Dec 20 04:41:51 musicaluis volumio[23898]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Dec 20 04:41:51 musicaluis volumio[23898]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Dec 20 04:41:51 musicaluis volumio[23898]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Dec 20 04:41:51 musicaluis volumio[23898]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Dec 20 04:41:51 musicaluis volumio[23898]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Dec 20 04:41:51 musicaluis volumio[23898]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Dec 20 04:41:51 musicaluis volumio[23898]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Dec 20 04:41:51 musicaluis volumio[23898]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Dec 20 04:41:51 musicaluis volumio[23898]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Dec 20 04:41:51 musicaluis volumio[23898]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Dec 20 04:41:51 musicaluis volumio[23898]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Dec 20 04:41:51 musicaluis volumio[23898]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Dec 20 04:41:51 musicaluis volumio[23898]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 20 04:41:51 musicaluis volumio[23898]: Error: getaddrinfo EBUSY ws.audioscrobbler.com Dec 20 04:41:51 musicaluis volumio[23898]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:67:26) { Dec 20 04:41:51 musicaluis volumio[23898]: errno: -16, Dec 20 04:41:51 musicaluis volumio[23898]: code: 'EBUSY', Dec 20 04:41:51 musicaluis volumio[23898]: syscall: 'getaddrinfo', Dec 20 04:41:51 musicaluis volumio[23898]: hostname: 'ws.audioscrobbler.com' Dec 20 04:41:51 musicaluis volumio[23898]: } Dec 20 04:41:51 musicaluis volumio[23898]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 20 04:41:52 musicaluis sudo[24078]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-12-20 04:40 Dec 20 04:41:52 musicaluis sudo[24078]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 04:41:52 musicaluis sudo[24078]: pam_unix(sudo:session): session closed for user root Dec 20 04:41:52 musicaluis volumio-remote-updater[578]: [2024-12-20 04:41:52] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Dec 20 04:41:52 musicaluis volumio-remote-updater[578]: [2024-12-20 04:41:52] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Dec 20 04:41:52 musicaluis systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Dec 20 04:41:52 musicaluis sudo[24034]: pam_unix(sudo:session): session closed for user root Dec 20 04:41:52 musicaluis sudo[24044]: pam_unix(sudo:session): session closed for user root Dec 20 04:41:52 musicaluis systemd[1]: volumio.service: Failed with result 'exit-code'. Dec 20 04:41:52 musicaluis systemd[1]: Started dynamicswap service. Dec 20 04:41:52 musicaluis systemd[1]: dynamicswap.service: Succeeded. Dec 20 04:41:52 musicaluis mpd[24061]: Dec 20 04:41 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 20 04:41:52 musicaluis systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Dec 20 04:41:52 musicaluis systemd[1]: volumio.service: Scheduled restart job, restart counter is at 272. Dec 20 04:41:52 musicaluis systemd[1]: Started Music Player Daemon. Dec 20 04:41:52 musicaluis systemd[1]: Stopped Volumio Backend Module. Dec 20 04:41:52 musicaluis systemd[1]: Started Volumio Backend Module. Dec 20 04:41:52 musicaluis systemd[1]: Started dynamicswap service. Dec 20 04:41:52 musicaluis systemd[1]: dynamicswap.service: Succeeded. Dec 20 04:41:53 musicaluis ntpd[765]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Dec 20 04:41:54 musicaluis volumio[24093]: info: ------------------------------------------- Dec 20 04:41:54 musicaluis volumio[24093]: info: ----- Volumio3 ---- Dec 20 04:41:54 musicaluis volumio[24093]: info: ------------------------------------------- Dec 20 04:41:54 musicaluis volumio[24093]: info: ----- System startup ---- Dec 20 04:41:54 musicaluis volumio[24093]: info: ------------------------------------------- Dec 20 04:41:54 musicaluis volumio-time-update[568]: volumio-time-update-util: Fetching time from Volumio... Dec 20 04:41:55 musicaluis volumio-time-update[568]: volumio-time-update-util: Date not found in response Dec 20 04:41:55 musicaluis volumio-time-update[568]: volumio-time-update-util: Retrying in 5 seconds... Dec 20 04:41:55 musicaluis volumio[24093]: info: MYVOLUMIO Environment detected Dec 20 04:41:55 musicaluis volumio[24093]: info: Plugin folders cleanup Dec 20 04:41:55 musicaluis volumio[24093]: info: Scanning into folder /volumio/app/plugins/ Dec 20 04:41:55 musicaluis volumio[24093]: info: Scanning category audio_interface Dec 20 04:41:55 musicaluis volumio[24093]: info: Scanning category miscellanea Dec 20 04:41:55 musicaluis volumio[24093]: info: Scanning category music_service Dec 20 04:41:55 musicaluis volumio[24093]: info: Scanning category plugins.json Dec 20 04:41:55 musicaluis volumio[24093]: info: Scanning category system_controller Dec 20 04:41:55 musicaluis volumio[24093]: info: Scanning category user_interface Dec 20 04:41:55 musicaluis volumio[24093]: info: Scanning into folder /data/plugins/ Dec 20 04:41:55 musicaluis volumio[24093]: info: Scanning category audio_interface Dec 20 04:41:55 musicaluis volumio[24093]: info: Scanning category music_service Dec 20 04:41:55 musicaluis volumio[24093]: info: Scanning category system_controller Dec 20 04:41:55 musicaluis volumio[24093]: info: Scanning category user_interface Dec 20 04:41:55 musicaluis volumio[24093]: info: Plugin folders cleanup completed Dec 20 04:41:55 musicaluis volumio[24093]: info: ------------------------------------------- Dec 20 04:41:55 musicaluis volumio[24093]: info: ----- Core plugins startup ---- Dec 20 04:41:55 musicaluis volumio[24093]: info: ------------------------------------------- Dec 20 04:41:55 musicaluis volumio[24093]: info: Loading plugins from folder /volumio/app/plugins/ Dec 20 04:41:55 musicaluis volumio[24093]: info: Adding plugin upnp to MyMusic Plugins Dec 20 04:41:55 musicaluis volumio[24093]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 20 04:41:55 musicaluis volumio[24093]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 20 04:41:55 musicaluis volumio[24093]: info: Loading plugins from folder /data/plugins/ Dec 20 04:41:55 musicaluis volumio[24093]: info: Loading plugin "system"... Dec 20 04:41:55 musicaluis volumio[24093]: info: Loading plugin "appearance"... Dec 20 04:41:56 musicaluis volumio[24093]: info: Loading plugin "network"... Dec 20 04:41:56 musicaluis volumio[24093]: info: Refreshing Cached IP Addresses Dec 20 04:41:56 musicaluis sudo[24125]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 20 04:41:56 musicaluis sudo[24125]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 04:41:56 musicaluis sudo[24125]: pam_unix(sudo:session): session closed for user root Dec 20 04:41:56 musicaluis sudo[24127]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 20 04:41:56 musicaluis sudo[24127]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 04:41:56 musicaluis sudo[24127]: pam_unix(sudo:session): session closed for user root Dec 20 04:41:56 musicaluis volumio[24093]: info: Loading plugin "services"... Dec 20 04:41:56 musicaluis volumio[24093]: info: Loading plugin "alsa_controller"... Dec 20 04:41:56 musicaluis sudo[24139]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 20 04:41:56 musicaluis sudo[24139]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 04:41:56 musicaluis volumio[24093]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 20 04:41:56 musicaluis volumio[24093]: info: Loading plugin "wizard"... Dec 20 04:41:56 musicaluis volumio[24093]: info: Loading plugin "networkfs"... Dec 20 04:41:56 musicaluis volumio[24093]: info: Starting Udev Watcher for removable devices Dec 20 04:41:56 musicaluis volumio[24093]: info: Ignoring mount for partition: boot Dec 20 04:41:56 musicaluis volumio[24093]: info: Ignoring mount for partition: volumio Dec 20 04:41:56 musicaluis volumio[24093]: info: Ignoring mount for partition: volumio_data Dec 20 04:41:56 musicaluis volumio[24093]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 20 04:41:56 musicaluis volumio[24093]: info: Loading plugin "volumio_command_line_client"... Dec 20 04:41:56 musicaluis volumio[24093]: info: Loading plugin "upnp"... Dec 20 04:41:56 musicaluis volumio[24093]: info: [1734666116915] Starting Upmpd Daemon Dec 20 04:41:56 musicaluis volumio[24093]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 20 04:41:56 musicaluis volumio[24093]: info: Loading plugin "my_music"... Dec 20 04:41:56 musicaluis volumio[24093]: info: Loading plugin "mpd"... Dec 20 04:41:57 musicaluis volumio[24093]: info: Loading plugin "upnp_browser"... Dec 20 04:41:57 musicaluis volumio[24093]: info: Loading plugin "alarm-clock"... Dec 20 04:41:57 musicaluis volumio-remote-updater[578]: [2024-12-20 04:41:57] [connect] Successful connection Dec 20 04:41:57 musicaluis sudo[24139]: pam_unix(sudo:session): session closed for user root Dec 20 04:41:57 musicaluis volumio[24093]: info: Loading plugin "airplay_emulation"... Dec 20 04:41:57 musicaluis volumio[24093]: info: Starting Shairport Sync Dec 20 04:41:57 musicaluis volumio[24093]: info: Loading plugin "last_100"... Dec 20 04:41:57 musicaluis volumio[24093]: info: Loading plugin "webradio"... Dec 20 04:41:57 musicaluis volumio[24093]: info: Loading plugin "i2s_dacs"... Dec 20 04:41:57 musicaluis volumio[24093]: info: Loading plugin "volumiodiscovery"... Dec 20 04:41:57 musicaluis volumio[24093]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 20 04:41:57 musicaluis volumio[24093]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 20 04:41:57 musicaluis volumio[24093]: *** WARNING *** For more information see Dec 20 04:41:57 musicaluis volumio[24093]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 20 04:41:57 musicaluis node[24093]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 20 04:41:57 musicaluis volumio[24093]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 20 04:41:57 musicaluis volumio[24093]: *** WARNING *** For more information see Dec 20 04:41:57 musicaluis node[24093]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 20 04:41:57 musicaluis node[24093]: *** WARNING *** For more information see Dec 20 04:41:57 musicaluis node[24093]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 20 04:41:57 musicaluis node[24093]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 20 04:41:57 musicaluis node[24093]: *** WARNING *** For more information see Dec 20 04:41:57 musicaluis volumio[24093]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 20 04:41:57 musicaluis volumio[24093]: info: Discovery: Started advertising with name: MusicaLuis Dec 20 04:41:57 musicaluis volumio[24093]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 20 04:41:57 musicaluis volumio[24093]: info: Loading plugin "autostart"... Dec 20 04:41:58 musicaluis ntpd[765]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Dec 20 04:41:58 musicaluis volumio[24093]: info: Applying required configuration parameters for plugin autostart Dec 20 04:41:58 musicaluis volumio[24093]: info: AutoStart - onVolumioStart - read config.json Dec 20 04:41:58 musicaluis volumio[24093]: info: Loading plugin "now_playing"... Dec 20 04:41:59 musicaluis volumio[24093]: info: Loading plugin "outputs"... Dec 20 04:41:59 musicaluis volumio[24093]: info: Loading plugin "albumart"... Dec 20 04:41:59 musicaluis volumio[24093]: info: Plugin example_plugin is not enabled Dec 20 04:41:59 musicaluis volumio[24093]: info: Loading plugin "inputs"... Dec 20 04:41:59 musicaluis volumio[24093]: info: Loading plugin "updater_comm"... Dec 20 04:41:59 musicaluis volumio[24093]: info: Plugin mpdemulation is not enabled Dec 20 04:41:59 musicaluis volumio[24093]: info: Loading plugin "rest_api"... Dec 20 04:41:59 musicaluis volumio[24093]: info: Loading plugin "websocket"... Dec 20 04:41:59 musicaluis volumio[24093]: info: Starting Socket.io Server version 2.3.0 Dec 20 04:41:59 musicaluis volumio[24093]: info: Loading plugin "fusiondsp"... Dec 20 04:41:59 musicaluis volumio[24093]: Forking 3 albumart workers Dec 20 04:41:59 musicaluis volumio[24093]: info: Applying required configuration parameters for plugin fusiondsp Dec 20 04:42:00 musicaluis volumio-time-update[568]: volumio-time-update-util: Fetching time from Volumio... Dec 20 04:42:00 musicaluis volumio-time-update[568]: volumio-time-update-util: Date not found in response Dec 20 04:42:00 musicaluis volumio-time-update[568]: volumio-time-update-util: Retrying in 5 seconds... Dec 20 04:42:00 musicaluis volumio[24093]: info: Loading plugin "volusonic"... Dec 20 04:42:00 musicaluis volumio[24093]: Starting albumart workers Dec 20 04:42:00 musicaluis volumio[24093]: Starting albumart workers Dec 20 04:42:00 musicaluis volumio[24093]: Starting albumart workers Dec 20 04:42:01 musicaluis volumio[24093]: info: Applying required configuration parameters for plugin volusonic Dec 20 04:42:01 musicaluis volumio[24093]: info: Loading plugin "lastfm"... Dec 20 04:42:01 musicaluis volumio[24093]: info: Loading i18n strings for locale en Dec 20 04:42:01 musicaluis volumio[24093]: info: Plugin volusonic has duplicated i18n key SAVE. It is ignored. Dec 20 04:42:01 musicaluis volumio[24093]: Updating browse sources language Dec 20 04:42:01 musicaluis volumio[24093]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 04:42:02 musicaluis volumio[24093]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 20 04:42:02 musicaluis volumio[24093]: info: CoreCommandRouter::initPlayerControls Dec 20 04:42:02 musicaluis volumio[24093]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:42:02 musicaluis volumio[24093]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:42:02 musicaluis volumio[24093]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:42:02 musicaluis volumio[24093]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:42:02 musicaluis volumio[24093]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:42:02 musicaluis volumio[24093]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:42:02 musicaluis volumio[24093]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:42:02 musicaluis volumio[24093]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:42:02 musicaluis volumio[24093]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 20 04:42:02 musicaluis volumio[24093]: Express server listening on port 3000 Dec 20 04:42:02 musicaluis volumio[24093]: [Metrics] WebUI: 7s 939.51ms Dec 20 04:42:02 musicaluis volumio[24093]: info: CoreStateMachine::resetVolumioState Dec 20 04:42:02 musicaluis volumio[24093]: info: CoreStateMachine::getcurrentVolume Dec 20 04:42:02 musicaluis volumio[24093]: info: CoreCommandRouter::volumioRetrievevolume Dec 20 04:42:02 musicaluis volumio[24093]: info: Volumio Network Manager: Network status updated: 0 Dec 20 04:42:02 musicaluis volumio-remote-updater[578]: [2024-12-20 04:42:02] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1734666117 101 Dec 20 04:42:02 musicaluis volumio[24093]: 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 Dec 20 04:42:02 musicaluis volumio[24093]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Dec 20 04:42:02 musicaluis volumio[24093]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 04:42:02 musicaluis volumio[24093]: info: Reloading queue from file Dec 20 04:42:02 musicaluis volumio[24093]: info: VolumeController:: Volume=100 Mute =false Dec 20 04:42:02 musicaluis volumio[24093]: info: CoreStateMachine::pushState Dec 20 04:42:02 musicaluis volumio[24093]: info: CorePlayQueue::getTrack 0 Dec 20 04:42:02 musicaluis volumio[24093]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 20 04:42:02 musicaluis volumio[24093]: info: CoreCommandRouter::volumioPushState Dec 20 04:42:02 musicaluis volumio[24093]: info: CoreStateMachine::updateTrackBlock Dec 20 04:42:02 musicaluis volumio[24093]: info: CorePlayQueue::getTrackBlock Dec 20 04:42:02 musicaluis volumio[24093]: info: CoreCommandRouter::volumioRetrievevolume Dec 20 04:42:02 musicaluis volumio[24093]: info: CoreStateMachine::setRepeat null single undefined Dec 20 04:42:02 musicaluis volumio[24093]: info: CoreStateMachine::pushState Dec 20 04:42:02 musicaluis volumio[24093]: info: CorePlayQueue::getTrack 0 Dec 20 04:42:02 musicaluis volumio[24093]: info: CoreCommandRouter::volumioPushState Dec 20 04:42:02 musicaluis volumio[24093]: info: CoreStateMachine::setRandom null Dec 20 04:42:02 musicaluis volumio[24093]: info: CoreStateMachine::pushState Dec 20 04:42:02 musicaluis volumio[24093]: info: CorePlayQueue::getTrack 0 Dec 20 04:42:02 musicaluis volumio[24093]: info: CoreCommandRouter::volumioPushState Dec 20 04:42:02 musicaluis volumio[24093]: info: Setting Device type: Raspberry PI Dec 20 04:42:02 musicaluis volumio[24093]: info: Completed loading Core Plugins Dec 20 04:42:02 musicaluis volumio[24093]: info: Preparing to generate the ALSA configuration file Dec 20 04:42:02 musicaluis volumio[24093]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Dec 20 04:42:02 musicaluis volumio[24093]: info: Reading ALSA contributions from plugins. Dec 20 04:42:02 musicaluis volumio[24093]: info: VolumeController:: Volume=100 Mute =false Dec 20 04:42:02 musicaluis volumio[24093]: info: CoreStateMachine::pushState Dec 20 04:42:02 musicaluis volumio[24093]: info: CorePlayQueue::getTrack 0 Dec 20 04:42:02 musicaluis volumio[24093]: info: CoreCommandRouter::volumioPushState Dec 20 04:42:02 musicaluis volumio[24093]: info: Asound.conf file unchanged, so no further update is needed Dec 20 04:42:02 musicaluis volumio[24093]: info: Output device has changed, restarting MPD Dec 20 04:42:02 musicaluis sudo[24226]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 20 04:42:02 musicaluis volumio[24093]: info: Output device has changed, restarting Shairport Sync Dec 20 04:42:02 musicaluis volumio[24093]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:42:02 musicaluis volumio[24093]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 04:42:02 musicaluis sudo[24226]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 04:42:02 musicaluis sudo[24226]: pam_unix(sudo:session): session closed for user root Dec 20 04:42:02 musicaluis sudo[24228]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 20 04:42:02 musicaluis sudo[24228]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 04:42:02 musicaluis systemd[1]: Stopping Music Player Daemon... Dec 20 04:42:02 musicaluis volumio[24093]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 20 04:42:02 musicaluis volumio[24093]: info: ___________ START PLUGINS ___________ Dec 20 04:42:02 musicaluis systemd[1]: mpd.service: Succeeded. Dec 20 04:42:02 musicaluis systemd[1]: Stopped Music Player Daemon. Dec 20 04:42:02 musicaluis volumio[24093]: info: ControllerMpd::onStart: Initializing MPD Dec 20 04:42:02 musicaluis volumio[24093]: info: Creating MPD Configuration file Dec 20 04:42:02 musicaluis systemd[1]: Starting Music Player Daemon... Dec 20 04:42:02 musicaluis sudo[24236]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 20 04:42:02 musicaluis sudo[24236]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 04:42:02 musicaluis sudo[24236]: pam_unix(sudo:session): session closed for user root Dec 20 04:42:02 musicaluis volumio[24093]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 20 04:42:02 musicaluis volumio[24093]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 20 04:42:02 musicaluis volumio[24093]: info: [1734666122790] CoreMusicLibrary::Adding element Media Servers Dec 20 04:42:02 musicaluis volumio[24093]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 04:42:02 musicaluis sudo[24239]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 20 04:42:02 musicaluis sudo[24239]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 04:42:02 musicaluis volumio[24093]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:42:02 musicaluis volumio[24093]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 04:42:02 musicaluis systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Dec 20 04:42:02 musicaluis systemd[1]: mpd.service: Succeeded. Dec 20 04:42:02 musicaluis systemd[1]: Stopped Music Player Daemon. Dec 20 04:42:02 musicaluis systemd[1]: Starting Music Player Daemon... Dec 20 04:42:02 musicaluis volumio[24093]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 20 04:42:02 musicaluis volumio[24093]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:42:02 musicaluis volumio[24093]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 04:42:02 musicaluis volumio[24093]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 20 04:42:02 musicaluis volumio[24093]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 20 04:42:02 musicaluis volumio[24093]: info: [1734666122938] CoreMusicLibrary::Adding element Last_100 Dec 20 04:42:02 musicaluis volumio[24093]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 04:42:02 musicaluis volumio[24093]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 20 04:42:02 musicaluis volumio[24093]: info: [1734666122942] CoreMusicLibrary::Adding element Webradio Dec 20 04:42:02 musicaluis volumio[24093]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 04:42:02 musicaluis volumio[24093]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 20 04:42:02 musicaluis sudo[24242]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 20 04:42:02 musicaluis sudo[24242]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 04:42:02 musicaluis volumio[24093]: info: Initializing BBC Radios Dec 20 04:42:02 musicaluis sudo[24242]: pam_unix(sudo:session): session closed for user root Dec 20 04:42:03 musicaluis volumio[24093]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 20 04:42:03 musicaluis volumio[24093]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 04:42:03 musicaluis volumio[24093]: info: AutoStart - onStart Dec 20 04:42:03 musicaluis volumio[24093]: info: [now-playing-config] Config is up to date. Dec 20 04:42:03 musicaluis volumio[24093]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 20 04:42:03 musicaluis volumio[24093]: info: Discovery: Getting this device information Dec 20 04:42:03 musicaluis volumio[24093]: info: CoreCommandRouter::volumioGetState Dec 20 04:42:03 musicaluis volumio[24093]: info: CorePlayQueue::getTrack 0 Dec 20 04:42:03 musicaluis volumio[24093]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 20 04:42:03 musicaluis ntpd[765]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Dec 20 04:42:03 musicaluis volumio[24093]: info: Loading i18n strings for locale en Dec 20 04:42:03 musicaluis volumio[24093]: info: Plugin volusonic has duplicated i18n key SAVE. It is ignored. Dec 20 04:42:03 musicaluis volumio[24093]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Dec 20 04:42:03 musicaluis volumio[24093]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:42:03 musicaluis volumio[24093]: info: FusionDsp - mixtype--------------------- Hardware Dec 20 04:42:03 musicaluis volumio[24093]: info: Loading i18n strings for locale en Dec 20 04:42:03 musicaluis volumio[24093]: info: Plugin volusonic has duplicated i18n key SAVE. It is ignored. Dec 20 04:42:03 musicaluis volumio[24093]: Updating browse sources language Dec 20 04:42:03 musicaluis volumio[24093]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 04:42:03 musicaluis volumio[24093]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 20 04:42:03 musicaluis volumio[24093]: info: [1734666123147] CoreMusicLibrary::Adding element Volusonic Dec 20 04:42:03 musicaluis volumio[24093]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 04:42:03 musicaluis volumio[24093]: Cannot find translation for source Volusonic Dec 20 04:42:03 musicaluis volumio[24093]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 20 04:42:03 musicaluis volumio[24093]: info: [1734666123151] CoreMusicLibrary::Adding element LastFM Dec 20 04:42:03 musicaluis volumio[24093]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 04:42:03 musicaluis volumio[24093]: Cannot find translation for source Volusonic Dec 20 04:42:03 musicaluis volumio[24093]: Cannot find translation for source LastFM Dec 20 04:42:03 musicaluis volumio[24093]: info: [LastFM] scrobbler initiated! Dec 20 04:42:03 musicaluis volumio[24093]: info: [LastFM] extended logging: false Dec 20 04:42:03 musicaluis volumio[24093]: info: [LastFM] try scrobble stream/radio plays: true Dec 20 04:42:03 musicaluis volumio[24093]: info: [LastFM] Left init routine Dec 20 04:42:03 musicaluis volumio[24093]: info: [LastFM] Socket already connected: true Dec 20 04:42:03 musicaluis volumio[24093]: info: Volumio Calling Home Dec 20 04:42:03 musicaluis volumio[24093]: info: [now-playing-app] App is listening on port 4004. Dec 20 04:42:03 musicaluis volumio[24093]: info: Preparing to generate the ALSA configuration file Dec 20 04:42:03 musicaluis volumio[24093]: Unhandled rejection Error: No sockets available, cannot start. Dec 20 04:42:03 musicaluis volumio[24093]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Dec 20 04:42:03 musicaluis volumio[24093]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Dec 20 04:42:03 musicaluis volumio[24093]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Dec 20 04:42:03 musicaluis volumio[24093]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Dec 20 04:42:03 musicaluis volumio[24093]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Dec 20 04:42:03 musicaluis volumio[24093]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Dec 20 04:42:03 musicaluis volumio[24093]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Dec 20 04:42:03 musicaluis volumio[24093]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Dec 20 04:42:03 musicaluis volumio[24093]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Dec 20 04:42:03 musicaluis volumio[24093]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Dec 20 04:42:03 musicaluis volumio[24093]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Dec 20 04:42:03 musicaluis volumio[24093]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Dec 20 04:42:03 musicaluis volumio[24093]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Dec 20 04:42:03 musicaluis volumio[24093]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Dec 20 04:42:03 musicaluis volumio[24093]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Dec 20 04:42:03 musicaluis volumio[24093]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Dec 20 04:42:03 musicaluis volumio[24093]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 20 04:42:03 musicaluis volumio[24093]: Error: getaddrinfo EBUSY ws.audioscrobbler.com Dec 20 04:42:03 musicaluis volumio[24093]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:67:26) { Dec 20 04:42:03 musicaluis volumio[24093]: errno: -16, Dec 20 04:42:03 musicaluis volumio[24093]: code: 'EBUSY', Dec 20 04:42:03 musicaluis volumio[24093]: syscall: 'getaddrinfo', Dec 20 04:42:03 musicaluis volumio[24093]: hostname: 'ws.audioscrobbler.com' Dec 20 04:42:03 musicaluis volumio[24093]: } Dec 20 04:42:03 musicaluis volumio[24093]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 20 04:42:04 musicaluis sudo[24272]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-12-20 04:41 Dec 20 04:42:04 musicaluis sudo[24272]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 04:42:04 musicaluis sudo[24272]: pam_unix(sudo:session): session closed for user root Dec 20 04:42:04 musicaluis volumio-remote-updater[578]: [2024-12-20 04:42:04] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Dec 20 04:42:04 musicaluis systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Dec 20 04:42:04 musicaluis sudo[24239]: pam_unix(sudo:session): session closed for user root Dec 20 04:42:04 musicaluis volumio-remote-updater[578]: [2024-12-20 04:42:04] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Dec 20 04:42:04 musicaluis sudo[24228]: pam_unix(sudo:session): session closed for user root Dec 20 04:42:04 musicaluis systemd[1]: volumio.service: Failed with result 'exit-code'. Dec 20 04:42:04 musicaluis systemd[1]: Started dynamicswap service. Dec 20 04:42:04 musicaluis systemd[1]: dynamicswap.service: Succeeded. Dec 20 04:42:04 musicaluis systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Dec 20 04:42:04 musicaluis systemd[1]: volumio.service: Scheduled restart job, restart counter is at 273. Dec 20 04:42:04 musicaluis systemd[1]: Started dynamicswap service. Dec 20 04:42:04 musicaluis systemd[1]: Stopped Volumio Backend Module. Dec 20 04:42:04 musicaluis systemd[1]: Started Volumio Backend Module. Dec 20 04:42:04 musicaluis systemd[1]: dynamicswap.service: Succeeded. Dec 20 04:42:04 musicaluis mpd[24250]: Dec 20 04:42 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 20 04:42:04 musicaluis systemd[1]: Started Music Player Daemon. Dec 20 04:42:05 musicaluis volumio-time-update[568]: volumio-time-update-util: Fetching time from Volumio... Dec 20 04:42:05 musicaluis volumio-time-update[568]: volumio-time-update-util: Date not found in response Dec 20 04:42:05 musicaluis volumio-time-update[568]: volumio-time-update-util: Retrying in 5 seconds... Dec 20 04:42:06 musicaluis volumio[24286]: info: ------------------------------------------- Dec 20 04:42:06 musicaluis volumio[24286]: info: ----- Volumio3 ---- Dec 20 04:42:06 musicaluis volumio[24286]: info: ------------------------------------------- Dec 20 04:42:06 musicaluis volumio[24286]: info: ----- System startup ---- Dec 20 04:42:06 musicaluis volumio[24286]: info: ------------------------------------------- Dec 20 04:42:07 musicaluis volumio[24286]: info: MYVOLUMIO Environment detected Dec 20 04:42:07 musicaluis volumio[24286]: info: Plugin folders cleanup Dec 20 04:42:07 musicaluis volumio[24286]: info: Scanning into folder /volumio/app/plugins/ Dec 20 04:42:07 musicaluis volumio[24286]: info: Scanning category audio_interface Dec 20 04:42:07 musicaluis volumio[24286]: info: Scanning category miscellanea Dec 20 04:42:07 musicaluis volumio[24286]: info: Scanning category music_service Dec 20 04:42:07 musicaluis volumio[24286]: info: Scanning category plugins.json Dec 20 04:42:07 musicaluis volumio[24286]: info: Scanning category system_controller Dec 20 04:42:07 musicaluis volumio[24286]: info: Scanning category user_interface Dec 20 04:42:07 musicaluis volumio[24286]: info: Scanning into folder /data/plugins/ Dec 20 04:42:07 musicaluis volumio[24286]: info: Scanning category audio_interface Dec 20 04:42:07 musicaluis volumio[24286]: info: Scanning category music_service Dec 20 04:42:07 musicaluis volumio[24286]: info: Scanning category system_controller Dec 20 04:42:07 musicaluis volumio[24286]: info: Scanning category user_interface Dec 20 04:42:07 musicaluis volumio[24286]: info: Plugin folders cleanup completed Dec 20 04:42:07 musicaluis volumio[24286]: info: ------------------------------------------- Dec 20 04:42:07 musicaluis volumio[24286]: info: ----- Core plugins startup ---- Dec 20 04:42:07 musicaluis volumio[24286]: info: ------------------------------------------- Dec 20 04:42:07 musicaluis volumio[24286]: info: Loading plugins from folder /volumio/app/plugins/ Dec 20 04:42:07 musicaluis volumio[24286]: info: Adding plugin upnp to MyMusic Plugins Dec 20 04:42:07 musicaluis volumio[24286]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 20 04:42:07 musicaluis volumio[24286]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 20 04:42:07 musicaluis volumio[24286]: info: Loading plugins from folder /data/plugins/ Dec 20 04:42:07 musicaluis volumio[24286]: info: Loading plugin "system"... Dec 20 04:42:07 musicaluis volumio[24286]: info: Loading plugin "appearance"... Dec 20 04:42:08 musicaluis volumio[24286]: info: Loading plugin "network"... Dec 20 04:42:08 musicaluis volumio[24286]: info: Refreshing Cached IP Addresses Dec 20 04:42:08 musicaluis sudo[24322]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 20 04:42:08 musicaluis sudo[24322]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 04:42:08 musicaluis sudo[24322]: pam_unix(sudo:session): session closed for user root Dec 20 04:42:08 musicaluis sudo[24324]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 20 04:42:08 musicaluis sudo[24324]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 04:42:08 musicaluis sudo[24324]: pam_unix(sudo:session): session closed for user root Dec 20 04:42:08 musicaluis volumio[24286]: info: Loading plugin "services"... Dec 20 04:42:08 musicaluis volumio[24286]: info: Loading plugin "alsa_controller"... Dec 20 04:42:08 musicaluis sudo[24337]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 20 04:42:08 musicaluis sudo[24337]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 04:42:08 musicaluis volumio[24286]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 20 04:42:08 musicaluis volumio[24286]: info: Loading plugin "wizard"... Dec 20 04:42:08 musicaluis volumio[24286]: info: Loading plugin "networkfs"... Dec 20 04:42:08 musicaluis volumio[24286]: info: Starting Udev Watcher for removable devices Dec 20 04:42:08 musicaluis volumio[24286]: info: Ignoring mount for partition: boot Dec 20 04:42:08 musicaluis volumio[24286]: info: Ignoring mount for partition: volumio Dec 20 04:42:08 musicaluis volumio[24286]: info: Ignoring mount for partition: volumio_data Dec 20 04:42:08 musicaluis volumio[24286]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 20 04:42:08 musicaluis volumio[24286]: info: Loading plugin "volumio_command_line_client"... Dec 20 04:42:08 musicaluis volumio[24286]: info: Loading plugin "upnp"... Dec 20 04:42:08 musicaluis volumio[24286]: info: [1734666128635] Starting Upmpd Daemon Dec 20 04:42:08 musicaluis volumio[24286]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 20 04:42:08 musicaluis volumio[24286]: info: Loading plugin "my_music"... Dec 20 04:42:08 musicaluis volumio[24286]: info: Loading plugin "mpd"... Dec 20 04:42:08 musicaluis volumio[24286]: info: Loading plugin "upnp_browser"... Dec 20 04:42:09 musicaluis volumio[24286]: info: Loading plugin "alarm-clock"... Dec 20 04:42:09 musicaluis sudo[24337]: pam_unix(sudo:session): session closed for user root Dec 20 04:42:09 musicaluis volumio-remote-updater[578]: [2024-12-20 04:42:09] [connect] Successful connection Dec 20 04:42:09 musicaluis volumio[24286]: info: Loading plugin "airplay_emulation"... Dec 20 04:42:09 musicaluis volumio[24286]: info: Starting Shairport Sync Dec 20 04:42:09 musicaluis volumio[24286]: info: Loading plugin "last_100"... Dec 20 04:42:09 musicaluis volumio[24286]: info: Loading plugin "webradio"... Dec 20 04:42:09 musicaluis volumio[24286]: info: Loading plugin "i2s_dacs"... Dec 20 04:42:09 musicaluis volumio[24286]: info: Loading plugin "volumiodiscovery"... Dec 20 04:42:09 musicaluis volumio[24286]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 20 04:42:09 musicaluis volumio[24286]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 20 04:42:09 musicaluis volumio[24286]: *** WARNING *** For more information see Dec 20 04:42:09 musicaluis node[24286]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 20 04:42:09 musicaluis volumio[24286]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 20 04:42:09 musicaluis volumio[24286]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 20 04:42:09 musicaluis volumio[24286]: *** WARNING *** For more information see Dec 20 04:42:09 musicaluis node[24286]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 20 04:42:09 musicaluis node[24286]: *** WARNING *** For more information see Dec 20 04:42:09 musicaluis node[24286]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 20 04:42:09 musicaluis node[24286]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 20 04:42:09 musicaluis node[24286]: *** WARNING *** For more information see Dec 20 04:42:09 musicaluis volumio[24286]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 20 04:42:09 musicaluis volumio[24286]: info: Discovery: Started advertising with name: MusicaLuis Dec 20 04:42:09 musicaluis volumio[24286]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 20 04:42:09 musicaluis volumio[24286]: info: Loading plugin "autostart"... Dec 20 04:42:09 musicaluis volumio[24286]: info: Applying required configuration parameters for plugin autostart Dec 20 04:42:10 musicaluis volumio[24286]: info: AutoStart - onVolumioStart - read config.json Dec 20 04:42:10 musicaluis volumio[24286]: info: Loading plugin "now_playing"... Dec 20 04:42:10 musicaluis volumio-time-update[568]: volumio-time-update-util: Fetching time from Volumio... Dec 20 04:42:10 musicaluis volumio-time-update[568]: volumio-time-update-util: Date not found in response Dec 20 04:42:10 musicaluis volumio-time-update[568]: volumio-time-update-util: Retrying in 5 seconds... Dec 20 04:42:11 musicaluis volumio[24286]: info: Loading plugin "outputs"... Dec 20 04:42:11 musicaluis volumio[24286]: info: Loading plugin "albumart"... Dec 20 04:42:11 musicaluis volumio[24286]: info: Plugin example_plugin is not enabled Dec 20 04:42:11 musicaluis volumio[24286]: info: Loading plugin "inputs"... Dec 20 04:42:11 musicaluis volumio[24286]: info: Loading plugin "updater_comm"... Dec 20 04:42:11 musicaluis volumio[24286]: info: Plugin mpdemulation is not enabled Dec 20 04:42:11 musicaluis volumio[24286]: info: Loading plugin "rest_api"... Dec 20 04:42:11 musicaluis volumio[24286]: info: Loading plugin "websocket"... Dec 20 04:42:11 musicaluis volumio[24286]: info: Starting Socket.io Server version 2.3.0 Dec 20 04:42:11 musicaluis volumio[24286]: info: Loading plugin "fusiondsp"... Dec 20 04:42:11 musicaluis volumio[24286]: Forking 3 albumart workers Dec 20 04:42:11 musicaluis volumio[24286]: info: Applying required configuration parameters for plugin fusiondsp Dec 20 04:42:11 musicaluis volumio[24286]: info: Loading plugin "volusonic"... Dec 20 04:42:12 musicaluis volumio[24286]: Starting albumart workers Dec 20 04:42:12 musicaluis volumio[24286]: Starting albumart workers Dec 20 04:42:12 musicaluis volumio[24286]: Starting albumart workers Dec 20 04:42:12 musicaluis volumio[24286]: info: Applying required configuration parameters for plugin volusonic Dec 20 04:42:12 musicaluis volumio[24286]: info: Loading plugin "lastfm"... Dec 20 04:42:13 musicaluis volumio[24286]: info: Loading i18n strings for locale en Dec 20 04:42:13 musicaluis volumio[24286]: info: Plugin volusonic has duplicated i18n key SAVE. It is ignored. Dec 20 04:42:13 musicaluis volumio[24286]: Updating browse sources language Dec 20 04:42:13 musicaluis volumio[24286]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 04:42:13 musicaluis volumio[24286]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 20 04:42:13 musicaluis volumio[24286]: info: CoreCommandRouter::initPlayerControls Dec 20 04:42:13 musicaluis volumio[24286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:42:13 musicaluis volumio[24286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:42:13 musicaluis volumio[24286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:42:13 musicaluis volumio[24286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:42:13 musicaluis volumio[24286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:42:13 musicaluis volumio[24286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:42:13 musicaluis volumio[24286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:42:13 musicaluis volumio[24286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:42:13 musicaluis volumio[24286]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 20 04:42:13 musicaluis volumio[24286]: Express server listening on port 3000 Dec 20 04:42:13 musicaluis volumio[24286]: [Metrics] WebUI: 7s 928.12ms Dec 20 04:42:13 musicaluis volumio[24286]: info: CoreStateMachine::resetVolumioState Dec 20 04:42:13 musicaluis volumio[24286]: info: CoreStateMachine::getcurrentVolume Dec 20 04:42:13 musicaluis volumio[24286]: info: CoreCommandRouter::volumioRetrievevolume Dec 20 04:42:13 musicaluis volumio[24286]: info: Volumio Network Manager: Network status updated: 0 Dec 20 04:42:13 musicaluis volumio-remote-updater[578]: [2024-12-20 04:42:13] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1734666129 101 Dec 20 04:42:13 musicaluis volumio[24286]: 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 Dec 20 04:42:14 musicaluis volumio[24286]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Dec 20 04:42:14 musicaluis volumio[24286]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 04:42:14 musicaluis volumio[24286]: info: Reloading queue from file Dec 20 04:42:14 musicaluis volumio[24286]: info: VolumeController:: Volume=100 Mute =false Dec 20 04:42:14 musicaluis volumio[24286]: info: CoreStateMachine::pushState Dec 20 04:42:14 musicaluis volumio[24286]: info: CorePlayQueue::getTrack 0 Dec 20 04:42:14 musicaluis volumio[24286]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 20 04:42:14 musicaluis volumio[24286]: info: CoreCommandRouter::volumioPushState Dec 20 04:42:14 musicaluis volumio[24286]: info: CoreStateMachine::updateTrackBlock Dec 20 04:42:14 musicaluis volumio[24286]: info: CorePlayQueue::getTrackBlock Dec 20 04:42:14 musicaluis volumio[24286]: info: CoreCommandRouter::volumioRetrievevolume Dec 20 04:42:14 musicaluis volumio[24286]: info: CoreStateMachine::setRepeat null single undefined Dec 20 04:42:14 musicaluis volumio[24286]: info: CoreStateMachine::pushState Dec 20 04:42:14 musicaluis volumio[24286]: info: CorePlayQueue::getTrack 0 Dec 20 04:42:14 musicaluis volumio[24286]: info: CoreCommandRouter::volumioPushState Dec 20 04:42:14 musicaluis volumio[24286]: info: CoreStateMachine::setRandom null Dec 20 04:42:14 musicaluis volumio[24286]: info: CoreStateMachine::pushState Dec 20 04:42:14 musicaluis volumio[24286]: info: CorePlayQueue::getTrack 0 Dec 20 04:42:14 musicaluis volumio[24286]: info: CoreCommandRouter::volumioPushState Dec 20 04:42:14 musicaluis volumio[24286]: info: Setting Device type: Raspberry PI Dec 20 04:42:14 musicaluis volumio[24286]: info: Completed loading Core Plugins Dec 20 04:42:14 musicaluis volumio[24286]: info: Preparing to generate the ALSA configuration file Dec 20 04:42:14 musicaluis volumio[24286]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Dec 20 04:42:14 musicaluis volumio[24286]: info: Reading ALSA contributions from plugins. Dec 20 04:42:14 musicaluis volumio[24286]: info: VolumeController:: Volume=100 Mute =false Dec 20 04:42:14 musicaluis volumio[24286]: info: CoreStateMachine::pushState Dec 20 04:42:14 musicaluis volumio[24286]: info: CorePlayQueue::getTrack 0 Dec 20 04:42:14 musicaluis volumio[24286]: info: CoreCommandRouter::volumioPushState Dec 20 04:42:14 musicaluis volumio[24286]: info: Asound.conf file unchanged, so no further update is needed Dec 20 04:42:14 musicaluis volumio[24286]: info: Output device has changed, restarting MPD Dec 20 04:42:14 musicaluis sudo[24420]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 20 04:42:14 musicaluis volumio[24286]: info: Output device has changed, restarting Shairport Sync Dec 20 04:42:14 musicaluis sudo[24420]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 04:42:14 musicaluis volumio[24286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:42:14 musicaluis volumio[24286]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 04:42:14 musicaluis sudo[24420]: pam_unix(sudo:session): session closed for user root Dec 20 04:42:14 musicaluis sudo[24422]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 20 04:42:14 musicaluis sudo[24422]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 04:42:14 musicaluis systemd[1]: Stopping Music Player Daemon... Dec 20 04:42:14 musicaluis volumio[24286]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 20 04:42:14 musicaluis volumio[24286]: info: ___________ START PLUGINS ___________ Dec 20 04:42:14 musicaluis systemd[1]: mpd.service: Succeeded. Dec 20 04:42:14 musicaluis volumio[24286]: info: ControllerMpd::onStart: Initializing MPD Dec 20 04:42:14 musicaluis volumio[24286]: info: Creating MPD Configuration file Dec 20 04:42:14 musicaluis systemd[1]: Stopped Music Player Daemon. Dec 20 04:42:14 musicaluis sudo[24430]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 20 04:42:14 musicaluis sudo[24430]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 04:42:14 musicaluis systemd[1]: Starting Music Player Daemon... Dec 20 04:42:14 musicaluis volumio[24286]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 20 04:42:14 musicaluis volumio[24286]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 20 04:42:14 musicaluis volumio[24286]: info: [1734666134479] CoreMusicLibrary::Adding element Media Servers Dec 20 04:42:14 musicaluis volumio[24286]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 04:42:14 musicaluis sudo[24430]: pam_unix(sudo:session): session closed for user root Dec 20 04:42:14 musicaluis sudo[24432]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 20 04:42:14 musicaluis volumio[24286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:42:14 musicaluis volumio[24286]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 04:42:14 musicaluis sudo[24432]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 04:42:14 musicaluis systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Dec 20 04:42:14 musicaluis systemd[1]: mpd.service: Succeeded. Dec 20 04:42:14 musicaluis systemd[1]: Stopped Music Player Daemon. Dec 20 04:42:14 musicaluis systemd[1]: Starting Music Player Daemon... Dec 20 04:42:14 musicaluis volumio[24286]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 20 04:42:14 musicaluis volumio[24286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:42:14 musicaluis volumio[24286]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 04:42:14 musicaluis volumio[24286]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 20 04:42:14 musicaluis volumio[24286]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 20 04:42:14 musicaluis volumio[24286]: info: [1734666134613] CoreMusicLibrary::Adding element Last_100 Dec 20 04:42:14 musicaluis volumio[24286]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 04:42:14 musicaluis volumio[24286]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 20 04:42:14 musicaluis volumio[24286]: info: [1734666134616] CoreMusicLibrary::Adding element Webradio Dec 20 04:42:14 musicaluis volumio[24286]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 04:42:14 musicaluis volumio[24286]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 20 04:42:14 musicaluis volumio[24286]: info: Initializing BBC Radios Dec 20 04:42:14 musicaluis sudo[24438]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 20 04:42:14 musicaluis sudo[24438]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 04:42:14 musicaluis sudo[24438]: pam_unix(sudo:session): session closed for user root Dec 20 04:42:14 musicaluis volumio[24286]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 20 04:42:14 musicaluis volumio[24286]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 04:42:14 musicaluis volumio[24286]: info: AutoStart - onStart Dec 20 04:42:14 musicaluis volumio[24286]: info: [now-playing-config] Config is up to date. Dec 20 04:42:14 musicaluis volumio[24286]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 20 04:42:14 musicaluis volumio[24286]: info: Discovery: Getting this device information Dec 20 04:42:14 musicaluis volumio[24286]: info: CoreCommandRouter::volumioGetState Dec 20 04:42:14 musicaluis volumio[24286]: info: CorePlayQueue::getTrack 0 Dec 20 04:42:14 musicaluis volumio[24286]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 20 04:42:14 musicaluis volumio[24286]: info: Loading i18n strings for locale en Dec 20 04:42:14 musicaluis volumio[24286]: info: Plugin volusonic has duplicated i18n key SAVE. It is ignored. Dec 20 04:42:14 musicaluis volumio[24286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Dec 20 04:42:14 musicaluis volumio[24286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:42:14 musicaluis volumio[24286]: info: FusionDsp - mixtype--------------------- Hardware Dec 20 04:42:14 musicaluis volumio[24286]: info: Loading i18n strings for locale en Dec 20 04:42:14 musicaluis volumio[24286]: info: Plugin volusonic has duplicated i18n key SAVE. It is ignored. Dec 20 04:42:14 musicaluis volumio[24286]: Updating browse sources language Dec 20 04:42:14 musicaluis volumio[24286]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 04:42:14 musicaluis volumio[24286]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 20 04:42:14 musicaluis volumio[24286]: info: [1734666134807] CoreMusicLibrary::Adding element Volusonic Dec 20 04:42:14 musicaluis volumio[24286]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 04:42:14 musicaluis volumio[24286]: Cannot find translation for source Volusonic Dec 20 04:42:14 musicaluis volumio[24286]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 20 04:42:14 musicaluis volumio[24286]: info: [1734666134811] CoreMusicLibrary::Adding element LastFM Dec 20 04:42:14 musicaluis volumio[24286]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 04:42:14 musicaluis volumio[24286]: Cannot find translation for source Volusonic Dec 20 04:42:14 musicaluis volumio[24286]: Cannot find translation for source LastFM Dec 20 04:42:14 musicaluis volumio[24286]: info: [LastFM] scrobbler initiated! Dec 20 04:42:14 musicaluis volumio[24286]: info: [LastFM] extended logging: false Dec 20 04:42:14 musicaluis volumio[24286]: info: [LastFM] try scrobble stream/radio plays: true Dec 20 04:42:14 musicaluis volumio[24286]: info: [LastFM] Left init routine Dec 20 04:42:14 musicaluis volumio[24286]: info: [LastFM] Socket already connected: true Dec 20 04:42:14 musicaluis volumio[24286]: info: Volumio Calling Home Dec 20 04:42:14 musicaluis volumio[24286]: info: [now-playing-app] App is listening on port 4004. Dec 20 04:42:14 musicaluis volumio[24286]: info: Preparing to generate the ALSA configuration file Dec 20 04:42:15 musicaluis volumio[24286]: Unhandled rejection Error: No sockets available, cannot start. Dec 20 04:42:15 musicaluis volumio[24286]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Dec 20 04:42:15 musicaluis volumio[24286]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Dec 20 04:42:15 musicaluis volumio[24286]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Dec 20 04:42:15 musicaluis volumio[24286]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Dec 20 04:42:15 musicaluis volumio[24286]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Dec 20 04:42:15 musicaluis volumio[24286]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Dec 20 04:42:15 musicaluis volumio[24286]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Dec 20 04:42:15 musicaluis volumio[24286]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Dec 20 04:42:15 musicaluis volumio[24286]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Dec 20 04:42:15 musicaluis volumio[24286]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Dec 20 04:42:15 musicaluis volumio[24286]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Dec 20 04:42:15 musicaluis volumio[24286]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Dec 20 04:42:15 musicaluis volumio[24286]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Dec 20 04:42:15 musicaluis volumio[24286]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Dec 20 04:42:15 musicaluis volumio[24286]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Dec 20 04:42:15 musicaluis volumio[24286]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Dec 20 04:42:15 musicaluis volumio[24286]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 20 04:42:15 musicaluis volumio[24286]: Error: getaddrinfo EBUSY ws.audioscrobbler.com Dec 20 04:42:15 musicaluis volumio[24286]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:67:26) { Dec 20 04:42:15 musicaluis volumio[24286]: errno: -16, Dec 20 04:42:15 musicaluis volumio[24286]: code: 'EBUSY', Dec 20 04:42:15 musicaluis volumio[24286]: syscall: 'getaddrinfo', Dec 20 04:42:15 musicaluis volumio[24286]: hostname: 'ws.audioscrobbler.com' Dec 20 04:42:15 musicaluis volumio[24286]: } Dec 20 04:42:15 musicaluis volumio[24286]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 20 04:42:15 musicaluis volumio-time-update[568]: volumio-time-update-util: Fetching time from Volumio... Dec 20 04:42:15 musicaluis volumio-time-update[568]: volumio-time-update-util: Date not found in response Dec 20 04:42:15 musicaluis volumio-time-update[568]: volumio-time-update-util: Retrying in 5 seconds... Dec 20 04:42:15 musicaluis sudo[24471]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-12-20 04:41 Dec 20 04:42:15 musicaluis sudo[24471]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 04:42:15 musicaluis sudo[24471]: pam_unix(sudo:session): session closed for user root Dec 20 04:42:16 musicaluis volumio-remote-updater[578]: [2024-12-20 04:42:16] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Dec 20 04:42:16 musicaluis volumio-remote-updater[578]: [2024-12-20 04:42:16] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Dec 20 04:42:16 musicaluis systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Dec 20 04:42:16 musicaluis sudo[24422]: pam_unix(sudo:session): session closed for user root Dec 20 04:42:16 musicaluis sudo[24432]: pam_unix(sudo:session): session closed for user root Dec 20 04:42:16 musicaluis systemd[1]: volumio.service: Failed with result 'exit-code'. Dec 20 04:42:16 musicaluis systemd[1]: Started dynamicswap service. Dec 20 04:42:16 musicaluis systemd[1]: dynamicswap.service: Succeeded. Dec 20 04:42:16 musicaluis systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Dec 20 04:42:16 musicaluis systemd[1]: volumio.service: Scheduled restart job, restart counter is at 274. Dec 20 04:42:16 musicaluis systemd[1]: Stopped Volumio Backend Module. Dec 20 04:42:16 musicaluis systemd[1]: Started Volumio Backend Module. Dec 20 04:42:16 musicaluis systemd[1]: Started dynamicswap service. Dec 20 04:42:16 musicaluis systemd[1]: dynamicswap.service: Succeeded. Dec 20 04:42:16 musicaluis mpd[24450]: Dec 20 04:42 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 20 04:42:16 musicaluis systemd[1]: Started Music Player Daemon. Dec 20 04:42:18 musicaluis volumio[24484]: info: ------------------------------------------- Dec 20 04:42:18 musicaluis volumio[24484]: info: ----- Volumio3 ---- Dec 20 04:42:18 musicaluis volumio[24484]: info: ------------------------------------------- Dec 20 04:42:18 musicaluis volumio[24484]: info: ----- System startup ---- Dec 20 04:42:18 musicaluis volumio[24484]: info: ------------------------------------------- Dec 20 04:42:18 musicaluis volumio[24484]: info: MYVOLUMIO Environment detected Dec 20 04:42:18 musicaluis volumio[24484]: info: Plugin folders cleanup Dec 20 04:42:18 musicaluis volumio[24484]: info: Scanning into folder /volumio/app/plugins/ Dec 20 04:42:18 musicaluis volumio[24484]: info: Scanning category audio_interface Dec 20 04:42:18 musicaluis volumio[24484]: info: Scanning category miscellanea Dec 20 04:42:18 musicaluis volumio[24484]: info: Scanning category music_service Dec 20 04:42:18 musicaluis volumio[24484]: info: Scanning category plugins.json Dec 20 04:42:18 musicaluis volumio[24484]: info: Scanning category system_controller Dec 20 04:42:18 musicaluis volumio[24484]: info: Scanning category user_interface Dec 20 04:42:18 musicaluis volumio[24484]: info: Scanning into folder /data/plugins/ Dec 20 04:42:18 musicaluis volumio[24484]: info: Scanning category audio_interface Dec 20 04:42:18 musicaluis volumio[24484]: info: Scanning category music_service Dec 20 04:42:18 musicaluis volumio[24484]: info: Scanning category system_controller Dec 20 04:42:18 musicaluis volumio[24484]: info: Scanning category user_interface Dec 20 04:42:18 musicaluis volumio[24484]: info: Plugin folders cleanup completed Dec 20 04:42:18 musicaluis volumio[24484]: info: ------------------------------------------- Dec 20 04:42:18 musicaluis volumio[24484]: info: ----- Core plugins startup ---- Dec 20 04:42:18 musicaluis volumio[24484]: info: ------------------------------------------- Dec 20 04:42:18 musicaluis volumio[24484]: info: Loading plugins from folder /volumio/app/plugins/ Dec 20 04:42:18 musicaluis volumio[24484]: info: Adding plugin upnp to MyMusic Plugins Dec 20 04:42:18 musicaluis volumio[24484]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 20 04:42:18 musicaluis volumio[24484]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 20 04:42:18 musicaluis volumio[24484]: info: Loading plugins from folder /data/plugins/ Dec 20 04:42:18 musicaluis volumio[24484]: info: Loading plugin "system"... Dec 20 04:42:19 musicaluis volumio[24484]: info: Loading plugin "appearance"... Dec 20 04:42:20 musicaluis volumio[24484]: info: Loading plugin "network"... Dec 20 04:42:20 musicaluis volumio[24484]: info: Refreshing Cached IP Addresses Dec 20 04:42:20 musicaluis sudo[24516]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 20 04:42:20 musicaluis sudo[24516]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 04:42:20 musicaluis sudo[24516]: pam_unix(sudo:session): session closed for user root Dec 20 04:42:20 musicaluis sudo[24518]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 20 04:42:20 musicaluis sudo[24518]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 04:42:20 musicaluis sudo[24518]: pam_unix(sudo:session): session closed for user root Dec 20 04:42:20 musicaluis volumio[24484]: info: Loading plugin "services"... Dec 20 04:42:20 musicaluis volumio[24484]: info: Loading plugin "alsa_controller"... Dec 20 04:42:20 musicaluis sudo[24531]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 20 04:42:20 musicaluis sudo[24531]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 04:42:20 musicaluis volumio-time-update[568]: volumio-time-update-util: Fetching time from Volumio... Dec 20 04:42:20 musicaluis volumio-time-update[568]: volumio-time-update-util: Date not found in response Dec 20 04:42:20 musicaluis volumio-time-update[568]: volumio-time-update-util: Retrying in 5 seconds... Dec 20 04:42:20 musicaluis volumio[24484]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 20 04:42:20 musicaluis volumio[24484]: info: Loading plugin "wizard"... Dec 20 04:42:20 musicaluis volumio[24484]: info: Loading plugin "networkfs"... Dec 20 04:42:20 musicaluis volumio[24484]: info: Starting Udev Watcher for removable devices Dec 20 04:42:20 musicaluis volumio[24484]: info: Ignoring mount for partition: boot Dec 20 04:42:20 musicaluis volumio[24484]: info: Ignoring mount for partition: volumio Dec 20 04:42:20 musicaluis volumio[24484]: info: Ignoring mount for partition: volumio_data Dec 20 04:42:20 musicaluis volumio[24484]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 20 04:42:20 musicaluis volumio[24484]: info: Loading plugin "volumio_command_line_client"... Dec 20 04:42:20 musicaluis volumio[24484]: info: Loading plugin "upnp"... Dec 20 04:42:20 musicaluis volumio[24484]: info: [1734666140312] Starting Upmpd Daemon Dec 20 04:42:20 musicaluis volumio[24484]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 20 04:42:20 musicaluis volumio[24484]: info: Loading plugin "my_music"... Dec 20 04:42:20 musicaluis volumio[24484]: info: Loading plugin "mpd"... Dec 20 04:42:20 musicaluis volumio[24484]: info: Loading plugin "upnp_browser"... Dec 20 04:42:20 musicaluis volumio[24484]: info: Loading plugin "alarm-clock"... Dec 20 04:42:21 musicaluis volumio-remote-updater[578]: [2024-12-20 04:42:21] [connect] Successful connection Dec 20 04:42:21 musicaluis sudo[24531]: pam_unix(sudo:session): session closed for user root Dec 20 04:42:21 musicaluis volumio[24484]: info: Loading plugin "airplay_emulation"... Dec 20 04:42:21 musicaluis volumio[24484]: info: Starting Shairport Sync Dec 20 04:42:21 musicaluis volumio[24484]: info: Loading plugin "last_100"... Dec 20 04:42:21 musicaluis volumio[24484]: info: Loading plugin "webradio"... Dec 20 04:42:21 musicaluis volumio[24484]: info: Loading plugin "i2s_dacs"... Dec 20 04:42:21 musicaluis volumio[24484]: info: Loading plugin "volumiodiscovery"... Dec 20 04:42:21 musicaluis volumio[24484]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 20 04:42:21 musicaluis volumio[24484]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 20 04:42:21 musicaluis volumio[24484]: *** WARNING *** For more information see Dec 20 04:42:21 musicaluis node[24484]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 20 04:42:21 musicaluis volumio[24484]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 20 04:42:21 musicaluis volumio[24484]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 20 04:42:21 musicaluis volumio[24484]: *** WARNING *** For more information see Dec 20 04:42:21 musicaluis node[24484]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 20 04:42:21 musicaluis node[24484]: *** WARNING *** For more information see Dec 20 04:42:21 musicaluis node[24484]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 20 04:42:21 musicaluis node[24484]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 20 04:42:21 musicaluis node[24484]: *** WARNING *** For more information see Dec 20 04:42:21 musicaluis volumio[24484]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 20 04:42:21 musicaluis volumio[24484]: info: Discovery: Started advertising with name: MusicaLuis Dec 20 04:42:21 musicaluis volumio[24484]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 20 04:42:21 musicaluis volumio[24484]: info: Loading plugin "autostart"... Dec 20 04:42:21 musicaluis volumio[24484]: info: Applying required configuration parameters for plugin autostart Dec 20 04:42:21 musicaluis volumio[24484]: info: AutoStart - onVolumioStart - read config.json Dec 20 04:42:21 musicaluis volumio[24484]: info: Loading plugin "now_playing"... Dec 20 04:42:22 musicaluis volumio[24484]: info: Loading plugin "outputs"... Dec 20 04:42:22 musicaluis volumio[24484]: info: Loading plugin "albumart"... Dec 20 04:42:22 musicaluis volumio[24484]: info: Plugin example_plugin is not enabled Dec 20 04:42:22 musicaluis volumio[24484]: info: Loading plugin "inputs"... Dec 20 04:42:22 musicaluis volumio[24484]: info: Loading plugin "updater_comm"... Dec 20 04:42:22 musicaluis volumio[24484]: info: Plugin mpdemulation is not enabled Dec 20 04:42:22 musicaluis volumio[24484]: info: Loading plugin "rest_api"... Dec 20 04:42:22 musicaluis volumio[24484]: info: Loading plugin "websocket"... Dec 20 04:42:22 musicaluis volumio[24484]: info: Starting Socket.io Server version 2.3.0 Dec 20 04:42:22 musicaluis volumio[24484]: info: Loading plugin "fusiondsp"... Dec 20 04:42:23 musicaluis volumio[24484]: Forking 3 albumart workers Dec 20 04:42:23 musicaluis volumio[24484]: info: Applying required configuration parameters for plugin fusiondsp Dec 20 04:42:23 musicaluis volumio[24484]: info: Loading plugin "volusonic"... Dec 20 04:42:24 musicaluis volumio[24484]: Starting albumart workers Dec 20 04:42:24 musicaluis volumio[24484]: Starting albumart workers Dec 20 04:42:24 musicaluis volumio[24484]: Starting albumart workers Dec 20 04:42:24 musicaluis volumio[24484]: info: Applying required configuration parameters for plugin volusonic Dec 20 04:42:24 musicaluis volumio[24484]: info: Loading plugin "lastfm"... Dec 20 04:42:25 musicaluis volumio-time-update[568]: volumio-time-update-util: Fetching time from Volumio... Dec 20 04:42:25 musicaluis volumio-time-update[568]: volumio-time-update-util: Date not found in response Dec 20 04:42:25 musicaluis volumio-time-update[568]: volumio-time-update-util: Retrying in 5 seconds... Dec 20 04:42:25 musicaluis volumio[24484]: info: Loading i18n strings for locale en Dec 20 04:42:25 musicaluis volumio[24484]: info: Plugin volusonic has duplicated i18n key SAVE. It is ignored. Dec 20 04:42:25 musicaluis volumio[24484]: Updating browse sources language Dec 20 04:42:25 musicaluis volumio[24484]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 04:42:25 musicaluis volumio[24484]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 20 04:42:25 musicaluis volumio[24484]: info: CoreCommandRouter::initPlayerControls Dec 20 04:42:25 musicaluis volumio[24484]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:42:25 musicaluis volumio[24484]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:42:25 musicaluis volumio[24484]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:42:25 musicaluis volumio[24484]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:42:25 musicaluis volumio[24484]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:42:25 musicaluis volumio[24484]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:42:25 musicaluis volumio[24484]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:42:25 musicaluis volumio[24484]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:42:25 musicaluis volumio[24484]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 20 04:42:25 musicaluis volumio[24484]: Express server listening on port 3000 Dec 20 04:42:25 musicaluis volumio[24484]: [Metrics] WebUI: 8s 45.56ms Dec 20 04:42:25 musicaluis volumio[24484]: info: CoreStateMachine::resetVolumioState Dec 20 04:42:25 musicaluis volumio[24484]: info: CoreStateMachine::getcurrentVolume Dec 20 04:42:25 musicaluis volumio[24484]: info: CoreCommandRouter::volumioRetrievevolume Dec 20 04:42:25 musicaluis volumio[24484]: info: Volumio Network Manager: Network status updated: 0 Dec 20 04:42:25 musicaluis volumio-remote-updater[578]: [2024-12-20 04:42:25] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1734666141 101 Dec 20 04:42:25 musicaluis volumio[24484]: 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 Dec 20 04:42:25 musicaluis volumio[24484]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Dec 20 04:42:25 musicaluis volumio[24484]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 04:42:25 musicaluis volumio[24484]: info: Reloading queue from file Dec 20 04:42:25 musicaluis volumio[24484]: info: VolumeController:: Volume=100 Mute =false Dec 20 04:42:25 musicaluis volumio[24484]: info: CoreStateMachine::pushState Dec 20 04:42:25 musicaluis volumio[24484]: info: CorePlayQueue::getTrack 0 Dec 20 04:42:25 musicaluis volumio[24484]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 20 04:42:25 musicaluis volumio[24484]: info: CoreCommandRouter::volumioPushState Dec 20 04:42:25 musicaluis volumio[24484]: info: CoreStateMachine::updateTrackBlock Dec 20 04:42:25 musicaluis volumio[24484]: info: CorePlayQueue::getTrackBlock Dec 20 04:42:25 musicaluis volumio[24484]: info: CoreCommandRouter::volumioRetrievevolume Dec 20 04:42:25 musicaluis volumio[24484]: info: Setting Device type: Raspberry PI Dec 20 04:42:25 musicaluis volumio[24484]: info: CoreStateMachine::setRepeat null single undefined Dec 20 04:42:25 musicaluis volumio[24484]: info: CoreStateMachine::pushState Dec 20 04:42:25 musicaluis volumio[24484]: info: CorePlayQueue::getTrack 0 Dec 20 04:42:25 musicaluis volumio[24484]: info: CoreCommandRouter::volumioPushState Dec 20 04:42:25 musicaluis volumio[24484]: info: CoreStateMachine::setRandom null Dec 20 04:42:25 musicaluis volumio[24484]: info: CoreStateMachine::pushState Dec 20 04:42:25 musicaluis volumio[24484]: info: CorePlayQueue::getTrack 0 Dec 20 04:42:25 musicaluis volumio[24484]: info: CoreCommandRouter::volumioPushState Dec 20 04:42:25 musicaluis volumio[24484]: info: Completed loading Core Plugins Dec 20 04:42:25 musicaluis volumio[24484]: info: Preparing to generate the ALSA configuration file Dec 20 04:42:25 musicaluis volumio[24484]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Dec 20 04:42:25 musicaluis volumio[24484]: info: Reading ALSA contributions from plugins. Dec 20 04:42:26 musicaluis volumio[24484]: info: VolumeController:: Volume=100 Mute =false Dec 20 04:42:26 musicaluis volumio[24484]: info: CoreStateMachine::pushState Dec 20 04:42:26 musicaluis volumio[24484]: info: CorePlayQueue::getTrack 0 Dec 20 04:42:26 musicaluis volumio[24484]: info: CoreCommandRouter::volumioPushState Dec 20 04:42:26 musicaluis volumio[24484]: info: Asound.conf file unchanged, so no further update is needed Dec 20 04:42:26 musicaluis volumio[24484]: info: Output device has changed, restarting MPD Dec 20 04:42:26 musicaluis sudo[24619]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 20 04:42:26 musicaluis volumio[24484]: info: Output device has changed, restarting Shairport Sync Dec 20 04:42:26 musicaluis sudo[24619]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 04:42:26 musicaluis volumio[24484]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:42:26 musicaluis volumio[24484]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 04:42:26 musicaluis sudo[24619]: pam_unix(sudo:session): session closed for user root Dec 20 04:42:26 musicaluis sudo[24621]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 20 04:42:26 musicaluis sudo[24621]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 04:42:26 musicaluis systemd[1]: Stopping Music Player Daemon... Dec 20 04:42:26 musicaluis volumio[24484]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 20 04:42:26 musicaluis volumio[24484]: info: ___________ START PLUGINS ___________ Dec 20 04:42:26 musicaluis systemd[1]: mpd.service: Succeeded. Dec 20 04:42:26 musicaluis systemd[1]: Stopped Music Player Daemon. Dec 20 04:42:26 musicaluis volumio[24484]: info: ControllerMpd::onStart: Initializing MPD Dec 20 04:42:26 musicaluis volumio[24484]: info: Creating MPD Configuration file Dec 20 04:42:26 musicaluis sudo[24629]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 20 04:42:26 musicaluis sudo[24629]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 04:42:26 musicaluis systemd[1]: Starting Music Player Daemon... Dec 20 04:42:26 musicaluis volumio[24484]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 20 04:42:26 musicaluis volumio[24484]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 20 04:42:26 musicaluis volumio[24484]: info: [1734666146258] CoreMusicLibrary::Adding element Media Servers Dec 20 04:42:26 musicaluis volumio[24484]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 04:42:26 musicaluis volumio[24484]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:42:26 musicaluis volumio[24484]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 04:42:26 musicaluis sudo[24629]: pam_unix(sudo:session): session closed for user root Dec 20 04:42:26 musicaluis sudo[24631]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 20 04:42:26 musicaluis sudo[24631]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 04:42:26 musicaluis systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Dec 20 04:42:26 musicaluis systemd[1]: mpd.service: Succeeded. Dec 20 04:42:26 musicaluis systemd[1]: Stopped Music Player Daemon. Dec 20 04:42:26 musicaluis systemd[1]: Starting Music Player Daemon... Dec 20 04:42:26 musicaluis volumio[24484]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 20 04:42:26 musicaluis volumio[24484]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:42:26 musicaluis volumio[24484]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 04:42:26 musicaluis volumio[24484]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 20 04:42:26 musicaluis volumio[24484]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 20 04:42:26 musicaluis volumio[24484]: info: [1734666146389] CoreMusicLibrary::Adding element Last_100 Dec 20 04:42:26 musicaluis volumio[24484]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 04:42:26 musicaluis volumio[24484]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 20 04:42:26 musicaluis volumio[24484]: info: [1734666146393] CoreMusicLibrary::Adding element Webradio Dec 20 04:42:26 musicaluis volumio[24484]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 04:42:26 musicaluis volumio[24484]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 20 04:42:26 musicaluis volumio[24484]: info: Initializing BBC Radios Dec 20 04:42:26 musicaluis sudo[24637]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 20 04:42:26 musicaluis sudo[24637]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 04:42:26 musicaluis sudo[24637]: pam_unix(sudo:session): session closed for user root Dec 20 04:42:26 musicaluis volumio[24484]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 20 04:42:26 musicaluis volumio[24484]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 04:42:26 musicaluis volumio[24484]: info: AutoStart - onStart Dec 20 04:42:26 musicaluis volumio[24484]: info: [now-playing-config] Config is up to date. Dec 20 04:42:26 musicaluis volumio[24484]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 20 04:42:26 musicaluis volumio[24484]: info: Discovery: Getting this device information Dec 20 04:42:26 musicaluis volumio[24484]: info: CoreCommandRouter::volumioGetState Dec 20 04:42:26 musicaluis volumio[24484]: info: CorePlayQueue::getTrack 0 Dec 20 04:42:26 musicaluis volumio[24484]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 20 04:42:26 musicaluis volumio[24484]: info: Loading i18n strings for locale en Dec 20 04:42:26 musicaluis volumio[24484]: info: Plugin volusonic has duplicated i18n key SAVE. It is ignored. Dec 20 04:42:26 musicaluis volumio[24484]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Dec 20 04:42:26 musicaluis volumio[24484]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:42:26 musicaluis volumio[24484]: info: FusionDsp - mixtype--------------------- Hardware Dec 20 04:42:26 musicaluis volumio[24484]: info: Loading i18n strings for locale en Dec 20 04:42:26 musicaluis volumio[24484]: info: Plugin volusonic has duplicated i18n key SAVE. It is ignored. Dec 20 04:42:26 musicaluis volumio[24484]: Updating browse sources language Dec 20 04:42:26 musicaluis volumio[24484]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 04:42:26 musicaluis volumio[24484]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 20 04:42:26 musicaluis volumio[24484]: info: [1734666146594] CoreMusicLibrary::Adding element Volusonic Dec 20 04:42:26 musicaluis volumio[24484]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 04:42:26 musicaluis volumio[24484]: Cannot find translation for source Volusonic Dec 20 04:42:26 musicaluis volumio[24484]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 20 04:42:26 musicaluis volumio[24484]: info: [1734666146598] CoreMusicLibrary::Adding element LastFM Dec 20 04:42:26 musicaluis volumio[24484]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 04:42:26 musicaluis volumio[24484]: Cannot find translation for source Volusonic Dec 20 04:42:26 musicaluis volumio[24484]: Cannot find translation for source LastFM Dec 20 04:42:26 musicaluis volumio[24484]: info: [LastFM] scrobbler initiated! Dec 20 04:42:26 musicaluis volumio[24484]: info: [LastFM] extended logging: false Dec 20 04:42:26 musicaluis volumio[24484]: info: [LastFM] try scrobble stream/radio plays: true Dec 20 04:42:26 musicaluis volumio[24484]: info: [LastFM] Left init routine Dec 20 04:42:26 musicaluis volumio[24484]: info: [LastFM] Socket already connected: true Dec 20 04:42:26 musicaluis volumio[24484]: info: Volumio Calling Home Dec 20 04:42:26 musicaluis volumio[24484]: info: [now-playing-app] App is listening on port 4004. Dec 20 04:42:26 musicaluis volumio[24484]: info: Preparing to generate the ALSA configuration file Dec 20 04:42:26 musicaluis volumio[24484]: Unhandled rejection Error: No sockets available, cannot start. Dec 20 04:42:26 musicaluis volumio[24484]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Dec 20 04:42:26 musicaluis volumio[24484]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Dec 20 04:42:26 musicaluis volumio[24484]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Dec 20 04:42:26 musicaluis volumio[24484]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Dec 20 04:42:26 musicaluis volumio[24484]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Dec 20 04:42:26 musicaluis volumio[24484]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Dec 20 04:42:26 musicaluis volumio[24484]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Dec 20 04:42:26 musicaluis volumio[24484]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Dec 20 04:42:26 musicaluis volumio[24484]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Dec 20 04:42:26 musicaluis volumio[24484]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Dec 20 04:42:26 musicaluis volumio[24484]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Dec 20 04:42:26 musicaluis volumio[24484]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Dec 20 04:42:26 musicaluis volumio[24484]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Dec 20 04:42:26 musicaluis volumio[24484]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Dec 20 04:42:26 musicaluis volumio[24484]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Dec 20 04:42:26 musicaluis volumio[24484]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Dec 20 04:42:26 musicaluis volumio[24484]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 20 04:42:26 musicaluis volumio[24484]: Error: getaddrinfo EBUSY ws.audioscrobbler.com Dec 20 04:42:26 musicaluis volumio[24484]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:67:26) { Dec 20 04:42:26 musicaluis volumio[24484]: errno: -16, Dec 20 04:42:26 musicaluis volumio[24484]: code: 'EBUSY', Dec 20 04:42:26 musicaluis volumio[24484]: syscall: 'getaddrinfo', Dec 20 04:42:26 musicaluis volumio[24484]: hostname: 'ws.audioscrobbler.com' Dec 20 04:42:26 musicaluis volumio[24484]: } Dec 20 04:42:26 musicaluis volumio[24484]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 20 04:42:27 musicaluis sudo[24667]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-12-20 04:41 Dec 20 04:42:27 musicaluis sudo[24667]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 04:42:27 musicaluis sudo[24667]: pam_unix(sudo:session): session closed for user root Dec 20 04:42:27 musicaluis volumio-remote-updater[578]: [2024-12-20 04:42:27] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Dec 20 04:42:27 musicaluis volumio-remote-updater[578]: [2024-12-20 04:42:27] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Dec 20 04:42:27 musicaluis systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Dec 20 04:42:27 musicaluis sudo[24621]: pam_unix(sudo:session): session closed for user root Dec 20 04:42:27 musicaluis sudo[24631]: pam_unix(sudo:session): session closed for user root Dec 20 04:42:27 musicaluis systemd[1]: volumio.service: Failed with result 'exit-code'. Dec 20 04:42:28 musicaluis systemd[1]: Started dynamicswap service. Dec 20 04:42:28 musicaluis systemd[1]: dynamicswap.service: Succeeded. Dec 20 04:42:28 musicaluis systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Dec 20 04:42:28 musicaluis systemd[1]: volumio.service: Scheduled restart job, restart counter is at 275. Dec 20 04:42:28 musicaluis systemd[1]: Stopped Volumio Backend Module. Dec 20 04:42:28 musicaluis systemd[1]: Started Volumio Backend Module. Dec 20 04:42:28 musicaluis systemd[1]: Started dynamicswap service. Dec 20 04:42:28 musicaluis systemd[1]: dynamicswap.service: Succeeded. Dec 20 04:42:28 musicaluis mpd[24648]: Dec 20 04:42 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 20 04:42:28 musicaluis systemd[1]: Started Music Player Daemon. Dec 20 04:42:29 musicaluis volumio[24718]: info: ------------------------------------------- Dec 20 04:42:29 musicaluis volumio[24718]: info: ----- Volumio3 ---- Dec 20 04:42:29 musicaluis volumio[24718]: info: ------------------------------------------- Dec 20 04:42:29 musicaluis volumio[24718]: info: ----- System startup ---- Dec 20 04:42:29 musicaluis volumio[24718]: info: ------------------------------------------- Dec 20 04:42:30 musicaluis volumio-time-update[568]: volumio-time-update-util: Fetching time from Volumio... Dec 20 04:42:30 musicaluis volumio-time-update[568]: volumio-time-update-util: Date not found in response Dec 20 04:42:30 musicaluis volumio-time-update[568]: volumio-time-update-util: Retrying in 5 seconds... Dec 20 04:42:30 musicaluis volumio[24718]: info: MYVOLUMIO Environment detected Dec 20 04:42:30 musicaluis volumio[24718]: info: Plugin folders cleanup Dec 20 04:42:30 musicaluis volumio[24718]: info: Scanning into folder /volumio/app/plugins/ Dec 20 04:42:30 musicaluis volumio[24718]: info: Scanning category audio_interface Dec 20 04:42:30 musicaluis volumio[24718]: info: Scanning category miscellanea Dec 20 04:42:30 musicaluis volumio[24718]: info: Scanning category music_service Dec 20 04:42:30 musicaluis volumio[24718]: info: Scanning category plugins.json Dec 20 04:42:30 musicaluis volumio[24718]: info: Scanning category system_controller Dec 20 04:42:30 musicaluis volumio[24718]: info: Scanning category user_interface Dec 20 04:42:30 musicaluis volumio[24718]: info: Scanning into folder /data/plugins/ Dec 20 04:42:30 musicaluis volumio[24718]: info: Scanning category audio_interface Dec 20 04:42:30 musicaluis volumio[24718]: info: Scanning category music_service Dec 20 04:42:30 musicaluis volumio[24718]: info: Scanning category system_controller Dec 20 04:42:30 musicaluis volumio[24718]: info: Scanning category user_interface Dec 20 04:42:30 musicaluis volumio[24718]: info: Plugin folders cleanup completed Dec 20 04:42:30 musicaluis volumio[24718]: info: ------------------------------------------- Dec 20 04:42:30 musicaluis volumio[24718]: info: ----- Core plugins startup ---- Dec 20 04:42:30 musicaluis volumio[24718]: info: ------------------------------------------- Dec 20 04:42:30 musicaluis volumio[24718]: info: Loading plugins from folder /volumio/app/plugins/ Dec 20 04:42:30 musicaluis volumio[24718]: info: Adding plugin upnp to MyMusic Plugins Dec 20 04:42:30 musicaluis volumio[24718]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 20 04:42:30 musicaluis volumio[24718]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 20 04:42:30 musicaluis volumio[24718]: info: Loading plugins from folder /data/plugins/ Dec 20 04:42:30 musicaluis volumio[24718]: info: Loading plugin "system"... Dec 20 04:42:30 musicaluis volumio[24718]: info: Loading plugin "appearance"... Dec 20 04:42:31 musicaluis volumio[24718]: info: Loading plugin "network"... Dec 20 04:42:31 musicaluis volumio[24718]: info: Refreshing Cached IP Addresses Dec 20 04:42:31 musicaluis sudo[24753]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 20 04:42:31 musicaluis sudo[24753]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 04:42:31 musicaluis sudo[24753]: pam_unix(sudo:session): session closed for user root Dec 20 04:42:31 musicaluis sudo[24755]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 20 04:42:31 musicaluis sudo[24755]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 04:42:31 musicaluis sudo[24755]: pam_unix(sudo:session): session closed for user root Dec 20 04:42:31 musicaluis volumio[24718]: info: Loading plugin "services"... Dec 20 04:42:31 musicaluis volumio[24718]: info: Loading plugin "alsa_controller"... Dec 20 04:42:31 musicaluis sudo[24764]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 20 04:42:31 musicaluis sudo[24764]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 04:42:32 musicaluis volumio[24718]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 20 04:42:32 musicaluis volumio[24718]: info: Loading plugin "wizard"... Dec 20 04:42:32 musicaluis volumio[24718]: info: Loading plugin "networkfs"... Dec 20 04:42:32 musicaluis volumio[24718]: info: Starting Udev Watcher for removable devices Dec 20 04:42:32 musicaluis volumio[24718]: info: Ignoring mount for partition: boot Dec 20 04:42:32 musicaluis volumio[24718]: info: Ignoring mount for partition: volumio Dec 20 04:42:32 musicaluis volumio[24718]: info: Ignoring mount for partition: volumio_data Dec 20 04:42:32 musicaluis volumio[24718]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 20 04:42:32 musicaluis volumio[24718]: info: Loading plugin "volumio_command_line_client"... Dec 20 04:42:32 musicaluis volumio[24718]: info: Loading plugin "upnp"... Dec 20 04:42:32 musicaluis volumio[24718]: info: [1734666152133] Starting Upmpd Daemon Dec 20 04:42:32 musicaluis volumio[24718]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 20 04:42:32 musicaluis volumio[24718]: info: Loading plugin "my_music"... Dec 20 04:42:32 musicaluis volumio[24718]: info: Loading plugin "mpd"... Dec 20 04:42:32 musicaluis volumio[24718]: info: Loading plugin "upnp_browser"... Dec 20 04:42:32 musicaluis volumio[24718]: info: Loading plugin "alarm-clock"... Dec 20 04:42:32 musicaluis sudo[24764]: pam_unix(sudo:session): session closed for user root Dec 20 04:42:32 musicaluis volumio-remote-updater[578]: [2024-12-20 04:42:32] [connect] Successful connection Dec 20 04:42:32 musicaluis volumio[24718]: info: Loading plugin "airplay_emulation"... Dec 20 04:42:32 musicaluis volumio[24718]: info: Starting Shairport Sync Dec 20 04:42:32 musicaluis volumio[24718]: info: Loading plugin "last_100"... Dec 20 04:42:32 musicaluis volumio[24718]: info: Loading plugin "webradio"... Dec 20 04:42:33 musicaluis volumio[24718]: info: Loading plugin "i2s_dacs"... Dec 20 04:42:33 musicaluis volumio[24718]: info: Loading plugin "volumiodiscovery"... Dec 20 04:42:33 musicaluis volumio[24718]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 20 04:42:33 musicaluis volumio[24718]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 20 04:42:33 musicaluis volumio[24718]: *** WARNING *** For more information see Dec 20 04:42:33 musicaluis node[24718]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 20 04:42:33 musicaluis volumio[24718]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 20 04:42:33 musicaluis volumio[24718]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 20 04:42:33 musicaluis volumio[24718]: *** WARNING *** For more information see Dec 20 04:42:33 musicaluis node[24718]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 20 04:42:33 musicaluis node[24718]: *** WARNING *** For more information see Dec 20 04:42:33 musicaluis node[24718]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 20 04:42:33 musicaluis node[24718]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 20 04:42:33 musicaluis node[24718]: *** WARNING *** For more information see Dec 20 04:42:33 musicaluis volumio[24718]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 20 04:42:33 musicaluis volumio[24718]: info: Discovery: Started advertising with name: MusicaLuis Dec 20 04:42:33 musicaluis volumio[24718]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 20 04:42:33 musicaluis volumio[24718]: info: Loading plugin "autostart"... Dec 20 04:42:33 musicaluis volumio[24718]: info: Applying required configuration parameters for plugin autostart Dec 20 04:42:33 musicaluis volumio[24718]: info: AutoStart - onVolumioStart - read config.json Dec 20 04:42:33 musicaluis volumio[24718]: info: Loading plugin "now_playing"... Dec 20 04:42:34 musicaluis volumio[24718]: info: Loading plugin "outputs"... Dec 20 04:42:34 musicaluis volumio[24718]: info: Loading plugin "albumart"... Dec 20 04:42:34 musicaluis volumio[24718]: info: Plugin example_plugin is not enabled Dec 20 04:42:34 musicaluis volumio[24718]: info: Loading plugin "inputs"... Dec 20 04:42:34 musicaluis volumio[24718]: info: Loading plugin "updater_comm"... Dec 20 04:42:34 musicaluis volumio[24718]: info: Plugin mpdemulation is not enabled Dec 20 04:42:34 musicaluis volumio[24718]: info: Loading plugin "rest_api"... Dec 20 04:42:34 musicaluis volumio[24718]: info: Loading plugin "websocket"... Dec 20 04:42:34 musicaluis volumio[24718]: info: Starting Socket.io Server version 2.3.0 Dec 20 04:42:34 musicaluis volumio[24718]: info: Loading plugin "fusiondsp"... Dec 20 04:42:35 musicaluis volumio[24718]: Forking 3 albumart workers Dec 20 04:42:35 musicaluis volumio[24718]: info: Applying required configuration parameters for plugin fusiondsp Dec 20 04:42:35 musicaluis volumio-time-update[568]: volumio-time-update-util: Fetching time from Volumio... Dec 20 04:42:35 musicaluis volumio[24718]: info: Loading plugin "volusonic"... Dec 20 04:42:35 musicaluis volumio-time-update[568]: volumio-time-update-util: Date not found in response Dec 20 04:42:35 musicaluis volumio-time-update[568]: volumio-time-update-util: Retrying in 5 seconds... Dec 20 04:42:36 musicaluis volumio[24718]: Starting albumart workers Dec 20 04:42:36 musicaluis volumio[24718]: Starting albumart workers Dec 20 04:42:36 musicaluis volumio[24718]: Starting albumart workers Dec 20 04:42:36 musicaluis volumio[24718]: info: Applying required configuration parameters for plugin volusonic Dec 20 04:42:36 musicaluis volumio[24718]: info: Loading plugin "lastfm"... Dec 20 04:42:37 musicaluis volumio[24718]: info: Loading i18n strings for locale en Dec 20 04:42:37 musicaluis volumio[24718]: info: Plugin volusonic has duplicated i18n key SAVE. It is ignored. Dec 20 04:42:37 musicaluis volumio[24718]: Updating browse sources language Dec 20 04:42:37 musicaluis volumio[24718]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 04:42:37 musicaluis volumio[24718]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 20 04:42:37 musicaluis volumio[24718]: info: CoreCommandRouter::initPlayerControls Dec 20 04:42:37 musicaluis volumio[24718]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:42:37 musicaluis volumio[24718]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:42:37 musicaluis volumio[24718]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:42:37 musicaluis volumio[24718]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:42:37 musicaluis volumio[24718]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:42:37 musicaluis volumio[24718]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:42:37 musicaluis volumio[24718]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:42:37 musicaluis volumio[24718]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:42:37 musicaluis volumio[24718]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 20 04:42:37 musicaluis volumio[24718]: Express server listening on port 3000 Dec 20 04:42:37 musicaluis volumio[24718]: [Metrics] WebUI: 7s 915.77ms Dec 20 04:42:37 musicaluis volumio[24718]: info: CoreStateMachine::resetVolumioState Dec 20 04:42:37 musicaluis volumio[24718]: info: CoreStateMachine::getcurrentVolume Dec 20 04:42:37 musicaluis volumio[24718]: info: CoreCommandRouter::volumioRetrievevolume Dec 20 04:42:37 musicaluis volumio[24718]: info: Volumio Network Manager: Network status updated: 0 Dec 20 04:42:37 musicaluis volumio-remote-updater[578]: [2024-12-20 04:42:37] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1734666152 101 Dec 20 04:42:37 musicaluis volumio[24718]: 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 Dec 20 04:42:37 musicaluis volumio[24718]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Dec 20 04:42:37 musicaluis volumio[24718]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 04:42:37 musicaluis volumio[24718]: info: Reloading queue from file Dec 20 04:42:37 musicaluis volumio[24718]: info: VolumeController:: Volume=100 Mute =false Dec 20 04:42:37 musicaluis volumio[24718]: info: CoreStateMachine::pushState Dec 20 04:42:37 musicaluis volumio[24718]: info: CorePlayQueue::getTrack 0 Dec 20 04:42:37 musicaluis volumio[24718]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 20 04:42:37 musicaluis volumio[24718]: info: CoreCommandRouter::volumioPushState Dec 20 04:42:37 musicaluis volumio[24718]: info: CoreStateMachine::updateTrackBlock Dec 20 04:42:37 musicaluis volumio[24718]: info: CorePlayQueue::getTrackBlock Dec 20 04:42:37 musicaluis volumio[24718]: info: CoreCommandRouter::volumioRetrievevolume Dec 20 04:42:37 musicaluis volumio[24718]: info: CoreStateMachine::setRepeat null single undefined Dec 20 04:42:37 musicaluis volumio[24718]: info: CoreStateMachine::pushState Dec 20 04:42:37 musicaluis volumio[24718]: info: CorePlayQueue::getTrack 0 Dec 20 04:42:37 musicaluis volumio[24718]: info: CoreCommandRouter::volumioPushState Dec 20 04:42:37 musicaluis volumio[24718]: info: CoreStateMachine::setRandom null Dec 20 04:42:37 musicaluis volumio[24718]: info: CoreStateMachine::pushState Dec 20 04:42:37 musicaluis volumio[24718]: info: CorePlayQueue::getTrack 0 Dec 20 04:42:37 musicaluis volumio[24718]: info: CoreCommandRouter::volumioPushState Dec 20 04:42:37 musicaluis volumio[24718]: info: Setting Device type: Raspberry PI Dec 20 04:42:37 musicaluis volumio[24718]: info: Completed loading Core Plugins Dec 20 04:42:37 musicaluis volumio[24718]: info: Preparing to generate the ALSA configuration file Dec 20 04:42:37 musicaluis volumio[24718]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Dec 20 04:42:37 musicaluis volumio[24718]: info: Reading ALSA contributions from plugins. Dec 20 04:42:37 musicaluis volumio[24718]: info: VolumeController:: Volume=100 Mute =false Dec 20 04:42:37 musicaluis volumio[24718]: info: CoreStateMachine::pushState Dec 20 04:42:37 musicaluis volumio[24718]: info: CorePlayQueue::getTrack 0 Dec 20 04:42:37 musicaluis volumio[24718]: info: CoreCommandRouter::volumioPushState Dec 20 04:42:37 musicaluis volumio[24718]: info: Asound.conf file unchanged, so no further update is needed Dec 20 04:42:37 musicaluis volumio[24718]: info: Output device has changed, restarting MPD Dec 20 04:42:37 musicaluis sudo[24851]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 20 04:42:37 musicaluis volumio[24718]: info: Output device has changed, restarting Shairport Sync Dec 20 04:42:37 musicaluis sudo[24851]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 04:42:37 musicaluis volumio[24718]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:42:37 musicaluis volumio[24718]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 04:42:37 musicaluis sudo[24851]: pam_unix(sudo:session): session closed for user root Dec 20 04:42:37 musicaluis sudo[24853]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 20 04:42:37 musicaluis sudo[24853]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 04:42:37 musicaluis systemd[1]: Stopping Music Player Daemon... Dec 20 04:42:37 musicaluis volumio[24718]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 20 04:42:37 musicaluis volumio[24718]: info: ___________ START PLUGINS ___________ Dec 20 04:42:37 musicaluis volumio[24718]: info: ControllerMpd::onStart: Initializing MPD Dec 20 04:42:37 musicaluis volumio[24718]: info: Creating MPD Configuration file Dec 20 04:42:37 musicaluis systemd[1]: mpd.service: Succeeded. Dec 20 04:42:37 musicaluis systemd[1]: Stopped Music Player Daemon. Dec 20 04:42:37 musicaluis systemd[1]: Starting Music Player Daemon... Dec 20 04:42:37 musicaluis sudo[24861]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 20 04:42:37 musicaluis volumio[24718]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 20 04:42:37 musicaluis volumio[24718]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 20 04:42:37 musicaluis sudo[24861]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 04:42:37 musicaluis volumio[24718]: info: [1734666157970] CoreMusicLibrary::Adding element Media Servers Dec 20 04:42:37 musicaluis volumio[24718]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 04:42:37 musicaluis sudo[24861]: pam_unix(sudo:session): session closed for user root Dec 20 04:42:37 musicaluis sudo[24863]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 20 04:42:37 musicaluis sudo[24863]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 04:42:37 musicaluis volumio[24718]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:42:37 musicaluis volumio[24718]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 04:42:38 musicaluis systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Dec 20 04:42:38 musicaluis systemd[1]: mpd.service: Succeeded. Dec 20 04:42:38 musicaluis systemd[1]: Stopped Music Player Daemon. Dec 20 04:42:38 musicaluis systemd[1]: Starting Music Player Daemon... Dec 20 04:42:38 musicaluis volumio[24718]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 20 04:42:38 musicaluis volumio[24718]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:42:38 musicaluis volumio[24718]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 04:42:38 musicaluis volumio[24718]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 20 04:42:38 musicaluis volumio[24718]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 20 04:42:38 musicaluis volumio[24718]: info: [1734666158112] CoreMusicLibrary::Adding element Last_100 Dec 20 04:42:38 musicaluis volumio[24718]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 04:42:38 musicaluis volumio[24718]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 20 04:42:38 musicaluis volumio[24718]: info: [1734666158115] CoreMusicLibrary::Adding element Webradio Dec 20 04:42:38 musicaluis volumio[24718]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 04:42:38 musicaluis volumio[24718]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 20 04:42:38 musicaluis volumio[24718]: info: Initializing BBC Radios Dec 20 04:42:38 musicaluis sudo[24869]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 20 04:42:38 musicaluis sudo[24869]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 04:42:38 musicaluis sudo[24869]: pam_unix(sudo:session): session closed for user root Dec 20 04:42:38 musicaluis volumio[24718]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 20 04:42:38 musicaluis volumio[24718]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 04:42:38 musicaluis volumio[24718]: info: AutoStart - onStart Dec 20 04:42:38 musicaluis volumio[24718]: info: [now-playing-config] Config is up to date. Dec 20 04:42:38 musicaluis volumio[24718]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 20 04:42:38 musicaluis volumio[24718]: info: Discovery: Getting this device information Dec 20 04:42:38 musicaluis volumio[24718]: info: CoreCommandRouter::volumioGetState Dec 20 04:42:38 musicaluis volumio[24718]: info: CorePlayQueue::getTrack 0 Dec 20 04:42:38 musicaluis volumio[24718]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 20 04:42:38 musicaluis volumio[24718]: info: Loading i18n strings for locale en Dec 20 04:42:38 musicaluis volumio[24718]: info: Plugin volusonic has duplicated i18n key SAVE. It is ignored. Dec 20 04:42:38 musicaluis volumio[24718]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Dec 20 04:42:38 musicaluis volumio[24718]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:42:38 musicaluis volumio[24718]: info: FusionDsp - mixtype--------------------- Hardware Dec 20 04:42:38 musicaluis volumio[24718]: info: Loading i18n strings for locale en Dec 20 04:42:38 musicaluis volumio[24718]: info: Plugin volusonic has duplicated i18n key SAVE. It is ignored. Dec 20 04:42:38 musicaluis volumio[24718]: Updating browse sources language Dec 20 04:42:38 musicaluis volumio[24718]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 04:42:38 musicaluis volumio[24718]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 20 04:42:38 musicaluis volumio[24718]: info: [1734666158317] CoreMusicLibrary::Adding element Volusonic Dec 20 04:42:38 musicaluis volumio[24718]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 04:42:38 musicaluis volumio[24718]: Cannot find translation for source Volusonic Dec 20 04:42:38 musicaluis volumio[24718]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 20 04:42:38 musicaluis volumio[24718]: info: [1734666158321] CoreMusicLibrary::Adding element LastFM Dec 20 04:42:38 musicaluis volumio[24718]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 04:42:38 musicaluis volumio[24718]: Cannot find translation for source Volusonic Dec 20 04:42:38 musicaluis volumio[24718]: Cannot find translation for source LastFM Dec 20 04:42:38 musicaluis volumio[24718]: info: [LastFM] scrobbler initiated! Dec 20 04:42:38 musicaluis volumio[24718]: info: [LastFM] extended logging: false Dec 20 04:42:38 musicaluis volumio[24718]: info: [LastFM] try scrobble stream/radio plays: true Dec 20 04:42:38 musicaluis volumio[24718]: info: [LastFM] Left init routine Dec 20 04:42:38 musicaluis volumio[24718]: info: [LastFM] Socket already connected: true Dec 20 04:42:38 musicaluis volumio[24718]: info: Volumio Calling Home Dec 20 04:42:38 musicaluis volumio[24718]: info: [now-playing-app] App is listening on port 4004. Dec 20 04:42:38 musicaluis volumio[24718]: info: Preparing to generate the ALSA configuration file Dec 20 04:42:38 musicaluis volumio[24718]: Unhandled rejection Error: No sockets available, cannot start. Dec 20 04:42:38 musicaluis volumio[24718]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Dec 20 04:42:38 musicaluis volumio[24718]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Dec 20 04:42:38 musicaluis volumio[24718]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Dec 20 04:42:38 musicaluis volumio[24718]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Dec 20 04:42:38 musicaluis volumio[24718]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Dec 20 04:42:38 musicaluis volumio[24718]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Dec 20 04:42:38 musicaluis volumio[24718]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Dec 20 04:42:38 musicaluis volumio[24718]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Dec 20 04:42:38 musicaluis volumio[24718]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Dec 20 04:42:38 musicaluis volumio[24718]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Dec 20 04:42:38 musicaluis volumio[24718]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Dec 20 04:42:38 musicaluis volumio[24718]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Dec 20 04:42:38 musicaluis volumio[24718]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Dec 20 04:42:38 musicaluis volumio[24718]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Dec 20 04:42:38 musicaluis volumio[24718]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Dec 20 04:42:38 musicaluis volumio[24718]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Dec 20 04:42:38 musicaluis volumio[24718]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 20 04:42:38 musicaluis volumio[24718]: Error: getaddrinfo EBUSY ws.audioscrobbler.com Dec 20 04:42:38 musicaluis volumio[24718]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:67:26) { Dec 20 04:42:38 musicaluis volumio[24718]: errno: -16, Dec 20 04:42:38 musicaluis volumio[24718]: code: 'EBUSY', Dec 20 04:42:38 musicaluis volumio[24718]: syscall: 'getaddrinfo', Dec 20 04:42:38 musicaluis volumio[24718]: hostname: 'ws.audioscrobbler.com' Dec 20 04:42:38 musicaluis volumio[24718]: } Dec 20 04:42:38 musicaluis volumio[24718]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 20 04:42:39 musicaluis sudo[24900]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-12-20 04:41 Dec 20 04:42:39 musicaluis sudo[24900]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 04:42:39 musicaluis sudo[24900]: pam_unix(sudo:session): session closed for user root Dec 20 04:42:39 musicaluis volumio-remote-updater[578]: [2024-12-20 04:42:39] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Dec 20 04:42:39 musicaluis volumio-remote-updater[578]: [2024-12-20 04:42:39] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Dec 20 04:42:39 musicaluis systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Dec 20 04:42:39 musicaluis sudo[24853]: pam_unix(sudo:session): session closed for user root Dec 20 04:42:39 musicaluis sudo[24863]: pam_unix(sudo:session): session closed for user root Dec 20 04:42:39 musicaluis systemd[1]: volumio.service: Failed with result 'exit-code'. Dec 20 04:42:39 musicaluis systemd[1]: Started dynamicswap service. Dec 20 04:42:39 musicaluis systemd[1]: dynamicswap.service: Succeeded. Dec 20 04:42:39 musicaluis mpd[24877]: Dec 20 04:42 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 20 04:42:39 musicaluis systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Dec 20 04:42:39 musicaluis systemd[1]: volumio.service: Scheduled restart job, restart counter is at 276. Dec 20 04:42:39 musicaluis systemd[1]: Started Music Player Daemon. Dec 20 04:42:39 musicaluis systemd[1]: Started dynamicswap service. Dec 20 04:42:39 musicaluis systemd[1]: Stopped Volumio Backend Module. Dec 20 04:42:39 musicaluis systemd[1]: Started Volumio Backend Module. Dec 20 04:42:39 musicaluis systemd[1]: dynamicswap.service: Succeeded. Dec 20 04:42:40 musicaluis volumio-time-update[568]: volumio-time-update-util: Fetching time from Volumio... Dec 20 04:42:40 musicaluis volumio-time-update[568]: volumio-time-update-util: Date not found in response Dec 20 04:42:40 musicaluis volumio-time-update[568]: volumio-time-update-util: Retrying in 5 seconds... Dec 20 04:42:41 musicaluis volumio[24916]: info: ------------------------------------------- Dec 20 04:42:41 musicaluis volumio[24916]: info: ----- Volumio3 ---- Dec 20 04:42:41 musicaluis volumio[24916]: info: ------------------------------------------- Dec 20 04:42:41 musicaluis volumio[24916]: info: ----- System startup ---- Dec 20 04:42:41 musicaluis volumio[24916]: info: ------------------------------------------- Dec 20 04:42:42 musicaluis volumio[24916]: info: MYVOLUMIO Environment detected Dec 20 04:42:42 musicaluis volumio[24916]: info: Plugin folders cleanup Dec 20 04:42:42 musicaluis volumio[24916]: info: Scanning into folder /volumio/app/plugins/ Dec 20 04:42:42 musicaluis volumio[24916]: info: Scanning category audio_interface Dec 20 04:42:42 musicaluis volumio[24916]: info: Scanning category miscellanea Dec 20 04:42:42 musicaluis volumio[24916]: info: Scanning category music_service Dec 20 04:42:42 musicaluis volumio[24916]: info: Scanning category plugins.json Dec 20 04:42:42 musicaluis volumio[24916]: info: Scanning category system_controller Dec 20 04:42:42 musicaluis volumio[24916]: info: Scanning category user_interface Dec 20 04:42:42 musicaluis volumio[24916]: info: Scanning into folder /data/plugins/ Dec 20 04:42:42 musicaluis volumio[24916]: info: Scanning category audio_interface Dec 20 04:42:42 musicaluis volumio[24916]: info: Scanning category music_service Dec 20 04:42:42 musicaluis volumio[24916]: info: Scanning category system_controller Dec 20 04:42:42 musicaluis volumio[24916]: info: Scanning category user_interface Dec 20 04:42:42 musicaluis volumio[24916]: info: Plugin folders cleanup completed Dec 20 04:42:42 musicaluis volumio[24916]: info: ------------------------------------------- Dec 20 04:42:42 musicaluis volumio[24916]: info: ----- Core plugins startup ---- Dec 20 04:42:42 musicaluis volumio[24916]: info: ------------------------------------------- Dec 20 04:42:42 musicaluis volumio[24916]: info: Loading plugins from folder /volumio/app/plugins/ Dec 20 04:42:42 musicaluis volumio[24916]: info: Adding plugin upnp to MyMusic Plugins Dec 20 04:42:42 musicaluis volumio[24916]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 20 04:42:42 musicaluis volumio[24916]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 20 04:42:42 musicaluis volumio[24916]: info: Loading plugins from folder /data/plugins/ Dec 20 04:42:42 musicaluis volumio[24916]: info: Loading plugin "system"... Dec 20 04:42:42 musicaluis volumio[24916]: info: Loading plugin "appearance"... Dec 20 04:42:43 musicaluis volumio[24916]: info: Loading plugin "network"... Dec 20 04:42:43 musicaluis volumio[24916]: info: Refreshing Cached IP Addresses Dec 20 04:42:43 musicaluis sudo[24948]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 20 04:42:43 musicaluis sudo[24948]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 04:42:43 musicaluis sudo[24948]: pam_unix(sudo:session): session closed for user root Dec 20 04:42:43 musicaluis sudo[24950]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 20 04:42:43 musicaluis sudo[24950]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 04:42:43 musicaluis sudo[24950]: pam_unix(sudo:session): session closed for user root Dec 20 04:42:43 musicaluis volumio[24916]: info: Loading plugin "services"... Dec 20 04:42:43 musicaluis volumio[24916]: info: Loading plugin "alsa_controller"... Dec 20 04:42:43 musicaluis sudo[24959]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 20 04:42:43 musicaluis sudo[24959]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 04:42:43 musicaluis volumio[24916]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 20 04:42:43 musicaluis volumio[24916]: info: Loading plugin "wizard"... Dec 20 04:42:43 musicaluis volumio[24916]: info: Loading plugin "networkfs"... Dec 20 04:42:43 musicaluis volumio[24916]: info: Starting Udev Watcher for removable devices Dec 20 04:42:43 musicaluis volumio[24916]: info: Ignoring mount for partition: boot Dec 20 04:42:43 musicaluis volumio[24916]: info: Ignoring mount for partition: volumio Dec 20 04:42:43 musicaluis volumio[24916]: info: Ignoring mount for partition: volumio_data Dec 20 04:42:43 musicaluis volumio[24916]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 20 04:42:43 musicaluis volumio[24916]: info: Loading plugin "volumio_command_line_client"... Dec 20 04:42:43 musicaluis volumio[24916]: info: Loading plugin "upnp"... Dec 20 04:42:43 musicaluis volumio[24916]: info: [1734666163851] Starting Upmpd Daemon Dec 20 04:42:43 musicaluis volumio[24916]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 20 04:42:43 musicaluis volumio[24916]: info: Loading plugin "my_music"... Dec 20 04:42:43 musicaluis volumio[24916]: info: Loading plugin "mpd"... Dec 20 04:42:44 musicaluis volumio[24916]: info: Loading plugin "upnp_browser"... Dec 20 04:42:44 musicaluis volumio[24916]: info: Loading plugin "alarm-clock"... Dec 20 04:42:44 musicaluis wpa_supplicant[886]: wlan0: CTRL-EVENT-DISCONNECTED bssid=9c:c9:eb:f7:26:e8 reason=1 Dec 20 04:42:44 musicaluis dhcpcd[908]: wlan0: carrier lost Dec 20 04:42:44 musicaluis wpa_supplicant[886]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Dec 20 04:42:44 musicaluis wpa_supplicant[886]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=DE Dec 20 04:42:44 musicaluis volumio-remote-updater[578]: [2024-12-20 04:42:44] [connect] Successful connection Dec 20 04:42:44 musicaluis sudo[24959]: pam_unix(sudo:session): session closed for user root Dec 20 04:42:44 musicaluis volumio[24916]: info: Loading plugin "airplay_emulation"... Dec 20 04:42:44 musicaluis volumio[24916]: info: Starting Shairport Sync Dec 20 04:42:44 musicaluis volumio[24916]: info: Loading plugin "last_100"... Dec 20 04:42:44 musicaluis volumio[24916]: info: Loading plugin "webradio"... Dec 20 04:42:44 musicaluis wpa_supplicant[886]: wlan0: Trying to associate with SSID 'NyMyL' Dec 20 04:42:44 musicaluis volumio[24916]: info: Loading plugin "i2s_dacs"... Dec 20 04:42:44 musicaluis volumio[24916]: info: Loading plugin "volumiodiscovery"... Dec 20 04:42:44 musicaluis volumio[24916]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 20 04:42:44 musicaluis volumio[24916]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 20 04:42:44 musicaluis volumio[24916]: *** WARNING *** For more information see Dec 20 04:42:44 musicaluis volumio[24916]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 20 04:42:44 musicaluis node[24916]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 20 04:42:44 musicaluis volumio[24916]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 20 04:42:44 musicaluis volumio[24916]: *** WARNING *** For more information see Dec 20 04:42:44 musicaluis node[24916]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 20 04:42:44 musicaluis node[24916]: *** WARNING *** For more information see Dec 20 04:42:44 musicaluis node[24916]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 20 04:42:44 musicaluis node[24916]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 20 04:42:44 musicaluis node[24916]: *** WARNING *** For more information see Dec 20 04:42:44 musicaluis volumio[24916]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 20 04:42:44 musicaluis volumio[24916]: info: Discovery: Started advertising with name: MusicaLuis Dec 20 04:42:44 musicaluis dhcpcd[908]: wlan0: carrier acquired Dec 20 04:42:44 musicaluis wpa_supplicant[886]: wlan0: Associated with 9c:c9:eb:0e:40:61 Dec 20 04:42:44 musicaluis wpa_supplicant[886]: wlan0: CTRL-EVENT-CONNECTED - Connection to 9c:c9:eb:0e:40:61 completed [id=0 id_str=] Dec 20 04:42:44 musicaluis wpa_supplicant[886]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Dec 20 04:42:44 musicaluis wpa_supplicant[886]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=DE Dec 20 04:42:44 musicaluis dhcpcd[908]: wlan0: IAID eb:2e:97:5b Dec 20 04:42:44 musicaluis dhcpcd[908]: wlan0: probing address 192.168.1.77/24 Dec 20 04:42:44 musicaluis volumio[24916]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 20 04:42:44 musicaluis volumio[24916]: info: Loading plugin "autostart"... Dec 20 04:42:44 musicaluis dhcpcd[908]: wlan0: carrier lost Dec 20 04:42:45 musicaluis dhcpcd[908]: wlan0: carrier acquired Dec 20 04:42:45 musicaluis dhcpcd[908]: wlan0: IAID eb:2e:97:5b Dec 20 04:42:45 musicaluis dhcpcd[908]: wlan0: probing address 192.168.1.77/24 Dec 20 04:42:45 musicaluis volumio[24916]: info: Applying required configuration parameters for plugin autostart Dec 20 04:42:45 musicaluis volumio[24916]: info: AutoStart - onVolumioStart - read config.json Dec 20 04:42:45 musicaluis volumio[24916]: info: Loading plugin "now_playing"... Dec 20 04:42:45 musicaluis volumio-time-update[568]: volumio-time-update-util: Fetching time from Volumio... Dec 20 04:42:45 musicaluis volumio-time-update[568]: volumio-time-update-util: Date not found in response Dec 20 04:42:45 musicaluis volumio-time-update[568]: volumio-time-update-util: Retrying in 5 seconds... Dec 20 04:42:45 musicaluis dhcpcd[908]: wlan0: soliciting an IPv6 router Dec 20 04:42:46 musicaluis volumio[24916]: info: Loading plugin "outputs"... Dec 20 04:42:46 musicaluis volumio[24916]: info: Loading plugin "albumart"... Dec 20 04:42:46 musicaluis volumio[24916]: info: Plugin example_plugin is not enabled Dec 20 04:42:46 musicaluis volumio[24916]: info: Loading plugin "inputs"... Dec 20 04:42:46 musicaluis volumio[24916]: info: Loading plugin "updater_comm"... Dec 20 04:42:46 musicaluis volumio[24916]: info: Plugin mpdemulation is not enabled Dec 20 04:42:46 musicaluis volumio[24916]: info: Loading plugin "rest_api"... Dec 20 04:42:46 musicaluis volumio[24916]: info: Loading plugin "websocket"... Dec 20 04:42:46 musicaluis volumio[24916]: info: Starting Socket.io Server version 2.3.0 Dec 20 04:42:46 musicaluis volumio[24916]: info: Loading plugin "fusiondsp"... Dec 20 04:42:46 musicaluis volumio[24916]: Forking 3 albumart workers Dec 20 04:42:46 musicaluis volumio[24916]: info: Applying required configuration parameters for plugin fusiondsp Dec 20 04:42:47 musicaluis volumio[24916]: info: Loading plugin "volusonic"... Dec 20 04:42:47 musicaluis volumio[24916]: Starting albumart workers Dec 20 04:42:47 musicaluis volumio[24916]: Starting albumart workers Dec 20 04:42:47 musicaluis volumio[24916]: Starting albumart workers Dec 20 04:42:48 musicaluis volumio[24916]: info: Applying required configuration parameters for plugin volusonic Dec 20 04:42:48 musicaluis volumio[24916]: info: Loading plugin "lastfm"... Dec 20 04:42:48 musicaluis volumio[24916]: info: Loading i18n strings for locale en Dec 20 04:42:48 musicaluis volumio[24916]: info: Plugin volusonic has duplicated i18n key SAVE. It is ignored. Dec 20 04:42:48 musicaluis volumio[24916]: Updating browse sources language Dec 20 04:42:48 musicaluis volumio[24916]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 04:42:48 musicaluis volumio[24916]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 20 04:42:48 musicaluis volumio[24916]: info: CoreCommandRouter::initPlayerControls Dec 20 04:42:48 musicaluis volumio[24916]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:42:48 musicaluis volumio[24916]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:42:48 musicaluis volumio[24916]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:42:48 musicaluis volumio[24916]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:42:48 musicaluis volumio[24916]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:42:48 musicaluis volumio[24916]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:42:48 musicaluis volumio[24916]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:42:48 musicaluis volumio[24916]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:42:48 musicaluis volumio[24916]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 20 04:42:48 musicaluis volumio[24916]: Express server listening on port 3000 Dec 20 04:42:48 musicaluis volumio[24916]: [Metrics] WebUI: 7s 873.97ms Dec 20 04:42:49 musicaluis volumio[24916]: info: CoreStateMachine::resetVolumioState Dec 20 04:42:49 musicaluis volumio[24916]: info: CoreStateMachine::getcurrentVolume Dec 20 04:42:49 musicaluis volumio[24916]: info: CoreCommandRouter::volumioRetrievevolume Dec 20 04:42:49 musicaluis volumio[24916]: info: Volumio Network Manager: Network status updated: 0 Dec 20 04:42:49 musicaluis volumio-remote-updater[578]: [2024-12-20 04:42:49] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1734666164 101 Dec 20 04:42:49 musicaluis volumio[24916]: 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 Dec 20 04:42:49 musicaluis volumio[24916]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Dec 20 04:42:49 musicaluis volumio[24916]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 04:42:49 musicaluis volumio[24916]: info: Reloading queue from file Dec 20 04:42:49 musicaluis volumio[24916]: info: VolumeController:: Volume=100 Mute =false Dec 20 04:42:49 musicaluis volumio[24916]: info: CoreStateMachine::pushState Dec 20 04:42:49 musicaluis volumio[24916]: info: CorePlayQueue::getTrack 0 Dec 20 04:42:49 musicaluis volumio[24916]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 20 04:42:49 musicaluis volumio[24916]: info: CoreCommandRouter::volumioPushState Dec 20 04:42:49 musicaluis volumio[24916]: info: CoreStateMachine::updateTrackBlock Dec 20 04:42:49 musicaluis volumio[24916]: info: CorePlayQueue::getTrackBlock Dec 20 04:42:49 musicaluis volumio[24916]: info: CoreCommandRouter::volumioRetrievevolume Dec 20 04:42:49 musicaluis volumio[24916]: info: CoreStateMachine::setRepeat null single undefined Dec 20 04:42:49 musicaluis volumio[24916]: info: CoreStateMachine::pushState Dec 20 04:42:49 musicaluis volumio[24916]: info: CorePlayQueue::getTrack 0 Dec 20 04:42:49 musicaluis volumio[24916]: info: CoreCommandRouter::volumioPushState Dec 20 04:42:49 musicaluis volumio[24916]: info: CoreStateMachine::setRandom null Dec 20 04:42:49 musicaluis volumio[24916]: info: CoreStateMachine::pushState Dec 20 04:42:49 musicaluis volumio[24916]: info: CorePlayQueue::getTrack 0 Dec 20 04:42:49 musicaluis volumio[24916]: info: CoreCommandRouter::volumioPushState Dec 20 04:42:49 musicaluis volumio[24916]: info: Setting Device type: Raspberry PI Dec 20 04:42:49 musicaluis volumio[24916]: info: Completed loading Core Plugins Dec 20 04:42:49 musicaluis volumio[24916]: info: Preparing to generate the ALSA configuration file Dec 20 04:42:49 musicaluis volumio[24916]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Dec 20 04:42:49 musicaluis volumio[24916]: info: Reading ALSA contributions from plugins. Dec 20 04:42:49 musicaluis volumio[24916]: info: VolumeController:: Volume=100 Mute =false Dec 20 04:42:49 musicaluis volumio[24916]: info: CoreStateMachine::pushState Dec 20 04:42:49 musicaluis volumio[24916]: info: CorePlayQueue::getTrack 0 Dec 20 04:42:49 musicaluis volumio[24916]: info: CoreCommandRouter::volumioPushState Dec 20 04:42:49 musicaluis volumio[24916]: info: Asound.conf file unchanged, so no further update is needed Dec 20 04:42:49 musicaluis volumio[24916]: info: Output device has changed, restarting MPD Dec 20 04:42:49 musicaluis sudo[25088]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 20 04:42:49 musicaluis sudo[25088]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 04:42:49 musicaluis volumio[24916]: info: Output device has changed, restarting Shairport Sync Dec 20 04:42:49 musicaluis volumio[24916]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:42:49 musicaluis volumio[24916]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 04:42:49 musicaluis sudo[25088]: pam_unix(sudo:session): session closed for user root Dec 20 04:42:49 musicaluis sudo[25090]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 20 04:42:49 musicaluis sudo[25090]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 04:42:49 musicaluis systemd[1]: Stopping Music Player Daemon... Dec 20 04:42:49 musicaluis volumio[24916]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 20 04:42:49 musicaluis volumio[24916]: info: ___________ START PLUGINS ___________ Dec 20 04:42:49 musicaluis volumio[24916]: info: ControllerMpd::onStart: Initializing MPD Dec 20 04:42:49 musicaluis volumio[24916]: info: Creating MPD Configuration file Dec 20 04:42:49 musicaluis systemd[1]: mpd.service: Succeeded. Dec 20 04:42:49 musicaluis systemd[1]: Stopped Music Player Daemon. Dec 20 04:42:49 musicaluis sudo[25098]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 20 04:42:49 musicaluis sudo[25098]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 04:42:49 musicaluis systemd[1]: Starting Music Player Daemon... Dec 20 04:42:49 musicaluis volumio[24916]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 20 04:42:49 musicaluis volumio[24916]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 20 04:42:49 musicaluis volumio[24916]: info: [1734666169660] CoreMusicLibrary::Adding element Media Servers Dec 20 04:42:49 musicaluis volumio[24916]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 04:42:49 musicaluis volumio[24916]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:42:49 musicaluis volumio[24916]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 04:42:49 musicaluis sudo[25098]: pam_unix(sudo:session): session closed for user root Dec 20 04:42:49 musicaluis sudo[25100]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 20 04:42:49 musicaluis sudo[25100]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 04:42:49 musicaluis systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Dec 20 04:42:49 musicaluis systemd[1]: mpd.service: Succeeded. Dec 20 04:42:49 musicaluis systemd[1]: Stopped Music Player Daemon. Dec 20 04:42:49 musicaluis systemd[1]: Starting Music Player Daemon... Dec 20 04:42:49 musicaluis volumio[24916]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 20 04:42:49 musicaluis volumio[24916]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:42:49 musicaluis volumio[24916]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 04:42:49 musicaluis volumio[24916]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 20 04:42:49 musicaluis volumio[24916]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 20 04:42:49 musicaluis volumio[24916]: info: [1734666169793] CoreMusicLibrary::Adding element Last_100 Dec 20 04:42:49 musicaluis volumio[24916]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 04:42:49 musicaluis volumio[24916]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 20 04:42:49 musicaluis volumio[24916]: info: [1734666169797] CoreMusicLibrary::Adding element Webradio Dec 20 04:42:49 musicaluis volumio[24916]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 04:42:49 musicaluis volumio[24916]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 20 04:42:49 musicaluis volumio[24916]: info: Initializing BBC Radios Dec 20 04:42:49 musicaluis sudo[25106]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 20 04:42:49 musicaluis sudo[25106]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 04:42:49 musicaluis sudo[25106]: pam_unix(sudo:session): session closed for user root Dec 20 04:42:49 musicaluis volumio[24916]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 20 04:42:49 musicaluis volumio[24916]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 04:42:49 musicaluis volumio[24916]: info: AutoStart - onStart Dec 20 04:42:49 musicaluis volumio[24916]: info: [now-playing-config] Config is up to date. Dec 20 04:42:49 musicaluis volumio[24916]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 20 04:42:49 musicaluis volumio[24916]: info: Discovery: Getting this device information Dec 20 04:42:49 musicaluis volumio[24916]: info: CoreCommandRouter::volumioGetState Dec 20 04:42:49 musicaluis volumio[24916]: info: CorePlayQueue::getTrack 0 Dec 20 04:42:49 musicaluis volumio[24916]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 20 04:42:49 musicaluis volumio[24916]: info: Loading i18n strings for locale en Dec 20 04:42:49 musicaluis volumio[24916]: info: Plugin volusonic has duplicated i18n key SAVE. It is ignored. Dec 20 04:42:49 musicaluis volumio[24916]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Dec 20 04:42:49 musicaluis volumio[24916]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 04:42:49 musicaluis volumio[24916]: info: FusionDsp - mixtype--------------------- Hardware Dec 20 04:42:49 musicaluis volumio[24916]: info: Loading i18n strings for locale en Dec 20 04:42:49 musicaluis volumio[24916]: info: Plugin volusonic has duplicated i18n key SAVE. It is ignored. Dec 20 04:42:49 musicaluis volumio[24916]: Updating browse sources language Dec 20 04:42:49 musicaluis volumio[24916]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 04:42:49 musicaluis volumio[24916]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 20 04:42:49 musicaluis volumio[24916]: info: [1734666169993] CoreMusicLibrary::Adding element Volusonic Dec 20 04:42:49 musicaluis volumio[24916]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 04:42:49 musicaluis volumio[24916]: Cannot find translation for source Volusonic Dec 20 04:42:49 musicaluis volumio[24916]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 20 04:42:49 musicaluis volumio[24916]: info: [1734666169997] CoreMusicLibrary::Adding element LastFM Dec 20 04:42:49 musicaluis volumio[24916]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 04:42:49 musicaluis volumio[24916]: Cannot find translation for source Volusonic Dec 20 04:42:49 musicaluis volumio[24916]: Cannot find translation for source LastFM Dec 20 04:42:50 musicaluis volumio[24916]: info: [LastFM] scrobbler initiated! Dec 20 04:42:50 musicaluis volumio[24916]: info: [LastFM] extended logging: false Dec 20 04:42:50 musicaluis volumio[24916]: info: [LastFM] try scrobble stream/radio plays: true Dec 20 04:42:50 musicaluis volumio[24916]: info: [LastFM] Left init routine Dec 20 04:42:50 musicaluis volumio[24916]: info: [LastFM] Socket already connected: true Dec 20 04:42:50 musicaluis volumio[24916]: info: Volumio Calling Home Dec 20 04:42:50 musicaluis ntpd[765]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Dec 20 04:42:50 musicaluis volumio[24916]: info: [now-playing-app] App is listening on port 4004. Dec 20 04:42:50 musicaluis volumio[24916]: info: Preparing to generate the ALSA configuration file Dec 20 04:42:50 musicaluis volumio[24916]: Unhandled rejection Error: No sockets available, cannot start. Dec 20 04:42:50 musicaluis volumio[24916]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Dec 20 04:42:50 musicaluis volumio[24916]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Dec 20 04:42:50 musicaluis volumio[24916]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Dec 20 04:42:50 musicaluis volumio[24916]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Dec 20 04:42:50 musicaluis volumio[24916]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Dec 20 04:42:50 musicaluis volumio[24916]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Dec 20 04:42:50 musicaluis volumio[24916]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Dec 20 04:42:50 musicaluis volumio[24916]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Dec 20 04:42:50 musicaluis volumio[24916]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Dec 20 04:42:50 musicaluis volumio[24916]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Dec 20 04:42:50 musicaluis volumio[24916]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Dec 20 04:42:50 musicaluis volumio[24916]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Dec 20 04:42:50 musicaluis volumio[24916]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Dec 20 04:42:50 musicaluis volumio[24916]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Dec 20 04:42:50 musicaluis volumio[24916]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Dec 20 04:42:50 musicaluis volumio[24916]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Dec 20 04:42:50 musicaluis volumio[24916]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 20 04:42:50 musicaluis volumio[24916]: Error: getaddrinfo EBUSY ws.audioscrobbler.com Dec 20 04:42:50 musicaluis volumio[24916]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:67:26) { Dec 20 04:42:50 musicaluis volumio[24916]: errno: -16, Dec 20 04:42:50 musicaluis volumio[24916]: code: 'EBUSY', Dec 20 04:42:50 musicaluis volumio[24916]: syscall: 'getaddrinfo', Dec 20 04:42:50 musicaluis volumio[24916]: hostname: 'ws.audioscrobbler.com' Dec 20 04:42:50 musicaluis volumio[24916]: } Dec 20 04:42:50 musicaluis volumio[24916]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 20 04:42:50 musicaluis dhcpcd[908]: wlan0: using static address 192.168.1.77/24 Dec 20 04:42:50 musicaluis avahi-daemon[587]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.77. Dec 20 04:42:50 musicaluis avahi-daemon[587]: New relevant interface wlan0.IPv4 for mDNS. Dec 20 04:42:50 musicaluis dhcpcd[908]: wlan0: adding route to 192.168.1.0/24 Dec 20 04:42:50 musicaluis avahi-daemon[587]: Registering new address record for 192.168.1.77 on wlan0.IPv4. Dec 20 04:42:50 musicaluis dhcpcd[908]: wlan0: adding default route via 192.168.1.1 Dec 20 04:42:50 musicaluis volumio-time-update[568]: volumio-time-update-util: Fetching time from Volumio... Dec 20 04:42:50 musicaluis sudo[25162]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-12-20 04:41 Dec 20 04:42:50 musicaluis sudo[25162]: 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="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"