-- Logs begin at Wed 2024-05-08 22:21:01 UTC, end at Thu 2024-05-09 23:20:52 UTC. -- May 09 23:19:00 tunes sudo[12625]: pam_unix(sudo:session): session closed for user root May 09 23:19:00 tunes volumio[12590]: info: Creating MPD Configuration file May 09 23:19:00 tunes volumio[12590]: info: Loading plugin "upnp_browser"... May 09 23:19:00 tunes sudo[12634]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 09 23:19:00 tunes sudo[12634]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 23:19:00 tunes sudo[12634]: pam_unix(sudo:session): session closed for user root May 09 23:19:00 tunes sudo[12636]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 09 23:19:00 tunes sudo[12636]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 23:19:00 tunes systemd[1]: Stopping Music Player Daemon... May 09 23:19:01 tunes systemd[1]: mpd.service: Succeeded. May 09 23:19:01 tunes systemd[1]: Stopped Music Player Daemon. May 09 23:19:01 tunes systemd[1]: Starting Music Player Daemon... May 09 23:19:01 tunes volumio[12590]: info: Loading plugin "networkfs"... May 09 23:19:02 tunes volumio[12590]: info: Cannot mount NAS centralmusic at system boot, trial number 1 ,retrying in 5 seconds May 09 23:19:02 tunes volumio[12590]: info: Starting Udev Watcher for removable devices May 09 23:19:02 tunes volumio[12590]: info: Ignoring mount for partition: boot May 09 23:19:02 tunes volumio[12590]: info: Ignoring mount for partition: volumio May 09 23:19:02 tunes volumio[12590]: info: Ignoring mount for partition: volumio_data May 09 23:19:02 tunes volumio[12590]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 09 23:19:02 tunes volumio[12590]: info: Loading plugin "alarm-clock"... May 09 23:19:02 tunes ntpd[663]: Soliciting pool server 198.71.50.75 May 09 23:19:03 tunes volumio[12590]: info: Loading plugin "airplay_emulation"... May 09 23:19:03 tunes volumio[12590]: info: Starting Shairport Sync May 09 23:19:03 tunes volumio[12590]: info: Loading plugin "last_100"... May 09 23:19:03 tunes volumio[12590]: info: Loading plugin "webradio"... May 09 23:19:03 tunes volumio[12590]: info: Loading plugin "i2s_dacs"... May 09 23:19:03 tunes volumio[12590]: info: Loading plugin "volumiodiscovery"... May 09 23:19:03 tunes volumio[12590]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 09 23:19:03 tunes node[12590]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 09 23:19:03 tunes volumio[12590]: *** WARNING *** Please fix your application to use the native API of Avahi! May 09 23:19:03 tunes node[12590]: *** WARNING *** Please fix your application to use the native API of Avahi! May 09 23:19:03 tunes volumio[12590]: *** WARNING *** For more information see May 09 23:19:03 tunes node[12590]: *** WARNING *** For more information see May 09 23:19:03 tunes volumio[12590]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 09 23:19:03 tunes node[12590]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 09 23:19:03 tunes volumio[12590]: *** WARNING *** Please fix your application to use the native API of Avahi! May 09 23:19:03 tunes node[12590]: *** WARNING *** Please fix your application to use the native API of Avahi! May 09 23:19:03 tunes volumio[12590]: *** WARNING *** For more information see May 09 23:19:03 tunes node[12590]: *** WARNING *** For more information see May 09 23:19:03 tunes volumio[12590]: info: Applying required configuration parameters for plugin volumiodiscovery May 09 23:19:03 tunes volumio[12590]: info: Discovery: Started advertising with name: Tunes May 09 23:19:03 tunes volumio[12590]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 09 23:19:03 tunes volumio[12590]: info: Loading plugin "spop"... May 09 23:19:04 tunes mpd[12642]: May 09 23:19 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 09 23:19:06 tunes volumio[12590]: info: Loading plugin "outputs"... May 09 23:19:06 tunes volumio[12590]: info: Loading plugin "albumart"... May 09 23:19:06 tunes volumio[12590]: info: Plugin example_plugin is not enabled May 09 23:19:06 tunes volumio[12590]: info: Loading plugin "inputs"... May 09 23:19:06 tunes volumio[12590]: info: Loading plugin "updater_comm"... May 09 23:19:06 tunes volumio[12590]: info: Plugin mpdemulation is not enabled May 09 23:19:06 tunes volumio[12590]: info: Loading plugin "rest_api"... May 09 23:19:06 tunes volumio[12590]: info: Loading plugin "websocket"... May 09 23:19:06 tunes volumio[12590]: info: Loading plugin "lastfm"... May 09 23:19:07 tunes volumio[12590]: Forking 3 albumart workers May 09 23:19:07 tunes volumio-remote-updater[540]: [2024-05-09 23:19:07] [connect] Successful connection May 09 23:19:08 tunes volumio[12590]: info: Loading plugin "randomizer"... May 09 23:19:08 tunes mpd[12642]: May 09 23:19 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured May 09 23:19:08 tunes mpd[12642]: May 09 23:19 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured May 09 23:19:08 tunes systemd[1]: Started Music Player Daemon. May 09 23:19:08 tunes sudo[12636]: pam_unix(sudo:session): session closed for user root May 09 23:19:08 tunes volumio[12590]: Starting albumart workers May 09 23:19:09 tunes volumio[12590]: Starting albumart workers May 09 23:19:09 tunes volumio[12590]: info: Applying required configuration parameters for plugin randomizer May 09 23:19:09 tunes volumio[12590]: Starting albumart workers May 09 23:19:09 tunes volumio[12590]: info: Loading i18n strings for locale en May 09 23:19:09 tunes volumio[12590]: Updating browse sources language May 09 23:19:09 tunes volumio[12590]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 09 23:19:09 tunes volumio[12590]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 09 23:19:09 tunes volumio[12590]: info: CoreCommandRouter::initPlayerControls May 09 23:19:09 tunes volumio[12590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 23:19:09 tunes volumio[12590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 23:19:09 tunes volumio[12590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 23:19:09 tunes volumio[12590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 23:19:09 tunes volumio[12590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 23:19:09 tunes volumio[12590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 23:19:09 tunes volumio[12590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 23:19:09 tunes volumio[12590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 23:19:10 tunes volumio[12590]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 09 23:19:10 tunes volumio[12590]: Express server listening on port 3000 May 09 23:19:10 tunes volumio[12590]: [Metrics] WebUI: 16s 883.34ms May 09 23:19:10 tunes volumio[12590]: info: CoreStateMachine::resetVolumioState May 09 23:19:10 tunes volumio[12590]: info: CoreStateMachine::getcurrentVolume May 09 23:19:10 tunes volumio[12590]: info: CoreCommandRouter::volumioRetrievevolume May 09 23:19:10 tunes volumio[12590]: info: Cannot mount NAS centralmusic at system boot, trial number 2 ,retrying in 5 seconds May 09 23:19:10 tunes sudo[12690]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 09 23:19:10 tunes sudo[12692]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 09 23:19:10 tunes sudo[12692]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 23:19:10 tunes sudo[12690]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 23:19:10 tunes sudo[12690]: pam_unix(sudo:session): session closed for user root May 09 23:19:10 tunes sudo[12692]: pam_unix(sudo:session): session closed for user root May 09 23:19:10 tunes volumio[12590]: info: MPD Permissions set May 09 23:19:10 tunes volumio-remote-updater[540]: [2024-05-09 23:19:10] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1715296747 101 May 09 23:19:10 tunes volumio[12590]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1 May 09 23:19:10 tunes volumio[12590]: info: VolumeController:: Volume=40 Mute =false May 09 23:19:10 tunes volumio[12590]: info: CoreStateMachine::pushState May 09 23:19:10 tunes volumio[12590]: info: CorePlayQueue::getTrack 0 May 09 23:19:10 tunes volumio[12590]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 23:19:10 tunes volumio[12590]: info: CoreCommandRouter::volumioPushState May 09 23:19:10 tunes volumio[12590]: info: CoreStateMachine::updateTrackBlock May 09 23:19:10 tunes volumio[12590]: info: CorePlayQueue::getTrackBlock May 09 23:19:10 tunes volumio[12590]: info: CoreCommandRouter::volumioRetrievevolume May 09 23:19:11 tunes volumio[12590]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 May 09 23:19:11 tunes volumio[12590]: info: MPD running with PID12642 May 09 23:19:11 tunes volumio[12590]: ,establishing connection May 09 23:19:11 tunes volumio[12590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 23:19:11 tunes volumio[12590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 23:19:11 tunes volumio[12590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 23:19:11 tunes volumio[12590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 23:19:11 tunes volumio[12590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 23:19:11 tunes volumio[12590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 23:19:11 tunes volumio[12590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 23:19:11 tunes volumio[12590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 23:19:11 tunes volumio[12590]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 23:19:11 tunes volumio[12590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 23:19:11 tunes volumio[12590]: info: Reloading queue from file May 09 23:19:11 tunes volumio[12590]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 23:19:11 tunes volumio[12590]: info: Executing start script for DAC IQaudIO DAC Plus May 09 23:19:11 tunes volumio[12590]: error: updateQueue error: null May 09 23:19:11 tunes volumio[12590]: info: CoreStateMachine::setRepeat true single undefined May 09 23:19:11 tunes volumio[12590]: info: CoreStateMachine::pushState May 09 23:19:11 tunes volumio[12590]: info: CorePlayQueue::getTrack 0 May 09 23:19:11 tunes volumio[12590]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 23:19:11 tunes volumio[12590]: info: CoreCommandRouter::volumioPushState May 09 23:19:11 tunes volumio[12590]: info: CoreStateMachine::setRandom false May 09 23:19:11 tunes volumio[12590]: info: CoreStateMachine::pushState May 09 23:19:11 tunes volumio[12590]: info: CorePlayQueue::getTrack 0 May 09 23:19:11 tunes volumio[12590]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 23:19:11 tunes volumio[12590]: info: CoreCommandRouter::volumioPushState May 09 23:19:11 tunes volumio[12590]: info: Setting Device type: Raspberry PI May 09 23:19:11 tunes volumio[12590]: info: DAC script executed May 09 23:19:11 tunes volumio[12590]: info: Completed loading Core Plugins May 09 23:19:11 tunes volumio[12590]: info: Preparing to generate the ALSA configuration file May 09 23:19:11 tunes volumio[12590]: error: updateQueue error: null May 09 23:19:11 tunes volumio[12590]: info: VolumeController:: Volume=40 Mute =false May 09 23:19:11 tunes volumio[12590]: info: CoreStateMachine::pushState May 09 23:19:11 tunes volumio[12590]: info: CorePlayQueue::getTrack 0 May 09 23:19:11 tunes volumio[12590]: info: CoreCommandRouter::volumioPushState May 09 23:19:11 tunes sudo[12731]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 09 23:19:11 tunes sudo[12731]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 23:19:11 tunes sudo[12731]: pam_unix(sudo:session): session closed for user root May 09 23:19:11 tunes volumio[12590]: info: Asound.conf file unchanged, so no further update is needed May 09 23:19:11 tunes volumio[12590]: info: Output device has changed, restarting MPD May 09 23:19:11 tunes volumio[12590]: info: Output device has changed, restarting Shairport Sync May 09 23:19:11 tunes volumio[12590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 23:19:11 tunes volumio[12590]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 23:19:11 tunes sudo[12734]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 09 23:19:11 tunes sudo[12734]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 23:19:11 tunes sudo[12734]: pam_unix(sudo:session): session closed for user root May 09 23:19:11 tunes sudo[12736]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 09 23:19:11 tunes sudo[12736]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 23:19:11 tunes systemd[1]: Stopping Music Player Daemon... May 09 23:19:11 tunes volumio[12590]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 09 23:19:11 tunes volumio[12590]: info: ___________ START PLUGINS ___________ May 09 23:19:11 tunes volumio[12590]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 09 23:19:11 tunes volumio[12590]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 09 23:19:11 tunes volumio[12590]: info: [1715296751873] CoreMusicLibrary::Adding element Media Servers May 09 23:19:11 tunes volumio[12590]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 09 23:19:11 tunes volumio[12590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 23:19:11 tunes volumio[12590]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 23:19:12 tunes volumio[12590]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 09 23:19:12 tunes volumio[12590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 23:19:12 tunes volumio[12590]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 23:19:12 tunes systemd[1]: mpd.service: Succeeded. May 09 23:19:12 tunes systemd[1]: Stopped Music Player Daemon. May 09 23:19:12 tunes systemd[1]: Starting Music Player Daemon... May 09 23:19:12 tunes volumio[12590]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 09 23:19:12 tunes volumio[12590]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 09 23:19:12 tunes volumio[12590]: info: [1715296752147] CoreMusicLibrary::Adding element Last_100 May 09 23:19:12 tunes volumio[12590]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 09 23:19:12 tunes volumio[12590]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 09 23:19:12 tunes volumio[12590]: info: [1715296752156] CoreMusicLibrary::Adding element Webradio May 09 23:19:12 tunes volumio[12590]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 09 23:19:12 tunes volumio[12590]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 09 23:19:12 tunes volumio[12590]: info: Checking for old spotify connect plugin installed May 09 23:19:12 tunes volumio[12590]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 09 23:19:12 tunes volumio[12590]: info: [1715296752185] CoreMusicLibrary::Adding element LastFM May 09 23:19:12 tunes volumio[12590]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 09 23:19:12 tunes volumio[12590]: Cannot find translation for source LastFM May 09 23:19:12 tunes volumio[12590]: info: [LastFM] scrobbler initiated! May 09 23:19:12 tunes volumio[12590]: info: [LastFM] extended logging: false May 09 23:19:12 tunes volumio[12590]: info: [LastFM] try scrobble stream/radio plays: true May 09 23:19:12 tunes volumio[12590]: info: [LastFM] Left init routine May 09 23:19:12 tunes volumio[12590]: info: [LastFM] Socket already connected: true May 09 23:19:12 tunes volumio[12590]: info: Volumio Calling Home May 09 23:19:12 tunes volumio[12590]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 09 23:19:12 tunes volumio[12590]: [SpotifyConnect] Creating VLS config file May 09 23:19:12 tunes volumio[12590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 23:19:12 tunes volumio[12590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 23:19:12 tunes volumio[12590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 23:19:12 tunes volumio[12590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 23:19:12 tunes volumio[12590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 23:19:12 tunes volumio[12590]: [SpotifyConnect] Starting metadata listener May 09 23:19:12 tunes sudo[12758]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect.service May 09 23:19:12 tunes sudo[12758]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 23:19:12 tunes sudo[12758]: pam_unix(sudo:session): session closed for user root May 09 23:19:12 tunes volumio[12590]: info: Upmpdcli Daemon Started May 09 23:19:12 tunes volumio[12590]: [SpotifyConnect] Vollibrespot Daemon service started! May 09 23:19:12 tunes volumio[12590]: [Metrics] SpotifyConnect: 0s 344.68ms May 09 23:19:12 tunes volumio[12590]: info: Completed starting Core Plugins May 09 23:19:12 tunes volumio[12590]: info: ------------------------------------------- May 09 23:19:12 tunes volumio[12590]: info: ----- MyVolumio plugins startup ---- May 09 23:19:12 tunes volumio[12590]: info: ------------------------------------------- May 09 23:19:12 tunes volumio[12590]: info: [MyVolumio PluginManager] Fetching plans data.... May 09 23:19:12 tunes volumio[12590]: info: MPD Permissions set May 09 23:19:12 tunes volumio[12590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 23:19:12 tunes volumio[12590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 23:19:12 tunes volumio[12590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 23:19:12 tunes volumio[12590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 23:19:12 tunes volumio[12590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 23:19:12 tunes volumio[12590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 23:19:12 tunes volumio[12590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 23:19:12 tunes volumio[12590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 23:19:12 tunes volumio[12590]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 23:19:12 tunes volumio[12590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 23:19:12 tunes volumio[12590]: info: Starting Shairport Sync May 09 23:19:12 tunes volumio[12590]: info: Starting Shairport Sync May 09 23:19:12 tunes volumio[12590]: info: Starting Shairport Sync May 09 23:19:12 tunes sudo[12766]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 09 23:19:12 tunes sudo[12766]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 23:19:13 tunes systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 09 23:19:13 tunes sudo[12768]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 09 23:19:13 tunes systemd[1]: shairport-sync.service: Succeeded. May 09 23:19:13 tunes systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 09 23:19:13 tunes sudo[12771]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 09 23:19:13 tunes sudo[12768]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 23:19:13 tunes sudo[12771]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 23:19:13 tunes systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 09 23:19:13 tunes sudo[12766]: pam_unix(sudo:session): session closed for user root May 09 23:19:13 tunes volumio[12590]: info: Shairport-Sync Started May 09 23:19:13 tunes volumio[12590]: Error adding Membership: Error: addMembership EINVAL May 09 23:19:13 tunes systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 09 23:19:13 tunes systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM May 09 23:19:13 tunes systemd[1]: shairport-sync.service: Succeeded. May 09 23:19:13 tunes systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 09 23:19:13 tunes systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 09 23:19:13 tunes sudo[12768]: pam_unix(sudo:session): session closed for user root May 09 23:19:13 tunes sudo[12771]: pam_unix(sudo:session): session closed for user root May 09 23:19:13 tunes volumio[12590]: info: Shairport-Sync Started May 09 23:19:13 tunes volumio[12590]: info: Shairport-Sync Started May 09 23:19:14 tunes mpd[12760]: May 09 23:19 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 09 23:19:15 tunes volumio[12590]: info: Cannot mount NAS centralmusic at system boot, trial number 3 ,retrying in 5 seconds May 09 23:19:16 tunes volumio[12590]: error: MPD error: Error: This socket has been ended by the other party May 09 23:19:16 tunes volumio[12590]: error: This socket has been ended by the other party {"code":"EPIPE"} May 09 23:19:16 tunes volumio[12590]: error: MPD error: Error: This socket has been ended by the other party May 09 23:19:16 tunes volumio[12590]: error: This socket has been ended by the other party {"code":"EPIPE"} May 09 23:19:16 tunes volumio[12590]: error: MPD error: Error: This socket has been ended by the other party May 09 23:19:16 tunes volumio[12590]: error: This socket has been ended by the other party {"code":"EPIPE"} May 09 23:19:16 tunes volumio[12590]: error: MPD error: Error: This socket has been ended by the other party May 09 23:19:16 tunes volumio[12590]: error: This socket has been ended by the other party {"code":"EPIPE"} May 09 23:19:16 tunes volumio[12590]: error: MPD error: Error: This socket has been ended by the other party May 09 23:19:16 tunes volumio[12590]: error: This socket has been ended by the other party {"code":"EPIPE"} May 09 23:19:16 tunes volumio[12590]: error: MPD error: Error: This socket has been ended by the other party May 09 23:19:16 tunes volumio[12590]: error: This socket has been ended by the other party {"code":"EPIPE"} May 09 23:19:16 tunes volumio[12590]: error: MPD error: Error: This socket has been ended by the other party May 09 23:19:16 tunes volumio[12590]: error: This socket has been ended by the other party {"code":"EPIPE"} May 09 23:19:16 tunes volumio[12590]: error: MPD error: Error: This socket has been ended by the other party May 09 23:19:16 tunes volumio[12590]: error: This socket has been ended by the other party {"code":"EPIPE"} May 09 23:19:16 tunes volumio[12590]: error: MPD error: Error: This socket has been ended by the other party May 09 23:19:16 tunes volumio[12590]: error: This socket has been ended by the other party {"code":"EPIPE"} May 09 23:19:16 tunes volumio[12590]: info: Checking Spotify Web API May 09 23:19:17 tunes mpd[12760]: May 09 23:19 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured May 09 23:19:17 tunes mpd[12760]: May 09 23:19 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured May 09 23:19:17 tunes systemd[1]: Started Music Player Daemon. May 09 23:19:17 tunes sudo[12736]: pam_unix(sudo:session): session closed for user root May 09 23:19:17 tunes volumio[12590]: error: updateQueue error: null May 09 23:19:20 tunes volumio[12590]: info: Cannot mount NAS centralmusic at system boot, trial number 4 ,retrying in 5 seconds May 09 23:19:25 tunes volumio[12590]: info: Cannot mount NAS at system boot, trial number 4 ,stopping May 09 23:19:25 tunes nmbd[622]: [2024/05/09 23:19:25.483063, 0] ../source3/libsmb/nmblib.c:917(send_udp) May 09 23:19:25 tunes nmbd[622]: Packet send failed to 192.168.1.255(138) ERRNO=Network is unreachable May 09 23:19:32 tunes volumio[12590]: error: MyVolumio Plugin failed to start in a timely fashion May 09 23:19:32 tunes volumio[12590]: info: BOOT COMPLETED May 09 23:19:32 tunes volumio[12590]: [Metrics] CommandRouter: 38s 296.30ms May 09 23:19:32 tunes volumio[12590]: info: CoreCommandRouter::volumiosetStartupVolume May 09 23:19:32 tunes volumio[12590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 23:19:32 tunes volumio[12590]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 23:19:32 tunes volumio[12590]: info: CoreCommandRouter::Close All Modals sent May 09 23:19:32 tunes volumio[12590]: info: CoreCommandRouter::Close All Modals sent May 09 23:19:32 tunes kernel: pcm512x 1-004c: No SCLK, using BCLK: -2 May 09 23:19:33 tunes volumio[12590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 09 23:19:33 tunes volumio[12590]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 09 23:19:33 tunes volumio[12590]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 09 23:19:42 tunes volumio[12590]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 09 23:19:42 tunes volumio[12590]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 23:19:42 tunes volumio[12590]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 09 23:19:42 tunes volumio[12590]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=3.569&uuid=1a9bc0627ee96b07a7db1a914b9814e6" http://updates.volumio.org/downloader-v1/track-device May 09 23:19:42 tunes volumio[12590]: % Total % Received % Xferd Average Speed Time Time Time Current May 09 23:19:42 tunes volumio[12590]: Dload Upload Total Spent Left Speed May 09 23:19:42 tunes volumio[12590]: [1.6K blob data] May 09 23:19:42 tunes volumio[12590]: retrying in 5 seconds, trial 0 May 09 23:19:42 tunes volumio[12590]: info: Volumio Calling Home May 09 23:19:44 tunes ntpd[663]: error resolving pool 0.debian.pool.ntp.org: System error (-11) May 09 23:19:51 tunes volumio[12590]: info: Discovery: adding ad5c80f0-a889-469d-91ae-8e2deea2ef1a May 09 23:19:51 tunes volumio[12590]: info: Discovery: Found device Tunes May 09 23:19:51 tunes volumio[12590]: info: CoreCommandRouter::volumioGetState May 09 23:19:51 tunes volumio[12590]: info: CorePlayQueue::getTrack 0 May 09 23:19:51 tunes volumio[12590]: info: Discovery: this is already registered, ad5c80f0-a889-469d-91ae-8e2deea2ef1a May 09 23:19:51 tunes volumio[12590]: info: Discovery: Found device Tunes May 09 23:19:51 tunes volumio[12590]: info: CoreCommandRouter::volumioGetState May 09 23:19:51 tunes volumio[12590]: info: CorePlayQueue::getTrack 0 May 09 23:19:51 tunes volumio[12590]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 3 May 09 23:19:51 tunes volumio[12590]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 4 May 09 23:19:51 tunes volumio[12590]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 5 May 09 23:19:59 tunes ntpd[663]: Soliciting pool server 2607:f130:0:16b::4203:ca67 May 09 23:20:04 tunes ntpd[663]: error resolving pool 1.debian.pool.ntp.org: System error (-11) May 09 23:20:06 tunes ntpd[663]: Soliciting pool server 23.150.40.242 May 09 23:20:11 tunes volumio[12590]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 09 23:20:11 tunes volumio[12590]: Error: getaddrinfo ENOTFOUND ws.audioscrobbler.com May 09 23:20:11 tunes volumio[12590]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:67:26) { May 09 23:20:11 tunes volumio[12590]: errno: -3007, May 09 23:20:11 tunes volumio[12590]: code: 'ENOTFOUND', May 09 23:20:11 tunes volumio[12590]: syscall: 'getaddrinfo', May 09 23:20:11 tunes volumio[12590]: hostname: 'ws.audioscrobbler.com' May 09 23:20:11 tunes volumio[12590]: } May 09 23:20:11 tunes volumio[12590]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 09 23:20:12 tunes sudo[12800]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-09 23:19 May 09 23:20:12 tunes sudo[12800]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 23:20:12 tunes sudo[12800]: pam_unix(sudo:session): session closed for user root May 09 23:20:24 tunes kernel: hwmon hwmon1: Voltage normalised May 09 23:20:27 tunes kernel: hwmon hwmon1: Undervoltage detected! May 09 23:20:29 tunes dhcpcd[780]: eth0: carrier lost May 09 23:20:29 tunes kernel: smsc95xx 1-1.1:1.0 eth0: Link is Down May 09 23:20:29 tunes avahi-daemon[522]: Withdrawing address record for 169.254.176.151 on eth0. May 09 23:20:29 tunes dhcpcd[780]: eth0: deleting route to 169.254.0.0/16 May 09 23:20:29 tunes avahi-daemon[522]: Leaving mDNS multicast group on interface eth0.IPv4 with address 169.254.176.151. May 09 23:20:29 tunes dhcpcd[780]: eth0: deleting default route May 09 23:20:29 tunes avahi-daemon[522]: Interface eth0.IPv4 no longer relevant for mDNS. May 09 23:20:29 tunes ntpd[663]: error resolving pool 0.debian.pool.ntp.org: System error (-11) May 09 23:20:30 tunes ifplugd(eth0)[660]: Link beat lost. May 09 23:20:30 tunes dhcpcd[780]: eth0: carrier acquired May 09 23:20:30 tunes kernel: smsc95xx 1-1.1:1.0 eth0: Link is Up - 100Mbps/Full - flow control off May 09 23:20:30 tunes dhcpcd[780]: eth0: IAID eb:72:5c:96 May 09 23:20:31 tunes dhcpcd[780]: eth0: soliciting a DHCP lease May 09 23:20:31 tunes dhcpcd[780]: eth0: soliciting an IPv6 router May 09 23:20:31 tunes ifplugd(eth0)[660]: Link beat detected. May 09 23:20:31 tunes ntpd[663]: Deleting interface #5 eth0, 169.254.176.151#123, interface stats: received=0, sent=14, dropped=0, active_time=100 secs May 09 23:20:31 tunes ntpd[663]: 45.79.111.167 local addr 169.254.176.151 -> May 09 23:20:31 tunes ntpd[663]: 216.229.4.69 local addr 169.254.176.151 -> May 09 23:20:31 tunes ntpd[663]: 23.92.29.162 local addr 169.254.176.151 -> May 09 23:20:31 tunes ntpd[663]: 152.70.159.102 local addr 169.254.176.151 -> May 09 23:20:31 tunes ntpd[663]: 64.39.144.67 local addr 169.254.176.151 -> May 09 23:20:31 tunes ntpd[663]: 5.161.111.190 local addr 169.254.176.151 -> May 09 23:20:33 tunes volumio-remote-updater[540]: [2024-05-09 23:20:33] [info] asio async_read_at_least error: system:104 (Connection reset by peer) May 09 23:20:33 tunes volumio-remote-updater[540]: [2024-05-09 23:20:33] [error] handle_read_frame error: websocketpp.transport:2 (Underlying Transport Error) May 09 23:20:33 tunes volumio-remote-updater[540]: [2024-05-09 23:20:33] [disconnect] Disconnect close local:[1006,Underlying Transport Error] remote:[1006] May 09 23:20:33 tunes systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE May 09 23:20:33 tunes systemd[1]: volumio.service: Failed with result 'exit-code'. May 09 23:20:33 tunes systemd[1]: Started dynamicswap service. May 09 23:20:33 tunes systemd[1]: dynamicswap.service: Succeeded. May 09 23:20:33 tunes systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. May 09 23:20:33 tunes systemd[1]: volumio.service: Scheduled restart job, restart counter is at 2. May 09 23:20:33 tunes systemd[1]: Started dynamicswap service. May 09 23:20:33 tunes systemd[1]: Stopped Volumio Backend Module. May 09 23:20:33 tunes systemd[1]: Started Volumio Backend Module. May 09 23:20:33 tunes systemd[1]: dynamicswap.service: Succeeded. May 09 23:20:33 tunes nmbd[622]: [2024/05/09 23:20:33.602007, 0] ../source3/libsmb/nmblib.c:917(send_udp) May 09 23:20:33 tunes nmbd[622]: Packet send failed to 169.254.255.255(138) ERRNO=Network is unreachable May 09 23:20:36 tunes dhcpcd[780]: eth0: probing for an IPv4LL address May 09 23:20:36 tunes volumio[12848]: info: ------------------------------------------- May 09 23:20:36 tunes volumio[12848]: info: ----- Volumio3 ---- May 09 23:20:36 tunes volumio[12848]: info: ------------------------------------------- May 09 23:20:36 tunes volumio[12848]: info: ----- System startup ---- May 09 23:20:36 tunes volumio[12848]: info: ------------------------------------------- May 09 23:20:38 tunes volumio-remote-updater[540]: [2024-05-09 23:20:38] [connect] Successful connection May 09 23:20:38 tunes volumio[12848]: info: MYVOLUMIO Environment detected May 09 23:20:38 tunes volumio[12848]: info: Plugin folders cleanup May 09 23:20:38 tunes volumio[12848]: info: Scanning into folder /volumio/app/plugins/ May 09 23:20:38 tunes volumio[12848]: info: Scanning category audio_interface May 09 23:20:38 tunes volumio[12848]: info: Scanning category miscellanea May 09 23:20:38 tunes volumio[12848]: info: Scanning category music_service May 09 23:20:38 tunes volumio[12848]: info: Scanning category plugins.json May 09 23:20:38 tunes volumio[12848]: info: Scanning category system_controller May 09 23:20:38 tunes volumio[12848]: info: Scanning category user_interface May 09 23:20:38 tunes volumio[12848]: info: Scanning into folder /data/plugins/ May 09 23:20:38 tunes volumio[12848]: info: Scanning category music_service May 09 23:20:38 tunes volumio[12848]: info: Scanning category user_interface May 09 23:20:38 tunes volumio[12848]: info: Plugin folders cleanup completed May 09 23:20:38 tunes volumio[12848]: info: ------------------------------------------- May 09 23:20:38 tunes volumio[12848]: info: ----- Core plugins startup ---- May 09 23:20:38 tunes volumio[12848]: info: ------------------------------------------- May 09 23:20:38 tunes volumio[12848]: info: Loading plugins from folder /volumio/app/plugins/ May 09 23:20:38 tunes volumio[12848]: info: Adding plugin upnp to MyMusic Plugins May 09 23:20:38 tunes volumio[12848]: info: Adding plugin airplay_emulation to MyMusic Plugins May 09 23:20:38 tunes volumio[12848]: info: Adding plugin upnp_browser to MyMusic Plugins May 09 23:20:38 tunes volumio[12848]: info: Loading plugins from folder /data/plugins/ May 09 23:20:38 tunes volumio[12848]: info: Loading plugin "system"... May 09 23:20:38 tunes volumio[12848]: info: Loading plugin "appearance"... May 09 23:20:40 tunes volumio[12848]: info: Loading plugin "network"... May 09 23:20:40 tunes volumio[12848]: info: Refreshing Cached IP Addresses May 09 23:20:40 tunes sudo[12872]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 09 23:20:40 tunes sudo[12872]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 23:20:40 tunes sudo[12872]: pam_unix(sudo:session): session closed for user root May 09 23:20:40 tunes volumio[12848]: info: Loading plugin "services"... May 09 23:20:40 tunes sudo[12874]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 09 23:20:40 tunes sudo[12874]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 23:20:40 tunes volumio[12848]: info: Loading plugin "alsa_controller"... May 09 23:20:40 tunes sudo[12874]: pam_unix(sudo:session): session closed for user root May 09 23:20:40 tunes sudo[12877]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 09 23:20:40 tunes sudo[12877]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 23:20:40 tunes volumio[12848]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 09 23:20:40 tunes volumio[12848]: info: Loading plugin "wizard"... May 09 23:20:40 tunes volumio[12848]: info: Loading plugin "volumio_command_line_client"... May 09 23:20:40 tunes volumio[12848]: info: Loading plugin "upnp"... May 09 23:20:40 tunes volumio[12848]: info: [1715296840519] Starting Upmpd Daemon May 09 23:20:40 tunes volumio[12848]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 09 23:20:40 tunes volumio[12848]: info: Loading plugin "my_music"... May 09 23:20:40 tunes volumio[12848]: info: Loading plugin "mpd"... May 09 23:20:41 tunes sudo[12877]: pam_unix(sudo:session): session closed for user root May 09 23:20:41 tunes volumio[12848]: info: Creating MPD Configuration file May 09 23:20:41 tunes volumio[12848]: info: Loading plugin "upnp_browser"... May 09 23:20:41 tunes sudo[12885]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 09 23:20:41 tunes sudo[12885]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 23:20:41 tunes dhcpcd[780]: eth0: using IPv4LL address 169.254.176.151 May 09 23:20:41 tunes avahi-daemon[522]: Joining mDNS multicast group on interface eth0.IPv4 with address 169.254.176.151. May 09 23:20:41 tunes avahi-daemon[522]: New relevant interface eth0.IPv4 for mDNS. May 09 23:20:41 tunes dhcpcd[780]: eth0: adding route to 169.254.0.0/16 May 09 23:20:41 tunes avahi-daemon[522]: Registering new address record for 169.254.176.151 on eth0.IPv4. May 09 23:20:41 tunes dhcpcd[780]: eth0: adding default route May 09 23:20:41 tunes sudo[12885]: pam_unix(sudo:session): session closed for user root May 09 23:20:41 tunes sudo[12887]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 09 23:20:41 tunes sudo[12887]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 23:20:41 tunes systemd[1]: Stopping Music Player Daemon... May 09 23:20:41 tunes systemd[1]: mpd.service: Succeeded. May 09 23:20:41 tunes systemd[1]: Stopped Music Player Daemon. May 09 23:20:41 tunes systemd[1]: Starting Music Player Daemon... May 09 23:20:41 tunes volumio[12848]: info: Loading plugin "networkfs"... May 09 23:20:42 tunes dhcpcd[780]: eth0: carrier lost May 09 23:20:42 tunes kernel: smsc95xx 1-1.1:1.0 eth0: Link is Down May 09 23:20:42 tunes dhcpcd[780]: eth0: deleting route to 169.254.0.0/16 May 09 23:20:42 tunes dhcpcd[780]: eth0: deleting default route May 09 23:20:42 tunes avahi-daemon[522]: Withdrawing address record for 169.254.176.151 on eth0. May 09 23:20:42 tunes avahi-daemon[522]: Leaving mDNS multicast group on interface eth0.IPv4 with address 169.254.176.151. May 09 23:20:42 tunes avahi-daemon[522]: Interface eth0.IPv4 no longer relevant for mDNS. May 09 23:20:42 tunes volumio[12848]: info: Cannot mount NAS centralmusic at system boot, trial number 1 ,retrying in 5 seconds May 09 23:20:42 tunes volumio[12848]: info: Starting Udev Watcher for removable devices May 09 23:20:42 tunes ifplugd(eth0)[660]: Link beat lost. May 09 23:20:42 tunes volumio[12848]: info: Ignoring mount for partition: boot May 09 23:20:42 tunes volumio[12848]: info: Ignoring mount for partition: volumio May 09 23:20:42 tunes volumio[12848]: info: Ignoring mount for partition: volumio_data May 09 23:20:42 tunes volumio[12848]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 09 23:20:42 tunes volumio[12848]: info: Loading plugin "alarm-clock"... May 09 23:20:43 tunes volumio[12848]: info: Loading plugin "airplay_emulation"... May 09 23:20:43 tunes volumio[12848]: info: Starting Shairport Sync May 09 23:20:43 tunes volumio[12848]: info: Loading plugin "last_100"... May 09 23:20:43 tunes volumio[12848]: info: Loading plugin "webradio"... May 09 23:20:43 tunes volumio[12848]: info: Loading plugin "i2s_dacs"... May 09 23:20:43 tunes volumio[12848]: info: Loading plugin "volumiodiscovery"... May 09 23:20:43 tunes volumio[12848]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 09 23:20:43 tunes volumio[12848]: *** WARNING *** Please fix your application to use the native API of Avahi! May 09 23:20:43 tunes volumio[12848]: *** WARNING *** For more information see May 09 23:20:43 tunes node[12848]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 09 23:20:43 tunes volumio[12848]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 09 23:20:43 tunes volumio[12848]: *** WARNING *** Please fix your application to use the native API of Avahi! May 09 23:20:43 tunes volumio[12848]: *** WARNING *** For more information see May 09 23:20:43 tunes node[12848]: *** WARNING *** Please fix your application to use the native API of Avahi! May 09 23:20:43 tunes node[12848]: *** WARNING *** For more information see May 09 23:20:43 tunes node[12848]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 09 23:20:43 tunes node[12848]: *** WARNING *** Please fix your application to use the native API of Avahi! May 09 23:20:43 tunes node[12848]: *** WARNING *** For more information see May 09 23:20:43 tunes volumio[12848]: info: Applying required configuration parameters for plugin volumiodiscovery May 09 23:20:43 tunes volumio[12848]: info: Discovery: Started advertising with name: Tunes May 09 23:20:43 tunes volumio[12848]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 09 23:20:43 tunes volumio[12848]: info: Loading plugin "spop"... May 09 23:20:43 tunes dhcpcd[780]: eth0: carrier acquired May 09 23:20:43 tunes kernel: smsc95xx 1-1.1:1.0 eth0: Link is Up - 100Mbps/Full - flow control off May 09 23:20:43 tunes dhcpcd[780]: eth0: IAID eb:72:5c:96 May 09 23:20:43 tunes dhcpcd[780]: eth0: soliciting a DHCP lease May 09 23:20:44 tunes dhcpcd[780]: eth0: soliciting an IPv6 router May 09 23:20:44 tunes mpd[12904]: May 09 23:20 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 09 23:20:44 tunes ifplugd(eth0)[660]: Link beat detected. May 09 23:20:45 tunes volumio[12848]: info: Loading plugin "outputs"... May 09 23:20:45 tunes volumio[12848]: info: Loading plugin "albumart"... May 09 23:20:45 tunes volumio[12848]: info: Plugin example_plugin is not enabled May 09 23:20:45 tunes volumio[12848]: info: Loading plugin "inputs"... May 09 23:20:45 tunes volumio[12848]: info: Loading plugin "updater_comm"... May 09 23:20:45 tunes volumio[12848]: info: Plugin mpdemulation is not enabled May 09 23:20:45 tunes volumio[12848]: info: Loading plugin "rest_api"... May 09 23:20:45 tunes volumio[12848]: info: Loading plugin "websocket"... May 09 23:20:45 tunes volumio[12848]: info: Loading plugin "lastfm"... May 09 23:20:46 tunes volumio[12848]: Forking 3 albumart workers May 09 23:20:46 tunes volumio[12848]: info: Loading plugin "randomizer"... May 09 23:20:46 tunes ntpd[663]: error resolving pool 1.debian.pool.ntp.org: System error (-11) May 09 23:20:47 tunes dhcpcd[780]: eth0: offered 192.168.1.185 from 192.168.1.1 May 09 23:20:47 tunes dhcpcd[780]: eth0: probing address 192.168.1.185/24 May 09 23:20:48 tunes volumio[12848]: info: Applying required configuration parameters for plugin randomizer May 09 23:20:48 tunes volumio[12848]: info: Loading i18n strings for locale en May 09 23:20:48 tunes volumio[12848]: Updating browse sources language May 09 23:20:48 tunes volumio[12848]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 09 23:20:48 tunes mpd[12904]: May 09 23:20 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured May 09 23:20:48 tunes mpd[12904]: May 09 23:20 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured May 09 23:20:48 tunes systemd[1]: Started Music Player Daemon. May 09 23:20:48 tunes sudo[12887]: pam_unix(sudo:session): session closed for user root May 09 23:20:48 tunes volumio[12848]: Starting albumart workers May 09 23:20:48 tunes volumio[12848]: Starting albumart workers May 09 23:20:48 tunes volumio[12848]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 09 23:20:48 tunes volumio[12848]: info: CoreCommandRouter::initPlayerControls May 09 23:20:48 tunes volumio[12848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 23:20:48 tunes volumio[12848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 23:20:48 tunes volumio[12848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 23:20:48 tunes volumio[12848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 23:20:48 tunes volumio[12848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 23:20:48 tunes volumio[12848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 23:20:48 tunes volumio[12848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 23:20:48 tunes volumio[12848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 23:20:48 tunes volumio[12848]: Starting albumart workers May 09 23:20:48 tunes volumio[12848]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 09 23:20:48 tunes volumio[12848]: Express server listening on port 3000 May 09 23:20:48 tunes volumio[12848]: [Metrics] WebUI: 12s 836.64ms May 09 23:20:48 tunes volumio[12848]: info: CoreStateMachine::resetVolumioState May 09 23:20:48 tunes volumio[12848]: info: CoreStateMachine::getcurrentVolume May 09 23:20:48 tunes volumio[12848]: info: CoreCommandRouter::volumioRetrievevolume May 09 23:20:48 tunes volumio[12848]: info: Cannot mount NAS centralmusic at system boot, trial number 2 ,retrying in 5 seconds May 09 23:20:49 tunes volumio[12848]: info: MPD Permissions set May 09 23:20:49 tunes volumio[12848]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 1 May 09 23:20:49 tunes volumio[12848]: info: MPD running with PID12904 May 09 23:20:49 tunes volumio[12848]: ,establishing connection May 09 23:20:49 tunes volumio[12848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 23:20:49 tunes volumio[12848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 23:20:49 tunes volumio[12848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 23:20:49 tunes volumio[12848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 23:20:49 tunes volumio[12848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 23:20:49 tunes volumio[12848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 23:20:49 tunes volumio[12848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 23:20:49 tunes volumio[12848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 23:20:49 tunes volumio[12848]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 23:20:49 tunes volumio[12848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 23:20:49 tunes volumio[12848]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 23:20:49 tunes volumio[12848]: info: Executing start script for DAC IQaudIO DAC Plus May 09 23:20:49 tunes volumio[12848]: info: Reloading queue from file May 09 23:20:49 tunes volumio[12848]: error: updateQueue error: null May 09 23:20:49 tunes volumio[12848]: info: VolumeController:: Volume=40 Mute =false May 09 23:20:49 tunes volumio[12848]: info: CoreStateMachine::pushState May 09 23:20:49 tunes volumio[12848]: info: CorePlayQueue::getTrack 0 May 09 23:20:49 tunes volumio[12848]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 23:20:49 tunes volumio[12848]: info: CoreCommandRouter::volumioPushState May 09 23:20:49 tunes volumio[12848]: info: CoreStateMachine::updateTrackBlock May 09 23:20:49 tunes volumio[12848]: info: CorePlayQueue::getTrackBlock May 09 23:20:49 tunes volumio[12848]: info: CoreCommandRouter::volumioRetrievevolume May 09 23:20:49 tunes volumio[12848]: info: Setting Device type: Raspberry PI May 09 23:20:49 tunes volumio[12848]: info: CoreStateMachine::setRepeat true single undefined May 09 23:20:49 tunes volumio[12848]: info: CoreStateMachine::pushState May 09 23:20:49 tunes volumio[12848]: info: CorePlayQueue::getTrack 0 May 09 23:20:49 tunes volumio[12848]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 23:20:49 tunes volumio[12848]: info: CoreCommandRouter::volumioPushState May 09 23:20:49 tunes volumio[12848]: info: CoreStateMachine::setRandom false May 09 23:20:49 tunes volumio[12848]: info: CoreStateMachine::pushState May 09 23:20:49 tunes volumio[12848]: info: CorePlayQueue::getTrack 0 May 09 23:20:49 tunes volumio[12848]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 23:20:49 tunes volumio[12848]: info: CoreCommandRouter::volumioPushState May 09 23:20:50 tunes volumio[12848]: info: DAC script executed May 09 23:20:50 tunes volumio[12848]: info: Completed loading Core Plugins May 09 23:20:50 tunes volumio[12848]: info: Preparing to generate the ALSA configuration file May 09 23:20:50 tunes volumio[1035]: [Vollibrespot] : error sending packet Os { code: 101, kind: NetworkUnreachable, message: "Network is unreachable" } May 09 23:20:50 tunes volumio[12848]: error: updateQueue error: null May 09 23:20:50 tunes volumio[12848]: info: Discovery: adding ad5c80f0-a889-469d-91ae-8e2deea2ef1a May 09 23:20:50 tunes volumio[12848]: info: Discovery: Found device Tunes May 09 23:20:50 tunes volumio[12848]: info: CoreCommandRouter::volumioGetState May 09 23:20:50 tunes volumio[12848]: info: CorePlayQueue::getTrack 0 May 09 23:20:50 tunes volumio[12848]: info: Asound.conf file unchanged, so no further update is needed May 09 23:20:50 tunes volumio[12848]: info: Output device has changed, restarting MPD May 09 23:20:50 tunes volumio[12848]: info: Output device has changed, restarting Shairport Sync May 09 23:20:50 tunes sudo[13016]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 09 23:20:50 tunes sudo[13016]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 23:20:50 tunes volumio[12848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 23:20:50 tunes volumio[12848]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 23:20:50 tunes sudo[13016]: pam_unix(sudo:session): session closed for user root May 09 23:20:50 tunes sudo[13018]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 09 23:20:50 tunes sudo[13018]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 23:20:50 tunes systemd[1]: Stopping Music Player Daemon... May 09 23:20:50 tunes volumio[12848]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 09 23:20:50 tunes volumio[12848]: info: ___________ START PLUGINS ___________ May 09 23:20:50 tunes volumio[12848]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 09 23:20:50 tunes volumio[12848]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 09 23:20:50 tunes volumio[12848]: info: [1715296850510] CoreMusicLibrary::Adding element Media Servers May 09 23:20:50 tunes volumio[12848]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 09 23:20:50 tunes volumio[12848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 23:20:50 tunes volumio[12848]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 23:20:50 tunes volumio-remote-updater[540]: [2024-05-09 23:20:50] [connect] Successful connection May 09 23:20:50 tunes systemd[1]: mpd.service: Succeeded. May 09 23:20:50 tunes systemd[1]: Stopped Music Player Daemon. May 09 23:20:50 tunes volumio[12848]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 09 23:20:50 tunes volumio[12848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 23:20:50 tunes volumio[12848]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 23:20:50 tunes systemd[1]: Starting Music Player Daemon... May 09 23:20:50 tunes volumio[12848]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 09 23:20:50 tunes volumio[12848]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 09 23:20:50 tunes volumio[12848]: info: [1715296850770] CoreMusicLibrary::Adding element Last_100 May 09 23:20:50 tunes volumio[12848]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 09 23:20:50 tunes volumio[12848]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 09 23:20:50 tunes volumio[12848]: info: [1715296850778] CoreMusicLibrary::Adding element Webradio May 09 23:20:50 tunes volumio[12848]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 09 23:20:50 tunes volumio[12848]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 09 23:20:50 tunes volumio[12848]: info: Checking for old spotify connect plugin installed May 09 23:20:50 tunes volumio[12848]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 09 23:20:50 tunes volumio[12848]: info: [1715296850805] CoreMusicLibrary::Adding element LastFM May 09 23:20:50 tunes volumio[12848]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 09 23:20:50 tunes volumio[12848]: Cannot find translation for source LastFM May 09 23:20:50 tunes volumio[12848]: info: [LastFM] scrobbler initiated! May 09 23:20:50 tunes volumio[12848]: info: [LastFM] extended logging: false May 09 23:20:50 tunes volumio[12848]: info: [LastFM] try scrobble stream/radio plays: true May 09 23:20:50 tunes volumio[12848]: info: [LastFM] Left init routine May 09 23:20:50 tunes volumio[12848]: info: [LastFM] Socket already connected: true May 09 23:20:50 tunes volumio[12848]: info: Volumio Calling Home May 09 23:20:51 tunes volumio[12848]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 09 23:20:51 tunes volumio[12848]: [SpotifyConnect] Creating VLS config file May 09 23:20:51 tunes volumio[12848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 23:20:51 tunes volumio[12848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 23:20:51 tunes volumio[12848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 23:20:51 tunes volumio[12848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 23:20:51 tunes volumio[12848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 23:20:51 tunes volumio[12848]: [SpotifyConnect] Starting metadata listener May 09 23:20:51 tunes sudo[13036]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect.service May 09 23:20:51 tunes sudo[13036]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 23:20:51 tunes sudo[13036]: pam_unix(sudo:session): session closed for user root May 09 23:20:51 tunes volumio[12848]: info: VolumeController:: Volume=40 Mute =false May 09 23:20:51 tunes volumio[12848]: info: CoreStateMachine::pushState May 09 23:20:51 tunes volumio[12848]: info: CorePlayQueue::getTrack 0 May 09 23:20:51 tunes volumio[12848]: info: CoreCommandRouter::volumioPushState May 09 23:20:51 tunes sudo[13040]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 09 23:20:51 tunes sudo[13040]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 23:20:51 tunes volumio[12848]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 09 23:20:51 tunes sudo[13040]: pam_unix(sudo:session): session closed for user root May 09 23:20:51 tunes sudo[13042]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 09 23:20:51 tunes sudo[13042]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 23:20:51 tunes volumio[12848]: Error: getaddrinfo ENOTFOUND ws.audioscrobbler.com May 09 23:20:51 tunes volumio[12848]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:67:26) { May 09 23:20:51 tunes volumio[12848]: errno: -3007, May 09 23:20:51 tunes volumio[12848]: code: 'ENOTFOUND', May 09 23:20:51 tunes volumio[12848]: syscall: 'getaddrinfo', May 09 23:20:51 tunes volumio[12848]: hostname: 'ws.audioscrobbler.com' May 09 23:20:51 tunes volumio[12848]: } May 09 23:20:51 tunes volumio[12848]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 09 23:20:51 tunes sudo[13042]: pam_unix(sudo:session): session closed for user root May 09 23:20:52 tunes sudo[13054]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-09 23:19 May 09 23:20:52 tunes sudo[13054]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 23:20:52 tunes dhcpcd[780]: eth0: leased 192.168.1.185 for 86400 seconds May 09 23:20:52 tunes dhcpcd[780]: eth0: adding route to 192.168.1.0/24 May 09 23:20:52 tunes avahi-daemon[522]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.185. May 09 23:20:52 tunes avahi-daemon[522]: New relevant interface eth0.IPv4 for mDNS. May 09 23:20:52 tunes dhcpcd[780]: eth0: adding default route via 192.168.1.1 May 09 23:20:52 tunes avahi-daemon[522]: Registering new address record for 192.168.1.185 on eth0.IPv4. May 09 23:20:52 tunes dnsmasq[759]: reading /etc/resolv.conf May 09 23:20:52 tunes dnsmasq[759]: using nameserver 192.168.1.1#53 May 09 23:20:52 tunes dnsmasq[759]: using nameserver 208.67.222.222#53 May 09 23:20:52 tunes dnsmasq[759]: using nameserver 208.67.220.220#53 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="893d2e91c55a7857b58762e70c2f65b9d163562b" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="30b77d58bf3c2745acc494ddafed946392a79905" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 20 Oct 2023 03:38:28 PM CEST" VOLUMIO_VERSION="3.569" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4163d3756b55b3bf7c480d7285f68954"