-- Logs begin at Fri 2025-02-07 14:02:17 UTC, end at Fri 2025-02-07 14:21:02 UTC. -- Feb 07 14:20:01 volumio sudo[17658]: pam_unix(sudo:session): session closed for user root Feb 07 14:20:01 volumio volumio[17575]: info: MPD running with PID17617 Feb 07 14:20:01 volumio volumio[17575]: ,establishing connection Feb 07 14:20:01 volumio volumio[17575]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Feb 07 14:20:01 volumio volumio[17575]: error: MPD error: The expression evaluated to a falsy value: Feb 07 14:20:01 volumio volumio[17575]: assert.ok(self.idling) Feb 07 14:20:01 volumio volumio[17575]: error: MPD error: The expression evaluated to a falsy value: Feb 07 14:20:01 volumio volumio[17575]: assert.ok(self.idling) Feb 07 14:20:01 volumio volumio[17575]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 1 Feb 07 14:20:01 volumio volumio-remote-updater[454]: [2025-02-07 14:20:01] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1738937999 101 Feb 07 14:20:01 volumio volumio[17575]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 2 Feb 07 14:20:01 volumio volumio[17575]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:01 volumio volumio[17575]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:01 volumio volumio[17575]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:01 volumio volumio[17575]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:01 volumio volumio[17575]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:01 volumio volumio[17575]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:01 volumio volumio[17575]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:01 volumio volumio[17575]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:01 volumio volumio[17575]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 14:20:01 volumio volumio[17575]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:01 volumio volumio[17575]: info: Reloading queue from file Feb 07 14:20:01 volumio volumio[17575]: info: VolumeController:: Volume=undefined Mute =false Feb 07 14:20:01 volumio volumio[17575]: info: CoreStateMachine::pushState Feb 07 14:20:01 volumio volumio[17575]: info: CorePlayQueue::getTrack 0 Feb 07 14:20:01 volumio volumio[17575]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 07 14:20:01 volumio volumio[17575]: info: CoreCommandRouter::volumioPushState Feb 07 14:20:01 volumio volumio[17575]: info: CoreStateMachine::updateTrackBlock Feb 07 14:20:01 volumio volumio[17575]: info: CorePlayQueue::getTrackBlock Feb 07 14:20:01 volumio volumio[17575]: info: CoreCommandRouter::volumioRetrievevolume Feb 07 14:20:01 volumio volumio[17575]: info: CoreStateMachine::setRepeat null single undefined Feb 07 14:20:01 volumio volumio[17575]: info: CoreStateMachine::pushState Feb 07 14:20:01 volumio volumio[17575]: info: CorePlayQueue::getTrack 0 Feb 07 14:20:01 volumio volumio[17575]: info: CoreCommandRouter::volumioPushState Feb 07 14:20:01 volumio volumio[17575]: info: CoreStateMachine::setRandom false Feb 07 14:20:01 volumio volumio[17575]: info: CoreStateMachine::pushState Feb 07 14:20:01 volumio volumio[17575]: info: CorePlayQueue::getTrack 0 Feb 07 14:20:01 volumio volumio[17575]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 07 14:20:01 volumio volumio[17575]: info: CoreCommandRouter::volumioPushState Feb 07 14:20:01 volumio volumio[17575]: info: Setting Device type: Raspberry PI Feb 07 14:20:01 volumio volumio[17575]: error: updateQueue error: null Feb 07 14:20:01 volumio volumio[17575]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 07 14:20:01 volumio volumio[17575]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Feb 07 14:20:01 volumio volumio[17575]: info: VolumeController:: Volume=undefined Mute =false Feb 07 14:20:01 volumio volumio[17575]: info: CoreStateMachine::pushState Feb 07 14:20:01 volumio volumio[17575]: info: CorePlayQueue::getTrack 0 Feb 07 14:20:01 volumio volumio[17575]: info: CoreCommandRouter::volumioPushState Feb 07 14:20:01 volumio volumio[17575]: info: Completed loading Core Plugins Feb 07 14:20:01 volumio volumio[17575]: info: Preparing to generate the ALSA configuration file Feb 07 14:20:01 volumio volumio[17575]: info: Asound.conf file unchanged, so no further update is needed Feb 07 14:20:01 volumio volumio[17575]: info: Output device has changed, restarting MPD Feb 07 14:20:01 volumio sudo[17696]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 07 14:20:01 volumio sudo[17696]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 07 14:20:01 volumio volumio[17575]: info: Output device has changed, restarting Shairport Sync Feb 07 14:20:01 volumio volumio[17575]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:01 volumio volumio[17575]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 14:20:01 volumio sudo[17696]: pam_unix(sudo:session): session closed for user root Feb 07 14:20:01 volumio sudo[17699]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 07 14:20:01 volumio sudo[17699]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 07 14:20:01 volumio systemd[1]: Stopping Music Player Daemon... Feb 07 14:20:01 volumio systemd[1]: mpd.service: Succeeded. Feb 07 14:20:01 volumio systemd[1]: Stopped Music Player Daemon. Feb 07 14:20:01 volumio systemd[1]: Starting Music Player Daemon... Feb 07 14:20:01 volumio volumio[17575]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 07 14:20:01 volumio volumio[17575]: info: ___________ START PLUGINS ___________ Feb 07 14:20:01 volumio volumio[17575]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 07 14:20:01 volumio volumio[17575]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 07 14:20:01 volumio volumio[17575]: info: [1738938001882] CoreMusicLibrary::Adding element Media Servers Feb 07 14:20:01 volumio volumio[17575]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 14:20:01 volumio volumio[17575]: Cannot find translation for sourceMedia Servers Feb 07 14:20:01 volumio volumio[17575]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:01 volumio volumio[17575]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 14:20:01 volumio volumio[17575]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 07 14:20:01 volumio volumio[17575]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:01 volumio volumio[17575]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 14:20:01 volumio volumio[17575]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 07 14:20:01 volumio volumio[17575]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 07 14:20:01 volumio volumio[17575]: info: [1738938001978] CoreMusicLibrary::Adding element Last_100 Feb 07 14:20:01 volumio volumio[17575]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 14:20:01 volumio volumio[17575]: Cannot find translation for sourceMedia Servers Feb 07 14:20:01 volumio volumio[17575]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 07 14:20:01 volumio volumio[17575]: info: [1738938001982] CoreMusicLibrary::Adding element Webradio Feb 07 14:20:01 volumio volumio[17575]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 14:20:01 volumio volumio[17575]: Cannot find translation for sourceMedia Servers Feb 07 14:20:01 volumio volumio[17575]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 07 14:20:01 volumio volumio[17575]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 07 14:20:01 volumio volumio[17575]: info: [1738938001991] CoreMusicLibrary::Adding element LastFM Feb 07 14:20:01 volumio volumio[17575]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 14:20:01 volumio volumio[17575]: Cannot find translation for sourceMedia Servers Feb 07 14:20:01 volumio volumio[17575]: Cannot find translation for sourceLastFM Feb 07 14:20:01 volumio volumio[17575]: info: [LastFM] scrobbler initiated! Feb 07 14:20:01 volumio volumio[17575]: info: [LastFM] extended logging: false Feb 07 14:20:01 volumio volumio[17575]: info: [LastFM] try scrobble stream/radio plays: true Feb 07 14:20:02 volumio volumio[17575]: info: [LastFM] Left init routine Feb 07 14:20:02 volumio volumio[17575]: info: [LastFM] Socket already connected: true Feb 07 14:20:02 volumio volumio[17575]: info: Volumio Calling Home Feb 07 14:20:02 volumio volumio[17575]: info: Completed starting Core Plugins Feb 07 14:20:02 volumio volumio[17575]: info: ------------------------------------------- Feb 07 14:20:02 volumio volumio[17575]: info: ----- MyVolumio plugins startup ---- Feb 07 14:20:02 volumio volumio[17575]: info: ------------------------------------------- Feb 07 14:20:02 volumio volumio[17575]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 07 14:20:02 volumio volumio[17575]: Unhandled rejection Error: No sockets available, cannot start. Feb 07 14:20:02 volumio volumio[17575]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Feb 07 14:20:02 volumio volumio[17575]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Feb 07 14:20:02 volumio volumio[17575]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Feb 07 14:20:02 volumio volumio[17575]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Feb 07 14:20:02 volumio volumio[17575]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Feb 07 14:20:02 volumio volumio[17575]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Feb 07 14:20:02 volumio volumio[17575]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Feb 07 14:20:02 volumio volumio[17575]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Feb 07 14:20:02 volumio volumio[17575]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Feb 07 14:20:02 volumio volumio[17575]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Feb 07 14:20:02 volumio volumio[17575]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Feb 07 14:20:02 volumio volumio[17575]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Feb 07 14:20:02 volumio volumio[17575]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Feb 07 14:20:02 volumio volumio[17575]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Feb 07 14:20:02 volumio volumio[17575]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Feb 07 14:20:02 volumio volumio[17575]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Feb 07 14:20:02 volumio volumio[17575]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 07 14:20:02 volumio volumio[17575]: Error: getaddrinfo EBUSY ws.audioscrobbler.com Feb 07 14:20:02 volumio volumio[17575]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:67:26) { Feb 07 14:20:02 volumio volumio[17575]: errno: -16, Feb 07 14:20:02 volumio volumio[17575]: code: 'EBUSY', Feb 07 14:20:02 volumio volumio[17575]: syscall: 'getaddrinfo', Feb 07 14:20:02 volumio volumio[17575]: hostname: 'ws.audioscrobbler.com' Feb 07 14:20:02 volumio volumio[17575]: } Feb 07 14:20:02 volumio volumio[17575]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 07 14:20:02 volumio sudo[17725]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-07 14:19 Feb 07 14:20:02 volumio sudo[17725]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 07 14:20:02 volumio mpd[17712]: Feb 07 14:20 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 07 14:20:02 volumio mpd[17712]: Feb 07 14:20 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Feb 07 14:20:02 volumio mpd[17712]: Feb 07 14:20 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Feb 07 14:20:02 volumio systemd[1]: Started Music Player Daemon. Feb 07 14:20:02 volumio sudo[17699]: pam_unix(sudo:session): session closed for user root Feb 07 14:20:02 volumio sudo[17725]: pam_unix(sudo:session): session closed for user root Feb 07 14:20:02 volumio volumio-remote-updater[454]: [2025-02-07 14:20:02] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Feb 07 14:20:02 volumio volumio-remote-updater[454]: [2025-02-07 14:20:02] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Feb 07 14:20:02 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 07 14:20:02 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 07 14:20:02 volumio systemd[1]: Started dynamicswap service. Feb 07 14:20:02 volumio systemd[1]: dynamicswap.service: Succeeded. Feb 07 14:20:02 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Feb 07 14:20:02 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 25317. Feb 07 14:20:02 volumio systemd[1]: Stopped Volumio Backend Module. Feb 07 14:20:02 volumio systemd[1]: Started Volumio Backend Module. Feb 07 14:20:02 volumio systemd[1]: Started dynamicswap service. Feb 07 14:20:02 volumio systemd[1]: dynamicswap.service: Succeeded. Feb 07 14:20:03 volumio volumio[17740]: info: ------------------------------------------- Feb 07 14:20:03 volumio volumio[17740]: info: ----- Volumio3 ---- Feb 07 14:20:03 volumio volumio[17740]: info: ------------------------------------------- Feb 07 14:20:03 volumio volumio[17740]: info: ----- System startup ---- Feb 07 14:20:03 volumio volumio[17740]: info: ------------------------------------------- Feb 07 14:20:04 volumio volumio[17740]: info: MYVOLUMIO Environment detected Feb 07 14:20:04 volumio volumio[17740]: info: Plugin folders cleanup Feb 07 14:20:04 volumio volumio[17740]: info: Scanning into folder /volumio/app/plugins/ Feb 07 14:20:04 volumio volumio[17740]: info: Scanning category audio_interface Feb 07 14:20:04 volumio volumio[17740]: info: Scanning category miscellanea Feb 07 14:20:04 volumio volumio[17740]: info: Scanning category music_service Feb 07 14:20:04 volumio volumio[17740]: info: Scanning category plugins.json Feb 07 14:20:04 volumio volumio[17740]: info: Scanning category system_controller Feb 07 14:20:04 volumio volumio[17740]: info: Scanning category user_interface Feb 07 14:20:04 volumio volumio[17740]: info: Scanning into folder /data/plugins/ Feb 07 14:20:04 volumio volumio[17740]: info: Scanning category user_interface Feb 07 14:20:04 volumio volumio[17740]: info: Plugin folders cleanup completed Feb 07 14:20:04 volumio volumio[17740]: info: ------------------------------------------- Feb 07 14:20:04 volumio volumio[17740]: info: ----- Core plugins startup ---- Feb 07 14:20:04 volumio volumio[17740]: info: ------------------------------------------- Feb 07 14:20:04 volumio volumio[17740]: info: Loading plugins from folder /volumio/app/plugins/ Feb 07 14:20:04 volumio volumio[17740]: info: Adding plugin upnp to MyMusic Plugins Feb 07 14:20:04 volumio volumio[17740]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 07 14:20:04 volumio volumio[17740]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 07 14:20:04 volumio volumio[17740]: info: Loading plugins from folder /data/plugins/ Feb 07 14:20:04 volumio volumio[17740]: info: Loading plugin "system"... Feb 07 14:20:04 volumio volumio[17740]: info: Loading plugin "appearance"... Feb 07 14:20:04 volumio wpa_supplicant[697]: wlan0: Failed to initiate sched scan Feb 07 14:20:04 volumio volumio[17740]: info: Loading plugin "network"... Feb 07 14:20:04 volumio volumio[17740]: info: Refreshing Cached IP Addresses Feb 07 14:20:04 volumio sudo[17764]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 07 14:20:04 volumio sudo[17764]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 07 14:20:04 volumio sudo[17764]: pam_unix(sudo:session): session closed for user root Feb 07 14:20:04 volumio sudo[17766]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 07 14:20:04 volumio sudo[17766]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 07 14:20:04 volumio volumio[17740]: info: Loading plugin "services"... Feb 07 14:20:04 volumio volumio[17740]: info: Loading plugin "alsa_controller"... Feb 07 14:20:04 volumio sudo[17766]: pam_unix(sudo:session): session closed for user root Feb 07 14:20:04 volumio sudo[17769]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 07 14:20:04 volumio sudo[17769]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 07 14:20:05 volumio volumio[17740]: warn: Unable to locate the audio output device JDS Labs Atom DAC. Please configure a valid output device. Feb 07 14:20:05 volumio volumio[17740]: info: Loading plugin "wizard"... Feb 07 14:20:05 volumio volumio[17740]: info: Loading plugin "volumio_command_line_client"... Feb 07 14:20:05 volumio volumio[17740]: info: Loading plugin "upnp"... Feb 07 14:20:05 volumio volumio[17740]: info: [1738938005033] Starting Upmpd Daemon Feb 07 14:20:05 volumio volumio[17740]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 07 14:20:05 volumio volumio[17740]: info: Loading plugin "my_music"... Feb 07 14:20:05 volumio volumio[17740]: info: Loading plugin "mpd"... Feb 07 14:20:05 volumio volumio[17740]: info: Creating MPD Configuration file Feb 07 14:20:05 volumio sudo[17775]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 07 14:20:05 volumio sudo[17775]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 07 14:20:05 volumio volumio[17740]: info: Loading plugin "upnp_browser"... Feb 07 14:20:05 volumio sudo[17775]: pam_unix(sudo:session): session closed for user root Feb 07 14:20:05 volumio sudo[17777]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 07 14:20:05 volumio sudo[17777]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 07 14:20:05 volumio systemd[1]: Stopping Music Player Daemon... Feb 07 14:20:05 volumio systemd[1]: mpd.service: Succeeded. Feb 07 14:20:05 volumio systemd[1]: Stopped Music Player Daemon. Feb 07 14:20:05 volumio systemd[1]: Starting Music Player Daemon... Feb 07 14:20:05 volumio volumio[17740]: info: Loading plugin "networkfs"... Feb 07 14:20:05 volumio volumio[17740]: info: Starting Udev Watcher for removable devices Feb 07 14:20:05 volumio volumio[17740]: info: Ignoring mount for partition: boot Feb 07 14:20:05 volumio volumio[17740]: info: Ignoring mount for partition: volumio Feb 07 14:20:05 volumio volumio[17740]: info: Ignoring mount for partition: volumio_data Feb 07 14:20:05 volumio volumio[17740]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 07 14:20:05 volumio volumio[17740]: info: Loading plugin "alarm-clock"... Feb 07 14:20:05 volumio volumio[17740]: info: Loading plugin "airplay_emulation"... Feb 07 14:20:05 volumio volumio[17740]: info: Starting Shairport Sync Feb 07 14:20:05 volumio volumio[17740]: info: Loading plugin "last_100"... Feb 07 14:20:05 volumio volumio[17740]: info: Loading plugin "webradio"... Feb 07 14:20:06 volumio mpd[17782]: Feb 07 14:20 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 07 14:20:06 volumio mpd[17782]: Feb 07 14:20 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Feb 07 14:20:06 volumio mpd[17782]: Feb 07 14:20 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Feb 07 14:20:06 volumio systemd[1]: Started Music Player Daemon. Feb 07 14:20:06 volumio sudo[17777]: pam_unix(sudo:session): session closed for user root Feb 07 14:20:06 volumio volumio[17740]: info: Loading plugin "i2s_dacs"... Feb 07 14:20:06 volumio volumio[17740]: info: I2S DAC not set, start Auto-detection Feb 07 14:20:06 volumio volumio[17740]: info: Loading plugin "volumiodiscovery"... Feb 07 14:20:06 volumio volumio[17740]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 07 14:20:06 volumio volumio[17740]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 07 14:20:06 volumio volumio[17740]: *** WARNING *** For more information see Feb 07 14:20:06 volumio node[17740]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 07 14:20:06 volumio volumio[17740]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 07 14:20:06 volumio volumio[17740]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 07 14:20:06 volumio volumio[17740]: *** WARNING *** For more information see Feb 07 14:20:06 volumio node[17740]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 07 14:20:06 volumio node[17740]: *** WARNING *** For more information see Feb 07 14:20:06 volumio node[17740]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 07 14:20:06 volumio node[17740]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 07 14:20:06 volumio node[17740]: *** WARNING *** For more information see Feb 07 14:20:06 volumio volumio[17740]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 07 14:20:06 volumio volumio[17740]: info: Discovery: Started advertising with name: Volumio Feb 07 14:20:06 volumio volumio[17740]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 07 14:20:06 volumio volumio[17740]: info: Loading plugin "outputs"... Feb 07 14:20:06 volumio volumio[17740]: info: Loading plugin "albumart"... Feb 07 14:20:06 volumio volumio[17740]: info: Plugin example_plugin is not enabled Feb 07 14:20:06 volumio volumio[17740]: info: Loading plugin "inputs"... Feb 07 14:20:06 volumio volumio[17740]: info: Loading plugin "updater_comm"... Feb 07 14:20:06 volumio volumio[17740]: info: Plugin mpdemulation is not enabled Feb 07 14:20:06 volumio volumio[17740]: info: Loading plugin "rest_api"... Feb 07 14:20:06 volumio volumio[17740]: info: Loading plugin "websocket"... Feb 07 14:20:06 volumio volumio[17740]: info: Loading plugin "lastfm"... Feb 07 14:20:06 volumio volumio[17740]: Forking 3 albumart workers Feb 07 14:20:06 volumio ntpd[619]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Feb 07 14:20:06 volumio volumio[17740]: info: Loading i18n strings for locale en Feb 07 14:20:06 volumio volumio[17740]: Updating browse sources language Feb 07 14:20:06 volumio volumio[17740]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 14:20:06 volumio volumio[17740]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 07 14:20:06 volumio volumio[17740]: info: CoreCommandRouter::initPlayerControls Feb 07 14:20:06 volumio volumio[17740]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:06 volumio volumio[17740]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:06 volumio volumio[17740]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:06 volumio volumio[17740]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:06 volumio volumio[17740]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:06 volumio volumio[17740]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:06 volumio volumio[17740]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:06 volumio volumio[17740]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:06 volumio volumio[17740]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 07 14:20:06 volumio volumio[17740]: Express server listening on port 3000 Feb 07 14:20:06 volumio volumio[17740]: [Metrics] WebUI: 3s 186.89ms Feb 07 14:20:06 volumio volumio[17740]: info: CoreStateMachine::resetVolumioState Feb 07 14:20:06 volumio volumio[17740]: info: CoreStateMachine::getcurrentVolume Feb 07 14:20:06 volumio volumio[17740]: info: CoreCommandRouter::volumioRetrievevolume Feb 07 14:20:06 volumio volumio[17740]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 14:20:06 volumio volumio[17740]: info: MPD Permissions set Feb 07 14:20:06 volumio volumio[17740]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Feb 07 14:20:06 volumio volumio[17740]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 1 Feb 07 14:20:06 volumio volumio[17740]: info: MPD running with PID17782 Feb 07 14:20:06 volumio volumio[17740]: ,establishing connection Feb 07 14:20:06 volumio volumio[17740]: Starting albumart workers Feb 07 14:20:07 volumio volumio[17740]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:07 volumio volumio[17740]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:07 volumio volumio[17740]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:07 volumio volumio[17740]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:07 volumio volumio[17740]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:07 volumio volumio[17740]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:07 volumio volumio[17740]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:07 volumio volumio[17740]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:07 volumio volumio[17740]: Starting albumart workers Feb 07 14:20:07 volumio volumio[17740]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 14:20:07 volumio volumio[17740]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:07 volumio volumio[17740]: info: Reloading queue from file Feb 07 14:20:07 volumio volumio[17740]: error: updateQueue error: null Feb 07 14:20:07 volumio volumio[17740]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 07 14:20:07 volumio volumio[17740]: Starting albumart workers Feb 07 14:20:07 volumio volumio[17740]: info: VolumeController:: Volume=undefined Mute =false Feb 07 14:20:07 volumio volumio[17740]: info: CoreStateMachine::pushState Feb 07 14:20:07 volumio volumio[17740]: info: CorePlayQueue::getTrack 0 Feb 07 14:20:07 volumio volumio[17740]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 07 14:20:07 volumio volumio[17740]: info: CoreCommandRouter::volumioPushState Feb 07 14:20:07 volumio volumio[17740]: info: CoreStateMachine::updateTrackBlock Feb 07 14:20:07 volumio volumio[17740]: info: CorePlayQueue::getTrackBlock Feb 07 14:20:07 volumio volumio[17740]: info: CoreCommandRouter::volumioRetrievevolume Feb 07 14:20:07 volumio volumio[17740]: info: CoreStateMachine::setRepeat null single undefined Feb 07 14:20:07 volumio volumio[17740]: info: CoreStateMachine::pushState Feb 07 14:20:07 volumio volumio[17740]: info: CorePlayQueue::getTrack 0 Feb 07 14:20:07 volumio volumio[17740]: info: CoreCommandRouter::volumioPushState Feb 07 14:20:07 volumio volumio[17740]: info: CoreStateMachine::setRandom false Feb 07 14:20:07 volumio volumio[17740]: info: CoreStateMachine::pushState Feb 07 14:20:07 volumio volumio[17740]: info: CorePlayQueue::getTrack 0 Feb 07 14:20:07 volumio volumio[17740]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 07 14:20:07 volumio volumio[17740]: info: CoreCommandRouter::volumioPushState Feb 07 14:20:07 volumio volumio[17740]: info: Setting Device type: Raspberry PI Feb 07 14:20:07 volumio volumio[17740]: error: updateQueue error: null Feb 07 14:20:07 volumio volumio[17740]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Feb 07 14:20:07 volumio volumio[17740]: info: Completed loading Core Plugins Feb 07 14:20:07 volumio volumio[17740]: info: Preparing to generate the ALSA configuration file Feb 07 14:20:07 volumio volumio[17740]: info: VolumeController:: Volume=undefined Mute =false Feb 07 14:20:07 volumio volumio[17740]: info: CoreStateMachine::pushState Feb 07 14:20:07 volumio volumio[17740]: info: CorePlayQueue::getTrack 0 Feb 07 14:20:07 volumio volumio[17740]: info: CoreCommandRouter::volumioPushState Feb 07 14:20:07 volumio volumio[17740]: info: Asound.conf file unchanged, so no further update is needed Feb 07 14:20:07 volumio volumio[17740]: info: Output device has changed, restarting MPD Feb 07 14:20:07 volumio volumio[17740]: info: Output device has changed, restarting Shairport Sync Feb 07 14:20:07 volumio volumio[17740]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:07 volumio volumio[17740]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 14:20:07 volumio sudo[17841]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 07 14:20:07 volumio sudo[17841]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 07 14:20:07 volumio sudo[17835]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 07 14:20:07 volumio sudo[17835]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 07 14:20:07 volumio systemd[1]: Stopping Music Player Daemon... Feb 07 14:20:07 volumio sudo[17835]: pam_unix(sudo:session): session closed for user root Feb 07 14:20:07 volumio systemd[1]: mpd.service: Succeeded. Feb 07 14:20:07 volumio systemd[1]: Stopped Music Player Daemon. Feb 07 14:20:07 volumio systemd[1]: Starting Music Player Daemon... Feb 07 14:20:07 volumio volumio[17740]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 07 14:20:07 volumio volumio[17740]: info: ___________ START PLUGINS ___________ Feb 07 14:20:07 volumio volumio[17740]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 07 14:20:07 volumio volumio[17740]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 07 14:20:07 volumio volumio[17740]: info: [1738938007505] CoreMusicLibrary::Adding element Media Servers Feb 07 14:20:07 volumio volumio[17740]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 14:20:07 volumio volumio[17740]: Cannot find translation for sourceMedia Servers Feb 07 14:20:07 volumio volumio[17740]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:07 volumio volumio[17740]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 14:20:07 volumio sudo[17769]: pam_unix(sudo:session): session closed for user root Feb 07 14:20:07 volumio volumio[17740]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 07 14:20:07 volumio volumio[17740]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:07 volumio volumio[17740]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 14:20:07 volumio volumio[17740]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 07 14:20:07 volumio volumio[17740]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 07 14:20:07 volumio volumio[17740]: info: [1738938007620] CoreMusicLibrary::Adding element Last_100 Feb 07 14:20:07 volumio volumio[17740]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 14:20:07 volumio volumio[17740]: Cannot find translation for sourceMedia Servers Feb 07 14:20:07 volumio volumio[17740]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 07 14:20:07 volumio volumio[17740]: info: [1738938007623] CoreMusicLibrary::Adding element Webradio Feb 07 14:20:07 volumio volumio[17740]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 14:20:07 volumio volumio[17740]: Cannot find translation for sourceMedia Servers Feb 07 14:20:07 volumio volumio[17740]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 07 14:20:07 volumio volumio[17740]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 07 14:20:07 volumio volumio[17740]: info: [1738938007632] CoreMusicLibrary::Adding element LastFM Feb 07 14:20:07 volumio volumio[17740]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 14:20:07 volumio volumio[17740]: Cannot find translation for sourceMedia Servers Feb 07 14:20:07 volumio volumio[17740]: Cannot find translation for sourceLastFM Feb 07 14:20:07 volumio volumio[17740]: info: [LastFM] scrobbler initiated! Feb 07 14:20:07 volumio volumio[17740]: info: [LastFM] extended logging: false Feb 07 14:20:07 volumio volumio[17740]: info: [LastFM] try scrobble stream/radio plays: true Feb 07 14:20:07 volumio volumio[17740]: info: [LastFM] Left init routine Feb 07 14:20:07 volumio volumio[17740]: info: [LastFM] Socket already connected: true Feb 07 14:20:07 volumio volumio[17740]: info: Volumio Calling Home Feb 07 14:20:07 volumio volumio-remote-updater[454]: [2025-02-07 14:20:07] [connect] Successful connection Feb 07 14:20:07 volumio volumio[17740]: info: Completed starting Core Plugins Feb 07 14:20:07 volumio volumio[17740]: info: ------------------------------------------- Feb 07 14:20:07 volumio volumio[17740]: info: ----- MyVolumio plugins startup ---- Feb 07 14:20:07 volumio volumio[17740]: info: ------------------------------------------- Feb 07 14:20:07 volumio volumio[17740]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 07 14:20:07 volumio volumio[17740]: Unhandled rejection Error: No sockets available, cannot start. Feb 07 14:20:07 volumio volumio[17740]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Feb 07 14:20:07 volumio volumio[17740]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Feb 07 14:20:07 volumio volumio[17740]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Feb 07 14:20:07 volumio volumio[17740]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Feb 07 14:20:07 volumio volumio[17740]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Feb 07 14:20:07 volumio volumio[17740]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Feb 07 14:20:07 volumio volumio[17740]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Feb 07 14:20:07 volumio volumio[17740]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Feb 07 14:20:07 volumio volumio[17740]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Feb 07 14:20:07 volumio volumio[17740]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Feb 07 14:20:07 volumio volumio[17740]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Feb 07 14:20:07 volumio volumio[17740]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Feb 07 14:20:07 volumio volumio[17740]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Feb 07 14:20:07 volumio volumio[17740]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Feb 07 14:20:07 volumio volumio[17740]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Feb 07 14:20:07 volumio volumio[17740]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Feb 07 14:20:07 volumio volumio[17740]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 07 14:20:07 volumio volumio[17740]: Error: getaddrinfo EBUSY ws.audioscrobbler.com Feb 07 14:20:07 volumio volumio[17740]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:67:26) { Feb 07 14:20:07 volumio volumio[17740]: errno: -16, Feb 07 14:20:07 volumio volumio[17740]: code: 'EBUSY', Feb 07 14:20:07 volumio volumio[17740]: syscall: 'getaddrinfo', Feb 07 14:20:07 volumio volumio[17740]: hostname: 'ws.audioscrobbler.com' Feb 07 14:20:07 volumio volumio[17740]: } Feb 07 14:20:07 volumio volumio[17740]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 07 14:20:08 volumio sudo[17887]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-07 14:19 Feb 07 14:20:08 volumio sudo[17887]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 07 14:20:08 volumio mpd[17874]: Feb 07 14:20 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 07 14:20:08 volumio mpd[17874]: Feb 07 14:20 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Feb 07 14:20:08 volumio mpd[17874]: Feb 07 14:20 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Feb 07 14:20:08 volumio systemd[1]: Started Music Player Daemon. Feb 07 14:20:08 volumio sudo[17841]: pam_unix(sudo:session): session closed for user root Feb 07 14:20:08 volumio sudo[17887]: pam_unix(sudo:session): session closed for user root Feb 07 14:20:08 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 07 14:20:08 volumio volumio-remote-updater[454]: [2025-02-07 14:20:08] [info] asio async_read_at_least error: system:104 (Connection reset by peer) Feb 07 14:20:08 volumio volumio-remote-updater[454]: [2025-02-07 14:20:08] [error] handle_read_http_response error: websocketpp.transport:2 (Underlying Transport Error) Feb 07 14:20:08 volumio volumio-remote-updater[454]: [2025-02-07 14:20:08] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Feb 07 14:20:08 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 07 14:20:08 volumio systemd[1]: Started dynamicswap service. Feb 07 14:20:08 volumio systemd[1]: dynamicswap.service: Succeeded. Feb 07 14:20:08 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Feb 07 14:20:08 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 25318. Feb 07 14:20:08 volumio systemd[1]: Started dynamicswap service. Feb 07 14:20:08 volumio systemd[1]: Stopped Volumio Backend Module. Feb 07 14:20:08 volumio systemd[1]: Started Volumio Backend Module. Feb 07 14:20:08 volumio systemd[1]: dynamicswap.service: Succeeded. Feb 07 14:20:09 volumio volumio[17903]: info: ------------------------------------------- Feb 07 14:20:09 volumio volumio[17903]: info: ----- Volumio3 ---- Feb 07 14:20:09 volumio volumio[17903]: info: ------------------------------------------- Feb 07 14:20:09 volumio volumio[17903]: info: ----- System startup ---- Feb 07 14:20:09 volumio volumio[17903]: info: ------------------------------------------- Feb 07 14:20:10 volumio volumio[17903]: info: MYVOLUMIO Environment detected Feb 07 14:20:10 volumio volumio[17903]: info: Plugin folders cleanup Feb 07 14:20:10 volumio volumio[17903]: info: Scanning into folder /volumio/app/plugins/ Feb 07 14:20:10 volumio volumio[17903]: info: Scanning category audio_interface Feb 07 14:20:10 volumio volumio[17903]: info: Scanning category miscellanea Feb 07 14:20:10 volumio volumio[17903]: info: Scanning category music_service Feb 07 14:20:10 volumio volumio[17903]: info: Scanning category plugins.json Feb 07 14:20:10 volumio volumio[17903]: info: Scanning category system_controller Feb 07 14:20:10 volumio volumio[17903]: info: Scanning category user_interface Feb 07 14:20:10 volumio volumio[17903]: info: Scanning into folder /data/plugins/ Feb 07 14:20:10 volumio volumio[17903]: info: Scanning category user_interface Feb 07 14:20:10 volumio volumio[17903]: info: Plugin folders cleanup completed Feb 07 14:20:10 volumio volumio[17903]: info: ------------------------------------------- Feb 07 14:20:10 volumio volumio[17903]: info: ----- Core plugins startup ---- Feb 07 14:20:10 volumio volumio[17903]: info: ------------------------------------------- Feb 07 14:20:10 volumio volumio[17903]: info: Loading plugins from folder /volumio/app/plugins/ Feb 07 14:20:10 volumio volumio[17903]: info: Adding plugin upnp to MyMusic Plugins Feb 07 14:20:10 volumio volumio[17903]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 07 14:20:10 volumio volumio[17903]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 07 14:20:10 volumio volumio[17903]: info: Loading plugins from folder /data/plugins/ Feb 07 14:20:10 volumio volumio[17903]: info: Loading plugin "system"... Feb 07 14:20:10 volumio volumio[17903]: info: Loading plugin "appearance"... Feb 07 14:20:10 volumio volumio[17903]: info: Loading plugin "network"... Feb 07 14:20:10 volumio volumio[17903]: info: Refreshing Cached IP Addresses Feb 07 14:20:10 volumio sudo[17926]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 07 14:20:10 volumio sudo[17926]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 07 14:20:10 volumio sudo[17926]: pam_unix(sudo:session): session closed for user root Feb 07 14:20:10 volumio sudo[17928]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 07 14:20:10 volumio sudo[17928]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 07 14:20:10 volumio volumio[17903]: info: Loading plugin "services"... Feb 07 14:20:10 volumio volumio[17903]: info: Loading plugin "alsa_controller"... Feb 07 14:20:10 volumio sudo[17928]: pam_unix(sudo:session): session closed for user root Feb 07 14:20:10 volumio sudo[17931]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 07 14:20:10 volumio sudo[17931]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 07 14:20:10 volumio sudo[17931]: pam_unix(sudo:session): session closed for user root Feb 07 14:20:10 volumio volumio[17903]: warn: Unable to locate the audio output device JDS Labs Atom DAC. Please configure a valid output device. Feb 07 14:20:10 volumio volumio[17903]: info: Loading plugin "wizard"... Feb 07 14:20:10 volumio volumio[17903]: info: Loading plugin "volumio_command_line_client"... Feb 07 14:20:10 volumio volumio[17903]: info: Loading plugin "upnp"... Feb 07 14:20:10 volumio volumio[17903]: info: [1738938010751] Starting Upmpd Daemon Feb 07 14:20:10 volumio volumio[17903]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 07 14:20:10 volumio volumio[17903]: info: Loading plugin "my_music"... Feb 07 14:20:10 volumio volumio[17903]: info: Loading plugin "mpd"... Feb 07 14:20:10 volumio volumio[17903]: info: Creating MPD Configuration file Feb 07 14:20:10 volumio sudo[17937]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 07 14:20:10 volumio volumio[17903]: info: Loading plugin "upnp_browser"... Feb 07 14:20:10 volumio sudo[17937]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 07 14:20:10 volumio sudo[17937]: pam_unix(sudo:session): session closed for user root Feb 07 14:20:11 volumio sudo[17939]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 07 14:20:11 volumio sudo[17939]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 07 14:20:11 volumio systemd[1]: Stopping Music Player Daemon... Feb 07 14:20:11 volumio systemd[1]: mpd.service: Succeeded. Feb 07 14:20:11 volumio systemd[1]: Stopped Music Player Daemon. Feb 07 14:20:11 volumio systemd[1]: Starting Music Player Daemon... Feb 07 14:20:11 volumio volumio[17903]: info: Loading plugin "networkfs"... Feb 07 14:20:11 volumio volumio[17903]: info: Starting Udev Watcher for removable devices Feb 07 14:20:11 volumio volumio[17903]: info: Ignoring mount for partition: boot Feb 07 14:20:11 volumio volumio[17903]: info: Ignoring mount for partition: volumio Feb 07 14:20:11 volumio volumio[17903]: info: Ignoring mount for partition: volumio_data Feb 07 14:20:11 volumio volumio[17903]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 07 14:20:11 volumio volumio[17903]: info: Loading plugin "alarm-clock"... Feb 07 14:20:11 volumio volumio[17903]: info: Loading plugin "airplay_emulation"... Feb 07 14:20:11 volumio volumio[17903]: info: Starting Shairport Sync Feb 07 14:20:11 volumio volumio[17903]: info: Loading plugin "last_100"... Feb 07 14:20:11 volumio volumio[17903]: info: Loading plugin "webradio"... Feb 07 14:20:11 volumio mpd[17944]: Feb 07 14:20 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 07 14:20:11 volumio mpd[17944]: Feb 07 14:20 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Feb 07 14:20:11 volumio mpd[17944]: Feb 07 14:20 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Feb 07 14:20:11 volumio systemd[1]: Started Music Player Daemon. Feb 07 14:20:11 volumio sudo[17939]: pam_unix(sudo:session): session closed for user root Feb 07 14:20:11 volumio volumio[17903]: info: Loading plugin "i2s_dacs"... Feb 07 14:20:11 volumio volumio[17903]: info: I2S DAC not set, start Auto-detection Feb 07 14:20:11 volumio volumio[17903]: info: Loading plugin "volumiodiscovery"... Feb 07 14:20:11 volumio volumio[17903]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 07 14:20:11 volumio volumio[17903]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 07 14:20:11 volumio volumio[17903]: *** WARNING *** For more information see Feb 07 14:20:11 volumio node[17903]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 07 14:20:11 volumio volumio[17903]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 07 14:20:11 volumio volumio[17903]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 07 14:20:11 volumio volumio[17903]: *** WARNING *** For more information see Feb 07 14:20:11 volumio node[17903]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 07 14:20:11 volumio node[17903]: *** WARNING *** For more information see Feb 07 14:20:11 volumio node[17903]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 07 14:20:11 volumio node[17903]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 07 14:20:11 volumio node[17903]: *** WARNING *** For more information see Feb 07 14:20:11 volumio volumio[17903]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 07 14:20:11 volumio volumio[17903]: info: Discovery: Started advertising with name: Volumio Feb 07 14:20:11 volumio volumio[17903]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 07 14:20:11 volumio volumio[17903]: info: Loading plugin "outputs"... Feb 07 14:20:11 volumio volumio[17903]: info: Loading plugin "albumart"... Feb 07 14:20:11 volumio volumio[17903]: info: Plugin example_plugin is not enabled Feb 07 14:20:11 volumio volumio[17903]: info: Loading plugin "inputs"... Feb 07 14:20:11 volumio volumio[17903]: info: Loading plugin "updater_comm"... Feb 07 14:20:11 volumio volumio[17903]: info: Plugin mpdemulation is not enabled Feb 07 14:20:11 volumio volumio[17903]: info: Loading plugin "rest_api"... Feb 07 14:20:11 volumio wpa_supplicant[697]: wlan0: Failed to initiate sched scan Feb 07 14:20:11 volumio volumio[17903]: info: Loading plugin "websocket"... Feb 07 14:20:11 volumio volumio[17903]: info: Loading plugin "lastfm"... Feb 07 14:20:12 volumio volumio[17903]: Forking 3 albumart workers Feb 07 14:20:12 volumio volumio[17903]: info: Loading i18n strings for locale en Feb 07 14:20:12 volumio volumio[17903]: Updating browse sources language Feb 07 14:20:12 volumio volumio[17903]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 14:20:12 volumio volumio[17903]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 07 14:20:12 volumio volumio[17903]: info: CoreCommandRouter::initPlayerControls Feb 07 14:20:12 volumio volumio[17903]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:12 volumio volumio[17903]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:12 volumio volumio[17903]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:12 volumio volumio[17903]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:12 volumio volumio[17903]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:12 volumio volumio[17903]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:12 volumio volumio[17903]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:12 volumio volumio[17903]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:12 volumio volumio[17903]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 07 14:20:12 volumio volumio[17903]: Express server listening on port 3000 Feb 07 14:20:12 volumio volumio[17903]: [Metrics] WebUI: 3s 115.81ms Feb 07 14:20:12 volumio volumio[17903]: info: CoreStateMachine::resetVolumioState Feb 07 14:20:12 volumio volumio[17903]: info: CoreStateMachine::getcurrentVolume Feb 07 14:20:12 volumio volumio[17903]: info: CoreCommandRouter::volumioRetrievevolume Feb 07 14:20:12 volumio volumio[17903]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 14:20:12 volumio volumio[17903]: info: MPD Permissions set Feb 07 14:20:12 volumio volumio[17903]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Feb 07 14:20:12 volumio volumio[17903]: wlan0 Interface doesn't support scanning : Device or resource busy Feb 07 14:20:12 volumio volumio[17903]: info: Cannot use regular scanning, forcing with ap-force Feb 07 14:20:12 volumio sudo[17985]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Feb 07 14:20:12 volumio sudo[17985]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 07 14:20:12 volumio volumio[17903]: Starting albumart workers Feb 07 14:20:12 volumio volumio[17903]: Starting albumart workers Feb 07 14:20:12 volumio volumio[17903]: Starting albumart workers Feb 07 14:20:15 volumio sudo[17985]: pam_unix(sudo:session): session closed for user root Feb 07 14:20:15 volumio volumio[17903]: info: MPD running with PID17944 Feb 07 14:20:15 volumio volumio[17903]: ,establishing connection Feb 07 14:20:15 volumio volumio[17903]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Feb 07 14:20:15 volumio volumio[17903]: error: MPD error: The expression evaluated to a falsy value: Feb 07 14:20:15 volumio volumio[17903]: assert.ok(self.idling) Feb 07 14:20:15 volumio volumio[17903]: error: MPD error: The expression evaluated to a falsy value: Feb 07 14:20:15 volumio volumio[17903]: assert.ok(self.idling) Feb 07 14:20:15 volumio volumio[17903]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 1 Feb 07 14:20:15 volumio volumio[17903]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:15 volumio volumio[17903]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:15 volumio volumio[17903]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:15 volumio volumio[17903]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:15 volumio volumio[17903]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:15 volumio volumio[17903]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:15 volumio volumio[17903]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:15 volumio volumio[17903]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:15 volumio volumio[17903]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 14:20:15 volumio volumio[17903]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:15 volumio volumio[17903]: info: Reloading queue from file Feb 07 14:20:15 volumio volumio[17903]: info: VolumeController:: Volume=undefined Mute =false Feb 07 14:20:15 volumio volumio[17903]: info: CoreStateMachine::pushState Feb 07 14:20:15 volumio volumio[17903]: info: CorePlayQueue::getTrack 0 Feb 07 14:20:15 volumio volumio[17903]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 07 14:20:15 volumio volumio[17903]: info: CoreCommandRouter::volumioPushState Feb 07 14:20:15 volumio volumio[17903]: info: CoreStateMachine::updateTrackBlock Feb 07 14:20:15 volumio volumio[17903]: info: CorePlayQueue::getTrackBlock Feb 07 14:20:15 volumio volumio[17903]: info: CoreCommandRouter::volumioRetrievevolume Feb 07 14:20:15 volumio volumio[17903]: info: CoreStateMachine::setRepeat null single undefined Feb 07 14:20:15 volumio volumio[17903]: info: CoreStateMachine::pushState Feb 07 14:20:15 volumio volumio[17903]: info: CorePlayQueue::getTrack 0 Feb 07 14:20:15 volumio volumio[17903]: info: CoreCommandRouter::volumioPushState Feb 07 14:20:15 volumio volumio[17903]: info: CoreStateMachine::setRandom false Feb 07 14:20:15 volumio volumio[17903]: info: CoreStateMachine::pushState Feb 07 14:20:15 volumio volumio[17903]: info: CorePlayQueue::getTrack 0 Feb 07 14:20:15 volumio volumio[17903]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 07 14:20:15 volumio volumio[17903]: info: CoreCommandRouter::volumioPushState Feb 07 14:20:15 volumio volumio[17903]: info: Setting Device type: Raspberry PI Feb 07 14:20:15 volumio volumio[17903]: error: updateQueue error: null Feb 07 14:20:15 volumio volumio[17903]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 07 14:20:15 volumio volumio[17903]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Feb 07 14:20:15 volumio volumio[17903]: info: VolumeController:: Volume=undefined Mute =false Feb 07 14:20:15 volumio volumio[17903]: info: CoreStateMachine::pushState Feb 07 14:20:15 volumio volumio[17903]: info: CorePlayQueue::getTrack 0 Feb 07 14:20:15 volumio volumio[17903]: info: CoreCommandRouter::volumioPushState Feb 07 14:20:15 volumio volumio[17903]: info: Completed loading Core Plugins Feb 07 14:20:15 volumio volumio[17903]: info: Preparing to generate the ALSA configuration file Feb 07 14:20:15 volumio volumio[17903]: info: Asound.conf file unchanged, so no further update is needed Feb 07 14:20:15 volumio volumio[17903]: info: Output device has changed, restarting MPD Feb 07 14:20:15 volumio sudo[18023]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 07 14:20:15 volumio sudo[18023]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 07 14:20:15 volumio volumio[17903]: info: Output device has changed, restarting Shairport Sync Feb 07 14:20:15 volumio volumio[17903]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:15 volumio volumio[17903]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 14:20:15 volumio sudo[18023]: pam_unix(sudo:session): session closed for user root Feb 07 14:20:15 volumio sudo[18026]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 07 14:20:15 volumio sudo[18026]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 07 14:20:15 volumio systemd[1]: Stopping Music Player Daemon... Feb 07 14:20:15 volumio volumio[17903]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 07 14:20:15 volumio volumio[17903]: info: ___________ START PLUGINS ___________ Feb 07 14:20:15 volumio volumio[17903]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 07 14:20:15 volumio volumio[17903]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 07 14:20:15 volumio volumio[17903]: info: [1738938015567] CoreMusicLibrary::Adding element Media Servers Feb 07 14:20:15 volumio volumio[17903]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 14:20:15 volumio systemd[1]: mpd.service: Succeeded. Feb 07 14:20:15 volumio volumio[17903]: Cannot find translation for sourceMedia Servers Feb 07 14:20:15 volumio systemd[1]: Stopped Music Player Daemon. Feb 07 14:20:15 volumio systemd[1]: Starting Music Player Daemon... Feb 07 14:20:15 volumio volumio[17903]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:15 volumio volumio[17903]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 14:20:15 volumio volumio[17903]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 07 14:20:15 volumio volumio[17903]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:15 volumio volumio[17903]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 14:20:15 volumio volumio[17903]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 07 14:20:15 volumio volumio[17903]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 07 14:20:15 volumio volumio[17903]: info: [1738938015661] CoreMusicLibrary::Adding element Last_100 Feb 07 14:20:15 volumio volumio[17903]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 14:20:15 volumio volumio[17903]: Cannot find translation for sourceMedia Servers Feb 07 14:20:15 volumio volumio[17903]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 07 14:20:15 volumio volumio[17903]: info: [1738938015663] CoreMusicLibrary::Adding element Webradio Feb 07 14:20:15 volumio volumio[17903]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 14:20:15 volumio volumio[17903]: Cannot find translation for sourceMedia Servers Feb 07 14:20:15 volumio volumio[17903]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 07 14:20:15 volumio volumio[17903]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 07 14:20:15 volumio volumio[17903]: info: [1738938015670] CoreMusicLibrary::Adding element LastFM Feb 07 14:20:15 volumio volumio[17903]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 14:20:15 volumio volumio[17903]: Cannot find translation for sourceMedia Servers Feb 07 14:20:15 volumio volumio[17903]: Cannot find translation for sourceLastFM Feb 07 14:20:15 volumio volumio[17903]: info: [LastFM] scrobbler initiated! Feb 07 14:20:15 volumio volumio[17903]: info: [LastFM] extended logging: false Feb 07 14:20:15 volumio volumio[17903]: info: [LastFM] try scrobble stream/radio plays: true Feb 07 14:20:15 volumio volumio[17903]: info: [LastFM] Left init routine Feb 07 14:20:15 volumio volumio[17903]: info: [LastFM] Socket already connected: true Feb 07 14:20:15 volumio volumio[17903]: info: Volumio Calling Home Feb 07 14:20:15 volumio volumio[17903]: info: Completed starting Core Plugins Feb 07 14:20:15 volumio volumio[17903]: info: ------------------------------------------- Feb 07 14:20:15 volumio volumio[17903]: info: ----- MyVolumio plugins startup ---- Feb 07 14:20:15 volumio volumio[17903]: info: ------------------------------------------- Feb 07 14:20:15 volumio volumio[17903]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 07 14:20:15 volumio volumio[17903]: Unhandled rejection Error: No sockets available, cannot start. Feb 07 14:20:15 volumio volumio[17903]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Feb 07 14:20:15 volumio volumio[17903]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Feb 07 14:20:15 volumio volumio[17903]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Feb 07 14:20:15 volumio volumio[17903]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Feb 07 14:20:15 volumio volumio[17903]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Feb 07 14:20:15 volumio volumio[17903]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Feb 07 14:20:15 volumio volumio[17903]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Feb 07 14:20:15 volumio volumio[17903]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Feb 07 14:20:15 volumio volumio[17903]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Feb 07 14:20:15 volumio volumio[17903]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Feb 07 14:20:15 volumio volumio[17903]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Feb 07 14:20:15 volumio volumio[17903]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Feb 07 14:20:15 volumio volumio[17903]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Feb 07 14:20:15 volumio volumio[17903]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Feb 07 14:20:15 volumio volumio[17903]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Feb 07 14:20:15 volumio volumio[17903]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Feb 07 14:20:15 volumio volumio[17903]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 07 14:20:15 volumio volumio[17903]: Error: getaddrinfo EBUSY ws.audioscrobbler.com Feb 07 14:20:15 volumio volumio[17903]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:67:26) { Feb 07 14:20:15 volumio volumio[17903]: errno: -16, Feb 07 14:20:15 volumio volumio[17903]: code: 'EBUSY', Feb 07 14:20:15 volumio volumio[17903]: syscall: 'getaddrinfo', Feb 07 14:20:15 volumio volumio[17903]: hostname: 'ws.audioscrobbler.com' Feb 07 14:20:15 volumio volumio[17903]: } Feb 07 14:20:15 volumio volumio[17903]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 07 14:20:15 volumio volumio-remote-updater[454]: [2025-02-07 14:20:15] [connect] Successful connection Feb 07 14:20:16 volumio sudo[18052]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-07 14:19 Feb 07 14:20:16 volumio sudo[18052]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 07 14:20:16 volumio mpd[18040]: Feb 07 14:20 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 07 14:20:16 volumio mpd[18040]: Feb 07 14:20 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Feb 07 14:20:16 volumio mpd[18040]: Feb 07 14:20 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Feb 07 14:20:16 volumio systemd[1]: Started Music Player Daemon. Feb 07 14:20:16 volumio sudo[18026]: pam_unix(sudo:session): session closed for user root Feb 07 14:20:16 volumio sudo[18052]: pam_unix(sudo:session): session closed for user root Feb 07 14:20:16 volumio volumio-remote-updater[454]: [2025-02-07 14:20:16] [info] asio async_read_at_least error: system:104 (Connection reset by peer) Feb 07 14:20:16 volumio volumio-remote-updater[454]: [2025-02-07 14:20:16] [error] handle_read_http_response error: websocketpp.transport:2 (Underlying Transport Error) Feb 07 14:20:16 volumio volumio-remote-updater[454]: [2025-02-07 14:20:16] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Feb 07 14:20:16 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 07 14:20:16 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 07 14:20:16 volumio systemd[1]: Started dynamicswap service. Feb 07 14:20:16 volumio systemd[1]: dynamicswap.service: Succeeded. Feb 07 14:20:16 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Feb 07 14:20:16 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 25319. Feb 07 14:20:16 volumio systemd[1]: Started dynamicswap service. Feb 07 14:20:16 volumio systemd[1]: Stopped Volumio Backend Module. Feb 07 14:20:16 volumio systemd[1]: Started Volumio Backend Module. Feb 07 14:20:16 volumio systemd[1]: dynamicswap.service: Succeeded. Feb 07 14:20:17 volumio volumio[18068]: info: ------------------------------------------- Feb 07 14:20:17 volumio volumio[18068]: info: ----- Volumio3 ---- Feb 07 14:20:17 volumio volumio[18068]: info: ------------------------------------------- Feb 07 14:20:17 volumio volumio[18068]: info: ----- System startup ---- Feb 07 14:20:17 volumio volumio[18068]: info: ------------------------------------------- Feb 07 14:20:18 volumio volumio[18068]: info: MYVOLUMIO Environment detected Feb 07 14:20:18 volumio volumio[18068]: info: Plugin folders cleanup Feb 07 14:20:18 volumio volumio[18068]: info: Scanning into folder /volumio/app/plugins/ Feb 07 14:20:18 volumio volumio[18068]: info: Scanning category audio_interface Feb 07 14:20:18 volumio volumio[18068]: info: Scanning category miscellanea Feb 07 14:20:18 volumio volumio[18068]: info: Scanning category music_service Feb 07 14:20:18 volumio volumio[18068]: info: Scanning category plugins.json Feb 07 14:20:18 volumio volumio[18068]: info: Scanning category system_controller Feb 07 14:20:18 volumio volumio[18068]: info: Scanning category user_interface Feb 07 14:20:18 volumio volumio[18068]: info: Scanning into folder /data/plugins/ Feb 07 14:20:18 volumio volumio[18068]: info: Scanning category user_interface Feb 07 14:20:18 volumio volumio[18068]: info: Plugin folders cleanup completed Feb 07 14:20:18 volumio volumio[18068]: info: ------------------------------------------- Feb 07 14:20:18 volumio volumio[18068]: info: ----- Core plugins startup ---- Feb 07 14:20:18 volumio volumio[18068]: info: ------------------------------------------- Feb 07 14:20:18 volumio volumio[18068]: info: Loading plugins from folder /volumio/app/plugins/ Feb 07 14:20:18 volumio volumio[18068]: info: Adding plugin upnp to MyMusic Plugins Feb 07 14:20:18 volumio volumio[18068]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 07 14:20:18 volumio volumio[18068]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 07 14:20:18 volumio volumio[18068]: info: Loading plugins from folder /data/plugins/ Feb 07 14:20:18 volumio volumio[18068]: info: Loading plugin "system"... Feb 07 14:20:18 volumio volumio[18068]: info: Loading plugin "appearance"... Feb 07 14:20:18 volumio volumio[18068]: info: Loading plugin "network"... Feb 07 14:20:18 volumio volumio[18068]: info: Refreshing Cached IP Addresses Feb 07 14:20:18 volumio sudo[18091]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 07 14:20:18 volumio sudo[18091]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 07 14:20:18 volumio sudo[18091]: pam_unix(sudo:session): session closed for user root Feb 07 14:20:18 volumio sudo[18093]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 07 14:20:18 volumio sudo[18093]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 07 14:20:18 volumio volumio[18068]: info: Loading plugin "services"... Feb 07 14:20:18 volumio sudo[18093]: pam_unix(sudo:session): session closed for user root Feb 07 14:20:18 volumio volumio[18068]: info: Loading plugin "alsa_controller"... Feb 07 14:20:18 volumio sudo[18097]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 07 14:20:18 volumio sudo[18097]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 07 14:20:18 volumio sudo[18097]: pam_unix(sudo:session): session closed for user root Feb 07 14:20:18 volumio volumio[18068]: warn: Unable to locate the audio output device JDS Labs Atom DAC. Please configure a valid output device. Feb 07 14:20:18 volumio volumio[18068]: info: Loading plugin "wizard"... Feb 07 14:20:18 volumio volumio[18068]: info: Loading plugin "volumio_command_line_client"... Feb 07 14:20:18 volumio volumio[18068]: info: Loading plugin "upnp"... Feb 07 14:20:18 volumio volumio[18068]: info: [1738938018786] Starting Upmpd Daemon Feb 07 14:20:18 volumio volumio[18068]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 07 14:20:18 volumio volumio[18068]: info: Loading plugin "my_music"... Feb 07 14:20:18 volumio volumio[18068]: info: Loading plugin "mpd"... Feb 07 14:20:18 volumio volumio[18068]: info: Creating MPD Configuration file Feb 07 14:20:19 volumio sudo[18102]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 07 14:20:19 volumio volumio[18068]: info: Loading plugin "upnp_browser"... Feb 07 14:20:19 volumio sudo[18102]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 07 14:20:19 volumio sudo[18102]: pam_unix(sudo:session): session closed for user root Feb 07 14:20:19 volumio sudo[18104]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 07 14:20:19 volumio sudo[18104]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 07 14:20:19 volumio systemd[1]: Stopping Music Player Daemon... Feb 07 14:20:19 volumio systemd[1]: mpd.service: Succeeded. Feb 07 14:20:19 volumio systemd[1]: Stopped Music Player Daemon. Feb 07 14:20:19 volumio systemd[1]: Starting Music Player Daemon... Feb 07 14:20:19 volumio volumio[18068]: info: Loading plugin "networkfs"... Feb 07 14:20:19 volumio volumio[18068]: info: Starting Udev Watcher for removable devices Feb 07 14:20:19 volumio wpa_supplicant[697]: wlan0: Failed to initiate sched scan Feb 07 14:20:19 volumio volumio[18068]: info: Ignoring mount for partition: boot Feb 07 14:20:19 volumio volumio[18068]: info: Ignoring mount for partition: volumio Feb 07 14:20:19 volumio volumio[18068]: info: Ignoring mount for partition: volumio_data Feb 07 14:20:19 volumio volumio[18068]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 07 14:20:19 volumio volumio[18068]: info: Loading plugin "alarm-clock"... Feb 07 14:20:19 volumio volumio[18068]: info: Loading plugin "airplay_emulation"... Feb 07 14:20:19 volumio volumio[18068]: info: Starting Shairport Sync Feb 07 14:20:19 volumio volumio[18068]: info: Loading plugin "last_100"... Feb 07 14:20:19 volumio volumio[18068]: info: Loading plugin "webradio"... Feb 07 14:20:19 volumio mpd[18109]: Feb 07 14:20 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 07 14:20:19 volumio mpd[18109]: Feb 07 14:20 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Feb 07 14:20:19 volumio mpd[18109]: Feb 07 14:20 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Feb 07 14:20:19 volumio systemd[1]: Started Music Player Daemon. Feb 07 14:20:19 volumio sudo[18104]: pam_unix(sudo:session): session closed for user root Feb 07 14:20:19 volumio volumio[18068]: info: Loading plugin "i2s_dacs"... Feb 07 14:20:19 volumio volumio[18068]: info: I2S DAC not set, start Auto-detection Feb 07 14:20:19 volumio volumio[18068]: info: Loading plugin "volumiodiscovery"... Feb 07 14:20:19 volumio volumio[18068]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 07 14:20:19 volumio volumio[18068]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 07 14:20:19 volumio volumio[18068]: *** WARNING *** For more information see Feb 07 14:20:19 volumio node[18068]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 07 14:20:19 volumio volumio[18068]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 07 14:20:19 volumio volumio[18068]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 07 14:20:19 volumio volumio[18068]: *** WARNING *** For more information see Feb 07 14:20:19 volumio node[18068]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 07 14:20:19 volumio node[18068]: *** WARNING *** For more information see Feb 07 14:20:19 volumio node[18068]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 07 14:20:19 volumio node[18068]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 07 14:20:19 volumio node[18068]: *** WARNING *** For more information see Feb 07 14:20:19 volumio volumio[18068]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 07 14:20:19 volumio volumio[18068]: info: Discovery: Started advertising with name: Volumio Feb 07 14:20:19 volumio volumio[18068]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 07 14:20:19 volumio volumio[18068]: info: Loading plugin "outputs"... Feb 07 14:20:19 volumio volumio[18068]: info: Loading plugin "albumart"... Feb 07 14:20:19 volumio volumio[18068]: info: Plugin example_plugin is not enabled Feb 07 14:20:19 volumio volumio[18068]: info: Loading plugin "inputs"... Feb 07 14:20:19 volumio volumio[18068]: info: Loading plugin "updater_comm"... Feb 07 14:20:19 volumio volumio[18068]: info: Plugin mpdemulation is not enabled Feb 07 14:20:19 volumio volumio[18068]: info: Loading plugin "rest_api"... Feb 07 14:20:20 volumio volumio[18068]: info: Loading plugin "websocket"... Feb 07 14:20:20 volumio volumio[18068]: info: Loading plugin "lastfm"... Feb 07 14:20:20 volumio volumio[18068]: Forking 3 albumart workers Feb 07 14:20:20 volumio volumio[18068]: info: Loading i18n strings for locale en Feb 07 14:20:20 volumio volumio[18068]: Updating browse sources language Feb 07 14:20:20 volumio volumio[18068]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 14:20:20 volumio volumio[18068]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 07 14:20:20 volumio volumio[18068]: info: CoreCommandRouter::initPlayerControls Feb 07 14:20:20 volumio volumio[18068]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:20 volumio volumio[18068]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:20 volumio volumio[18068]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:20 volumio volumio[18068]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:20 volumio volumio[18068]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:20 volumio volumio[18068]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:20 volumio volumio[18068]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:20 volumio volumio[18068]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:20 volumio volumio[18068]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 07 14:20:20 volumio volumio[18068]: Express server listening on port 3000 Feb 07 14:20:20 volumio volumio[18068]: [Metrics] WebUI: 3s 175.87ms Feb 07 14:20:20 volumio volumio[18068]: info: CoreStateMachine::resetVolumioState Feb 07 14:20:20 volumio volumio[18068]: info: CoreStateMachine::getcurrentVolume Feb 07 14:20:20 volumio volumio[18068]: info: CoreCommandRouter::volumioRetrievevolume Feb 07 14:20:20 volumio volumio[18068]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 14:20:20 volumio volumio[18068]: info: MPD Permissions set Feb 07 14:20:20 volumio volumio[18068]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Feb 07 14:20:20 volumio volumio[18068]: wlan0 Interface doesn't support scanning : Device or resource busy Feb 07 14:20:20 volumio volumio[18068]: info: Cannot use regular scanning, forcing with ap-force Feb 07 14:20:20 volumio sudo[18150]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Feb 07 14:20:20 volumio sudo[18150]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 07 14:20:20 volumio volumio[18068]: Starting albumart workers Feb 07 14:20:20 volumio volumio[18068]: Starting albumart workers Feb 07 14:20:20 volumio volumio[18068]: Starting albumart workers Feb 07 14:20:21 volumio ntpd[619]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Feb 07 14:20:23 volumio sudo[18150]: pam_unix(sudo:session): session closed for user root Feb 07 14:20:23 volumio volumio[18068]: info: MPD running with PID18109 Feb 07 14:20:23 volumio volumio[18068]: ,establishing connection Feb 07 14:20:23 volumio volumio[18068]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Feb 07 14:20:23 volumio volumio[18068]: error: MPD error: The expression evaluated to a falsy value: Feb 07 14:20:23 volumio volumio[18068]: assert.ok(self.idling) Feb 07 14:20:23 volumio volumio[18068]: error: MPD error: The expression evaluated to a falsy value: Feb 07 14:20:23 volumio volumio[18068]: assert.ok(self.idling) Feb 07 14:20:23 volumio volumio[18068]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 1 Feb 07 14:20:23 volumio volumio[18068]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:23 volumio volumio[18068]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:23 volumio volumio[18068]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:23 volumio volumio[18068]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:23 volumio volumio[18068]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:23 volumio volumio[18068]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:23 volumio volumio[18068]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:23 volumio volumio[18068]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:23 volumio volumio[18068]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 14:20:23 volumio volumio[18068]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:23 volumio volumio[18068]: info: Reloading queue from file Feb 07 14:20:23 volumio volumio[18068]: info: VolumeController:: Volume=undefined Mute =false Feb 07 14:20:23 volumio volumio[18068]: info: CoreStateMachine::pushState Feb 07 14:20:23 volumio volumio[18068]: info: CorePlayQueue::getTrack 0 Feb 07 14:20:23 volumio volumio[18068]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 07 14:20:23 volumio volumio[18068]: info: CoreCommandRouter::volumioPushState Feb 07 14:20:23 volumio volumio[18068]: info: CoreStateMachine::updateTrackBlock Feb 07 14:20:23 volumio volumio[18068]: info: CorePlayQueue::getTrackBlock Feb 07 14:20:23 volumio volumio[18068]: info: CoreCommandRouter::volumioRetrievevolume Feb 07 14:20:23 volumio volumio[18068]: info: CoreStateMachine::setRepeat null single undefined Feb 07 14:20:23 volumio volumio[18068]: info: CoreStateMachine::pushState Feb 07 14:20:23 volumio volumio[18068]: info: CorePlayQueue::getTrack 0 Feb 07 14:20:23 volumio volumio[18068]: info: CoreCommandRouter::volumioPushState Feb 07 14:20:23 volumio volumio[18068]: info: CoreStateMachine::setRandom false Feb 07 14:20:23 volumio volumio[18068]: info: CoreStateMachine::pushState Feb 07 14:20:23 volumio volumio[18068]: info: CorePlayQueue::getTrack 0 Feb 07 14:20:23 volumio volumio[18068]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 07 14:20:23 volumio volumio[18068]: info: CoreCommandRouter::volumioPushState Feb 07 14:20:23 volumio volumio[18068]: info: Setting Device type: Raspberry PI Feb 07 14:20:23 volumio volumio[18068]: error: updateQueue error: null Feb 07 14:20:23 volumio volumio[18068]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 07 14:20:23 volumio volumio[18068]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Feb 07 14:20:23 volumio volumio[18068]: info: VolumeController:: Volume=undefined Mute =false Feb 07 14:20:23 volumio volumio[18068]: info: CoreStateMachine::pushState Feb 07 14:20:23 volumio volumio[18068]: info: CorePlayQueue::getTrack 0 Feb 07 14:20:23 volumio volumio[18068]: info: CoreCommandRouter::volumioPushState Feb 07 14:20:23 volumio volumio[18068]: info: Completed loading Core Plugins Feb 07 14:20:23 volumio volumio[18068]: info: Preparing to generate the ALSA configuration file Feb 07 14:20:23 volumio volumio[18068]: info: Asound.conf file unchanged, so no further update is needed Feb 07 14:20:23 volumio volumio[18068]: info: Output device has changed, restarting MPD Feb 07 14:20:23 volumio sudo[18188]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 07 14:20:23 volumio sudo[18188]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 07 14:20:23 volumio volumio[18068]: info: Output device has changed, restarting Shairport Sync Feb 07 14:20:23 volumio volumio[18068]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:23 volumio sudo[18188]: pam_unix(sudo:session): session closed for user root Feb 07 14:20:23 volumio volumio[18068]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 14:20:23 volumio sudo[18191]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 07 14:20:23 volumio sudo[18191]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 07 14:20:23 volumio systemd[1]: Stopping Music Player Daemon... Feb 07 14:20:23 volumio volumio[18068]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 07 14:20:23 volumio volumio[18068]: info: ___________ START PLUGINS ___________ Feb 07 14:20:23 volumio systemd[1]: mpd.service: Succeeded. Feb 07 14:20:23 volumio systemd[1]: Stopped Music Player Daemon. Feb 07 14:20:23 volumio volumio[18068]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 07 14:20:23 volumio volumio[18068]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 07 14:20:23 volumio volumio[18068]: info: [1738938023566] CoreMusicLibrary::Adding element Media Servers Feb 07 14:20:23 volumio volumio[18068]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 14:20:23 volumio systemd[1]: Starting Music Player Daemon... Feb 07 14:20:23 volumio volumio[18068]: Cannot find translation for sourceMedia Servers Feb 07 14:20:23 volumio volumio[18068]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:23 volumio volumio[18068]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 14:20:23 volumio volumio[18068]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 07 14:20:23 volumio volumio[18068]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:23 volumio volumio[18068]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 14:20:23 volumio volumio[18068]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 07 14:20:23 volumio volumio[18068]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 07 14:20:23 volumio volumio[18068]: info: [1738938023660] CoreMusicLibrary::Adding element Last_100 Feb 07 14:20:23 volumio volumio[18068]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 14:20:23 volumio volumio[18068]: Cannot find translation for sourceMedia Servers Feb 07 14:20:23 volumio volumio[18068]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 07 14:20:23 volumio volumio[18068]: info: [1738938023663] CoreMusicLibrary::Adding element Webradio Feb 07 14:20:23 volumio volumio[18068]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 14:20:23 volumio volumio[18068]: Cannot find translation for sourceMedia Servers Feb 07 14:20:23 volumio volumio[18068]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 07 14:20:23 volumio volumio[18068]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 07 14:20:23 volumio volumio[18068]: info: [1738938023672] CoreMusicLibrary::Adding element LastFM Feb 07 14:20:23 volumio volumio[18068]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 14:20:23 volumio volumio[18068]: Cannot find translation for sourceMedia Servers Feb 07 14:20:23 volumio volumio[18068]: Cannot find translation for sourceLastFM Feb 07 14:20:23 volumio volumio[18068]: info: [LastFM] scrobbler initiated! Feb 07 14:20:23 volumio volumio[18068]: info: [LastFM] extended logging: false Feb 07 14:20:23 volumio volumio[18068]: info: [LastFM] try scrobble stream/radio plays: true Feb 07 14:20:23 volumio volumio[18068]: info: [LastFM] Left init routine Feb 07 14:20:23 volumio volumio[18068]: info: [LastFM] Socket already connected: true Feb 07 14:20:23 volumio volumio[18068]: info: Volumio Calling Home Feb 07 14:20:23 volumio volumio[18068]: info: Completed starting Core Plugins Feb 07 14:20:23 volumio volumio[18068]: info: ------------------------------------------- Feb 07 14:20:23 volumio volumio[18068]: info: ----- MyVolumio plugins startup ---- Feb 07 14:20:23 volumio volumio[18068]: info: ------------------------------------------- Feb 07 14:20:23 volumio volumio[18068]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 07 14:20:23 volumio volumio[18068]: Unhandled rejection Error: No sockets available, cannot start. Feb 07 14:20:23 volumio volumio[18068]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Feb 07 14:20:23 volumio volumio[18068]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Feb 07 14:20:23 volumio volumio[18068]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Feb 07 14:20:23 volumio volumio[18068]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Feb 07 14:20:23 volumio volumio[18068]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Feb 07 14:20:23 volumio volumio[18068]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Feb 07 14:20:23 volumio volumio[18068]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Feb 07 14:20:23 volumio volumio[18068]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Feb 07 14:20:23 volumio volumio[18068]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Feb 07 14:20:23 volumio volumio[18068]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Feb 07 14:20:23 volumio volumio[18068]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Feb 07 14:20:23 volumio volumio[18068]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Feb 07 14:20:23 volumio volumio[18068]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Feb 07 14:20:23 volumio volumio[18068]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Feb 07 14:20:23 volumio volumio[18068]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Feb 07 14:20:23 volumio volumio[18068]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Feb 07 14:20:23 volumio volumio[18068]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 07 14:20:23 volumio volumio[18068]: Error: getaddrinfo EBUSY ws.audioscrobbler.com Feb 07 14:20:23 volumio volumio[18068]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:67:26) { Feb 07 14:20:23 volumio volumio[18068]: errno: -16, Feb 07 14:20:23 volumio volumio[18068]: code: 'EBUSY', Feb 07 14:20:23 volumio volumio[18068]: syscall: 'getaddrinfo', Feb 07 14:20:23 volumio volumio[18068]: hostname: 'ws.audioscrobbler.com' Feb 07 14:20:23 volumio volumio[18068]: } Feb 07 14:20:23 volumio volumio[18068]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 07 14:20:24 volumio sudo[18217]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-07 14:19 Feb 07 14:20:24 volumio sudo[18217]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 07 14:20:24 volumio mpd[18205]: Feb 07 14:20 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 07 14:20:24 volumio mpd[18205]: Feb 07 14:20 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Feb 07 14:20:24 volumio mpd[18205]: Feb 07 14:20 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Feb 07 14:20:24 volumio systemd[1]: Started Music Player Daemon. Feb 07 14:20:24 volumio sudo[18191]: pam_unix(sudo:session): session closed for user root Feb 07 14:20:24 volumio sudo[18217]: pam_unix(sudo:session): session closed for user root Feb 07 14:20:24 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 07 14:20:24 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 07 14:20:24 volumio systemd[1]: Started dynamicswap service. Feb 07 14:20:24 volumio systemd[1]: dynamicswap.service: Succeeded. Feb 07 14:20:24 volumio ntpd[619]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Feb 07 14:20:24 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Feb 07 14:20:24 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 25320. Feb 07 14:20:24 volumio systemd[1]: Started dynamicswap service. Feb 07 14:20:24 volumio systemd[1]: Stopped Volumio Backend Module. Feb 07 14:20:24 volumio systemd[1]: Started Volumio Backend Module. Feb 07 14:20:24 volumio systemd[1]: dynamicswap.service: Succeeded. Feb 07 14:20:25 volumio volumio[18233]: info: ------------------------------------------- Feb 07 14:20:25 volumio volumio[18233]: info: ----- Volumio3 ---- Feb 07 14:20:25 volumio volumio[18233]: info: ------------------------------------------- Feb 07 14:20:25 volumio volumio[18233]: info: ----- System startup ---- Feb 07 14:20:25 volumio volumio[18233]: info: ------------------------------------------- Feb 07 14:20:26 volumio volumio[18233]: info: MYVOLUMIO Environment detected Feb 07 14:20:26 volumio volumio[18233]: info: Plugin folders cleanup Feb 07 14:20:26 volumio volumio[18233]: info: Scanning into folder /volumio/app/plugins/ Feb 07 14:20:26 volumio volumio[18233]: info: Scanning category audio_interface Feb 07 14:20:26 volumio volumio[18233]: info: Scanning category miscellanea Feb 07 14:20:26 volumio volumio[18233]: info: Scanning category music_service Feb 07 14:20:26 volumio volumio[18233]: info: Scanning category plugins.json Feb 07 14:20:26 volumio volumio[18233]: info: Scanning category system_controller Feb 07 14:20:26 volumio volumio[18233]: info: Scanning category user_interface Feb 07 14:20:26 volumio volumio[18233]: info: Scanning into folder /data/plugins/ Feb 07 14:20:26 volumio volumio[18233]: info: Scanning category user_interface Feb 07 14:20:26 volumio volumio[18233]: info: Plugin folders cleanup completed Feb 07 14:20:26 volumio volumio[18233]: info: ------------------------------------------- Feb 07 14:20:26 volumio volumio[18233]: info: ----- Core plugins startup ---- Feb 07 14:20:26 volumio volumio[18233]: info: ------------------------------------------- Feb 07 14:20:26 volumio volumio[18233]: info: Loading plugins from folder /volumio/app/plugins/ Feb 07 14:20:26 volumio volumio[18233]: info: Adding plugin upnp to MyMusic Plugins Feb 07 14:20:26 volumio volumio[18233]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 07 14:20:26 volumio volumio[18233]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 07 14:20:26 volumio volumio[18233]: info: Loading plugins from folder /data/plugins/ Feb 07 14:20:26 volumio volumio[18233]: info: Loading plugin "system"... Feb 07 14:20:26 volumio volumio[18233]: info: Loading plugin "appearance"... Feb 07 14:20:26 volumio volumio-remote-updater[454]: [2025-02-07 14:20:26] [connect] Successful connection Feb 07 14:20:26 volumio volumio[18233]: info: Loading plugin "network"... Feb 07 14:20:26 volumio volumio[18233]: info: Refreshing Cached IP Addresses Feb 07 14:20:26 volumio sudo[18256]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 07 14:20:26 volumio sudo[18256]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 07 14:20:26 volumio sudo[18256]: pam_unix(sudo:session): session closed for user root Feb 07 14:20:26 volumio sudo[18258]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 07 14:20:26 volumio sudo[18258]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 07 14:20:26 volumio volumio[18233]: info: Loading plugin "services"... Feb 07 14:20:26 volumio sudo[18258]: pam_unix(sudo:session): session closed for user root Feb 07 14:20:26 volumio volumio[18233]: info: Loading plugin "alsa_controller"... Feb 07 14:20:26 volumio sudo[18261]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 07 14:20:26 volumio sudo[18261]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 07 14:20:26 volumio sudo[18261]: pam_unix(sudo:session): session closed for user root Feb 07 14:20:26 volumio volumio[18233]: warn: Unable to locate the audio output device JDS Labs Atom DAC. Please configure a valid output device. Feb 07 14:20:26 volumio volumio[18233]: info: Loading plugin "wizard"... Feb 07 14:20:26 volumio volumio[18233]: info: Loading plugin "volumio_command_line_client"... Feb 07 14:20:26 volumio volumio[18233]: info: Loading plugin "upnp"... Feb 07 14:20:26 volumio volumio[18233]: info: [1738938026770] Starting Upmpd Daemon Feb 07 14:20:26 volumio volumio[18233]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 07 14:20:26 volumio volumio[18233]: info: Loading plugin "my_music"... Feb 07 14:20:26 volumio volumio[18233]: info: Loading plugin "mpd"... Feb 07 14:20:26 volumio wpa_supplicant[697]: wlan0: Failed to initiate sched scan Feb 07 14:20:26 volumio volumio[18233]: info: Creating MPD Configuration file Feb 07 14:20:27 volumio sudo[18267]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 07 14:20:27 volumio volumio[18233]: info: Loading plugin "upnp_browser"... Feb 07 14:20:27 volumio sudo[18267]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 07 14:20:27 volumio sudo[18267]: pam_unix(sudo:session): session closed for user root Feb 07 14:20:27 volumio sudo[18269]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 07 14:20:27 volumio sudo[18269]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 07 14:20:27 volumio systemd[1]: Stopping Music Player Daemon... Feb 07 14:20:27 volumio systemd[1]: mpd.service: Succeeded. Feb 07 14:20:27 volumio systemd[1]: Stopped Music Player Daemon. Feb 07 14:20:27 volumio systemd[1]: Starting Music Player Daemon... Feb 07 14:20:27 volumio volumio[18233]: info: Loading plugin "networkfs"... Feb 07 14:20:27 volumio volumio[18233]: info: Starting Udev Watcher for removable devices Feb 07 14:20:27 volumio volumio[18233]: info: Ignoring mount for partition: boot Feb 07 14:20:27 volumio volumio[18233]: info: Ignoring mount for partition: volumio Feb 07 14:20:27 volumio volumio[18233]: info: Ignoring mount for partition: volumio_data Feb 07 14:20:27 volumio volumio[18233]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 07 14:20:27 volumio volumio[18233]: info: Loading plugin "alarm-clock"... Feb 07 14:20:27 volumio volumio[18233]: info: Loading plugin "airplay_emulation"... Feb 07 14:20:27 volumio volumio[18233]: info: Starting Shairport Sync Feb 07 14:20:27 volumio volumio[18233]: info: Loading plugin "last_100"... Feb 07 14:20:27 volumio volumio[18233]: info: Loading plugin "webradio"... Feb 07 14:20:27 volumio mpd[18274]: Feb 07 14:20 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 07 14:20:27 volumio mpd[18274]: Feb 07 14:20 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Feb 07 14:20:27 volumio mpd[18274]: Feb 07 14:20 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Feb 07 14:20:27 volumio systemd[1]: Started Music Player Daemon. Feb 07 14:20:27 volumio sudo[18269]: pam_unix(sudo:session): session closed for user root Feb 07 14:20:27 volumio volumio[18233]: info: Loading plugin "i2s_dacs"... Feb 07 14:20:27 volumio volumio[18233]: info: I2S DAC not set, start Auto-detection Feb 07 14:20:27 volumio volumio[18233]: info: Loading plugin "volumiodiscovery"... Feb 07 14:20:27 volumio volumio[18233]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 07 14:20:27 volumio volumio[18233]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 07 14:20:27 volumio volumio[18233]: *** WARNING *** For more information see Feb 07 14:20:27 volumio node[18233]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 07 14:20:27 volumio volumio[18233]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 07 14:20:27 volumio volumio[18233]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 07 14:20:27 volumio volumio[18233]: *** WARNING *** For more information see Feb 07 14:20:27 volumio node[18233]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 07 14:20:27 volumio node[18233]: *** WARNING *** For more information see Feb 07 14:20:27 volumio node[18233]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 07 14:20:27 volumio node[18233]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 07 14:20:27 volumio node[18233]: *** WARNING *** For more information see Feb 07 14:20:27 volumio volumio[18233]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 07 14:20:27 volumio volumio[18233]: info: Discovery: Started advertising with name: Volumio Feb 07 14:20:27 volumio volumio[18233]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 07 14:20:27 volumio volumio[18233]: info: Loading plugin "outputs"... Feb 07 14:20:27 volumio volumio[18233]: info: Loading plugin "albumart"... Feb 07 14:20:27 volumio volumio[18233]: info: Plugin example_plugin is not enabled Feb 07 14:20:27 volumio volumio[18233]: info: Loading plugin "inputs"... Feb 07 14:20:27 volumio volumio[18233]: info: Loading plugin "updater_comm"... Feb 07 14:20:28 volumio volumio[18233]: info: Plugin mpdemulation is not enabled Feb 07 14:20:28 volumio volumio[18233]: info: Loading plugin "rest_api"... Feb 07 14:20:28 volumio volumio[18233]: info: Loading plugin "websocket"... Feb 07 14:20:28 volumio volumio[18233]: info: Loading plugin "lastfm"... Feb 07 14:20:28 volumio volumio[18233]: Forking 3 albumart workers Feb 07 14:20:28 volumio volumio[18233]: info: Loading i18n strings for locale en Feb 07 14:20:28 volumio volumio[18233]: Updating browse sources language Feb 07 14:20:28 volumio volumio[18233]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 14:20:28 volumio volumio[18233]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 07 14:20:28 volumio volumio[18233]: info: CoreCommandRouter::initPlayerControls Feb 07 14:20:28 volumio volumio[18233]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:28 volumio volumio[18233]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:28 volumio volumio[18233]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:28 volumio volumio[18233]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:28 volumio volumio[18233]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:28 volumio volumio[18233]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:28 volumio volumio[18233]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:28 volumio volumio[18233]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:28 volumio volumio[18233]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 07 14:20:28 volumio volumio[18233]: Express server listening on port 3000 Feb 07 14:20:28 volumio volumio[18233]: [Metrics] WebUI: 3s 172.14ms Feb 07 14:20:28 volumio volumio[18233]: info: CoreStateMachine::resetVolumioState Feb 07 14:20:28 volumio volumio[18233]: info: CoreStateMachine::getcurrentVolume Feb 07 14:20:28 volumio volumio[18233]: info: CoreCommandRouter::volumioRetrievevolume Feb 07 14:20:28 volumio volumio[18233]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 14:20:28 volumio volumio[18233]: info: MPD Permissions set Feb 07 14:20:28 volumio volumio[18233]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Feb 07 14:20:28 volumio volumio[18233]: wlan0 Interface doesn't support scanning : Device or resource busy Feb 07 14:20:28 volumio volumio[18233]: info: Cannot use regular scanning, forcing with ap-force Feb 07 14:20:28 volumio volumio[18233]: Starting albumart workers Feb 07 14:20:28 volumio sudo[18315]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Feb 07 14:20:28 volumio sudo[18315]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 07 14:20:28 volumio volumio[18233]: Starting albumart workers Feb 07 14:20:28 volumio volumio[18233]: Starting albumart workers Feb 07 14:20:31 volumio sudo[18315]: pam_unix(sudo:session): session closed for user root Feb 07 14:20:31 volumio volumio-remote-updater[454]: [2025-02-07 14:20:31] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1738938026 101 Feb 07 14:20:31 volumio volumio[18233]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1 Feb 07 14:20:31 volumio volumio[18233]: info: MPD running with PID18274 Feb 07 14:20:31 volumio volumio[18233]: ,establishing connection Feb 07 14:20:31 volumio volumio[18233]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Feb 07 14:20:31 volumio volumio[18233]: error: MPD error: The expression evaluated to a falsy value: Feb 07 14:20:31 volumio volumio[18233]: assert.ok(self.idling) Feb 07 14:20:31 volumio volumio[18233]: error: MPD error: The expression evaluated to a falsy value: Feb 07 14:20:31 volumio volumio[18233]: assert.ok(self.idling) Feb 07 14:20:31 volumio volumio[18233]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 Feb 07 14:20:31 volumio volumio[18233]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:31 volumio volumio[18233]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:31 volumio volumio[18233]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:31 volumio volumio[18233]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:31 volumio volumio[18233]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:31 volumio volumio[18233]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:31 volumio volumio[18233]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:31 volumio volumio[18233]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:31 volumio volumio[18233]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 14:20:31 volumio volumio[18233]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:31 volumio volumio[18233]: info: Reloading queue from file Feb 07 14:20:31 volumio volumio[18233]: info: VolumeController:: Volume=undefined Mute =false Feb 07 14:20:31 volumio volumio[18233]: info: CoreStateMachine::pushState Feb 07 14:20:31 volumio volumio[18233]: info: CorePlayQueue::getTrack 0 Feb 07 14:20:31 volumio volumio[18233]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 07 14:20:31 volumio volumio[18233]: info: CoreCommandRouter::volumioPushState Feb 07 14:20:31 volumio volumio[18233]: info: CoreStateMachine::updateTrackBlock Feb 07 14:20:31 volumio volumio[18233]: info: CorePlayQueue::getTrackBlock Feb 07 14:20:31 volumio volumio[18233]: info: CoreCommandRouter::volumioRetrievevolume Feb 07 14:20:31 volumio volumio[18233]: info: CoreStateMachine::setRepeat null single undefined Feb 07 14:20:31 volumio volumio[18233]: info: CoreStateMachine::pushState Feb 07 14:20:31 volumio volumio[18233]: info: CorePlayQueue::getTrack 0 Feb 07 14:20:31 volumio volumio[18233]: info: CoreCommandRouter::volumioPushState Feb 07 14:20:31 volumio volumio[18233]: info: CoreStateMachine::setRandom false Feb 07 14:20:31 volumio volumio[18233]: info: CoreStateMachine::pushState Feb 07 14:20:31 volumio volumio[18233]: info: CorePlayQueue::getTrack 0 Feb 07 14:20:31 volumio volumio[18233]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 07 14:20:31 volumio volumio[18233]: info: CoreCommandRouter::volumioPushState Feb 07 14:20:31 volumio volumio[18233]: info: Setting Device type: Raspberry PI Feb 07 14:20:31 volumio volumio[18233]: error: updateQueue error: null Feb 07 14:20:31 volumio volumio[18233]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 07 14:20:31 volumio volumio[18233]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Feb 07 14:20:31 volumio volumio[18233]: info: VolumeController:: Volume=undefined Mute =false Feb 07 14:20:31 volumio volumio[18233]: info: CoreStateMachine::pushState Feb 07 14:20:31 volumio volumio[18233]: info: CorePlayQueue::getTrack 0 Feb 07 14:20:31 volumio volumio[18233]: info: CoreCommandRouter::volumioPushState Feb 07 14:20:31 volumio volumio[18233]: info: Completed loading Core Plugins Feb 07 14:20:31 volumio volumio[18233]: info: Preparing to generate the ALSA configuration file Feb 07 14:20:31 volumio volumio[18233]: info: Asound.conf file unchanged, so no further update is needed Feb 07 14:20:31 volumio volumio[18233]: info: Output device has changed, restarting MPD Feb 07 14:20:31 volumio sudo[18353]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 07 14:20:31 volumio sudo[18353]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 07 14:20:31 volumio volumio[18233]: info: Output device has changed, restarting Shairport Sync Feb 07 14:20:31 volumio volumio[18233]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:31 volumio volumio[18233]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 14:20:31 volumio sudo[18353]: pam_unix(sudo:session): session closed for user root Feb 07 14:20:31 volumio sudo[18356]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 07 14:20:31 volumio sudo[18356]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 07 14:20:31 volumio systemd[1]: Stopping Music Player Daemon... Feb 07 14:20:31 volumio volumio[18233]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 07 14:20:31 volumio volumio[18233]: info: ___________ START PLUGINS ___________ Feb 07 14:20:31 volumio systemd[1]: mpd.service: Succeeded. Feb 07 14:20:31 volumio systemd[1]: Stopped Music Player Daemon. Feb 07 14:20:31 volumio volumio[18233]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 07 14:20:31 volumio volumio[18233]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 07 14:20:31 volumio volumio[18233]: info: [1738938031673] CoreMusicLibrary::Adding element Media Servers Feb 07 14:20:31 volumio volumio[18233]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 14:20:31 volumio volumio[18233]: Cannot find translation for sourceMedia Servers Feb 07 14:20:31 volumio systemd[1]: Starting Music Player Daemon... Feb 07 14:20:31 volumio volumio[18233]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:31 volumio volumio[18233]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 14:20:31 volumio volumio[18233]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 07 14:20:31 volumio volumio[18233]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:31 volumio volumio[18233]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 14:20:31 volumio volumio[18233]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 07 14:20:31 volumio volumio[18233]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 07 14:20:31 volumio volumio[18233]: info: [1738938031770] CoreMusicLibrary::Adding element Last_100 Feb 07 14:20:31 volumio volumio[18233]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 14:20:31 volumio volumio[18233]: Cannot find translation for sourceMedia Servers Feb 07 14:20:31 volumio volumio[18233]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 07 14:20:31 volumio volumio[18233]: info: [1738938031773] CoreMusicLibrary::Adding element Webradio Feb 07 14:20:31 volumio volumio[18233]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 14:20:31 volumio volumio[18233]: Cannot find translation for sourceMedia Servers Feb 07 14:20:31 volumio volumio[18233]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 07 14:20:31 volumio volumio[18233]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 07 14:20:31 volumio volumio[18233]: info: [1738938031782] CoreMusicLibrary::Adding element LastFM Feb 07 14:20:31 volumio volumio[18233]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 14:20:31 volumio volumio[18233]: Cannot find translation for sourceMedia Servers Feb 07 14:20:31 volumio volumio[18233]: Cannot find translation for sourceLastFM Feb 07 14:20:31 volumio volumio[18233]: info: [LastFM] scrobbler initiated! Feb 07 14:20:31 volumio volumio[18233]: info: [LastFM] extended logging: false Feb 07 14:20:31 volumio volumio[18233]: info: [LastFM] try scrobble stream/radio plays: true Feb 07 14:20:31 volumio volumio[18233]: info: [LastFM] Left init routine Feb 07 14:20:31 volumio volumio[18233]: info: [LastFM] Socket already connected: true Feb 07 14:20:31 volumio volumio[18233]: info: Volumio Calling Home Feb 07 14:20:31 volumio volumio[18233]: info: Completed starting Core Plugins Feb 07 14:20:31 volumio volumio[18233]: info: ------------------------------------------- Feb 07 14:20:31 volumio volumio[18233]: info: ----- MyVolumio plugins startup ---- Feb 07 14:20:31 volumio volumio[18233]: info: ------------------------------------------- Feb 07 14:20:31 volumio volumio[18233]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 07 14:20:31 volumio volumio[18233]: Unhandled rejection Error: No sockets available, cannot start. Feb 07 14:20:31 volumio volumio[18233]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Feb 07 14:20:31 volumio volumio[18233]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Feb 07 14:20:31 volumio volumio[18233]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Feb 07 14:20:31 volumio volumio[18233]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Feb 07 14:20:31 volumio volumio[18233]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Feb 07 14:20:31 volumio volumio[18233]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Feb 07 14:20:31 volumio volumio[18233]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Feb 07 14:20:31 volumio volumio[18233]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Feb 07 14:20:31 volumio volumio[18233]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Feb 07 14:20:31 volumio volumio[18233]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Feb 07 14:20:31 volumio volumio[18233]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Feb 07 14:20:31 volumio volumio[18233]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Feb 07 14:20:31 volumio volumio[18233]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Feb 07 14:20:31 volumio volumio[18233]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Feb 07 14:20:31 volumio volumio[18233]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Feb 07 14:20:31 volumio volumio[18233]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Feb 07 14:20:31 volumio volumio[18233]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 07 14:20:31 volumio volumio[18233]: Error: getaddrinfo EBUSY ws.audioscrobbler.com Feb 07 14:20:31 volumio volumio[18233]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:67:26) { Feb 07 14:20:31 volumio volumio[18233]: errno: -16, Feb 07 14:20:31 volumio volumio[18233]: code: 'EBUSY', Feb 07 14:20:31 volumio volumio[18233]: syscall: 'getaddrinfo', Feb 07 14:20:31 volumio volumio[18233]: hostname: 'ws.audioscrobbler.com' Feb 07 14:20:31 volumio volumio[18233]: } Feb 07 14:20:31 volumio volumio[18233]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 07 14:20:32 volumio sudo[18382]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-07 14:19 Feb 07 14:20:32 volumio sudo[18382]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 07 14:20:32 volumio mpd[18369]: Feb 07 14:20 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 07 14:20:32 volumio mpd[18369]: Feb 07 14:20 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Feb 07 14:20:32 volumio mpd[18369]: Feb 07 14:20 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Feb 07 14:20:32 volumio systemd[1]: Started Music Player Daemon. Feb 07 14:20:32 volumio sudo[18356]: pam_unix(sudo:session): session closed for user root Feb 07 14:20:32 volumio sudo[18382]: pam_unix(sudo:session): session closed for user root Feb 07 14:20:32 volumio volumio-remote-updater[454]: [2025-02-07 14:20:32] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Feb 07 14:20:32 volumio volumio-remote-updater[454]: [2025-02-07 14:20:32] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Feb 07 14:20:32 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 07 14:20:32 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 07 14:20:32 volumio systemd[1]: Started dynamicswap service. Feb 07 14:20:32 volumio systemd[1]: dynamicswap.service: Succeeded. Feb 07 14:20:32 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Feb 07 14:20:32 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 25321. Feb 07 14:20:32 volumio systemd[1]: Started dynamicswap service. Feb 07 14:20:32 volumio systemd[1]: Stopped Volumio Backend Module. Feb 07 14:20:32 volumio systemd[1]: Started Volumio Backend Module. Feb 07 14:20:32 volumio systemd[1]: dynamicswap.service: Succeeded. Feb 07 14:20:33 volumio volumio[18398]: info: ------------------------------------------- Feb 07 14:20:33 volumio volumio[18398]: info: ----- Volumio3 ---- Feb 07 14:20:33 volumio volumio[18398]: info: ------------------------------------------- Feb 07 14:20:33 volumio volumio[18398]: info: ----- System startup ---- Feb 07 14:20:33 volumio volumio[18398]: info: ------------------------------------------- Feb 07 14:20:34 volumio volumio[18398]: info: MYVOLUMIO Environment detected Feb 07 14:20:34 volumio volumio[18398]: info: Plugin folders cleanup Feb 07 14:20:34 volumio volumio[18398]: info: Scanning into folder /volumio/app/plugins/ Feb 07 14:20:34 volumio volumio[18398]: info: Scanning category audio_interface Feb 07 14:20:34 volumio volumio[18398]: info: Scanning category miscellanea Feb 07 14:20:34 volumio volumio[18398]: info: Scanning category music_service Feb 07 14:20:34 volumio volumio[18398]: info: Scanning category plugins.json Feb 07 14:20:34 volumio volumio[18398]: info: Scanning category system_controller Feb 07 14:20:34 volumio volumio[18398]: info: Scanning category user_interface Feb 07 14:20:34 volumio volumio[18398]: info: Scanning into folder /data/plugins/ Feb 07 14:20:34 volumio volumio[18398]: info: Scanning category user_interface Feb 07 14:20:34 volumio volumio[18398]: info: Plugin folders cleanup completed Feb 07 14:20:34 volumio volumio[18398]: info: ------------------------------------------- Feb 07 14:20:34 volumio volumio[18398]: info: ----- Core plugins startup ---- Feb 07 14:20:34 volumio volumio[18398]: info: ------------------------------------------- Feb 07 14:20:34 volumio volumio[18398]: info: Loading plugins from folder /volumio/app/plugins/ Feb 07 14:20:34 volumio volumio[18398]: info: Adding plugin upnp to MyMusic Plugins Feb 07 14:20:34 volumio volumio[18398]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 07 14:20:34 volumio volumio[18398]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 07 14:20:34 volumio volumio[18398]: info: Loading plugins from folder /data/plugins/ Feb 07 14:20:34 volumio volumio[18398]: info: Loading plugin "system"... Feb 07 14:20:34 volumio volumio[18398]: info: Loading plugin "appearance"... Feb 07 14:20:34 volumio wpa_supplicant[697]: wlan0: Failed to initiate sched scan Feb 07 14:20:34 volumio volumio[18398]: info: Loading plugin "network"... Feb 07 14:20:34 volumio volumio[18398]: info: Refreshing Cached IP Addresses Feb 07 14:20:34 volumio sudo[18421]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 07 14:20:34 volumio sudo[18421]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 07 14:20:34 volumio sudo[18421]: pam_unix(sudo:session): session closed for user root Feb 07 14:20:34 volumio sudo[18424]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 07 14:20:34 volumio volumio[18398]: info: Loading plugin "services"... Feb 07 14:20:34 volumio sudo[18424]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 07 14:20:34 volumio volumio[18398]: info: Loading plugin "alsa_controller"... Feb 07 14:20:34 volumio sudo[18424]: pam_unix(sudo:session): session closed for user root Feb 07 14:20:34 volumio sudo[18426]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 07 14:20:34 volumio sudo[18426]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 07 14:20:34 volumio volumio[18398]: warn: Unable to locate the audio output device JDS Labs Atom DAC. Please configure a valid output device. Feb 07 14:20:35 volumio volumio[18398]: info: Loading plugin "wizard"... Feb 07 14:20:35 volumio volumio[18398]: info: Loading plugin "volumio_command_line_client"... Feb 07 14:20:35 volumio volumio[18398]: info: Loading plugin "upnp"... Feb 07 14:20:35 volumio volumio[18398]: info: [1738938035012] Starting Upmpd Daemon Feb 07 14:20:35 volumio volumio[18398]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 07 14:20:35 volumio volumio[18398]: info: Loading plugin "my_music"... Feb 07 14:20:35 volumio volumio[18398]: info: Loading plugin "mpd"... Feb 07 14:20:35 volumio volumio[18398]: info: Creating MPD Configuration file Feb 07 14:20:35 volumio sudo[18432]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 07 14:20:35 volumio volumio[18398]: info: Loading plugin "upnp_browser"... Feb 07 14:20:35 volumio sudo[18432]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 07 14:20:35 volumio sudo[18432]: pam_unix(sudo:session): session closed for user root Feb 07 14:20:35 volumio sudo[18434]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 07 14:20:35 volumio sudo[18434]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 07 14:20:35 volumio systemd[1]: Stopping Music Player Daemon... Feb 07 14:20:35 volumio systemd[1]: mpd.service: Succeeded. Feb 07 14:20:35 volumio systemd[1]: Stopped Music Player Daemon. Feb 07 14:20:35 volumio systemd[1]: Starting Music Player Daemon... Feb 07 14:20:35 volumio volumio[18398]: info: Loading plugin "networkfs"... Feb 07 14:20:35 volumio volumio[18398]: info: Starting Udev Watcher for removable devices Feb 07 14:20:35 volumio volumio[18398]: info: Ignoring mount for partition: boot Feb 07 14:20:35 volumio volumio[18398]: info: Ignoring mount for partition: volumio Feb 07 14:20:35 volumio volumio[18398]: info: Ignoring mount for partition: volumio_data Feb 07 14:20:35 volumio volumio[18398]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 07 14:20:35 volumio volumio[18398]: info: Loading plugin "alarm-clock"... Feb 07 14:20:35 volumio volumio[18398]: info: Loading plugin "airplay_emulation"... Feb 07 14:20:35 volumio volumio[18398]: info: Starting Shairport Sync Feb 07 14:20:35 volumio volumio[18398]: info: Loading plugin "last_100"... Feb 07 14:20:35 volumio volumio[18398]: info: Loading plugin "webradio"... Feb 07 14:20:35 volumio mpd[18439]: Feb 07 14:20 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 07 14:20:36 volumio mpd[18439]: Feb 07 14:20 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Feb 07 14:20:36 volumio mpd[18439]: Feb 07 14:20 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Feb 07 14:20:36 volumio systemd[1]: Started Music Player Daemon. Feb 07 14:20:36 volumio sudo[18434]: pam_unix(sudo:session): session closed for user root Feb 07 14:20:36 volumio volumio[18398]: info: Loading plugin "i2s_dacs"... Feb 07 14:20:36 volumio volumio[18398]: info: I2S DAC not set, start Auto-detection Feb 07 14:20:36 volumio volumio[18398]: info: Loading plugin "volumiodiscovery"... Feb 07 14:20:36 volumio volumio[18398]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 07 14:20:36 volumio volumio[18398]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 07 14:20:36 volumio node[18398]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 07 14:20:36 volumio volumio[18398]: *** WARNING *** For more information see Feb 07 14:20:36 volumio volumio[18398]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 07 14:20:36 volumio volumio[18398]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 07 14:20:36 volumio volumio[18398]: *** WARNING *** For more information see Feb 07 14:20:36 volumio node[18398]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 07 14:20:36 volumio node[18398]: *** WARNING *** For more information see Feb 07 14:20:36 volumio node[18398]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 07 14:20:36 volumio node[18398]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 07 14:20:36 volumio node[18398]: *** WARNING *** For more information see Feb 07 14:20:36 volumio volumio[18398]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 07 14:20:36 volumio volumio[18398]: info: Discovery: Started advertising with name: Volumio Feb 07 14:20:36 volumio volumio[18398]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 07 14:20:36 volumio volumio[18398]: info: Loading plugin "outputs"... Feb 07 14:20:36 volumio volumio[18398]: info: Loading plugin "albumart"... Feb 07 14:20:36 volumio volumio[18398]: info: Plugin example_plugin is not enabled Feb 07 14:20:36 volumio volumio[18398]: info: Loading plugin "inputs"... Feb 07 14:20:36 volumio volumio[18398]: info: Loading plugin "updater_comm"... Feb 07 14:20:36 volumio volumio[18398]: info: Plugin mpdemulation is not enabled Feb 07 14:20:36 volumio volumio[18398]: info: Loading plugin "rest_api"... Feb 07 14:20:36 volumio volumio[18398]: info: Loading plugin "websocket"... Feb 07 14:20:36 volumio volumio[18398]: info: Loading plugin "lastfm"... Feb 07 14:20:36 volumio volumio[18398]: Forking 3 albumart workers Feb 07 14:20:36 volumio volumio[18398]: info: Loading i18n strings for locale en Feb 07 14:20:36 volumio volumio[18398]: Updating browse sources language Feb 07 14:20:36 volumio volumio[18398]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 14:20:36 volumio volumio[18398]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 07 14:20:36 volumio volumio[18398]: info: CoreCommandRouter::initPlayerControls Feb 07 14:20:36 volumio volumio[18398]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:36 volumio volumio[18398]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:36 volumio volumio[18398]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:36 volumio volumio[18398]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:36 volumio volumio[18398]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:36 volumio volumio[18398]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:36 volumio volumio[18398]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:36 volumio volumio[18398]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:36 volumio volumio[18398]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 07 14:20:36 volumio volumio[18398]: Express server listening on port 3000 Feb 07 14:20:36 volumio volumio[18398]: [Metrics] WebUI: 3s 146.37ms Feb 07 14:20:36 volumio volumio[18398]: info: CoreStateMachine::resetVolumioState Feb 07 14:20:36 volumio volumio[18398]: info: CoreStateMachine::getcurrentVolume Feb 07 14:20:36 volumio volumio[18398]: info: CoreCommandRouter::volumioRetrievevolume Feb 07 14:20:36 volumio volumio[18398]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 14:20:36 volumio volumio[18398]: info: MPD Permissions set Feb 07 14:20:36 volumio volumio[18398]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Feb 07 14:20:36 volumio volumio[18398]: Starting albumart workers Feb 07 14:20:36 volumio volumio[18398]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 1 Feb 07 14:20:36 volumio volumio[18398]: info: MPD running with PID18439 Feb 07 14:20:36 volumio volumio[18398]: ,establishing connection Feb 07 14:20:36 volumio volumio[18398]: Starting albumart workers Feb 07 14:20:36 volumio volumio[18398]: Starting albumart workers Feb 07 14:20:37 volumio volumio[18398]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:37 volumio volumio[18398]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:37 volumio volumio[18398]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:37 volumio volumio[18398]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:37 volumio volumio[18398]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:37 volumio volumio[18398]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:37 volumio volumio[18398]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:37 volumio volumio[18398]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:37 volumio volumio[18398]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 14:20:37 volumio volumio[18398]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:37 volumio volumio[18398]: info: Reloading queue from file Feb 07 14:20:37 volumio volumio[18398]: error: updateQueue error: null Feb 07 14:20:37 volumio volumio[18398]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 07 14:20:37 volumio volumio[18398]: info: VolumeController:: Volume=undefined Mute =false Feb 07 14:20:37 volumio volumio[18398]: info: CoreStateMachine::pushState Feb 07 14:20:37 volumio volumio[18398]: info: CorePlayQueue::getTrack 0 Feb 07 14:20:37 volumio volumio[18398]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 07 14:20:37 volumio volumio[18398]: info: CoreCommandRouter::volumioPushState Feb 07 14:20:37 volumio volumio[18398]: info: CoreStateMachine::updateTrackBlock Feb 07 14:20:37 volumio volumio[18398]: info: CorePlayQueue::getTrackBlock Feb 07 14:20:37 volumio volumio[18398]: info: CoreCommandRouter::volumioRetrievevolume Feb 07 14:20:37 volumio volumio[18398]: info: CoreStateMachine::setRepeat null single undefined Feb 07 14:20:37 volumio volumio[18398]: info: CoreStateMachine::pushState Feb 07 14:20:37 volumio volumio[18398]: info: CorePlayQueue::getTrack 0 Feb 07 14:20:37 volumio volumio[18398]: info: CoreCommandRouter::volumioPushState Feb 07 14:20:37 volumio volumio[18398]: info: CoreStateMachine::setRandom false Feb 07 14:20:37 volumio volumio[18398]: info: CoreStateMachine::pushState Feb 07 14:20:37 volumio volumio[18398]: info: CorePlayQueue::getTrack 0 Feb 07 14:20:37 volumio volumio[18398]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 07 14:20:37 volumio volumio[18398]: info: CoreCommandRouter::volumioPushState Feb 07 14:20:37 volumio volumio[18398]: info: Setting Device type: Raspberry PI Feb 07 14:20:37 volumio volumio[18398]: error: updateQueue error: null Feb 07 14:20:37 volumio volumio[18398]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Feb 07 14:20:37 volumio volumio[18398]: info: Completed loading Core Plugins Feb 07 14:20:37 volumio volumio[18398]: info: Preparing to generate the ALSA configuration file Feb 07 14:20:37 volumio volumio[18398]: info: VolumeController:: Volume=undefined Mute =false Feb 07 14:20:37 volumio volumio[18398]: info: CoreStateMachine::pushState Feb 07 14:20:37 volumio volumio[18398]: info: CorePlayQueue::getTrack 0 Feb 07 14:20:37 volumio volumio[18398]: info: CoreCommandRouter::volumioPushState Feb 07 14:20:37 volumio volumio[18398]: info: Asound.conf file unchanged, so no further update is needed Feb 07 14:20:37 volumio volumio[18398]: info: Output device has changed, restarting MPD Feb 07 14:20:37 volumio volumio[18398]: info: Output device has changed, restarting Shairport Sync Feb 07 14:20:37 volumio volumio[18398]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:37 volumio volumio[18398]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 14:20:37 volumio sudo[18505]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 07 14:20:37 volumio sudo[18505]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 07 14:20:37 volumio sudo[18492]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 07 14:20:37 volumio sudo[18492]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 07 14:20:37 volumio systemd[1]: Stopping Music Player Daemon... Feb 07 14:20:37 volumio sudo[18492]: pam_unix(sudo:session): session closed for user root Feb 07 14:20:37 volumio systemd[1]: mpd.service: Succeeded. Feb 07 14:20:37 volumio systemd[1]: Stopped Music Player Daemon. Feb 07 14:20:37 volumio systemd[1]: Starting Music Player Daemon... Feb 07 14:20:37 volumio volumio[18398]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 07 14:20:37 volumio volumio[18398]: info: ___________ START PLUGINS ___________ Feb 07 14:20:37 volumio volumio[18398]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 07 14:20:37 volumio volumio[18398]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 07 14:20:37 volumio volumio[18398]: info: [1738938037478] CoreMusicLibrary::Adding element Media Servers Feb 07 14:20:37 volumio volumio[18398]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 14:20:37 volumio volumio[18398]: Cannot find translation for sourceMedia Servers Feb 07 14:20:37 volumio volumio[18398]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:37 volumio volumio[18398]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 14:20:37 volumio volumio-remote-updater[454]: [2025-02-07 14:20:37] [connect] Successful connection Feb 07 14:20:37 volumio volumio[18398]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 07 14:20:37 volumio volumio[18398]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:37 volumio volumio[18398]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 14:20:37 volumio sudo[18426]: pam_unix(sudo:session): session closed for user root Feb 07 14:20:37 volumio volumio[18398]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 07 14:20:37 volumio volumio[18398]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 07 14:20:37 volumio volumio[18398]: info: [1738938037573] CoreMusicLibrary::Adding element Last_100 Feb 07 14:20:37 volumio volumio[18398]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 14:20:37 volumio volumio[18398]: Cannot find translation for sourceMedia Servers Feb 07 14:20:37 volumio volumio[18398]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 07 14:20:37 volumio volumio[18398]: info: [1738938037577] CoreMusicLibrary::Adding element Webradio Feb 07 14:20:37 volumio volumio[18398]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 14:20:37 volumio volumio[18398]: Cannot find translation for sourceMedia Servers Feb 07 14:20:37 volumio volumio[18398]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 07 14:20:37 volumio volumio[18398]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 07 14:20:37 volumio volumio[18398]: info: [1738938037588] CoreMusicLibrary::Adding element LastFM Feb 07 14:20:37 volumio volumio[18398]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 14:20:37 volumio volumio[18398]: Cannot find translation for sourceMedia Servers Feb 07 14:20:37 volumio volumio[18398]: Cannot find translation for sourceLastFM Feb 07 14:20:37 volumio volumio[18398]: info: [LastFM] scrobbler initiated! Feb 07 14:20:37 volumio volumio[18398]: info: [LastFM] extended logging: false Feb 07 14:20:37 volumio volumio[18398]: info: [LastFM] try scrobble stream/radio plays: true Feb 07 14:20:37 volumio volumio[18398]: info: [LastFM] Left init routine Feb 07 14:20:37 volumio volumio[18398]: info: [LastFM] Socket already connected: true Feb 07 14:20:37 volumio volumio[18398]: info: Volumio Calling Home Feb 07 14:20:37 volumio volumio[18398]: info: Completed starting Core Plugins Feb 07 14:20:37 volumio volumio[18398]: info: ------------------------------------------- Feb 07 14:20:37 volumio volumio[18398]: info: ----- MyVolumio plugins startup ---- Feb 07 14:20:37 volumio volumio[18398]: info: ------------------------------------------- Feb 07 14:20:37 volumio volumio[18398]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 07 14:20:37 volumio volumio[18398]: Unhandled rejection Error: No sockets available, cannot start. Feb 07 14:20:37 volumio volumio[18398]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Feb 07 14:20:37 volumio volumio[18398]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Feb 07 14:20:37 volumio volumio[18398]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Feb 07 14:20:37 volumio volumio[18398]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Feb 07 14:20:37 volumio volumio[18398]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Feb 07 14:20:37 volumio volumio[18398]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Feb 07 14:20:37 volumio volumio[18398]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Feb 07 14:20:37 volumio volumio[18398]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Feb 07 14:20:37 volumio volumio[18398]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Feb 07 14:20:37 volumio volumio[18398]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Feb 07 14:20:37 volumio volumio[18398]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Feb 07 14:20:37 volumio volumio[18398]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Feb 07 14:20:37 volumio volumio[18398]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Feb 07 14:20:37 volumio volumio[18398]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Feb 07 14:20:37 volumio volumio[18398]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Feb 07 14:20:37 volumio volumio[18398]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Feb 07 14:20:37 volumio volumio[18398]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 07 14:20:37 volumio volumio[18398]: Error: getaddrinfo EBUSY ws.audioscrobbler.com Feb 07 14:20:37 volumio volumio[18398]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:67:26) { Feb 07 14:20:37 volumio volumio[18398]: errno: -16, Feb 07 14:20:37 volumio volumio[18398]: code: 'EBUSY', Feb 07 14:20:37 volumio volumio[18398]: syscall: 'getaddrinfo', Feb 07 14:20:37 volumio volumio[18398]: hostname: 'ws.audioscrobbler.com' Feb 07 14:20:37 volumio volumio[18398]: } Feb 07 14:20:37 volumio volumio[18398]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 07 14:20:38 volumio sudo[18545]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-07 14:19 Feb 07 14:20:38 volumio sudo[18545]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 07 14:20:38 volumio mpd[18532]: Feb 07 14:20 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 07 14:20:38 volumio mpd[18532]: Feb 07 14:20 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Feb 07 14:20:38 volumio mpd[18532]: Feb 07 14:20 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Feb 07 14:20:38 volumio systemd[1]: Started Music Player Daemon. Feb 07 14:20:38 volumio sudo[18505]: pam_unix(sudo:session): session closed for user root Feb 07 14:20:38 volumio sudo[18545]: pam_unix(sudo:session): session closed for user root Feb 07 14:20:38 volumio volumio-remote-updater[454]: [2025-02-07 14:20:38] [info] asio async_read_at_least error: system:104 (Connection reset by peer) Feb 07 14:20:38 volumio volumio-remote-updater[454]: [2025-02-07 14:20:38] [error] handle_read_http_response error: websocketpp.transport:2 (Underlying Transport Error) Feb 07 14:20:38 volumio volumio-remote-updater[454]: [2025-02-07 14:20:38] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Feb 07 14:20:38 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 07 14:20:38 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 07 14:20:38 volumio systemd[1]: Started dynamicswap service. Feb 07 14:20:38 volumio systemd[1]: dynamicswap.service: Succeeded. Feb 07 14:20:38 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Feb 07 14:20:38 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 25322. Feb 07 14:20:38 volumio systemd[1]: Started dynamicswap service. Feb 07 14:20:38 volumio systemd[1]: Stopped Volumio Backend Module. Feb 07 14:20:38 volumio systemd[1]: Started Volumio Backend Module. Feb 07 14:20:38 volumio systemd[1]: dynamicswap.service: Succeeded. Feb 07 14:20:39 volumio volumio[18561]: info: ------------------------------------------- Feb 07 14:20:39 volumio volumio[18561]: info: ----- Volumio3 ---- Feb 07 14:20:39 volumio volumio[18561]: info: ------------------------------------------- Feb 07 14:20:39 volumio volumio[18561]: info: ----- System startup ---- Feb 07 14:20:39 volumio volumio[18561]: info: ------------------------------------------- Feb 07 14:20:39 volumio volumio[18561]: info: MYVOLUMIO Environment detected Feb 07 14:20:40 volumio volumio[18561]: info: Plugin folders cleanup Feb 07 14:20:40 volumio volumio[18561]: info: Scanning into folder /volumio/app/plugins/ Feb 07 14:20:40 volumio volumio[18561]: info: Scanning category audio_interface Feb 07 14:20:40 volumio volumio[18561]: info: Scanning category miscellanea Feb 07 14:20:40 volumio volumio[18561]: info: Scanning category music_service Feb 07 14:20:40 volumio volumio[18561]: info: Scanning category plugins.json Feb 07 14:20:40 volumio volumio[18561]: info: Scanning category system_controller Feb 07 14:20:40 volumio volumio[18561]: info: Scanning category user_interface Feb 07 14:20:40 volumio volumio[18561]: info: Scanning into folder /data/plugins/ Feb 07 14:20:40 volumio volumio[18561]: info: Scanning category user_interface Feb 07 14:20:40 volumio volumio[18561]: info: Plugin folders cleanup completed Feb 07 14:20:40 volumio volumio[18561]: info: ------------------------------------------- Feb 07 14:20:40 volumio volumio[18561]: info: ----- Core plugins startup ---- Feb 07 14:20:40 volumio volumio[18561]: info: ------------------------------------------- Feb 07 14:20:40 volumio volumio[18561]: info: Loading plugins from folder /volumio/app/plugins/ Feb 07 14:20:40 volumio volumio[18561]: info: Adding plugin upnp to MyMusic Plugins Feb 07 14:20:40 volumio volumio[18561]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 07 14:20:40 volumio volumio[18561]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 07 14:20:40 volumio volumio[18561]: info: Loading plugins from folder /data/plugins/ Feb 07 14:20:40 volumio volumio[18561]: info: Loading plugin "system"... Feb 07 14:20:40 volumio volumio[18561]: info: Loading plugin "appearance"... Feb 07 14:20:40 volumio volumio[18561]: info: Loading plugin "network"... Feb 07 14:20:40 volumio volumio[18561]: info: Refreshing Cached IP Addresses Feb 07 14:20:40 volumio sudo[18585]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 07 14:20:40 volumio sudo[18585]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 07 14:20:40 volumio sudo[18585]: pam_unix(sudo:session): session closed for user root Feb 07 14:20:40 volumio sudo[18587]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 07 14:20:40 volumio sudo[18587]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 07 14:20:40 volumio volumio[18561]: info: Loading plugin "services"... Feb 07 14:20:40 volumio volumio[18561]: info: Loading plugin "alsa_controller"... Feb 07 14:20:40 volumio sudo[18587]: pam_unix(sudo:session): session closed for user root Feb 07 14:20:40 volumio sudo[18590]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 07 14:20:40 volumio sudo[18590]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 07 14:20:40 volumio sudo[18590]: pam_unix(sudo:session): session closed for user root Feb 07 14:20:40 volumio volumio[18561]: warn: Unable to locate the audio output device JDS Labs Atom DAC. Please configure a valid output device. Feb 07 14:20:40 volumio volumio[18561]: info: Loading plugin "wizard"... Feb 07 14:20:40 volumio volumio[18561]: info: Loading plugin "volumio_command_line_client"... Feb 07 14:20:40 volumio volumio[18561]: info: Loading plugin "upnp"... Feb 07 14:20:40 volumio volumio[18561]: info: [1738938040764] Starting Upmpd Daemon Feb 07 14:20:40 volumio volumio[18561]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 07 14:20:40 volumio volumio[18561]: info: Loading plugin "my_music"... Feb 07 14:20:40 volumio volumio[18561]: info: Loading plugin "mpd"... Feb 07 14:20:40 volumio volumio[18561]: info: Creating MPD Configuration file Feb 07 14:20:40 volumio sudo[18596]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 07 14:20:40 volumio sudo[18596]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 07 14:20:40 volumio volumio[18561]: info: Loading plugin "upnp_browser"... Feb 07 14:20:41 volumio sudo[18596]: pam_unix(sudo:session): session closed for user root Feb 07 14:20:41 volumio sudo[18598]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 07 14:20:41 volumio sudo[18598]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 07 14:20:41 volumio systemd[1]: Stopping Music Player Daemon... Feb 07 14:20:41 volumio systemd[1]: mpd.service: Succeeded. Feb 07 14:20:41 volumio systemd[1]: Stopped Music Player Daemon. Feb 07 14:20:41 volumio systemd[1]: Starting Music Player Daemon... Feb 07 14:20:41 volumio volumio[18561]: info: Loading plugin "networkfs"... Feb 07 14:20:41 volumio volumio[18561]: info: Starting Udev Watcher for removable devices Feb 07 14:20:41 volumio volumio[18561]: info: Ignoring mount for partition: boot Feb 07 14:20:41 volumio volumio[18561]: info: Ignoring mount for partition: volumio Feb 07 14:20:41 volumio volumio[18561]: info: Ignoring mount for partition: volumio_data Feb 07 14:20:41 volumio volumio[18561]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 07 14:20:41 volumio volumio[18561]: info: Loading plugin "alarm-clock"... Feb 07 14:20:41 volumio volumio[18561]: info: Loading plugin "airplay_emulation"... Feb 07 14:20:41 volumio volumio[18561]: info: Starting Shairport Sync Feb 07 14:20:41 volumio volumio[18561]: info: Loading plugin "last_100"... Feb 07 14:20:41 volumio volumio[18561]: info: Loading plugin "webradio"... Feb 07 14:20:41 volumio volumio[18561]: info: Loading plugin "i2s_dacs"... Feb 07 14:20:41 volumio volumio[18561]: info: I2S DAC not set, start Auto-detection Feb 07 14:20:41 volumio mpd[18603]: Feb 07 14:20 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 07 14:20:41 volumio mpd[18603]: Feb 07 14:20 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Feb 07 14:20:41 volumio mpd[18603]: Feb 07 14:20 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Feb 07 14:20:41 volumio volumio[18561]: info: Loading plugin "volumiodiscovery"... Feb 07 14:20:41 volumio systemd[1]: Started Music Player Daemon. Feb 07 14:20:41 volumio sudo[18598]: pam_unix(sudo:session): session closed for user root Feb 07 14:20:41 volumio volumio[18561]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 07 14:20:41 volumio volumio[18561]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 07 14:20:41 volumio volumio[18561]: *** WARNING *** For more information see Feb 07 14:20:41 volumio node[18561]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 07 14:20:41 volumio volumio[18561]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 07 14:20:41 volumio volumio[18561]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 07 14:20:41 volumio volumio[18561]: *** WARNING *** For more information see Feb 07 14:20:41 volumio node[18561]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 07 14:20:41 volumio node[18561]: *** WARNING *** For more information see Feb 07 14:20:41 volumio node[18561]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 07 14:20:41 volumio node[18561]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 07 14:20:41 volumio node[18561]: *** WARNING *** For more information see Feb 07 14:20:41 volumio volumio[18561]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 07 14:20:41 volumio volumio[18561]: info: Discovery: Started advertising with name: Volumio Feb 07 14:20:41 volumio volumio[18561]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 07 14:20:41 volumio volumio[18561]: info: Loading plugin "outputs"... Feb 07 14:20:41 volumio volumio[18561]: info: Loading plugin "albumart"... Feb 07 14:20:41 volumio volumio[18561]: info: Plugin example_plugin is not enabled Feb 07 14:20:41 volumio volumio[18561]: info: Loading plugin "inputs"... Feb 07 14:20:41 volumio volumio[18561]: info: Loading plugin "updater_comm"... Feb 07 14:20:41 volumio wpa_supplicant[697]: wlan0: Failed to initiate sched scan Feb 07 14:20:41 volumio volumio[18561]: info: Plugin mpdemulation is not enabled Feb 07 14:20:41 volumio volumio[18561]: info: Loading plugin "rest_api"... Feb 07 14:20:41 volumio volumio[18561]: info: Loading plugin "websocket"... Feb 07 14:20:42 volumio volumio[18561]: info: Loading plugin "lastfm"... Feb 07 14:20:42 volumio volumio[18561]: Forking 3 albumart workers Feb 07 14:20:42 volumio volumio[18561]: info: Loading i18n strings for locale en Feb 07 14:20:42 volumio volumio[18561]: Updating browse sources language Feb 07 14:20:42 volumio volumio[18561]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 14:20:42 volumio volumio[18561]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 07 14:20:42 volumio volumio[18561]: info: CoreCommandRouter::initPlayerControls Feb 07 14:20:42 volumio volumio[18561]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:42 volumio volumio[18561]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:42 volumio volumio[18561]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:42 volumio volumio[18561]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:42 volumio volumio[18561]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:42 volumio volumio[18561]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:42 volumio volumio[18561]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:42 volumio volumio[18561]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:42 volumio volumio[18561]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 07 14:20:42 volumio volumio[18561]: Express server listening on port 3000 Feb 07 14:20:42 volumio volumio[18561]: [Metrics] WebUI: 3s 145.15ms Feb 07 14:20:42 volumio volumio[18561]: info: CoreStateMachine::resetVolumioState Feb 07 14:20:42 volumio volumio[18561]: info: CoreStateMachine::getcurrentVolume Feb 07 14:20:42 volumio volumio[18561]: info: CoreCommandRouter::volumioRetrievevolume Feb 07 14:20:42 volumio volumio[18561]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 14:20:42 volumio volumio[18561]: info: MPD Permissions set Feb 07 14:20:42 volumio volumio[18561]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Feb 07 14:20:42 volumio volumio[18561]: wlan0 Interface doesn't support scanning : Device or resource busy Feb 07 14:20:42 volumio volumio[18561]: info: Cannot use regular scanning, forcing with ap-force Feb 07 14:20:42 volumio volumio[18561]: Starting albumart workers Feb 07 14:20:42 volumio sudo[18644]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Feb 07 14:20:42 volumio sudo[18644]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 07 14:20:42 volumio volumio[18561]: Starting albumart workers Feb 07 14:20:42 volumio volumio[18561]: Starting albumart workers Feb 07 14:20:45 volumio sudo[18644]: pam_unix(sudo:session): session closed for user root Feb 07 14:20:45 volumio volumio[18561]: info: MPD running with PID18603 Feb 07 14:20:45 volumio volumio[18561]: ,establishing connection Feb 07 14:20:45 volumio volumio[18561]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Feb 07 14:20:45 volumio volumio[18561]: error: MPD error: The expression evaluated to a falsy value: Feb 07 14:20:45 volumio volumio[18561]: assert.ok(self.idling) Feb 07 14:20:45 volumio volumio[18561]: error: MPD error: The expression evaluated to a falsy value: Feb 07 14:20:45 volumio volumio[18561]: assert.ok(self.idling) Feb 07 14:20:45 volumio volumio[18561]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 1 Feb 07 14:20:45 volumio volumio[18561]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:45 volumio volumio[18561]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:45 volumio volumio[18561]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:45 volumio volumio[18561]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:45 volumio volumio[18561]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:45 volumio volumio[18561]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:45 volumio volumio[18561]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:45 volumio volumio[18561]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:45 volumio volumio[18561]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 14:20:45 volumio volumio[18561]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:45 volumio volumio[18561]: info: Reloading queue from file Feb 07 14:20:45 volumio volumio[18561]: info: VolumeController:: Volume=undefined Mute =false Feb 07 14:20:45 volumio volumio[18561]: info: CoreStateMachine::pushState Feb 07 14:20:45 volumio volumio[18561]: info: CorePlayQueue::getTrack 0 Feb 07 14:20:45 volumio volumio[18561]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 07 14:20:45 volumio volumio[18561]: info: CoreCommandRouter::volumioPushState Feb 07 14:20:45 volumio volumio[18561]: info: CoreStateMachine::updateTrackBlock Feb 07 14:20:45 volumio volumio[18561]: info: CorePlayQueue::getTrackBlock Feb 07 14:20:45 volumio volumio[18561]: info: CoreCommandRouter::volumioRetrievevolume Feb 07 14:20:45 volumio volumio[18561]: info: CoreStateMachine::setRepeat null single undefined Feb 07 14:20:45 volumio volumio[18561]: info: CoreStateMachine::pushState Feb 07 14:20:45 volumio volumio[18561]: info: CorePlayQueue::getTrack 0 Feb 07 14:20:45 volumio volumio[18561]: info: CoreCommandRouter::volumioPushState Feb 07 14:20:45 volumio volumio[18561]: info: CoreStateMachine::setRandom false Feb 07 14:20:45 volumio volumio[18561]: info: CoreStateMachine::pushState Feb 07 14:20:45 volumio volumio[18561]: info: CorePlayQueue::getTrack 0 Feb 07 14:20:45 volumio volumio[18561]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 07 14:20:45 volumio volumio[18561]: info: CoreCommandRouter::volumioPushState Feb 07 14:20:45 volumio volumio[18561]: info: Setting Device type: Raspberry PI Feb 07 14:20:45 volumio volumio[18561]: error: updateQueue error: null Feb 07 14:20:45 volumio volumio[18561]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 07 14:20:45 volumio volumio[18561]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Feb 07 14:20:45 volumio volumio[18561]: info: VolumeController:: Volume=undefined Mute =false Feb 07 14:20:45 volumio volumio[18561]: info: CoreStateMachine::pushState Feb 07 14:20:45 volumio volumio[18561]: info: CorePlayQueue::getTrack 0 Feb 07 14:20:45 volumio volumio[18561]: info: CoreCommandRouter::volumioPushState Feb 07 14:20:45 volumio volumio[18561]: info: Completed loading Core Plugins Feb 07 14:20:45 volumio volumio[18561]: info: Preparing to generate the ALSA configuration file Feb 07 14:20:45 volumio volumio[18561]: info: Asound.conf file unchanged, so no further update is needed Feb 07 14:20:45 volumio volumio[18561]: info: Output device has changed, restarting MPD Feb 07 14:20:45 volumio sudo[18682]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 07 14:20:45 volumio sudo[18682]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 07 14:20:45 volumio volumio[18561]: info: Output device has changed, restarting Shairport Sync Feb 07 14:20:45 volumio volumio[18561]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:45 volumio volumio[18561]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 14:20:45 volumio sudo[18682]: pam_unix(sudo:session): session closed for user root Feb 07 14:20:45 volumio sudo[18685]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 07 14:20:45 volumio sudo[18685]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 07 14:20:45 volumio systemd[1]: Stopping Music Player Daemon... Feb 07 14:20:45 volumio volumio[18561]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 07 14:20:45 volumio volumio[18561]: info: ___________ START PLUGINS ___________ Feb 07 14:20:45 volumio systemd[1]: mpd.service: Succeeded. Feb 07 14:20:45 volumio systemd[1]: Stopped Music Player Daemon. Feb 07 14:20:45 volumio volumio[18561]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 07 14:20:45 volumio volumio[18561]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 07 14:20:45 volumio volumio[18561]: info: [1738938045592] CoreMusicLibrary::Adding element Media Servers Feb 07 14:20:45 volumio volumio[18561]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 14:20:45 volumio volumio[18561]: Cannot find translation for sourceMedia Servers Feb 07 14:20:45 volumio systemd[1]: Starting Music Player Daemon... Feb 07 14:20:45 volumio volumio[18561]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:45 volumio volumio[18561]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 14:20:45 volumio volumio[18561]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 07 14:20:45 volumio volumio[18561]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:45 volumio volumio[18561]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 14:20:45 volumio volumio[18561]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 07 14:20:45 volumio volumio[18561]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 07 14:20:45 volumio volumio[18561]: info: [1738938045687] CoreMusicLibrary::Adding element Last_100 Feb 07 14:20:45 volumio volumio[18561]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 14:20:45 volumio volumio[18561]: Cannot find translation for sourceMedia Servers Feb 07 14:20:45 volumio volumio[18561]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 07 14:20:45 volumio volumio[18561]: info: [1738938045691] CoreMusicLibrary::Adding element Webradio Feb 07 14:20:45 volumio volumio[18561]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 14:20:45 volumio volumio[18561]: Cannot find translation for sourceMedia Servers Feb 07 14:20:45 volumio volumio[18561]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 07 14:20:45 volumio volumio[18561]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 07 14:20:45 volumio volumio[18561]: info: [1738938045700] CoreMusicLibrary::Adding element LastFM Feb 07 14:20:45 volumio volumio[18561]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 14:20:45 volumio volumio[18561]: Cannot find translation for sourceMedia Servers Feb 07 14:20:45 volumio volumio[18561]: Cannot find translation for sourceLastFM Feb 07 14:20:45 volumio volumio[18561]: info: [LastFM] scrobbler initiated! Feb 07 14:20:45 volumio volumio[18561]: info: [LastFM] extended logging: false Feb 07 14:20:45 volumio volumio[18561]: info: [LastFM] try scrobble stream/radio plays: true Feb 07 14:20:45 volumio volumio[18561]: info: [LastFM] Left init routine Feb 07 14:20:45 volumio volumio[18561]: info: [LastFM] Socket already connected: true Feb 07 14:20:45 volumio volumio[18561]: info: Volumio Calling Home Feb 07 14:20:45 volumio volumio[18561]: info: Completed starting Core Plugins Feb 07 14:20:45 volumio volumio[18561]: info: ------------------------------------------- Feb 07 14:20:45 volumio volumio[18561]: info: ----- MyVolumio plugins startup ---- Feb 07 14:20:45 volumio volumio[18561]: info: ------------------------------------------- Feb 07 14:20:45 volumio volumio[18561]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 07 14:20:45 volumio volumio[18561]: Unhandled rejection Error: No sockets available, cannot start. Feb 07 14:20:45 volumio volumio[18561]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Feb 07 14:20:45 volumio volumio[18561]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Feb 07 14:20:45 volumio volumio[18561]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Feb 07 14:20:45 volumio volumio[18561]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Feb 07 14:20:45 volumio volumio[18561]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Feb 07 14:20:45 volumio volumio[18561]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Feb 07 14:20:45 volumio volumio[18561]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Feb 07 14:20:45 volumio volumio[18561]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Feb 07 14:20:45 volumio volumio[18561]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Feb 07 14:20:45 volumio volumio[18561]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Feb 07 14:20:45 volumio volumio[18561]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Feb 07 14:20:45 volumio volumio[18561]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Feb 07 14:20:45 volumio volumio[18561]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Feb 07 14:20:45 volumio volumio[18561]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Feb 07 14:20:45 volumio volumio[18561]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Feb 07 14:20:45 volumio volumio[18561]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Feb 07 14:20:45 volumio volumio-remote-updater[454]: [2025-02-07 14:20:45] [connect] Successful connection Feb 07 14:20:45 volumio volumio[18561]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 07 14:20:45 volumio volumio[18561]: Error: getaddrinfo EBUSY ws.audioscrobbler.com Feb 07 14:20:45 volumio volumio[18561]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:67:26) { Feb 07 14:20:45 volumio volumio[18561]: errno: -16, Feb 07 14:20:45 volumio volumio[18561]: code: 'EBUSY', Feb 07 14:20:45 volumio volumio[18561]: syscall: 'getaddrinfo', Feb 07 14:20:45 volumio volumio[18561]: hostname: 'ws.audioscrobbler.com' Feb 07 14:20:45 volumio volumio[18561]: } Feb 07 14:20:45 volumio volumio[18561]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 07 14:20:46 volumio sudo[18711]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-07 14:19 Feb 07 14:20:46 volumio sudo[18711]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 07 14:20:46 volumio mpd[18698]: Feb 07 14:20 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 07 14:20:46 volumio mpd[18698]: Feb 07 14:20 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Feb 07 14:20:46 volumio mpd[18698]: Feb 07 14:20 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Feb 07 14:20:46 volumio systemd[1]: Started Music Player Daemon. Feb 07 14:20:46 volumio sudo[18685]: pam_unix(sudo:session): session closed for user root Feb 07 14:20:46 volumio sudo[18711]: pam_unix(sudo:session): session closed for user root Feb 07 14:20:46 volumio volumio-remote-updater[454]: [2025-02-07 14:20:46] [info] asio async_read_at_least error: system:104 (Connection reset by peer) Feb 07 14:20:46 volumio volumio-remote-updater[454]: [2025-02-07 14:20:46] [error] handle_read_http_response error: websocketpp.transport:2 (Underlying Transport Error) Feb 07 14:20:46 volumio volumio-remote-updater[454]: [2025-02-07 14:20:46] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Feb 07 14:20:46 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 07 14:20:46 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 07 14:20:46 volumio systemd[1]: Started dynamicswap service. Feb 07 14:20:46 volumio systemd[1]: dynamicswap.service: Succeeded. Feb 07 14:20:46 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Feb 07 14:20:46 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 25323. Feb 07 14:20:46 volumio systemd[1]: Stopped Volumio Backend Module. Feb 07 14:20:46 volumio systemd[1]: Started Volumio Backend Module. Feb 07 14:20:46 volumio systemd[1]: Started dynamicswap service. Feb 07 14:20:46 volumio systemd[1]: dynamicswap.service: Succeeded. Feb 07 14:20:47 volumio volumio[18726]: info: ------------------------------------------- Feb 07 14:20:47 volumio volumio[18726]: info: ----- Volumio3 ---- Feb 07 14:20:47 volumio volumio[18726]: info: ------------------------------------------- Feb 07 14:20:47 volumio volumio[18726]: info: ----- System startup ---- Feb 07 14:20:47 volumio volumio[18726]: info: ------------------------------------------- Feb 07 14:20:48 volumio volumio[18726]: info: MYVOLUMIO Environment detected Feb 07 14:20:48 volumio volumio[18726]: info: Plugin folders cleanup Feb 07 14:20:48 volumio volumio[18726]: info: Scanning into folder /volumio/app/plugins/ Feb 07 14:20:48 volumio volumio[18726]: info: Scanning category audio_interface Feb 07 14:20:48 volumio volumio[18726]: info: Scanning category miscellanea Feb 07 14:20:48 volumio volumio[18726]: info: Scanning category music_service Feb 07 14:20:48 volumio volumio[18726]: info: Scanning category plugins.json Feb 07 14:20:48 volumio volumio[18726]: info: Scanning category system_controller Feb 07 14:20:48 volumio volumio[18726]: info: Scanning category user_interface Feb 07 14:20:48 volumio volumio[18726]: info: Scanning into folder /data/plugins/ Feb 07 14:20:48 volumio volumio[18726]: info: Scanning category user_interface Feb 07 14:20:48 volumio volumio[18726]: info: Plugin folders cleanup completed Feb 07 14:20:48 volumio volumio[18726]: info: ------------------------------------------- Feb 07 14:20:48 volumio volumio[18726]: info: ----- Core plugins startup ---- Feb 07 14:20:48 volumio volumio[18726]: info: ------------------------------------------- Feb 07 14:20:48 volumio volumio[18726]: info: Loading plugins from folder /volumio/app/plugins/ Feb 07 14:20:48 volumio volumio[18726]: info: Adding plugin upnp to MyMusic Plugins Feb 07 14:20:48 volumio volumio[18726]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 07 14:20:48 volumio volumio[18726]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 07 14:20:48 volumio volumio[18726]: info: Loading plugins from folder /data/plugins/ Feb 07 14:20:48 volumio volumio[18726]: info: Loading plugin "system"... Feb 07 14:20:48 volumio volumio[18726]: info: Loading plugin "appearance"... Feb 07 14:20:48 volumio volumio[18726]: info: Loading plugin "network"... Feb 07 14:20:48 volumio volumio[18726]: info: Refreshing Cached IP Addresses Feb 07 14:20:48 volumio sudo[18750]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 07 14:20:48 volumio sudo[18750]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 07 14:20:48 volumio sudo[18750]: pam_unix(sudo:session): session closed for user root Feb 07 14:20:48 volumio sudo[18752]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 07 14:20:48 volumio sudo[18752]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 07 14:20:48 volumio volumio[18726]: info: Loading plugin "services"... Feb 07 14:20:48 volumio sudo[18752]: pam_unix(sudo:session): session closed for user root Feb 07 14:20:48 volumio volumio[18726]: info: Loading plugin "alsa_controller"... Feb 07 14:20:48 volumio sudo[18756]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 07 14:20:48 volumio sudo[18756]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 07 14:20:48 volumio sudo[18756]: pam_unix(sudo:session): session closed for user root Feb 07 14:20:48 volumio volumio[18726]: warn: Unable to locate the audio output device JDS Labs Atom DAC. Please configure a valid output device. Feb 07 14:20:48 volumio volumio[18726]: info: Loading plugin "wizard"... Feb 07 14:20:48 volumio volumio[18726]: info: Loading plugin "volumio_command_line_client"... Feb 07 14:20:48 volumio volumio[18726]: info: Loading plugin "upnp"... Feb 07 14:20:48 volumio volumio[18726]: info: [1738938048750] Starting Upmpd Daemon Feb 07 14:20:48 volumio volumio[18726]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 07 14:20:48 volumio volumio[18726]: info: Loading plugin "my_music"... Feb 07 14:20:48 volumio volumio[18726]: info: Loading plugin "mpd"... Feb 07 14:20:48 volumio volumio[18726]: info: Creating MPD Configuration file Feb 07 14:20:48 volumio sudo[18761]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 07 14:20:48 volumio volumio[18726]: info: Loading plugin "upnp_browser"... Feb 07 14:20:48 volumio sudo[18761]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 07 14:20:48 volumio sudo[18761]: pam_unix(sudo:session): session closed for user root Feb 07 14:20:48 volumio sudo[18763]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 07 14:20:48 volumio sudo[18763]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 07 14:20:49 volumio systemd[1]: Stopping Music Player Daemon... Feb 07 14:20:49 volumio systemd[1]: mpd.service: Succeeded. Feb 07 14:20:49 volumio systemd[1]: Stopped Music Player Daemon. Feb 07 14:20:49 volumio systemd[1]: Starting Music Player Daemon... Feb 07 14:20:49 volumio volumio[18726]: info: Loading plugin "networkfs"... Feb 07 14:20:49 volumio volumio[18726]: info: Starting Udev Watcher for removable devices Feb 07 14:20:49 volumio wpa_supplicant[697]: wlan0: Failed to initiate sched scan Feb 07 14:20:49 volumio volumio[18726]: info: Ignoring mount for partition: boot Feb 07 14:20:49 volumio volumio[18726]: info: Ignoring mount for partition: volumio Feb 07 14:20:49 volumio volumio[18726]: info: Ignoring mount for partition: volumio_data Feb 07 14:20:49 volumio volumio[18726]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 07 14:20:49 volumio volumio[18726]: info: Loading plugin "alarm-clock"... Feb 07 14:20:49 volumio volumio[18726]: info: Loading plugin "airplay_emulation"... Feb 07 14:20:49 volumio volumio[18726]: info: Starting Shairport Sync Feb 07 14:20:49 volumio volumio[18726]: info: Loading plugin "last_100"... Feb 07 14:20:49 volumio volumio[18726]: info: Loading plugin "webradio"... Feb 07 14:20:49 volumio mpd[18768]: Feb 07 14:20 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 07 14:20:49 volumio mpd[18768]: Feb 07 14:20 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Feb 07 14:20:49 volumio mpd[18768]: Feb 07 14:20 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Feb 07 14:20:49 volumio systemd[1]: Started Music Player Daemon. Feb 07 14:20:49 volumio sudo[18763]: pam_unix(sudo:session): session closed for user root Feb 07 14:20:49 volumio volumio[18726]: info: Loading plugin "i2s_dacs"... Feb 07 14:20:49 volumio volumio[18726]: info: I2S DAC not set, start Auto-detection Feb 07 14:20:49 volumio volumio[18726]: info: Loading plugin "volumiodiscovery"... Feb 07 14:20:49 volumio volumio[18726]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 07 14:20:49 volumio volumio[18726]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 07 14:20:49 volumio volumio[18726]: *** WARNING *** For more information see Feb 07 14:20:49 volumio node[18726]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 07 14:20:49 volumio volumio[18726]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 07 14:20:49 volumio volumio[18726]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 07 14:20:49 volumio volumio[18726]: *** WARNING *** For more information see Feb 07 14:20:49 volumio node[18726]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 07 14:20:49 volumio node[18726]: *** WARNING *** For more information see Feb 07 14:20:49 volumio node[18726]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 07 14:20:49 volumio node[18726]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 07 14:20:49 volumio node[18726]: *** WARNING *** For more information see Feb 07 14:20:49 volumio volumio[18726]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 07 14:20:49 volumio volumio[18726]: info: Discovery: Started advertising with name: Volumio Feb 07 14:20:49 volumio volumio[18726]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 07 14:20:49 volumio volumio[18726]: info: Loading plugin "outputs"... Feb 07 14:20:49 volumio volumio[18726]: info: Loading plugin "albumart"... Feb 07 14:20:49 volumio volumio[18726]: info: Plugin example_plugin is not enabled Feb 07 14:20:49 volumio volumio[18726]: info: Loading plugin "inputs"... Feb 07 14:20:49 volumio volumio[18726]: info: Loading plugin "updater_comm"... Feb 07 14:20:49 volumio volumio[18726]: info: Plugin mpdemulation is not enabled Feb 07 14:20:49 volumio volumio[18726]: info: Loading plugin "rest_api"... Feb 07 14:20:49 volumio volumio[18726]: info: Loading plugin "websocket"... Feb 07 14:20:49 volumio volumio[18726]: info: Loading plugin "lastfm"... Feb 07 14:20:50 volumio volumio[18726]: Forking 3 albumart workers Feb 07 14:20:50 volumio volumio[18726]: info: Loading i18n strings for locale en Feb 07 14:20:50 volumio volumio[18726]: Updating browse sources language Feb 07 14:20:50 volumio volumio[18726]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 14:20:50 volumio volumio[18726]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 07 14:20:50 volumio volumio[18726]: info: CoreCommandRouter::initPlayerControls Feb 07 14:20:50 volumio volumio[18726]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:50 volumio volumio[18726]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:50 volumio volumio[18726]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:50 volumio volumio[18726]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:50 volumio volumio[18726]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:50 volumio volumio[18726]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:50 volumio volumio[18726]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:50 volumio volumio[18726]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:50 volumio volumio[18726]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 07 14:20:50 volumio volumio[18726]: Express server listening on port 3000 Feb 07 14:20:50 volumio volumio[18726]: [Metrics] WebUI: 3s 101.19ms Feb 07 14:20:50 volumio volumio[18726]: info: CoreStateMachine::resetVolumioState Feb 07 14:20:50 volumio volumio[18726]: info: CoreStateMachine::getcurrentVolume Feb 07 14:20:50 volumio volumio[18726]: info: CoreCommandRouter::volumioRetrievevolume Feb 07 14:20:50 volumio volumio[18726]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 14:20:50 volumio volumio[18726]: info: MPD Permissions set Feb 07 14:20:50 volumio volumio[18726]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Feb 07 14:20:50 volumio volumio[18726]: wlan0 Interface doesn't support scanning : Device or resource busy Feb 07 14:20:50 volumio volumio[18726]: info: Cannot use regular scanning, forcing with ap-force Feb 07 14:20:50 volumio sudo[18809]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Feb 07 14:20:50 volumio sudo[18809]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 07 14:20:50 volumio volumio[18726]: Starting albumart workers Feb 07 14:20:50 volumio volumio[18726]: Starting albumart workers Feb 07 14:20:50 volumio volumio[18726]: Starting albumart workers Feb 07 14:20:53 volumio dhcpcd[709]: eth0: carrier acquired Feb 07 14:20:53 volumio dhcpcd[709]: DUID 00:01:00:01:29:81:35:3d:e4:5f:01:95:2f:ce Feb 07 14:20:53 volumio dhcpcd[709]: eth0: IAID 01:95:2f:ce Feb 07 14:20:53 volumio dhcpcd[709]: eth0: adding address fe80::e1f6:9bae:aa86:f530 Feb 07 14:20:53 volumio dhcpcd[709]: ipv6_addaddr1: Permission denied Feb 07 14:20:53 volumio kernel: bcmgenet fd580000.ethernet eth0: Link is Up - 1Gbps/Full - flow control rx/tx Feb 07 14:20:53 volumio sudo[18809]: pam_unix(sudo:session): session closed for user root Feb 07 14:20:53 volumio volumio[18726]: info: MPD running with PID18768 Feb 07 14:20:53 volumio volumio[18726]: ,establishing connection Feb 07 14:20:53 volumio volumio[18726]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Feb 07 14:20:53 volumio volumio[18726]: error: MPD error: The expression evaluated to a falsy value: Feb 07 14:20:53 volumio volumio[18726]: assert.ok(self.idling) Feb 07 14:20:53 volumio volumio[18726]: error: MPD error: The expression evaluated to a falsy value: Feb 07 14:20:53 volumio volumio[18726]: assert.ok(self.idling) Feb 07 14:20:53 volumio volumio[18726]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 1 Feb 07 14:20:53 volumio volumio[18726]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:53 volumio volumio[18726]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:53 volumio volumio[18726]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:53 volumio volumio[18726]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:53 volumio volumio[18726]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:53 volumio volumio[18726]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:53 volumio volumio[18726]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:53 volumio volumio[18726]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:53 volumio volumio[18726]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 14:20:53 volumio volumio[18726]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:53 volumio volumio[18726]: info: Reloading queue from file Feb 07 14:20:53 volumio volumio[18726]: info: VolumeController:: Volume=undefined Mute =false Feb 07 14:20:53 volumio volumio[18726]: info: CoreStateMachine::pushState Feb 07 14:20:53 volumio volumio[18726]: info: CorePlayQueue::getTrack 0 Feb 07 14:20:53 volumio volumio[18726]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 07 14:20:53 volumio volumio[18726]: info: CoreCommandRouter::volumioPushState Feb 07 14:20:53 volumio volumio[18726]: info: CoreStateMachine::updateTrackBlock Feb 07 14:20:53 volumio volumio[18726]: info: CorePlayQueue::getTrackBlock Feb 07 14:20:53 volumio volumio[18726]: info: CoreCommandRouter::volumioRetrievevolume Feb 07 14:20:53 volumio volumio[18726]: info: CoreStateMachine::setRepeat null single undefined Feb 07 14:20:53 volumio volumio[18726]: info: CoreStateMachine::pushState Feb 07 14:20:53 volumio volumio[18726]: info: CorePlayQueue::getTrack 0 Feb 07 14:20:53 volumio volumio[18726]: info: CoreCommandRouter::volumioPushState Feb 07 14:20:53 volumio volumio[18726]: info: CoreStateMachine::setRandom false Feb 07 14:20:53 volumio volumio[18726]: info: CoreStateMachine::pushState Feb 07 14:20:53 volumio volumio[18726]: info: CorePlayQueue::getTrack 0 Feb 07 14:20:53 volumio volumio[18726]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 07 14:20:53 volumio volumio[18726]: info: CoreCommandRouter::volumioPushState Feb 07 14:20:53 volumio volumio[18726]: info: Setting Device type: Raspberry PI Feb 07 14:20:53 volumio volumio[18726]: error: updateQueue error: null Feb 07 14:20:53 volumio volumio[18726]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 07 14:20:53 volumio volumio[18726]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Feb 07 14:20:53 volumio volumio[18726]: info: Completed loading Core Plugins Feb 07 14:20:53 volumio volumio[18726]: info: Preparing to generate the ALSA configuration file Feb 07 14:20:53 volumio volumio[18726]: info: VolumeController:: Volume=undefined Mute =false Feb 07 14:20:53 volumio volumio[18726]: info: CoreStateMachine::pushState Feb 07 14:20:53 volumio volumio[18726]: info: CorePlayQueue::getTrack 0 Feb 07 14:20:53 volumio volumio[18726]: info: CoreCommandRouter::volumioPushState Feb 07 14:20:53 volumio volumio[18726]: info: Asound.conf file unchanged, so no further update is needed Feb 07 14:20:53 volumio volumio[18726]: info: Output device has changed, restarting MPD Feb 07 14:20:53 volumio sudo[18848]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 07 14:20:53 volumio sudo[18848]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 07 14:20:53 volumio volumio[18726]: info: Output device has changed, restarting Shairport Sync Feb 07 14:20:53 volumio volumio[18726]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:53 volumio volumio[18726]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 14:20:53 volumio sudo[18848]: pam_unix(sudo:session): session closed for user root Feb 07 14:20:53 volumio sudo[18851]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 07 14:20:53 volumio sudo[18851]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 07 14:20:53 volumio systemd[1]: Stopping Music Player Daemon... Feb 07 14:20:53 volumio volumio[18726]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 07 14:20:53 volumio volumio[18726]: info: ___________ START PLUGINS ___________ Feb 07 14:20:53 volumio volumio[18726]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 07 14:20:53 volumio volumio[18726]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 07 14:20:53 volumio volumio[18726]: info: [1738938053597] CoreMusicLibrary::Adding element Media Servers Feb 07 14:20:53 volumio systemd[1]: mpd.service: Succeeded. Feb 07 14:20:53 volumio ifplugd(eth0)[617]: Link beat detected. Feb 07 14:20:53 volumio systemd[1]: Stopped Music Player Daemon. Feb 07 14:20:53 volumio systemd[1]: Starting Music Player Daemon... Feb 07 14:20:53 volumio volumio[18726]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 14:20:53 volumio volumio[18726]: Cannot find translation for sourceMedia Servers Feb 07 14:20:53 volumio dhcpcd[709]: eth0: soliciting an IPv6 router Feb 07 14:20:53 volumio volumio[18726]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:53 volumio volumio[18726]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 14:20:53 volumio volumio[18726]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 07 14:20:53 volumio volumio[18726]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:53 volumio volumio[18726]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 14:20:53 volumio dhcpcd[709]: eth0: soliciting a DHCP lease Feb 07 14:20:53 volumio volumio[18726]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 07 14:20:53 volumio volumio[18726]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 07 14:20:53 volumio volumio[18726]: info: [1738938053696] CoreMusicLibrary::Adding element Last_100 Feb 07 14:20:53 volumio volumio[18726]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 14:20:53 volumio volumio[18726]: Cannot find translation for sourceMedia Servers Feb 07 14:20:53 volumio ifplugd(eth0)[617]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Feb 07 14:20:53 volumio volumio[18726]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 07 14:20:53 volumio volumio[18726]: info: [1738938053700] CoreMusicLibrary::Adding element Webradio Feb 07 14:20:53 volumio volumio[18726]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 14:20:53 volumio volumio[18726]: Cannot find translation for sourceMedia Servers Feb 07 14:20:53 volumio volumio[18726]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 07 14:20:53 volumio volumio[18726]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 07 14:20:53 volumio volumio[18726]: info: [1738938053711] CoreMusicLibrary::Adding element LastFM Feb 07 14:20:53 volumio volumio[18726]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 14:20:53 volumio volumio[18726]: Cannot find translation for sourceMedia Servers Feb 07 14:20:53 volumio volumio[18726]: Cannot find translation for sourceLastFM Feb 07 14:20:53 volumio volumio[18726]: info: [LastFM] scrobbler initiated! Feb 07 14:20:53 volumio volumio[18726]: info: [LastFM] extended logging: false Feb 07 14:20:53 volumio volumio[18726]: info: [LastFM] try scrobble stream/radio plays: true Feb 07 14:20:53 volumio volumio[18726]: info: [LastFM] Left init routine Feb 07 14:20:53 volumio volumio[18726]: info: [LastFM] Socket already connected: true Feb 07 14:20:53 volumio volumio[18726]: info: Volumio Calling Home Feb 07 14:20:53 volumio volumio[18726]: info: Completed starting Core Plugins Feb 07 14:20:53 volumio volumio[18726]: info: ------------------------------------------- Feb 07 14:20:53 volumio volumio[18726]: info: ----- MyVolumio plugins startup ---- Feb 07 14:20:53 volumio volumio[18726]: info: ------------------------------------------- Feb 07 14:20:53 volumio volumio[18726]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 07 14:20:53 volumio dhcpcd[18890]: sending commands to master dhcpcd process Feb 07 14:20:53 volumio ifplugd(eth0)[617]: client: sending commands to master dhcpcd process Feb 07 14:20:53 volumio dhcpcd[709]: control command: /sbin/dhcpcd eth0 Feb 07 14:20:53 volumio volumio[18726]: Unhandled rejection Error: No sockets available, cannot start. Feb 07 14:20:53 volumio volumio[18726]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Feb 07 14:20:53 volumio volumio[18726]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Feb 07 14:20:53 volumio volumio[18726]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Feb 07 14:20:53 volumio volumio[18726]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Feb 07 14:20:53 volumio volumio[18726]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Feb 07 14:20:53 volumio volumio[18726]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Feb 07 14:20:53 volumio volumio[18726]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Feb 07 14:20:53 volumio volumio[18726]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Feb 07 14:20:53 volumio volumio[18726]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Feb 07 14:20:53 volumio volumio[18726]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Feb 07 14:20:53 volumio volumio[18726]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Feb 07 14:20:53 volumio volumio[18726]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Feb 07 14:20:53 volumio volumio[18726]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Feb 07 14:20:53 volumio volumio[18726]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Feb 07 14:20:53 volumio volumio[18726]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Feb 07 14:20:53 volumio volumio[18726]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Feb 07 14:20:53 volumio volumio[18726]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 07 14:20:53 volumio volumio[18726]: Error: getaddrinfo EBUSY ws.audioscrobbler.com Feb 07 14:20:53 volumio volumio[18726]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:67:26) { Feb 07 14:20:53 volumio volumio[18726]: errno: -16, Feb 07 14:20:53 volumio volumio[18726]: code: 'EBUSY', Feb 07 14:20:53 volumio volumio[18726]: syscall: 'getaddrinfo', Feb 07 14:20:53 volumio volumio[18726]: hostname: 'ws.audioscrobbler.com' Feb 07 14:20:53 volumio volumio[18726]: } Feb 07 14:20:53 volumio volumio[18726]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 07 14:20:53 volumio ifplugd(eth0)[617]: Program executed successfully. Feb 07 14:20:54 volumio sudo[18925]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-07 14:19 Feb 07 14:20:54 volumio sudo[18925]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 07 14:20:54 volumio mpd[18869]: Feb 07 14:20 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 07 14:20:54 volumio mpd[18869]: Feb 07 14:20 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Feb 07 14:20:54 volumio mpd[18869]: Feb 07 14:20 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Feb 07 14:20:54 volumio systemd[1]: Started Music Player Daemon. Feb 07 14:20:54 volumio sudo[18851]: pam_unix(sudo:session): session closed for user root Feb 07 14:20:54 volumio sudo[18925]: pam_unix(sudo:session): session closed for user root Feb 07 14:20:54 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 07 14:20:54 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 07 14:20:54 volumio systemd[1]: Started dynamicswap service. Feb 07 14:20:54 volumio systemd[1]: dynamicswap.service: Succeeded. Feb 07 14:20:54 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Feb 07 14:20:54 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 25324. Feb 07 14:20:54 volumio systemd[1]: Started dynamicswap service. Feb 07 14:20:54 volumio systemd[1]: Stopped Volumio Backend Module. Feb 07 14:20:54 volumio systemd[1]: Started Volumio Backend Module. Feb 07 14:20:54 volumio systemd[1]: dynamicswap.service: Succeeded. Feb 07 14:20:55 volumio volumio[18941]: info: ------------------------------------------- Feb 07 14:20:55 volumio volumio[18941]: info: ----- Volumio3 ---- Feb 07 14:20:55 volumio volumio[18941]: info: ------------------------------------------- Feb 07 14:20:55 volumio volumio[18941]: info: ----- System startup ---- Feb 07 14:20:55 volumio volumio[18941]: info: ------------------------------------------- Feb 07 14:20:56 volumio volumio[18941]: info: MYVOLUMIO Environment detected Feb 07 14:20:56 volumio volumio[18941]: info: Plugin folders cleanup Feb 07 14:20:56 volumio volumio[18941]: info: Scanning into folder /volumio/app/plugins/ Feb 07 14:20:56 volumio volumio[18941]: info: Scanning category audio_interface Feb 07 14:20:56 volumio volumio[18941]: info: Scanning category miscellanea Feb 07 14:20:56 volumio volumio[18941]: info: Scanning category music_service Feb 07 14:20:56 volumio volumio[18941]: info: Scanning category plugins.json Feb 07 14:20:56 volumio volumio[18941]: info: Scanning category system_controller Feb 07 14:20:56 volumio volumio[18941]: info: Scanning category user_interface Feb 07 14:20:56 volumio volumio[18941]: info: Scanning into folder /data/plugins/ Feb 07 14:20:56 volumio volumio[18941]: info: Scanning category user_interface Feb 07 14:20:56 volumio volumio[18941]: info: Plugin folders cleanup completed Feb 07 14:20:56 volumio volumio[18941]: info: ------------------------------------------- Feb 07 14:20:56 volumio volumio[18941]: info: ----- Core plugins startup ---- Feb 07 14:20:56 volumio volumio[18941]: info: ------------------------------------------- Feb 07 14:20:56 volumio volumio[18941]: info: Loading plugins from folder /volumio/app/plugins/ Feb 07 14:20:56 volumio volumio[18941]: info: Adding plugin upnp to MyMusic Plugins Feb 07 14:20:56 volumio volumio[18941]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 07 14:20:56 volumio volumio[18941]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 07 14:20:56 volumio volumio[18941]: info: Loading plugins from folder /data/plugins/ Feb 07 14:20:56 volumio volumio[18941]: info: Loading plugin "system"... Feb 07 14:20:56 volumio volumio[18941]: info: Loading plugin "appearance"... Feb 07 14:20:56 volumio volumio-remote-updater[454]: [2025-02-07 14:20:56] [connect] Successful connection Feb 07 14:20:56 volumio volumio[18941]: info: Loading plugin "network"... Feb 07 14:20:56 volumio volumio[18941]: info: Refreshing Cached IP Addresses Feb 07 14:20:56 volumio sudo[18964]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 07 14:20:56 volumio sudo[18964]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 07 14:20:56 volumio sudo[18964]: pam_unix(sudo:session): session closed for user root Feb 07 14:20:56 volumio volumio[18941]: info: Loading plugin "services"... Feb 07 14:20:56 volumio sudo[18966]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 07 14:20:56 volumio sudo[18966]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 07 14:20:56 volumio volumio[18941]: info: Loading plugin "alsa_controller"... Feb 07 14:20:56 volumio sudo[18966]: pam_unix(sudo:session): session closed for user root Feb 07 14:20:56 volumio sudo[18969]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 07 14:20:56 volumio sudo[18969]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 07 14:20:56 volumio sudo[18969]: pam_unix(sudo:session): session closed for user root Feb 07 14:20:56 volumio volumio[18941]: warn: Unable to locate the audio output device JDS Labs Atom DAC. Please configure a valid output device. Feb 07 14:20:56 volumio volumio[18941]: info: Loading plugin "wizard"... Feb 07 14:20:56 volumio volumio[18941]: info: Loading plugin "volumio_command_line_client"... Feb 07 14:20:57 volumio volumio[18941]: info: Loading plugin "upnp"... Feb 07 14:20:57 volumio volumio[18941]: info: [1738938057004] Starting Upmpd Daemon Feb 07 14:20:57 volumio volumio[18941]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 07 14:20:57 volumio volumio[18941]: info: Loading plugin "my_music"... Feb 07 14:20:57 volumio volumio[18941]: info: Loading plugin "mpd"... Feb 07 14:20:57 volumio dhcpcd[709]: eth0: offered 192.168.1.105 from 192.168.1.1 Feb 07 14:20:57 volumio wpa_supplicant[697]: wlan0: Failed to initiate sched scan Feb 07 14:20:57 volumio dhcpcd[709]: eth0: probing address 192.168.1.105/24 Feb 07 14:20:57 volumio volumio[18941]: info: Creating MPD Configuration file Feb 07 14:20:57 volumio sudo[18975]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 07 14:20:57 volumio volumio[18941]: info: Loading plugin "upnp_browser"... Feb 07 14:20:57 volumio sudo[18975]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 07 14:20:57 volumio sudo[18975]: pam_unix(sudo:session): session closed for user root Feb 07 14:20:57 volumio sudo[18977]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 07 14:20:57 volumio sudo[18977]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 07 14:20:57 volumio systemd[1]: Stopping Music Player Daemon... Feb 07 14:20:57 volumio systemd[1]: mpd.service: Succeeded. Feb 07 14:20:57 volumio systemd[1]: Stopped Music Player Daemon. Feb 07 14:20:57 volumio systemd[1]: Starting Music Player Daemon... Feb 07 14:20:57 volumio volumio[18941]: info: Loading plugin "networkfs"... Feb 07 14:20:57 volumio volumio[18941]: info: Starting Udev Watcher for removable devices Feb 07 14:20:57 volumio volumio[18941]: info: Ignoring mount for partition: boot Feb 07 14:20:57 volumio volumio[18941]: info: Ignoring mount for partition: volumio Feb 07 14:20:57 volumio volumio[18941]: info: Ignoring mount for partition: volumio_data Feb 07 14:20:57 volumio volumio[18941]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 07 14:20:57 volumio volumio[18941]: info: Loading plugin "alarm-clock"... Feb 07 14:20:57 volumio volumio[18941]: info: Loading plugin "airplay_emulation"... Feb 07 14:20:57 volumio volumio[18941]: info: Starting Shairport Sync Feb 07 14:20:57 volumio volumio[18941]: info: Loading plugin "last_100"... Feb 07 14:20:57 volumio volumio[18941]: info: Loading plugin "webradio"... Feb 07 14:20:57 volumio mpd[18982]: Feb 07 14:20 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 07 14:20:57 volumio mpd[18982]: Feb 07 14:20 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Feb 07 14:20:57 volumio mpd[18982]: Feb 07 14:20 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Feb 07 14:20:57 volumio systemd[1]: Started Music Player Daemon. Feb 07 14:20:57 volumio sudo[18977]: pam_unix(sudo:session): session closed for user root Feb 07 14:20:57 volumio volumio[18941]: info: Loading plugin "i2s_dacs"... Feb 07 14:20:57 volumio volumio[18941]: info: I2S DAC not set, start Auto-detection Feb 07 14:20:58 volumio volumio[18941]: info: Loading plugin "volumiodiscovery"... Feb 07 14:20:58 volumio volumio[18941]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 07 14:20:58 volumio volumio[18941]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 07 14:20:58 volumio volumio[18941]: *** WARNING *** For more information see Feb 07 14:20:58 volumio node[18941]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 07 14:20:58 volumio volumio[18941]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 07 14:20:58 volumio volumio[18941]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 07 14:20:58 volumio volumio[18941]: *** WARNING *** For more information see Feb 07 14:20:58 volumio node[18941]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 07 14:20:58 volumio node[18941]: *** WARNING *** For more information see Feb 07 14:20:58 volumio node[18941]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 07 14:20:58 volumio node[18941]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 07 14:20:58 volumio node[18941]: *** WARNING *** For more information see Feb 07 14:20:58 volumio volumio[18941]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 07 14:20:58 volumio volumio[18941]: info: Discovery: Started advertising with name: Volumio Feb 07 14:20:58 volumio volumio[18941]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 07 14:20:58 volumio volumio[18941]: info: Loading plugin "outputs"... Feb 07 14:20:58 volumio volumio[18941]: info: Loading plugin "albumart"... Feb 07 14:20:58 volumio volumio[18941]: info: Plugin example_plugin is not enabled Feb 07 14:20:58 volumio volumio[18941]: info: Loading plugin "inputs"... Feb 07 14:20:58 volumio volumio[18941]: info: Loading plugin "updater_comm"... Feb 07 14:20:58 volumio volumio[18941]: info: Plugin mpdemulation is not enabled Feb 07 14:20:58 volumio volumio[18941]: info: Loading plugin "rest_api"... Feb 07 14:20:58 volumio volumio[18941]: info: Loading plugin "websocket"... Feb 07 14:20:58 volumio volumio[18941]: info: Loading plugin "lastfm"... Feb 07 14:20:58 volumio volumio[18941]: Forking 3 albumart workers Feb 07 14:20:58 volumio volumio[18941]: info: Loading i18n strings for locale en Feb 07 14:20:58 volumio volumio[18941]: Updating browse sources language Feb 07 14:20:58 volumio volumio[18941]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 14:20:58 volumio volumio[18941]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 07 14:20:58 volumio volumio[18941]: info: CoreCommandRouter::initPlayerControls Feb 07 14:20:58 volumio volumio[18941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:58 volumio volumio[18941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:58 volumio volumio[18941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:58 volumio volumio[18941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:58 volumio volumio[18941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:58 volumio volumio[18941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:58 volumio volumio[18941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:58 volumio volumio[18941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:20:58 volumio volumio[18941]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 07 14:20:58 volumio volumio[18941]: Express server listening on port 3000 Feb 07 14:20:58 volumio volumio[18941]: [Metrics] WebUI: 3s 114.03ms Feb 07 14:20:58 volumio volumio[18941]: info: CoreStateMachine::resetVolumioState Feb 07 14:20:58 volumio volumio[18941]: info: CoreStateMachine::getcurrentVolume Feb 07 14:20:58 volumio volumio[18941]: info: CoreCommandRouter::volumioRetrievevolume Feb 07 14:20:58 volumio volumio[18941]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 14:20:58 volumio volumio[18941]: info: MPD Permissions set Feb 07 14:20:58 volumio volumio[18941]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Feb 07 14:20:58 volumio volumio[18941]: wlan0 Interface doesn't support scanning : Device or resource busy Feb 07 14:20:58 volumio volumio[18941]: info: Cannot use regular scanning, forcing with ap-force Feb 07 14:20:58 volumio sudo[19023]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Feb 07 14:20:58 volumio sudo[19023]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 07 14:20:58 volumio volumio[18941]: Starting albumart workers Feb 07 14:20:58 volumio volumio[18941]: Starting albumart workers Feb 07 14:20:58 volumio volumio[18941]: Starting albumart workers Feb 07 14:21:01 volumio sudo[19023]: pam_unix(sudo:session): session closed for user root Feb 07 14:21:01 volumio volumio-remote-updater[454]: [2025-02-07 14:21:01] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1738938056 101 Feb 07 14:21:01 volumio volumio[18941]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1 Feb 07 14:21:01 volumio volumio[18941]: info: MPD running with PID18982 Feb 07 14:21:01 volumio volumio[18941]: ,establishing connection Feb 07 14:21:01 volumio volumio[18941]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Feb 07 14:21:01 volumio volumio[18941]: error: MPD error: The expression evaluated to a falsy value: Feb 07 14:21:01 volumio volumio[18941]: assert.ok(self.idling) Feb 07 14:21:01 volumio volumio[18941]: error: MPD error: The expression evaluated to a falsy value: Feb 07 14:21:01 volumio volumio[18941]: assert.ok(self.idling) Feb 07 14:21:01 volumio volumio[18941]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 Feb 07 14:21:01 volumio volumio[18941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:21:01 volumio volumio[18941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:21:01 volumio volumio[18941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:21:01 volumio volumio[18941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:21:01 volumio volumio[18941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:21:01 volumio volumio[18941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:21:01 volumio volumio[18941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:21:01 volumio volumio[18941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:21:01 volumio volumio[18941]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 14:21:01 volumio volumio[18941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:21:01 volumio volumio[18941]: info: Reloading queue from file Feb 07 14:21:01 volumio volumio[18941]: info: VolumeController:: Volume=undefined Mute =false Feb 07 14:21:01 volumio volumio[18941]: info: CoreStateMachine::pushState Feb 07 14:21:01 volumio volumio[18941]: info: CorePlayQueue::getTrack 0 Feb 07 14:21:01 volumio volumio[18941]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 07 14:21:01 volumio volumio[18941]: info: CoreCommandRouter::volumioPushState Feb 07 14:21:01 volumio volumio[18941]: info: CoreStateMachine::updateTrackBlock Feb 07 14:21:01 volumio volumio[18941]: info: CorePlayQueue::getTrackBlock Feb 07 14:21:01 volumio volumio[18941]: info: CoreCommandRouter::volumioRetrievevolume Feb 07 14:21:01 volumio volumio[18941]: info: CoreStateMachine::setRepeat null single undefined Feb 07 14:21:01 volumio volumio[18941]: info: CoreStateMachine::pushState Feb 07 14:21:01 volumio volumio[18941]: info: CorePlayQueue::getTrack 0 Feb 07 14:21:01 volumio volumio[18941]: info: CoreCommandRouter::volumioPushState Feb 07 14:21:01 volumio volumio[18941]: info: CoreStateMachine::setRandom false Feb 07 14:21:01 volumio volumio[18941]: info: CoreStateMachine::pushState Feb 07 14:21:01 volumio volumio[18941]: info: CorePlayQueue::getTrack 0 Feb 07 14:21:01 volumio volumio[18941]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 07 14:21:01 volumio volumio[18941]: info: CoreCommandRouter::volumioPushState Feb 07 14:21:01 volumio volumio[18941]: info: Setting Device type: Raspberry PI Feb 07 14:21:01 volumio volumio[18941]: error: updateQueue error: null Feb 07 14:21:01 volumio volumio[18941]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 07 14:21:01 volumio volumio[18941]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Feb 07 14:21:01 volumio volumio[18941]: info: VolumeController:: Volume=undefined Mute =false Feb 07 14:21:01 volumio volumio[18941]: info: CoreStateMachine::pushState Feb 07 14:21:01 volumio volumio[18941]: info: CorePlayQueue::getTrack 0 Feb 07 14:21:01 volumio volumio[18941]: info: CoreCommandRouter::volumioPushState Feb 07 14:21:01 volumio volumio[18941]: info: Completed loading Core Plugins Feb 07 14:21:01 volumio volumio[18941]: info: Preparing to generate the ALSA configuration file Feb 07 14:21:01 volumio volumio[18941]: info: Asound.conf file unchanged, so no further update is needed Feb 07 14:21:01 volumio volumio[18941]: info: Output device has changed, restarting MPD Feb 07 14:21:01 volumio sudo[19061]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 07 14:21:01 volumio sudo[19061]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 07 14:21:01 volumio volumio[18941]: info: Output device has changed, restarting Shairport Sync Feb 07 14:21:01 volumio volumio[18941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:21:01 volumio sudo[19061]: pam_unix(sudo:session): session closed for user root Feb 07 14:21:01 volumio volumio[18941]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 14:21:01 volumio sudo[19064]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 07 14:21:01 volumio sudo[19064]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 07 14:21:01 volumio systemd[1]: Stopping Music Player Daemon... Feb 07 14:21:01 volumio volumio[18941]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 07 14:21:01 volumio volumio[18941]: info: ___________ START PLUGINS ___________ Feb 07 14:21:01 volumio systemd[1]: mpd.service: Succeeded. Feb 07 14:21:01 volumio systemd[1]: Stopped Music Player Daemon. Feb 07 14:21:01 volumio volumio[18941]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 07 14:21:01 volumio volumio[18941]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 07 14:21:01 volumio volumio[18941]: info: [1738938061793] CoreMusicLibrary::Adding element Media Servers Feb 07 14:21:01 volumio volumio[18941]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 14:21:01 volumio volumio[18941]: Cannot find translation for sourceMedia Servers Feb 07 14:21:01 volumio systemd[1]: Starting Music Player Daemon... Feb 07 14:21:01 volumio volumio[18941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:21:01 volumio volumio[18941]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 14:21:01 volumio volumio[18941]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 07 14:21:01 volumio volumio[18941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 14:21:01 volumio volumio[18941]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 14:21:01 volumio volumio[18941]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 07 14:21:01 volumio volumio[18941]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 07 14:21:01 volumio volumio[18941]: info: [1738938061889] CoreMusicLibrary::Adding element Last_100 Feb 07 14:21:01 volumio volumio[18941]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 14:21:01 volumio volumio[18941]: Cannot find translation for sourceMedia Servers Feb 07 14:21:01 volumio volumio[18941]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 07 14:21:01 volumio volumio[18941]: info: [1738938061892] CoreMusicLibrary::Adding element Webradio Feb 07 14:21:01 volumio volumio[18941]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 14:21:01 volumio volumio[18941]: Cannot find translation for sourceMedia Servers Feb 07 14:21:01 volumio volumio[18941]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 07 14:21:01 volumio volumio[18941]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 07 14:21:01 volumio volumio[18941]: info: [1738938061901] CoreMusicLibrary::Adding element LastFM Feb 07 14:21:01 volumio volumio[18941]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 14:21:01 volumio volumio[18941]: Cannot find translation for sourceMedia Servers Feb 07 14:21:01 volumio volumio[18941]: Cannot find translation for sourceLastFM Feb 07 14:21:01 volumio volumio[18941]: info: [LastFM] scrobbler initiated! Feb 07 14:21:01 volumio volumio[18941]: info: [LastFM] extended logging: false Feb 07 14:21:01 volumio volumio[18941]: info: [LastFM] try scrobble stream/radio plays: true Feb 07 14:21:01 volumio volumio[18941]: info: [LastFM] Left init routine Feb 07 14:21:01 volumio volumio[18941]: info: [LastFM] Socket already connected: true Feb 07 14:21:01 volumio volumio[18941]: info: Volumio Calling Home Feb 07 14:21:01 volumio volumio[18941]: info: Completed starting Core Plugins Feb 07 14:21:01 volumio volumio[18941]: info: ------------------------------------------- Feb 07 14:21:01 volumio volumio[18941]: info: ----- MyVolumio plugins startup ---- Feb 07 14:21:01 volumio volumio[18941]: info: ------------------------------------------- Feb 07 14:21:01 volumio volumio[18941]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 07 14:21:02 volumio volumio[18941]: Unhandled rejection Error: No sockets available, cannot start. Feb 07 14:21:02 volumio volumio[18941]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Feb 07 14:21:02 volumio volumio[18941]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Feb 07 14:21:02 volumio volumio[18941]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Feb 07 14:21:02 volumio volumio[18941]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Feb 07 14:21:02 volumio volumio[18941]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Feb 07 14:21:02 volumio volumio[18941]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Feb 07 14:21:02 volumio volumio[18941]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Feb 07 14:21:02 volumio volumio[18941]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Feb 07 14:21:02 volumio volumio[18941]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Feb 07 14:21:02 volumio volumio[18941]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Feb 07 14:21:02 volumio volumio[18941]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Feb 07 14:21:02 volumio volumio[18941]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Feb 07 14:21:02 volumio volumio[18941]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Feb 07 14:21:02 volumio volumio[18941]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Feb 07 14:21:02 volumio volumio[18941]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Feb 07 14:21:02 volumio volumio[18941]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Feb 07 14:21:02 volumio volumio[18941]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 07 14:21:02 volumio volumio[18941]: Error: getaddrinfo EBUSY ws.audioscrobbler.com Feb 07 14:21:02 volumio volumio[18941]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:67:26) { Feb 07 14:21:02 volumio volumio[18941]: errno: -16, Feb 07 14:21:02 volumio volumio[18941]: code: 'EBUSY', Feb 07 14:21:02 volumio volumio[18941]: syscall: 'getaddrinfo', Feb 07 14:21:02 volumio volumio[18941]: hostname: 'ws.audioscrobbler.com' Feb 07 14:21:02 volumio volumio[18941]: } Feb 07 14:21:02 volumio volumio[18941]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 07 14:21:02 volumio dhcpcd[709]: eth0: leased 192.168.1.105 for 86400 seconds Feb 07 14:21:02 volumio dhcpcd[709]: eth0: adding route to 192.168.1.0/24 Feb 07 14:21:02 volumio dhcpcd[709]: eth0: adding default route via 192.168.1.1 Feb 07 14:21:02 volumio avahi-daemon[461]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.105. Feb 07 14:21:02 volumio avahi-daemon[461]: New relevant interface eth0.IPv4 for mDNS. Feb 07 14:21:02 volumio avahi-daemon[461]: Registering new address record for 192.168.1.105 on eth0.IPv4. Feb 07 14:21:02 volumio sudo[19112]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-07 14:20 Feb 07 14:21:02 volumio sudo[19112]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 07 14:21:02 volumio mpd[19077]: Feb 07 14:21 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 07 14:21:02 volumio mpd[19077]: Feb 07 14:21 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Feb 07 14:21:02 volumio mpd[19077]: Feb 07 14:21 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Feb 07 14:21:02 volumio systemd[1]: Started Music Player Daemon. Feb 07 14:21:02 volumio sudo[19064]: pam_unix(sudo:session): session closed for user root 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="e5f8795c07ba36ff2b02d7ba0a21c7a0f29b897e" VOLUMIO_FE_VERSION="473d8facbed5f9754db84a81b093baace7aa4b64" VOLUMIO_FE3_VERSION="934cddf6d46478e7a53a8d3664cd346f4de7cb34" VOLUMIO_BE_VERSION="359b2717b79cfc72b7d1c918d0fc2885a93bca70" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 24 Jan 2022 10:51:57 AM CET" VOLUMIO_VERSION="3.198" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="74e6a9ad035e912732893c8d6b060b17"