-- 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"