-- Logs begin at Wed 2024-07-17 14:35:12 UTC, end at Thu 2024-07-18 11:35:55 UTC. -- Jul 18 11:34:00 office volumio[29027]: info: ------------------------------------------- Jul 18 11:34:00 office volumio[29027]: info: ----- Volumio3 ---- Jul 18 11:34:00 office volumio[29027]: info: ------------------------------------------- Jul 18 11:34:00 office volumio[29027]: info: ----- System startup ---- Jul 18 11:34:00 office volumio[29027]: info: ------------------------------------------- Jul 18 11:34:01 office volumio[29027]: info: MYVOLUMIO Environment detected Jul 18 11:34:01 office volumio[29027]: info: Plugin folders cleanup Jul 18 11:34:01 office volumio[29027]: info: Scanning into folder /volumio/app/plugins/ Jul 18 11:34:01 office volumio[29027]: info: Scanning category audio_interface Jul 18 11:34:01 office volumio[29027]: info: Scanning category miscellanea Jul 18 11:34:01 office volumio[29027]: info: Scanning category music_service Jul 18 11:34:01 office volumio[29027]: info: Scanning category plugins.json Jul 18 11:34:01 office volumio[29027]: info: Scanning category system_controller Jul 18 11:34:01 office volumio[29027]: info: Scanning category user_interface Jul 18 11:34:01 office volumio[29027]: info: Scanning into folder /data/plugins/ Jul 18 11:34:01 office volumio[29027]: info: Scanning category music_service Jul 18 11:34:01 office volumio[29027]: info: Plugin folders cleanup completed Jul 18 11:34:01 office volumio[29027]: info: ------------------------------------------- Jul 18 11:34:01 office volumio[29027]: info: ----- Core plugins startup ---- Jul 18 11:34:01 office volumio[29027]: info: ------------------------------------------- Jul 18 11:34:01 office volumio[29027]: info: Loading plugins from folder /volumio/app/plugins/ Jul 18 11:34:01 office volumio[29027]: info: Adding plugin upnp to MyMusic Plugins Jul 18 11:34:01 office volumio[29027]: info: Adding plugin airplay_emulation to MyMusic Plugins Jul 18 11:34:01 office volumio[29027]: info: Adding plugin upnp_browser to MyMusic Plugins Jul 18 11:34:01 office volumio[29027]: info: Loading plugins from folder /data/plugins/ Jul 18 11:34:01 office volumio[29027]: info: Loading plugin "system"... Jul 18 11:34:01 office volumio[29027]: info: Loading plugin "appearance"... Jul 18 11:34:02 office volumio[29027]: info: Loading plugin "network"... Jul 18 11:34:02 office volumio[29027]: info: Refreshing Cached IP Addresses Jul 18 11:34:02 office sudo[29050]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 18 11:34:02 office sudo[29050]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 11:34:02 office sudo[29050]: pam_unix(sudo:session): session closed for user root Jul 18 11:34:02 office sudo[29052]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 18 11:34:02 office sudo[29052]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 11:34:02 office volumio[29027]: info: Loading plugin "services"... Jul 18 11:34:02 office sudo[29052]: pam_unix(sudo:session): session closed for user root Jul 18 11:34:02 office volumio[29027]: info: Loading plugin "alsa_controller"... Jul 18 11:34:02 office sudo[29055]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jul 18 11:34:02 office sudo[29055]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 11:34:02 office volumio[29027]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 18 11:34:02 office volumio[29027]: info: Loading plugin "wizard"... Jul 18 11:34:02 office volumio[29027]: info: Loading plugin "volumio_command_line_client"... Jul 18 11:34:02 office volumio[29027]: info: Loading plugin "upnp"... Jul 18 11:34:02 office volumio[29027]: info: [1721302442863] Starting Upmpd Daemon Jul 18 11:34:02 office volumio[29027]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 18 11:34:02 office volumio[29027]: info: Loading plugin "my_music"... Jul 18 11:34:02 office volumio[29027]: info: Loading plugin "mpd"... Jul 18 11:34:03 office volumio[29027]: info: Creating MPD Configuration file Jul 18 11:34:03 office volumio[29027]: info: Loading plugin "upnp_browser"... Jul 18 11:34:03 office sudo[29063]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 18 11:34:03 office sudo[29063]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 11:34:03 office sudo[29063]: pam_unix(sudo:session): session closed for user root Jul 18 11:34:03 office sudo[29065]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 18 11:34:03 office sudo[29065]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 11:34:03 office systemd[1]: Stopping Music Player Daemon... Jul 18 11:34:03 office systemd[1]: mpd.service: Succeeded. Jul 18 11:34:03 office systemd[1]: Stopped Music Player Daemon. Jul 18 11:34:03 office systemd[1]: Starting Music Player Daemon... Jul 18 11:34:03 office volumio[29027]: info: Loading plugin "networkfs"... Jul 18 11:34:03 office volumio[29027]: info: Cannot mount NAS FLAC at system boot, trial number 1 ,retrying in 5 seconds Jul 18 11:34:03 office volumio[29027]: info: Cannot mount NAS MP3 at system boot, trial number 1 ,retrying in 5 seconds Jul 18 11:34:03 office volumio[29027]: info: Starting Udev Watcher for removable devices Jul 18 11:34:04 office volumio[29027]: info: Ignoring mount for partition: boot Jul 18 11:34:04 office volumio[29027]: info: Ignoring mount for partition: volumio Jul 18 11:34:04 office volumio[29027]: info: Ignoring mount for partition: volumio_data Jul 18 11:34:04 office volumio[29027]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 18 11:34:04 office volumio[29027]: info: Loading plugin "alarm-clock"... Jul 18 11:34:04 office volumio[29027]: info: Loading plugin "airplay_emulation"... Jul 18 11:34:04 office volumio[29027]: info: Starting Shairport Sync Jul 18 11:34:04 office volumio[29027]: info: Loading plugin "last_100"... Jul 18 11:34:04 office volumio[29027]: info: Loading plugin "webradio"... Jul 18 11:34:04 office volumio[29027]: info: Loading plugin "i2s_dacs"... Jul 18 11:34:04 office volumio[29027]: info: Loading plugin "volumiodiscovery"... Jul 18 11:34:04 office volumio[29027]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 18 11:34:04 office volumio[29027]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 18 11:34:04 office volumio[29027]: *** WARNING *** For more information see Jul 18 11:34:04 office node[29027]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 18 11:34:04 office volumio[29027]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 18 11:34:04 office volumio[29027]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 18 11:34:04 office volumio[29027]: *** WARNING *** For more information see Jul 18 11:34:04 office node[29027]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 18 11:34:04 office node[29027]: *** WARNING *** For more information see Jul 18 11:34:04 office node[29027]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 18 11:34:04 office node[29027]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 18 11:34:04 office node[29027]: *** WARNING *** For more information see Jul 18 11:34:04 office volumio[29027]: info: Applying required configuration parameters for plugin volumiodiscovery Jul 18 11:34:04 office volumio[29027]: info: Discovery: Started advertising with name: Office Jul 18 11:34:04 office volumio[29027]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 18 11:34:04 office volumio[29027]: info: Loading plugin "squeezelite_mc"... Jul 18 11:34:04 office mpd[29070]: Jul 18 11:34 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 18 11:34:04 office volumio-remote-updater[482]: [2024-07-18 11:34:04] [connect] Successful connection Jul 18 11:34:04 office wpa_supplicant[750]: wlan0: Failed to initiate sched scan Jul 18 11:34:05 office sudo[29055]: pam_unix(sudo:session): session closed for user root Jul 18 11:34:05 office mpd[29070]: Jul 18 11:34 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Jul 18 11:34:05 office mpd[29070]: Jul 18 11:34 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Jul 18 11:34:05 office systemd[1]: Started Music Player Daemon. Jul 18 11:34:05 office sudo[29065]: pam_unix(sudo:session): session closed for user root Jul 18 11:34:05 office volumio[29027]: info: Loading plugin "outputs"... Jul 18 11:34:05 office volumio[29027]: info: Loading plugin "albumart"... Jul 18 11:34:05 office volumio[29027]: info: Plugin example_plugin is not enabled Jul 18 11:34:05 office volumio[29027]: info: Loading plugin "inputs"... Jul 18 11:34:05 office volumio[29027]: info: Loading plugin "updater_comm"... Jul 18 11:34:05 office volumio[29027]: info: Plugin mpdemulation is not enabled Jul 18 11:34:05 office volumio[29027]: info: Loading plugin "rest_api"... Jul 18 11:34:05 office volumio[29027]: info: Loading plugin "websocket"... Jul 18 11:34:05 office volumio[29027]: info: Loading plugin "spop"... Jul 18 11:34:05 office volumio[29027]: Forking 3 albumart workers Jul 18 11:34:06 office volumio[29027]: info: Loading plugin "volspotconnect2"... Jul 18 11:34:07 office volumio[29027]: Starting albumart workers Jul 18 11:34:07 office volumio[29027]: Starting albumart workers Jul 18 11:34:07 office volumio[29027]: Starting albumart workers Jul 18 11:34:07 office volumio[29027]: info: Loading i18n strings for locale en Jul 18 11:34:07 office volumio[29027]: Updating browse sources language Jul 18 11:34:07 office volumio[29027]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 18 11:34:07 office volumio[29027]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 18 11:34:07 office volumio[29027]: info: CoreCommandRouter::initPlayerControls Jul 18 11:34:07 office volumio[29027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:07 office volumio[29027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:07 office volumio[29027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:07 office volumio[29027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:07 office volumio[29027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:07 office volumio[29027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:07 office volumio[29027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:07 office volumio[29027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:07 office volumio[29027]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 18 11:34:07 office volumio[29027]: Express server listening on port 3000 Jul 18 11:34:07 office volumio[29027]: [Metrics] WebUI: 7s 768.35ms Jul 18 11:34:07 office volumio[29027]: info: CoreStateMachine::resetVolumioState Jul 18 11:34:07 office volumio[29027]: info: CoreStateMachine::getcurrentVolume Jul 18 11:34:07 office volumio[29027]: info: CoreCommandRouter::volumioRetrievevolume Jul 18 11:34:08 office volumio[29027]: info: MPD Permissions set Jul 18 11:34:08 office volumio-remote-updater[482]: [2024-07-18 11:34:08] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1721302443 101 Jul 18 11:34:08 office volumio[29027]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1 Jul 18 11:34:08 office volumio[29027]: info: MPD running with PID29070 Jul 18 11:34:08 office volumio[29027]: ,establishing connection Jul 18 11:34:08 office volumio[29027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:08 office volumio[29027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:08 office volumio[29027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:08 office volumio[29027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:08 office volumio[29027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:08 office volumio[29027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:08 office volumio[29027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:08 office volumio[29027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:08 office volumio[29027]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 18 11:34:08 office volumio[29027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:08 office volumio[29027]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 18 11:34:08 office volumio[29027]: info: Reloading queue from file Jul 18 11:34:08 office volumio[29027]: error: updateQueue error: null Jul 18 11:34:08 office volumio[29027]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jul 18 11:34:08 office volumio[29027]: info: CoreStateMachine::setRepeat null single undefined Jul 18 11:34:08 office volumio[29027]: info: CoreStateMachine::pushState Jul 18 11:34:08 office volumio[29027]: info: CorePlayQueue::getTrack 0 Jul 18 11:34:08 office volumio[29027]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 11:34:08 office volumio[29027]: info: CoreCommandRouter::volumioPushState Jul 18 11:34:08 office volumio[29027]: info: CoreStateMachine::setRandom true Jul 18 11:34:08 office volumio[29027]: info: CoreStateMachine::pushState Jul 18 11:34:08 office volumio[29027]: info: CorePlayQueue::getTrack 0 Jul 18 11:34:08 office volumio[29027]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 11:34:08 office volumio[29027]: info: CoreCommandRouter::volumioPushState Jul 18 11:34:08 office volumio[29027]: info: Setting Device type: Raspberry PI Jul 18 11:34:08 office volumio[29027]: info: Completed loading Core Plugins Jul 18 11:34:08 office volumio[29027]: info: Preparing to generate the ALSA configuration file Jul 18 11:34:08 office volumio[29027]: info: VolumeController:: Volume=56 Mute =false Jul 18 11:34:08 office volumio[29027]: info: CoreStateMachine::pushState Jul 18 11:34:08 office volumio[29027]: info: CorePlayQueue::getTrack 0 Jul 18 11:34:08 office volumio[29027]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 11:34:08 office volumio[29027]: info: CoreCommandRouter::volumioPushState Jul 18 11:34:08 office volumio[29027]: info: CoreStateMachine::updateTrackBlock Jul 18 11:34:08 office volumio[29027]: info: CorePlayQueue::getTrackBlock Jul 18 11:34:08 office volumio[29027]: info: CoreCommandRouter::volumioRetrievevolume Jul 18 11:34:08 office volumio[29027]: info: Asound.conf file unchanged, so no further update is needed Jul 18 11:34:08 office volumio[29027]: info: Output device has changed, restarting MPD Jul 18 11:34:08 office sudo[29146]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 18 11:34:08 office sudo[29146]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 11:34:08 office volumio[29027]: info: Output device has changed, restarting Shairport Sync Jul 18 11:34:08 office volumio[29027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:08 office volumio[29027]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 18 11:34:08 office sudo[29146]: pam_unix(sudo:session): session closed for user root Jul 18 11:34:08 office sudo[29148]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 18 11:34:08 office sudo[29148]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 11:34:08 office systemd[1]: Stopping Music Player Daemon... Jul 18 11:34:08 office volumio[29027]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 18 11:34:08 office volumio[29027]: info: ___________ START PLUGINS ___________ Jul 18 11:34:08 office volumio[29027]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 18 11:34:08 office volumio[29027]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 18 11:34:08 office volumio[29027]: info: [1721302448493] CoreMusicLibrary::Adding element Media Servers Jul 18 11:34:08 office volumio[29027]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 18 11:34:08 office volumio[29027]: Cannot find translation for sourceMedia Servers Jul 18 11:34:08 office systemd[1]: mpd.service: Succeeded. Jul 18 11:34:08 office systemd[1]: Stopped Music Player Daemon. Jul 18 11:34:08 office systemd[1]: Starting Music Player Daemon... Jul 18 11:34:09 office volumio[29027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:09 office volumio[29027]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 18 11:34:10 office volumio[29027]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 18 11:34:10 office volumio[29027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:10 office volumio[29027]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 18 11:34:10 office volumio[29027]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 18 11:34:10 office volumio[29027]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 18 11:34:10 office mpd[29156]: Jul 18 11:34 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 18 11:34:10 office volumio[29027]: info: [1721302450093] CoreMusicLibrary::Adding element Last_100 Jul 18 11:34:10 office volumio[29027]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 18 11:34:10 office volumio[29027]: Cannot find translation for sourceMedia Servers Jul 18 11:34:10 office volumio[29027]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 18 11:34:10 office volumio[29027]: info: [1721302450098] CoreMusicLibrary::Adding element Webradio Jul 18 11:34:10 office volumio[29027]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 18 11:34:10 office volumio[29027]: Cannot find translation for sourceMedia Servers Jul 18 11:34:10 office volumio[29027]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 18 11:34:10 office volumio[29027]: info: [squeezelite_mc] Starting proxy server... Jul 18 11:34:10 office volumio[29027]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 18 11:34:10 office volumio[29027]: [SpotifyConnect] Creating VLS config file Jul 18 11:34:10 office volumio[29027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:10 office volumio[29027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:10 office volumio[29027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:10 office volumio[29027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:10 office volumio[29027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:10 office volumio[29027]: [SpotifyConnect] Starting metadata listener Jul 18 11:34:10 office sudo[29164]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hosts Jul 18 11:34:10 office sudo[29164]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 11:34:10 office sudo[29164]: pam_unix(sudo:session): session closed for user root Jul 18 11:34:10 office volumio[29027]: [SpotifyConnect] Creating VLS config file Jul 18 11:34:10 office volumio[29027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:10 office volumio[29027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:10 office volumio[29027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:10 office volumio[29027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:10 office volumio[29027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:10 office volumio[29027]: [SpotifyConnect] Starting metadata listener Jul 18 11:34:10 office sudo[29167]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect.service Jul 18 11:34:10 office sudo[29167]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 11:34:10 office volumio[29027]: info: Volumio Calling Home Jul 18 11:34:10 office sudo[29170]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect2.service Jul 18 11:34:10 office sudo[29170]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 11:34:10 office sudo[29167]: pam_unix(sudo:session): session closed for user root Jul 18 11:34:10 office sudo[29170]: pam_unix(sudo:session): session closed for user root Jul 18 11:34:10 office volumio[29027]: info: [squeezelite_mc] Proxy server started on port 46813 Jul 18 11:34:10 office volumio[29027]: [SpotifyConnect] Error: bind EADDRINUSE 0.0.0.0:5030 Jul 18 11:34:10 office volumio[29027]: at dgram.js:337:20 Jul 18 11:34:10 office volumio[29027]: at processTicksAndRejections (internal/process/task_queues.js:81:21) Jul 18 11:34:10 office mpd[29156]: Jul 18 11:34 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Jul 18 11:34:10 office mpd[29156]: Jul 18 11:34 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Jul 18 11:34:10 office systemd[1]: Started Music Player Daemon. Jul 18 11:34:10 office sudo[29148]: pam_unix(sudo:session): session closed for user root Jul 18 11:34:10 office volumio[29027]: info: CoreCommandRouter::volumioRetrievevolume Jul 18 11:34:10 office volumio[29027]: info: Cannot mount NAS FLAC at system boot, trial number 2 ,retrying in 5 seconds Jul 18 11:34:10 office volumio[29027]: info: Cannot mount NAS MP3 at system boot, trial number 2 ,retrying in 5 seconds Jul 18 11:34:10 office volumio[29027]: Unhandled rejection Error: No sockets available, cannot start. Jul 18 11:34:10 office volumio[29027]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Jul 18 11:34:10 office volumio[29027]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Jul 18 11:34:10 office volumio[29027]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Jul 18 11:34:10 office volumio[29027]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Jul 18 11:34:10 office volumio[29027]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Jul 18 11:34:10 office volumio[29027]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Jul 18 11:34:10 office volumio[29027]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Jul 18 11:34:10 office volumio[29027]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Jul 18 11:34:10 office volumio[29027]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Jul 18 11:34:10 office volumio[29027]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Jul 18 11:34:10 office volumio[29027]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Jul 18 11:34:10 office volumio[29027]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Jul 18 11:34:10 office volumio[29027]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Jul 18 11:34:10 office volumio[29027]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Jul 18 11:34:10 office volumio[29027]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Jul 18 11:34:10 office volumio[29027]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jul 18 11:34:10 office wpa_supplicant[750]: wlan0: Failed to initiate sched scan Jul 18 11:34:10 office volumio[29027]: error: MPD error: Error: write EPIPE Jul 18 11:34:10 office volumio[29027]: error: write EPIPE {"errno":-32,"code":"EPIPE","syscall":"write"} Jul 18 11:34:10 office volumio[29027]: [SpotifyConnect] Vollibrespot Daemon service started! Jul 18 11:34:10 office volumio[29027]: [Metrics] SpotifyConnect: 0s 345.96ms Jul 18 11:34:10 office volumio[29027]: [SpotifyConnect] Vollibrespot Daemon service started! Jul 18 11:34:10 office volumio[29027]: [Metrics] SpotifyConnect: 0s 348.42ms Jul 18 11:34:10 office volumio[29027]: info: MPD Permissions set Jul 18 11:34:10 office volumio[29027]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 Jul 18 11:34:10 office volumio[29027]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 3 Jul 18 11:34:10 office volumio[29027]: info: VolumeController:: Volume=56 Mute =false Jul 18 11:34:10 office volumio[29027]: info: CoreStateMachine::pushState Jul 18 11:34:10 office volumio[29027]: info: CorePlayQueue::getTrack 0 Jul 18 11:34:10 office volumio[29027]: info: CoreCommandRouter::volumioPushState Jul 18 11:34:10 office volumio[29027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:10 office volumio[29027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:10 office volumio[29027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:10 office volumio[29027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:10 office volumio[29027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:10 office volumio[29027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:10 office volumio[29027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:10 office volumio[29027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:10 office volumio[29027]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 18 11:34:10 office volumio[29027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:10 office volumio[29027]: error: updateQueue error: null Jul 18 11:34:10 office volumio[29027]: info: VolumeController:: Volume=56 Mute =false Jul 18 11:34:10 office volumio[29027]: info: CoreStateMachine::pushState Jul 18 11:34:10 office volumio[29027]: info: CorePlayQueue::getTrack 0 Jul 18 11:34:10 office volumio[29027]: info: CoreCommandRouter::volumioPushState Jul 18 11:34:10 office volumio[29027]: info: [squeezelite_mc] Server discovery started Jul 18 11:34:10 office volumio[29027]: info: [squeezelite_mc] Player finder started Jul 18 11:34:10 office volumio[29027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:10 office volumio[29027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:10 office volumio[29027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:10 office volumio[29027]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 18 11:34:10 office volumio[29027]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true Jul 18 11:34:10 office volumio[29027]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 18 11:34:10 office volumio[29027]: Error: send ENETUNREACH 255.255.255.255:3483 Jul 18 11:34:10 office volumio[29027]: at doSend (dgram.js:692:16) Jul 18 11:34:10 office volumio[29027]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Jul 18 11:34:10 office volumio[29027]: at afterDns (dgram.js:638:5) Jul 18 11:34:10 office volumio[29027]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Jul 18 11:34:10 office volumio[29027]: errno: -101, Jul 18 11:34:10 office volumio[29027]: code: 'ENETUNREACH', Jul 18 11:34:10 office volumio[29027]: syscall: 'send', Jul 18 11:34:10 office volumio[29027]: address: '255.255.255.255', Jul 18 11:34:10 office volumio[29027]: port: 3483 Jul 18 11:34:10 office volumio[29027]: } Jul 18 11:34:10 office volumio[29027]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 18 11:34:12 office sudo[29195]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-18 11:33 Jul 18 11:34:12 office sudo[29195]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 11:34:12 office sudo[29195]: pam_unix(sudo:session): session closed for user root Jul 18 11:34:12 office volumio-remote-updater[482]: [2024-07-18 11:34:12] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jul 18 11:34:12 office volumio-remote-updater[482]: [2024-07-18 11:34:12] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jul 18 11:34:12 office systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jul 18 11:34:12 office systemd[1]: volumio.service: Failed with result 'exit-code'. Jul 18 11:34:12 office systemd[1]: Started dynamicswap service. Jul 18 11:34:12 office systemd[1]: dynamicswap.service: Succeeded. Jul 18 11:34:12 office systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jul 18 11:34:12 office systemd[1]: volumio.service: Scheduled restart job, restart counter is at 69. Jul 18 11:34:12 office systemd[1]: Stopped Volumio Backend Module. Jul 18 11:34:12 office systemd[1]: Started Volumio Backend Module. Jul 18 11:34:12 office systemd[1]: Started dynamicswap service. Jul 18 11:34:12 office systemd[1]: dynamicswap.service: Succeeded. Jul 18 11:34:14 office volumio[29208]: info: ------------------------------------------- Jul 18 11:34:14 office volumio[29208]: info: ----- Volumio3 ---- Jul 18 11:34:14 office volumio[29208]: info: ------------------------------------------- Jul 18 11:34:14 office volumio[29208]: info: ----- System startup ---- Jul 18 11:34:14 office volumio[29208]: info: ------------------------------------------- Jul 18 11:34:15 office volumio[29208]: info: MYVOLUMIO Environment detected Jul 18 11:34:15 office volumio[29208]: info: Plugin folders cleanup Jul 18 11:34:15 office volumio[29208]: info: Scanning into folder /volumio/app/plugins/ Jul 18 11:34:15 office volumio[29208]: info: Scanning category audio_interface Jul 18 11:34:15 office volumio[29208]: info: Scanning category miscellanea Jul 18 11:34:15 office volumio[29208]: info: Scanning category music_service Jul 18 11:34:15 office volumio[29208]: info: Scanning category plugins.json Jul 18 11:34:15 office volumio[29208]: info: Scanning category system_controller Jul 18 11:34:15 office volumio[29208]: info: Scanning category user_interface Jul 18 11:34:15 office volumio[29208]: info: Scanning into folder /data/plugins/ Jul 18 11:34:15 office volumio[29208]: info: Scanning category music_service Jul 18 11:34:15 office volumio[29208]: info: Plugin folders cleanup completed Jul 18 11:34:15 office volumio[29208]: info: ------------------------------------------- Jul 18 11:34:15 office volumio[29208]: info: ----- Core plugins startup ---- Jul 18 11:34:15 office volumio[29208]: info: ------------------------------------------- Jul 18 11:34:15 office volumio[29208]: info: Loading plugins from folder /volumio/app/plugins/ Jul 18 11:34:15 office volumio[29208]: info: Adding plugin upnp to MyMusic Plugins Jul 18 11:34:15 office volumio[29208]: info: Adding plugin airplay_emulation to MyMusic Plugins Jul 18 11:34:15 office volumio[29208]: info: Adding plugin upnp_browser to MyMusic Plugins Jul 18 11:34:15 office volumio[29208]: info: Loading plugins from folder /data/plugins/ Jul 18 11:34:15 office volumio[29208]: info: Loading plugin "system"... Jul 18 11:34:15 office volumio[29208]: info: Loading plugin "appearance"... Jul 18 11:34:16 office volumio[29208]: info: Loading plugin "network"... Jul 18 11:34:16 office volumio[29208]: info: Refreshing Cached IP Addresses Jul 18 11:34:16 office volumio[29208]: info: Loading plugin "services"... Jul 18 11:34:16 office volumio[29208]: info: Loading plugin "alsa_controller"... Jul 18 11:34:16 office volumio[29208]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 18 11:34:16 office volumio[29208]: info: Loading plugin "wizard"... Jul 18 11:34:16 office volumio[29208]: info: Loading plugin "volumio_command_line_client"... Jul 18 11:34:16 office volumio[29208]: info: Loading plugin "upnp"... Jul 18 11:34:16 office volumio[29208]: info: [1721302456828] Starting Upmpd Daemon Jul 18 11:34:16 office volumio[29208]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 18 11:34:16 office volumio[29208]: info: Loading plugin "my_music"... Jul 18 11:34:16 office volumio[29208]: info: Loading plugin "mpd"... Jul 18 11:34:17 office volumio[29208]: info: Creating MPD Configuration file Jul 18 11:34:17 office volumio[29208]: info: Loading plugin "upnp_browser"... Jul 18 11:34:17 office wpa_supplicant[750]: wlan0: Failed to initiate sched scan Jul 18 11:34:17 office sudo[29232]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 18 11:34:17 office sudo[29242]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 18 11:34:17 office sudo[29234]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 18 11:34:17 office sudo[29236]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jul 18 11:34:17 office sudo[29236]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 11:34:17 office sudo[29234]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 11:34:17 office sudo[29242]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 11:34:17 office sudo[29234]: pam_unix(sudo:session): session closed for user root Jul 18 11:34:17 office sudo[29232]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 11:34:17 office sudo[29242]: pam_unix(sudo:session): session closed for user root Jul 18 11:34:17 office sudo[29244]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 18 11:34:17 office sudo[29244]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 11:34:17 office systemd[1]: Stopping Music Player Daemon... Jul 18 11:34:17 office sudo[29232]: pam_unix(sudo:session): session closed for user root Jul 18 11:34:17 office systemd[1]: mpd.service: Succeeded. Jul 18 11:34:17 office systemd[1]: Stopped Music Player Daemon. Jul 18 11:34:17 office systemd[1]: Starting Music Player Daemon... Jul 18 11:34:17 office volumio[29208]: info: Loading plugin "networkfs"... Jul 18 11:34:17 office volumio-remote-updater[482]: [2024-07-18 11:34:17] [connect] Successful connection Jul 18 11:34:17 office volumio[29208]: info: Cannot mount NAS FLAC at system boot, trial number 1 ,retrying in 5 seconds Jul 18 11:34:17 office volumio[29208]: info: Cannot mount NAS MP3 at system boot, trial number 1 ,retrying in 5 seconds Jul 18 11:34:17 office volumio[29208]: info: Starting Udev Watcher for removable devices Jul 18 11:34:18 office volumio[29208]: info: Ignoring mount for partition: boot Jul 18 11:34:18 office volumio[29208]: info: Ignoring mount for partition: volumio Jul 18 11:34:18 office volumio[29208]: info: Ignoring mount for partition: volumio_data Jul 18 11:34:18 office volumio[29208]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 18 11:34:18 office volumio[29208]: info: Loading plugin "alarm-clock"... Jul 18 11:34:18 office sudo[29236]: pam_unix(sudo:session): session closed for user root Jul 18 11:34:18 office volumio[29208]: info: Loading plugin "airplay_emulation"... Jul 18 11:34:18 office volumio[29208]: info: Starting Shairport Sync Jul 18 11:34:18 office volumio[29208]: info: Loading plugin "last_100"... Jul 18 11:34:18 office volumio[29208]: info: Loading plugin "webradio"... Jul 18 11:34:18 office volumio[29208]: info: Loading plugin "i2s_dacs"... Jul 18 11:34:18 office volumio[29208]: info: Loading plugin "volumiodiscovery"... Jul 18 11:34:18 office volumio[29208]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 18 11:34:18 office volumio[29208]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 18 11:34:18 office volumio[29208]: *** WARNING *** For more information see Jul 18 11:34:18 office node[29208]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 18 11:34:18 office volumio[29208]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 18 11:34:18 office volumio[29208]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 18 11:34:18 office volumio[29208]: *** WARNING *** For more information see Jul 18 11:34:18 office node[29208]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 18 11:34:18 office node[29208]: *** WARNING *** For more information see Jul 18 11:34:18 office node[29208]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 18 11:34:18 office node[29208]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 18 11:34:18 office node[29208]: *** WARNING *** For more information see Jul 18 11:34:18 office volumio[29208]: info: Applying required configuration parameters for plugin volumiodiscovery Jul 18 11:34:18 office volumio[29208]: info: Discovery: Started advertising with name: Office Jul 18 11:34:18 office volumio[29208]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 18 11:34:18 office volumio[29208]: info: Loading plugin "squeezelite_mc"... Jul 18 11:34:19 office mpd[29253]: exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 18 11:34:19 office mpd[29253]: output: No 'audio_output' defined in config file Jul 18 11:34:19 office mpd[29253]: output: Attempt to detect audio output device Jul 18 11:34:19 office mpd[29253]: output: Attempting to detect a alsa audio device Jul 18 11:34:19 office mpd[29253]: output: Successfully detected a alsa audio device Jul 18 11:34:19 office mpd[29253]: exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Jul 18 11:34:19 office mpd[29253]: exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Jul 18 11:34:19 office volumio[29208]: info: Loading plugin "outputs"... Jul 18 11:34:19 office volumio[29208]: info: Loading plugin "albumart"... Jul 18 11:34:19 office volumio[29208]: info: Plugin example_plugin is not enabled Jul 18 11:34:19 office volumio[29208]: info: Loading plugin "inputs"... Jul 18 11:34:19 office volumio[29208]: info: Loading plugin "updater_comm"... Jul 18 11:34:19 office volumio[29208]: info: Plugin mpdemulation is not enabled Jul 18 11:34:19 office volumio[29208]: info: Loading plugin "rest_api"... Jul 18 11:34:19 office volumio[29208]: info: Loading plugin "websocket"... Jul 18 11:34:19 office volumio[29208]: info: Loading plugin "spop"... Jul 18 11:34:19 office volumio[29208]: Forking 3 albumart workers Jul 18 11:34:19 office mpd[29253]: zeroconf: No global port, disabling zeroconf Jul 18 11:34:19 office systemd[1]: Started Music Player Daemon. Jul 18 11:34:19 office sudo[29244]: pam_unix(sudo:session): session closed for user root Jul 18 11:34:20 office volumio[29208]: info: Loading plugin "volspotconnect2"... Jul 18 11:34:20 office volumio[29208]: Starting albumart workers Jul 18 11:34:20 office volumio[29208]: Starting albumart workers Jul 18 11:34:20 office volumio[29208]: Starting albumart workers Jul 18 11:34:21 office volumio[29208]: info: Loading i18n strings for locale en Jul 18 11:34:21 office volumio[29208]: Updating browse sources language Jul 18 11:34:21 office volumio[29208]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 18 11:34:21 office ntpd[657]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Jul 18 11:34:21 office volumio[29208]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 18 11:34:21 office volumio[29208]: info: CoreCommandRouter::initPlayerControls Jul 18 11:34:21 office volumio[29208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:21 office volumio[29208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:21 office volumio[29208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:21 office volumio[29208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:21 office volumio[29208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:21 office volumio[29208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:21 office volumio[29208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:21 office volumio[29208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:21 office volumio[29208]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 18 11:34:21 office volumio[29208]: Express server listening on port 3000 Jul 18 11:34:21 office volumio[29208]: [Metrics] WebUI: 7s 691.37ms Jul 18 11:34:21 office volumio[29208]: info: CoreStateMachine::resetVolumioState Jul 18 11:34:21 office volumio[29208]: info: CoreStateMachine::getcurrentVolume Jul 18 11:34:21 office volumio[29208]: info: CoreCommandRouter::volumioRetrievevolume Jul 18 11:34:21 office mpd[29253]: client: [0] opened from local Jul 18 11:34:21 office volumio[29208]: info: MPD Permissions set Jul 18 11:34:22 office volumio-remote-updater[482]: [2024-07-18 11:34:22] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1721302457 101 Jul 18 11:34:22 office volumio[29208]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1 Jul 18 11:34:22 office mpd[29253]: exception: No database Jul 18 11:34:22 office mpd[29253]: exception: No database Jul 18 11:34:22 office volumio[29208]: info: MPD running with PID29253 Jul 18 11:34:22 office volumio[29208]: ,establishing connection Jul 18 11:34:22 office mpd[29253]: client: [1] opened from local Jul 18 11:34:22 office volumio[29208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:22 office volumio[29208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:22 office volumio[29208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:22 office volumio[29208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:22 office volumio[29208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:22 office volumio[29208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:22 office volumio[29208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:22 office volumio[29208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:22 office volumio[29208]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 18 11:34:22 office volumio[29208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:22 office volumio[29208]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 18 11:34:22 office volumio[29208]: info: Reloading queue from file Jul 18 11:34:22 office volumio[29208]: error: updateQueue error: null Jul 18 11:34:22 office volumio[29208]: error: Failed LSINFO: Error: [50@0] {lsinfo} No database Jul 18 11:34:22 office volumio[29208]: info: CoreStateMachine::setRepeat null single undefined Jul 18 11:34:22 office volumio[29208]: info: CoreStateMachine::pushState Jul 18 11:34:22 office volumio[29208]: info: CorePlayQueue::getTrack 0 Jul 18 11:34:22 office volumio[29208]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 11:34:22 office volumio[29208]: info: CoreCommandRouter::volumioPushState Jul 18 11:34:22 office volumio[29208]: info: CoreStateMachine::setRandom true Jul 18 11:34:22 office volumio[29208]: info: CoreStateMachine::pushState Jul 18 11:34:22 office volumio[29208]: info: CorePlayQueue::getTrack 0 Jul 18 11:34:22 office volumio[29208]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 11:34:22 office volumio[29208]: info: CoreCommandRouter::volumioPushState Jul 18 11:34:22 office volumio[29208]: info: Setting Device type: Raspberry PI Jul 18 11:34:22 office volumio[29208]: info: Completed loading Core Plugins Jul 18 11:34:22 office volumio[29208]: info: Preparing to generate the ALSA configuration file Jul 18 11:34:22 office volumio[29208]: info: VolumeController:: Volume=56 Mute =false Jul 18 11:34:22 office volumio[29208]: info: CoreStateMachine::pushState Jul 18 11:34:22 office volumio[29208]: info: CorePlayQueue::getTrack 0 Jul 18 11:34:22 office volumio[29208]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 11:34:22 office volumio[29208]: info: CoreCommandRouter::volumioPushState Jul 18 11:34:22 office volumio[29208]: info: CoreStateMachine::updateTrackBlock Jul 18 11:34:22 office volumio[29208]: info: CorePlayQueue::getTrackBlock Jul 18 11:34:22 office volumio[29208]: info: CoreCommandRouter::volumioRetrievevolume Jul 18 11:34:22 office volumio[29208]: error: updateQueue error: null Jul 18 11:34:22 office volumio[29208]: info: Asound.conf file unchanged, so no further update is needed Jul 18 11:34:22 office volumio[29208]: info: Output device has changed, restarting MPD Jul 18 11:34:22 office volumio[29208]: info: Output device has changed, restarting Shairport Sync Jul 18 11:34:22 office volumio[29208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:22 office volumio[29208]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 18 11:34:22 office volumio[29208]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 18 11:34:22 office volumio[29208]: info: ___________ START PLUGINS ___________ Jul 18 11:34:22 office volumio[29208]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 18 11:34:22 office volumio[29208]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 18 11:34:22 office volumio[29208]: info: [1721302462399] CoreMusicLibrary::Adding element Media Servers Jul 18 11:34:22 office volumio[29208]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 18 11:34:22 office volumio[29208]: Cannot find translation for sourceMedia Servers Jul 18 11:34:22 office sudo[29327]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 18 11:34:22 office sudo[29329]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 18 11:34:22 office sudo[29329]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 11:34:22 office volumio[29208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:22 office volumio[29208]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 18 11:34:22 office sudo[29327]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 11:34:22 office sudo[29327]: pam_unix(sudo:session): session closed for user root Jul 18 11:34:22 office systemd[1]: Stopping Music Player Daemon... Jul 18 11:34:22 office systemd[1]: mpd.service: Succeeded. Jul 18 11:34:22 office systemd[1]: Stopped Music Player Daemon. Jul 18 11:34:22 office systemd[1]: Starting Music Player Daemon... Jul 18 11:34:22 office volumio[29208]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 18 11:34:22 office volumio[29208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:22 office volumio[29208]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 18 11:34:22 office volumio[29208]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 18 11:34:22 office volumio[29208]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 18 11:34:22 office volumio[29208]: info: [1721302462655] CoreMusicLibrary::Adding element Last_100 Jul 18 11:34:22 office volumio[29208]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 18 11:34:22 office volumio[29208]: Cannot find translation for sourceMedia Servers Jul 18 11:34:22 office volumio[29208]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 18 11:34:22 office volumio[29208]: info: [1721302462659] CoreMusicLibrary::Adding element Webradio Jul 18 11:34:22 office volumio[29208]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 18 11:34:22 office volumio[29208]: Cannot find translation for sourceMedia Servers Jul 18 11:34:22 office volumio[29208]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 18 11:34:22 office volumio[29208]: info: [squeezelite_mc] Starting proxy server... Jul 18 11:34:22 office volumio[29208]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 18 11:34:22 office volumio[29208]: [SpotifyConnect] Creating VLS config file Jul 18 11:34:22 office volumio[29208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:22 office volumio[29208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:22 office volumio[29208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:22 office volumio[29208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:22 office volumio[29208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:22 office volumio[29208]: [SpotifyConnect] Starting metadata listener Jul 18 11:34:22 office sudo[29344]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hosts Jul 18 11:34:22 office sudo[29344]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 11:34:22 office sudo[29344]: pam_unix(sudo:session): session closed for user root Jul 18 11:34:22 office volumio[29208]: [SpotifyConnect] Creating VLS config file Jul 18 11:34:22 office volumio[29208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:22 office volumio[29208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:22 office volumio[29208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:22 office volumio[29208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:22 office volumio[29208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:22 office volumio[29208]: [SpotifyConnect] Starting metadata listener Jul 18 11:34:22 office sudo[29347]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect.service Jul 18 11:34:22 office sudo[29347]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 11:34:22 office volumio[29208]: info: Volumio Calling Home Jul 18 11:34:22 office sudo[29351]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect2.service Jul 18 11:34:22 office sudo[29351]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 11:34:22 office sudo[29347]: pam_unix(sudo:session): session closed for user root Jul 18 11:34:22 office sudo[29351]: pam_unix(sudo:session): session closed for user root Jul 18 11:34:22 office volumio[29208]: info: [squeezelite_mc] Proxy server started on port 36761 Jul 18 11:34:22 office volumio[29208]: [SpotifyConnect] Error: bind EADDRINUSE 0.0.0.0:5030 Jul 18 11:34:22 office volumio[29208]: at dgram.js:337:20 Jul 18 11:34:22 office volumio[29208]: at processTicksAndRejections (internal/process/task_queues.js:81:21) Jul 18 11:34:23 office volumio[29208]: info: CoreCommandRouter::volumioRetrievevolume Jul 18 11:34:23 office wpa_supplicant[750]: wlan0: Failed to initiate sched scan Jul 18 11:34:23 office volumio[29208]: Unhandled rejection Error: No sockets available, cannot start. Jul 18 11:34:23 office volumio[29208]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Jul 18 11:34:23 office volumio[29208]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Jul 18 11:34:23 office volumio[29208]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Jul 18 11:34:23 office volumio[29208]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Jul 18 11:34:23 office volumio[29208]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Jul 18 11:34:23 office volumio[29208]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Jul 18 11:34:23 office volumio[29208]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Jul 18 11:34:23 office volumio[29208]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Jul 18 11:34:23 office volumio[29208]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Jul 18 11:34:23 office volumio[29208]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Jul 18 11:34:23 office volumio[29208]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Jul 18 11:34:23 office volumio[29208]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Jul 18 11:34:23 office volumio[29208]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Jul 18 11:34:23 office volumio[29208]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Jul 18 11:34:23 office volumio[29208]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Jul 18 11:34:23 office volumio[29208]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jul 18 11:34:23 office volumio[29208]: info: Cannot mount NAS FLAC at system boot, trial number 2 ,retrying in 5 seconds Jul 18 11:34:23 office volumio[29208]: info: Cannot mount NAS MP3 at system boot, trial number 2 ,retrying in 5 seconds Jul 18 11:34:23 office volumio[29208]: [SpotifyConnect] Vollibrespot Daemon service started! Jul 18 11:34:23 office volumio[29208]: [Metrics] SpotifyConnect: 0s 313.00ms Jul 18 11:34:23 office volumio[29208]: [SpotifyConnect] Vollibrespot Daemon service started! Jul 18 11:34:23 office volumio[29208]: [Metrics] SpotifyConnect: 0s 315.33ms Jul 18 11:34:23 office volumio[29208]: info: MPD Permissions set Jul 18 11:34:23 office volumio[29208]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 Jul 18 11:34:23 office volumio[29208]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 3 Jul 18 11:34:23 office volumio[29208]: info: VolumeController:: Volume=56 Mute =false Jul 18 11:34:23 office volumio[29208]: info: CoreStateMachine::pushState Jul 18 11:34:23 office volumio[29208]: info: CorePlayQueue::getTrack 0 Jul 18 11:34:23 office volumio[29208]: info: CoreCommandRouter::volumioPushState Jul 18 11:34:23 office volumio[29208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:23 office volumio[29208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:23 office volumio[29208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:23 office volumio[29208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:23 office volumio[29208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:23 office volumio[29208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:23 office volumio[29208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:23 office volumio[29208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:23 office volumio[29208]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 18 11:34:23 office volumio[29208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:23 office volumio[29208]: info: VolumeController:: Volume=56 Mute =false Jul 18 11:34:23 office volumio[29208]: info: CoreStateMachine::pushState Jul 18 11:34:23 office volumio[29208]: info: CorePlayQueue::getTrack 0 Jul 18 11:34:23 office volumio[29208]: info: CoreCommandRouter::volumioPushState Jul 18 11:34:23 office volumio[29208]: info: [squeezelite_mc] Server discovery started Jul 18 11:34:23 office volumio[29208]: info: [squeezelite_mc] Player finder started Jul 18 11:34:23 office volumio[29208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:23 office volumio[29208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:23 office volumio[29208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:23 office volumio[29208]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 18 11:34:23 office volumio[29208]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true Jul 18 11:34:23 office volumio[29208]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 18 11:34:23 office volumio[29208]: Error: send ENETUNREACH 255.255.255.255:3483 Jul 18 11:34:23 office volumio[29208]: at doSend (dgram.js:692:16) Jul 18 11:34:23 office volumio[29208]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Jul 18 11:34:23 office volumio[29208]: at afterDns (dgram.js:638:5) Jul 18 11:34:23 office volumio[29208]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Jul 18 11:34:23 office volumio[29208]: errno: -101, Jul 18 11:34:23 office volumio[29208]: code: 'ENETUNREACH', Jul 18 11:34:23 office volumio[29208]: syscall: 'send', Jul 18 11:34:23 office volumio[29208]: address: '255.255.255.255', Jul 18 11:34:23 office volumio[29208]: port: 3483 Jul 18 11:34:23 office volumio[29208]: } Jul 18 11:34:23 office volumio[29208]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 18 11:34:23 office ntpd[657]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Jul 18 11:34:24 office mpd[29350]: exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 18 11:34:24 office mpd[29350]: output: No 'audio_output' defined in config file Jul 18 11:34:24 office mpd[29350]: output: Attempt to detect audio output device Jul 18 11:34:24 office mpd[29350]: output: Attempting to detect a alsa audio device Jul 18 11:34:24 office mpd[29350]: output: Successfully detected a alsa audio device Jul 18 11:34:24 office mpd[29350]: exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Jul 18 11:34:24 office mpd[29350]: exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Jul 18 11:34:24 office mpd[29350]: zeroconf: No global port, disabling zeroconf Jul 18 11:34:24 office systemd[1]: Started Music Player Daemon. Jul 18 11:34:24 office sudo[29329]: pam_unix(sudo:session): session closed for user root Jul 18 11:34:24 office sudo[29374]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-18 11:33 Jul 18 11:34:24 office sudo[29374]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 11:34:25 office sudo[29374]: pam_unix(sudo:session): session closed for user root Jul 18 11:34:25 office volumio-remote-updater[482]: [2024-07-18 11:34:25] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jul 18 11:34:25 office volumio-remote-updater[482]: [2024-07-18 11:34:25] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jul 18 11:34:25 office systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jul 18 11:34:25 office systemd[1]: volumio.service: Failed with result 'exit-code'. Jul 18 11:34:25 office systemd[1]: Started dynamicswap service. Jul 18 11:34:25 office systemd[1]: dynamicswap.service: Succeeded. Jul 18 11:34:25 office systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jul 18 11:34:25 office systemd[1]: volumio.service: Scheduled restart job, restart counter is at 70. Jul 18 11:34:25 office systemd[1]: Stopped Volumio Backend Module. Jul 18 11:34:25 office systemd[1]: Started Volumio Backend Module. Jul 18 11:34:25 office systemd[1]: Started dynamicswap service. Jul 18 11:34:25 office systemd[1]: dynamicswap.service: Succeeded. Jul 18 11:34:27 office volumio[29389]: info: ------------------------------------------- Jul 18 11:34:27 office volumio[29389]: info: ----- Volumio3 ---- Jul 18 11:34:27 office volumio[29389]: info: ------------------------------------------- Jul 18 11:34:27 office volumio[29389]: info: ----- System startup ---- Jul 18 11:34:27 office volumio[29389]: info: ------------------------------------------- Jul 18 11:34:28 office volumio[29389]: info: MYVOLUMIO Environment detected Jul 18 11:34:28 office volumio[29389]: info: Plugin folders cleanup Jul 18 11:34:28 office volumio[29389]: info: Scanning into folder /volumio/app/plugins/ Jul 18 11:34:28 office volumio[29389]: info: Scanning category audio_interface Jul 18 11:34:28 office volumio[29389]: info: Scanning category miscellanea Jul 18 11:34:28 office volumio[29389]: info: Scanning category music_service Jul 18 11:34:28 office volumio[29389]: info: Scanning category plugins.json Jul 18 11:34:28 office volumio[29389]: info: Scanning category system_controller Jul 18 11:34:28 office volumio[29389]: info: Scanning category user_interface Jul 18 11:34:28 office volumio[29389]: info: Scanning into folder /data/plugins/ Jul 18 11:34:28 office volumio[29389]: info: Scanning category music_service Jul 18 11:34:28 office volumio[29389]: info: Plugin folders cleanup completed Jul 18 11:34:28 office volumio[29389]: info: ------------------------------------------- Jul 18 11:34:28 office volumio[29389]: info: ----- Core plugins startup ---- Jul 18 11:34:28 office volumio[29389]: info: ------------------------------------------- Jul 18 11:34:28 office volumio[29389]: info: Loading plugins from folder /volumio/app/plugins/ Jul 18 11:34:28 office volumio[29389]: info: Adding plugin upnp to MyMusic Plugins Jul 18 11:34:28 office volumio[29389]: info: Adding plugin airplay_emulation to MyMusic Plugins Jul 18 11:34:28 office volumio[29389]: info: Adding plugin upnp_browser to MyMusic Plugins Jul 18 11:34:28 office volumio[29389]: info: Loading plugins from folder /data/plugins/ Jul 18 11:34:28 office volumio[29389]: info: Loading plugin "system"... Jul 18 11:34:28 office volumio[29389]: info: Loading plugin "appearance"... Jul 18 11:34:29 office volumio[29389]: info: Loading plugin "network"... Jul 18 11:34:29 office volumio[29389]: info: Refreshing Cached IP Addresses Jul 18 11:34:29 office volumio[29389]: info: Loading plugin "services"... Jul 18 11:34:29 office volumio[29389]: info: Loading plugin "alsa_controller"... Jul 18 11:34:29 office volumio[29389]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 18 11:34:29 office volumio[29389]: info: Loading plugin "wizard"... Jul 18 11:34:29 office volumio[29389]: info: Loading plugin "volumio_command_line_client"... Jul 18 11:34:29 office volumio[29389]: info: Loading plugin "upnp"... Jul 18 11:34:29 office volumio[29389]: info: [1721302469604] Starting Upmpd Daemon Jul 18 11:34:29 office volumio[29389]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 18 11:34:29 office volumio[29389]: info: Loading plugin "my_music"... Jul 18 11:34:29 office volumio[29389]: info: Loading plugin "mpd"... Jul 18 11:34:29 office volumio[29389]: info: Creating MPD Configuration file Jul 18 11:34:30 office wpa_supplicant[750]: wlan0: Failed to initiate sched scan Jul 18 11:34:30 office sudo[29413]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 18 11:34:30 office sudo[29413]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 11:34:30 office volumio[29389]: info: Loading plugin "upnp_browser"... Jul 18 11:34:30 office sudo[29417]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jul 18 11:34:30 office sudo[29417]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 11:34:30 office sudo[29413]: pam_unix(sudo:session): session closed for user root Jul 18 11:34:30 office sudo[29415]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 18 11:34:30 office sudo[29415]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 11:34:30 office sudo[29423]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 18 11:34:30 office sudo[29423]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 11:34:30 office sudo[29425]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 18 11:34:30 office sudo[29425]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 11:34:30 office sudo[29423]: pam_unix(sudo:session): session closed for user root Jul 18 11:34:30 office sudo[29415]: pam_unix(sudo:session): session closed for user root Jul 18 11:34:30 office systemd[1]: Stopping Music Player Daemon... Jul 18 11:34:30 office systemd[1]: mpd.service: Succeeded. Jul 18 11:34:30 office systemd[1]: Stopped Music Player Daemon. Jul 18 11:34:30 office systemd[1]: Starting Music Player Daemon... Jul 18 11:34:30 office volumio-remote-updater[482]: [2024-07-18 11:34:30] [connect] Successful connection Jul 18 11:34:30 office volumio[29389]: info: Loading plugin "networkfs"... Jul 18 11:34:30 office volumio[29389]: info: Cannot mount NAS FLAC at system boot, trial number 1 ,retrying in 5 seconds Jul 18 11:34:30 office volumio[29389]: info: Cannot mount NAS MP3 at system boot, trial number 1 ,retrying in 5 seconds Jul 18 11:34:30 office volumio[29389]: info: Starting Udev Watcher for removable devices Jul 18 11:34:30 office sudo[29417]: pam_unix(sudo:session): session closed for user root Jul 18 11:34:30 office volumio[29389]: info: Ignoring mount for partition: boot Jul 18 11:34:30 office volumio[29389]: info: Ignoring mount for partition: volumio Jul 18 11:34:30 office volumio[29389]: info: Ignoring mount for partition: volumio_data Jul 18 11:34:30 office volumio[29389]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 18 11:34:30 office volumio[29389]: info: Loading plugin "alarm-clock"... Jul 18 11:34:31 office volumio[29389]: info: Loading plugin "airplay_emulation"... Jul 18 11:34:31 office volumio[29389]: info: Starting Shairport Sync Jul 18 11:34:31 office volumio[29389]: info: Loading plugin "last_100"... Jul 18 11:34:31 office volumio[29389]: info: Loading plugin "webradio"... Jul 18 11:34:31 office volumio[29389]: info: Loading plugin "i2s_dacs"... Jul 18 11:34:31 office volumio[29389]: info: Loading plugin "volumiodiscovery"... Jul 18 11:34:31 office volumio[29389]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 18 11:34:31 office volumio[29389]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 18 11:34:31 office volumio[29389]: *** WARNING *** For more information see Jul 18 11:34:31 office node[29389]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 18 11:34:31 office volumio[29389]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 18 11:34:31 office volumio[29389]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 18 11:34:31 office volumio[29389]: *** WARNING *** For more information see Jul 18 11:34:31 office node[29389]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 18 11:34:31 office node[29389]: *** WARNING *** For more information see Jul 18 11:34:31 office node[29389]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 18 11:34:31 office node[29389]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 18 11:34:31 office node[29389]: *** WARNING *** For more information see Jul 18 11:34:31 office volumio[29389]: info: Applying required configuration parameters for plugin volumiodiscovery Jul 18 11:34:31 office volumio[29389]: info: Discovery: Started advertising with name: Office Jul 18 11:34:31 office volumio[29389]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 18 11:34:31 office volumio[29389]: info: Loading plugin "squeezelite_mc"... Jul 18 11:34:31 office mpd[29433]: exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 18 11:34:31 office mpd[29433]: output: No 'audio_output' defined in config file Jul 18 11:34:31 office mpd[29433]: output: Attempt to detect audio output device Jul 18 11:34:31 office mpd[29433]: output: Attempting to detect a alsa audio device Jul 18 11:34:31 office mpd[29433]: output: Successfully detected a alsa audio device Jul 18 11:34:31 office mpd[29433]: exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Jul 18 11:34:31 office mpd[29433]: exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Jul 18 11:34:32 office volumio[29389]: info: Loading plugin "outputs"... Jul 18 11:34:32 office volumio[29389]: info: Loading plugin "albumart"... Jul 18 11:34:32 office volumio[29389]: info: Plugin example_plugin is not enabled Jul 18 11:34:32 office volumio[29389]: info: Loading plugin "inputs"... Jul 18 11:34:32 office volumio[29389]: info: Loading plugin "updater_comm"... Jul 18 11:34:32 office volumio[29389]: info: Plugin mpdemulation is not enabled Jul 18 11:34:32 office volumio[29389]: info: Loading plugin "rest_api"... Jul 18 11:34:32 office volumio[29389]: info: Loading plugin "websocket"... Jul 18 11:34:32 office volumio[29389]: info: Loading plugin "spop"... Jul 18 11:34:32 office mpd[29433]: zeroconf: No global port, disabling zeroconf Jul 18 11:34:32 office systemd[1]: Started Music Player Daemon. Jul 18 11:34:32 office sudo[29425]: pam_unix(sudo:session): session closed for user root Jul 18 11:34:32 office volumio[29389]: Forking 3 albumart workers Jul 18 11:34:33 office volumio[29389]: info: Loading plugin "volspotconnect2"... Jul 18 11:34:33 office volumio[29389]: Starting albumart workers Jul 18 11:34:33 office ntpd[657]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Jul 18 11:34:33 office volumio[29389]: Starting albumart workers Jul 18 11:34:33 office volumio[29389]: Starting albumart workers Jul 18 11:34:34 office volumio[29389]: info: Loading i18n strings for locale en Jul 18 11:34:34 office volumio[29389]: Updating browse sources language Jul 18 11:34:34 office volumio[29389]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 18 11:34:34 office volumio[29389]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 18 11:34:34 office volumio[29389]: info: CoreCommandRouter::initPlayerControls Jul 18 11:34:34 office volumio[29389]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:34 office volumio[29389]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:34 office volumio[29389]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:34 office volumio[29389]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:34 office volumio[29389]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:34 office volumio[29389]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:34 office volumio[29389]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:34 office volumio[29389]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:34 office volumio[29389]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 18 11:34:34 office volumio[29389]: Express server listening on port 3000 Jul 18 11:34:34 office volumio[29389]: [Metrics] WebUI: 7s 698.56ms Jul 18 11:34:34 office volumio[29389]: info: CoreStateMachine::resetVolumioState Jul 18 11:34:34 office volumio[29389]: info: CoreStateMachine::getcurrentVolume Jul 18 11:34:34 office volumio[29389]: info: CoreCommandRouter::volumioRetrievevolume Jul 18 11:34:34 office mpd[29433]: client: [0] opened from local Jul 18 11:34:34 office volumio[29389]: info: MPD Permissions set Jul 18 11:34:34 office volumio-remote-updater[482]: [2024-07-18 11:34:34] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1721302470 101 Jul 18 11:34:34 office volumio[29389]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1 Jul 18 11:34:34 office mpd[29433]: exception: No database Jul 18 11:34:34 office mpd[29433]: exception: No database Jul 18 11:34:34 office volumio[29389]: info: MPD running with PID29433 Jul 18 11:34:34 office volumio[29389]: ,establishing connection Jul 18 11:34:34 office mpd[29433]: client: [1] opened from local Jul 18 11:34:34 office volumio[29389]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:34 office volumio[29389]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:34 office volumio[29389]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:34 office volumio[29389]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:34 office volumio[29389]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:34 office volumio[29389]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:34 office volumio[29389]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:34 office volumio[29389]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:34 office volumio[29389]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 18 11:34:34 office volumio[29389]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:34 office volumio[29389]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 18 11:34:34 office volumio[29389]: info: Reloading queue from file Jul 18 11:34:34 office volumio[29389]: error: updateQueue error: null Jul 18 11:34:34 office volumio[29389]: error: Failed LSINFO: Error: [50@0] {lsinfo} No database Jul 18 11:34:34 office volumio[29389]: info: CoreStateMachine::setRepeat null single undefined Jul 18 11:34:34 office volumio[29389]: info: CoreStateMachine::pushState Jul 18 11:34:34 office volumio[29389]: info: CorePlayQueue::getTrack 0 Jul 18 11:34:34 office volumio[29389]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 11:34:34 office volumio[29389]: info: CoreCommandRouter::volumioPushState Jul 18 11:34:34 office volumio[29389]: info: CoreStateMachine::setRandom true Jul 18 11:34:34 office volumio[29389]: info: CoreStateMachine::pushState Jul 18 11:34:34 office volumio[29389]: info: CorePlayQueue::getTrack 0 Jul 18 11:34:34 office volumio[29389]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 11:34:34 office volumio[29389]: info: CoreCommandRouter::volumioPushState Jul 18 11:34:35 office volumio[29389]: info: Setting Device type: Raspberry PI Jul 18 11:34:35 office volumio[29389]: info: Completed loading Core Plugins Jul 18 11:34:35 office volumio[29389]: info: Preparing to generate the ALSA configuration file Jul 18 11:34:35 office volumio[29389]: info: VolumeController:: Volume=56 Mute =false Jul 18 11:34:35 office volumio[29389]: info: CoreStateMachine::pushState Jul 18 11:34:35 office volumio[29389]: info: CorePlayQueue::getTrack 0 Jul 18 11:34:35 office volumio[29389]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 11:34:35 office volumio[29389]: info: CoreCommandRouter::volumioPushState Jul 18 11:34:35 office volumio[29389]: info: CoreStateMachine::updateTrackBlock Jul 18 11:34:35 office volumio[29389]: info: CorePlayQueue::getTrackBlock Jul 18 11:34:35 office volumio[29389]: info: CoreCommandRouter::volumioRetrievevolume Jul 18 11:34:35 office volumio[29389]: error: updateQueue error: null Jul 18 11:34:35 office volumio[29389]: info: Asound.conf file unchanged, so no further update is needed Jul 18 11:34:35 office volumio[29389]: info: Output device has changed, restarting MPD Jul 18 11:34:35 office sudo[29508]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 18 11:34:35 office sudo[29508]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 11:34:35 office volumio[29389]: info: Output device has changed, restarting Shairport Sync Jul 18 11:34:35 office sudo[29508]: pam_unix(sudo:session): session closed for user root Jul 18 11:34:35 office volumio[29389]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:35 office volumio[29389]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 18 11:34:35 office sudo[29511]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 18 11:34:35 office sudo[29511]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 11:34:35 office systemd[1]: Stopping Music Player Daemon... Jul 18 11:34:35 office volumio[29389]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 18 11:34:35 office systemd[1]: mpd.service: Succeeded. Jul 18 11:34:35 office systemd[1]: Stopped Music Player Daemon. Jul 18 11:34:35 office volumio[29389]: info: ___________ START PLUGINS ___________ Jul 18 11:34:35 office systemd[1]: Starting Music Player Daemon... Jul 18 11:34:35 office volumio[29389]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 18 11:34:35 office volumio[29389]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 18 11:34:35 office volumio[29389]: info: [1721302475232] CoreMusicLibrary::Adding element Media Servers Jul 18 11:34:35 office volumio[29389]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 18 11:34:35 office volumio[29389]: Cannot find translation for sourceMedia Servers Jul 18 11:34:35 office volumio[29389]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:35 office volumio[29389]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 18 11:34:35 office volumio[29389]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 18 11:34:35 office volumio[29389]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:35 office volumio[29389]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 18 11:34:35 office volumio[29389]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 18 11:34:35 office volumio[29389]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 18 11:34:35 office volumio[29389]: info: [1721302475387] CoreMusicLibrary::Adding element Last_100 Jul 18 11:34:35 office volumio[29389]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 18 11:34:35 office volumio[29389]: Cannot find translation for sourceMedia Servers Jul 18 11:34:35 office volumio[29389]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 18 11:34:35 office volumio[29389]: info: [1721302475391] CoreMusicLibrary::Adding element Webradio Jul 18 11:34:35 office volumio[29389]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 18 11:34:35 office volumio[29389]: Cannot find translation for sourceMedia Servers Jul 18 11:34:35 office volumio[29389]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 18 11:34:35 office volumio[29389]: info: [squeezelite_mc] Starting proxy server... Jul 18 11:34:35 office volumio[29389]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 18 11:34:35 office volumio[29389]: [SpotifyConnect] Creating VLS config file Jul 18 11:34:35 office volumio[29389]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:35 office volumio[29389]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:35 office volumio[29389]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:35 office volumio[29389]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:35 office volumio[29389]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:35 office volumio[29389]: [SpotifyConnect] Starting metadata listener Jul 18 11:34:35 office sudo[29526]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hosts Jul 18 11:34:35 office sudo[29526]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 11:34:35 office sudo[29526]: pam_unix(sudo:session): session closed for user root Jul 18 11:34:35 office volumio[29389]: [SpotifyConnect] Creating VLS config file Jul 18 11:34:35 office volumio[29389]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:35 office volumio[29389]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:35 office volumio[29389]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:35 office volumio[29389]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:35 office volumio[29389]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:35 office volumio[29389]: [SpotifyConnect] Starting metadata listener Jul 18 11:34:35 office sudo[29529]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect.service Jul 18 11:34:35 office sudo[29529]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 11:34:35 office volumio[29389]: info: Volumio Calling Home Jul 18 11:34:35 office sudo[29529]: pam_unix(sudo:session): session closed for user root Jul 18 11:34:35 office sudo[29532]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect2.service Jul 18 11:34:35 office sudo[29532]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 11:34:35 office wpa_supplicant[750]: wlan0: Failed to initiate sched scan Jul 18 11:34:35 office sudo[29532]: pam_unix(sudo:session): session closed for user root Jul 18 11:34:35 office volumio[29389]: info: [squeezelite_mc] Proxy server started on port 34819 Jul 18 11:34:35 office volumio[29389]: [SpotifyConnect] Error: bind EADDRINUSE 0.0.0.0:5030 Jul 18 11:34:35 office volumio[29389]: at dgram.js:337:20 Jul 18 11:34:35 office volumio[29389]: at processTicksAndRejections (internal/process/task_queues.js:81:21) Jul 18 11:34:35 office volumio[29389]: info: CoreCommandRouter::volumioRetrievevolume Jul 18 11:34:35 office volumio[29389]: Unhandled rejection Error: No sockets available, cannot start. Jul 18 11:34:35 office volumio[29389]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Jul 18 11:34:35 office volumio[29389]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Jul 18 11:34:35 office volumio[29389]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Jul 18 11:34:35 office volumio[29389]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Jul 18 11:34:35 office volumio[29389]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Jul 18 11:34:35 office volumio[29389]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Jul 18 11:34:35 office volumio[29389]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Jul 18 11:34:35 office volumio[29389]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Jul 18 11:34:35 office volumio[29389]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Jul 18 11:34:35 office volumio[29389]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Jul 18 11:34:35 office volumio[29389]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Jul 18 11:34:35 office volumio[29389]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Jul 18 11:34:35 office volumio[29389]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Jul 18 11:34:35 office volumio[29389]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Jul 18 11:34:35 office volumio[29389]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Jul 18 11:34:35 office volumio[29389]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jul 18 11:34:35 office volumio[29389]: info: Cannot mount NAS FLAC at system boot, trial number 2 ,retrying in 5 seconds Jul 18 11:34:35 office volumio[29389]: info: Cannot mount NAS MP3 at system boot, trial number 2 ,retrying in 5 seconds Jul 18 11:34:35 office volumio[29389]: [SpotifyConnect] Vollibrespot Daemon service started! Jul 18 11:34:35 office volumio[29389]: [Metrics] SpotifyConnect: 0s 308.56ms Jul 18 11:34:35 office volumio[29389]: [SpotifyConnect] Vollibrespot Daemon service started! Jul 18 11:34:35 office volumio[29389]: [Metrics] SpotifyConnect: 0s 311.42ms Jul 18 11:34:35 office volumio[29389]: info: MPD Permissions set Jul 18 11:34:35 office volumio[29389]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 Jul 18 11:34:35 office volumio[29389]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 3 Jul 18 11:34:35 office volumio[29389]: info: VolumeController:: Volume=56 Mute =false Jul 18 11:34:35 office volumio[29389]: info: CoreStateMachine::pushState Jul 18 11:34:35 office volumio[29389]: info: CorePlayQueue::getTrack 0 Jul 18 11:34:35 office volumio[29389]: info: CoreCommandRouter::volumioPushState Jul 18 11:34:35 office volumio[29389]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:35 office volumio[29389]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:35 office volumio[29389]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:35 office volumio[29389]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:35 office volumio[29389]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:35 office volumio[29389]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:35 office volumio[29389]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:35 office volumio[29389]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:36 office volumio[29389]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 18 11:34:36 office volumio[29389]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:36 office volumio[29389]: info: VolumeController:: Volume=56 Mute =false Jul 18 11:34:36 office volumio[29389]: info: CoreStateMachine::pushState Jul 18 11:34:36 office volumio[29389]: info: CorePlayQueue::getTrack 0 Jul 18 11:34:36 office volumio[29389]: info: CoreCommandRouter::volumioPushState Jul 18 11:34:36 office mpd[29524]: exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 18 11:34:36 office mpd[29524]: output: No 'audio_output' defined in config file Jul 18 11:34:36 office mpd[29524]: output: Attempt to detect audio output device Jul 18 11:34:36 office mpd[29524]: output: Attempting to detect a alsa audio device Jul 18 11:34:36 office mpd[29524]: output: Successfully detected a alsa audio device Jul 18 11:34:36 office mpd[29524]: exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Jul 18 11:34:36 office mpd[29524]: exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Jul 18 11:34:37 office mpd[29524]: zeroconf: No global port, disabling zeroconf Jul 18 11:34:37 office systemd[1]: Started Music Player Daemon. Jul 18 11:34:37 office sudo[29511]: pam_unix(sudo:session): session closed for user root Jul 18 11:34:37 office volumio[29389]: info: [squeezelite_mc] Server discovery started Jul 18 11:34:37 office volumio[29389]: info: [squeezelite_mc] Player finder started Jul 18 11:34:37 office volumio[29389]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:37 office volumio[29389]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:37 office volumio[29389]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:37 office volumio[29389]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 18 11:34:37 office volumio[29389]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true Jul 18 11:34:37 office volumio[29389]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 18 11:34:37 office volumio[29389]: Error: send ENETUNREACH 255.255.255.255:3483 Jul 18 11:34:37 office volumio[29389]: at doSend (dgram.js:692:16) Jul 18 11:34:37 office volumio[29389]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Jul 18 11:34:37 office volumio[29389]: at afterDns (dgram.js:638:5) Jul 18 11:34:37 office volumio[29389]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Jul 18 11:34:37 office volumio[29389]: errno: -101, Jul 18 11:34:37 office volumio[29389]: code: 'ENETUNREACH', Jul 18 11:34:37 office volumio[29389]: syscall: 'send', Jul 18 11:34:37 office volumio[29389]: address: '255.255.255.255', Jul 18 11:34:37 office volumio[29389]: port: 3483 Jul 18 11:34:37 office volumio[29389]: } Jul 18 11:34:37 office volumio[29389]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 18 11:34:38 office sudo[29557]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-18 11:33 Jul 18 11:34:38 office sudo[29557]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 11:34:38 office sudo[29557]: pam_unix(sudo:session): session closed for user root Jul 18 11:34:38 office volumio-remote-updater[482]: [2024-07-18 11:34:38] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jul 18 11:34:38 office volumio-remote-updater[482]: [2024-07-18 11:34:38] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jul 18 11:34:38 office systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jul 18 11:34:38 office systemd[1]: volumio.service: Failed with result 'exit-code'. Jul 18 11:34:38 office systemd[1]: Started dynamicswap service. Jul 18 11:34:38 office systemd[1]: dynamicswap.service: Succeeded. Jul 18 11:34:38 office systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jul 18 11:34:38 office systemd[1]: volumio.service: Scheduled restart job, restart counter is at 71. Jul 18 11:34:38 office systemd[1]: Stopped Volumio Backend Module. Jul 18 11:34:38 office systemd[1]: Started Volumio Backend Module. Jul 18 11:34:38 office systemd[1]: Started dynamicswap service. Jul 18 11:34:38 office systemd[1]: dynamicswap.service: Succeeded. Jul 18 11:34:40 office volumio[29570]: info: ------------------------------------------- Jul 18 11:34:40 office volumio[29570]: info: ----- Volumio3 ---- Jul 18 11:34:40 office volumio[29570]: info: ------------------------------------------- Jul 18 11:34:40 office volumio[29570]: info: ----- System startup ---- Jul 18 11:34:40 office volumio[29570]: info: ------------------------------------------- Jul 18 11:34:41 office volumio[29570]: info: MYVOLUMIO Environment detected Jul 18 11:34:41 office volumio[29570]: info: Plugin folders cleanup Jul 18 11:34:41 office volumio[29570]: info: Scanning into folder /volumio/app/plugins/ Jul 18 11:34:41 office volumio[29570]: info: Scanning category audio_interface Jul 18 11:34:41 office volumio[29570]: info: Scanning category miscellanea Jul 18 11:34:41 office volumio[29570]: info: Scanning category music_service Jul 18 11:34:41 office volumio[29570]: info: Scanning category plugins.json Jul 18 11:34:41 office volumio[29570]: info: Scanning category system_controller Jul 18 11:34:41 office volumio[29570]: info: Scanning category user_interface Jul 18 11:34:41 office volumio[29570]: info: Scanning into folder /data/plugins/ Jul 18 11:34:41 office volumio[29570]: info: Scanning category music_service Jul 18 11:34:41 office volumio[29570]: info: Plugin folders cleanup completed Jul 18 11:34:41 office volumio[29570]: info: ------------------------------------------- Jul 18 11:34:41 office volumio[29570]: info: ----- Core plugins startup ---- Jul 18 11:34:41 office volumio[29570]: info: ------------------------------------------- Jul 18 11:34:41 office volumio[29570]: info: Loading plugins from folder /volumio/app/plugins/ Jul 18 11:34:41 office volumio[29570]: info: Adding plugin upnp to MyMusic Plugins Jul 18 11:34:41 office volumio[29570]: info: Adding plugin airplay_emulation to MyMusic Plugins Jul 18 11:34:41 office volumio[29570]: info: Adding plugin upnp_browser to MyMusic Plugins Jul 18 11:34:41 office volumio[29570]: info: Loading plugins from folder /data/plugins/ Jul 18 11:34:41 office volumio[29570]: info: Loading plugin "system"... Jul 18 11:34:41 office volumio[29570]: info: Loading plugin "appearance"... Jul 18 11:34:42 office volumio[29570]: info: Loading plugin "network"... Jul 18 11:34:42 office volumio[29570]: info: Refreshing Cached IP Addresses Jul 18 11:34:42 office volumio[29570]: info: Loading plugin "services"... Jul 18 11:34:42 office volumio[29570]: info: Loading plugin "alsa_controller"... Jul 18 11:34:42 office sudo[29596]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 18 11:34:42 office sudo[29594]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 18 11:34:42 office sudo[29596]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 11:34:42 office sudo[29594]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 11:34:42 office wpa_supplicant[750]: wlan0: Failed to initiate sched scan Jul 18 11:34:42 office sudo[29598]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jul 18 11:34:42 office sudo[29596]: pam_unix(sudo:session): session closed for user root Jul 18 11:34:42 office sudo[29598]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 11:34:42 office sudo[29594]: pam_unix(sudo:session): session closed for user root Jul 18 11:34:42 office volumio[29570]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 18 11:34:42 office volumio[29570]: info: Loading plugin "wizard"... Jul 18 11:34:42 office volumio[29570]: info: Loading plugin "volumio_command_line_client"... Jul 18 11:34:42 office volumio[29570]: info: Loading plugin "upnp"... Jul 18 11:34:42 office volumio[29570]: info: [1721302482609] Starting Upmpd Daemon Jul 18 11:34:42 office volumio[29570]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 18 11:34:42 office volumio[29570]: info: Loading plugin "my_music"... Jul 18 11:34:42 office volumio[29570]: info: Loading plugin "mpd"... Jul 18 11:34:43 office volumio[29570]: info: Creating MPD Configuration file Jul 18 11:34:43 office volumio[29570]: info: Loading plugin "upnp_browser"... Jul 18 11:34:43 office sudo[29607]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 18 11:34:43 office sudo[29607]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 11:34:43 office sudo[29607]: pam_unix(sudo:session): session closed for user root Jul 18 11:34:43 office sudo[29609]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 18 11:34:43 office sudo[29609]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 11:34:43 office systemd[1]: Stopping Music Player Daemon... Jul 18 11:34:43 office systemd[1]: mpd.service: Succeeded. Jul 18 11:34:43 office systemd[1]: Stopped Music Player Daemon. Jul 18 11:34:43 office systemd[1]: Starting Music Player Daemon... Jul 18 11:34:43 office sudo[29598]: pam_unix(sudo:session): session closed for user root Jul 18 11:34:43 office volumio[29570]: info: Loading plugin "networkfs"... Jul 18 11:34:43 office volumio-remote-updater[482]: [2024-07-18 11:34:43] [connect] Successful connection Jul 18 11:34:43 office volumio[29570]: info: Cannot mount NAS FLAC at system boot, trial number 1 ,retrying in 5 seconds Jul 18 11:34:43 office volumio[29570]: info: Cannot mount NAS MP3 at system boot, trial number 1 ,retrying in 5 seconds Jul 18 11:34:43 office volumio[29570]: info: Starting Udev Watcher for removable devices Jul 18 11:34:43 office volumio[29570]: info: Ignoring mount for partition: boot Jul 18 11:34:43 office volumio[29570]: info: Ignoring mount for partition: volumio Jul 18 11:34:43 office volumio[29570]: info: Ignoring mount for partition: volumio_data Jul 18 11:34:43 office volumio[29570]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 18 11:34:43 office volumio[29570]: info: Loading plugin "alarm-clock"... Jul 18 11:34:44 office volumio[29570]: info: Loading plugin "airplay_emulation"... Jul 18 11:34:44 office volumio[29570]: info: Starting Shairport Sync Jul 18 11:34:44 office volumio[29570]: info: Loading plugin "last_100"... Jul 18 11:34:44 office volumio[29570]: info: Loading plugin "webradio"... Jul 18 11:34:44 office volumio[29570]: info: Loading plugin "i2s_dacs"... Jul 18 11:34:44 office volumio[29570]: info: Loading plugin "volumiodiscovery"... Jul 18 11:34:44 office volumio[29570]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 18 11:34:44 office volumio[29570]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 18 11:34:44 office volumio[29570]: *** WARNING *** For more information see Jul 18 11:34:44 office node[29570]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 18 11:34:44 office volumio[29570]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 18 11:34:44 office volumio[29570]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 18 11:34:44 office volumio[29570]: *** WARNING *** For more information see Jul 18 11:34:44 office node[29570]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 18 11:34:44 office node[29570]: *** WARNING *** For more information see Jul 18 11:34:44 office node[29570]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 18 11:34:44 office node[29570]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 18 11:34:44 office node[29570]: *** WARNING *** For more information see Jul 18 11:34:44 office volumio[29570]: info: Applying required configuration parameters for plugin volumiodiscovery Jul 18 11:34:44 office volumio[29570]: info: Discovery: Started advertising with name: Office Jul 18 11:34:44 office volumio[29570]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 18 11:34:44 office volumio[29570]: info: Loading plugin "squeezelite_mc"... Jul 18 11:34:44 office mpd[29614]: exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 18 11:34:44 office mpd[29614]: output: No 'audio_output' defined in config file Jul 18 11:34:44 office mpd[29614]: output: Attempt to detect audio output device Jul 18 11:34:44 office mpd[29614]: output: Attempting to detect a alsa audio device Jul 18 11:34:44 office mpd[29614]: output: Successfully detected a alsa audio device Jul 18 11:34:44 office mpd[29614]: exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Jul 18 11:34:44 office mpd[29614]: exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Jul 18 11:34:45 office mpd[29614]: zeroconf: No global port, disabling zeroconf Jul 18 11:34:45 office systemd[1]: Started Music Player Daemon. Jul 18 11:34:45 office sudo[29609]: pam_unix(sudo:session): session closed for user root Jul 18 11:34:45 office volumio[29570]: info: Loading plugin "outputs"... Jul 18 11:34:45 office volumio[29570]: info: Loading plugin "albumart"... Jul 18 11:34:45 office volumio[29570]: info: Plugin example_plugin is not enabled Jul 18 11:34:45 office volumio[29570]: info: Loading plugin "inputs"... Jul 18 11:34:45 office volumio[29570]: info: Loading plugin "updater_comm"... Jul 18 11:34:45 office volumio[29570]: info: Plugin mpdemulation is not enabled Jul 18 11:34:45 office volumio[29570]: info: Loading plugin "rest_api"... Jul 18 11:34:45 office volumio[29570]: info: Loading plugin "websocket"... Jul 18 11:34:45 office volumio[29570]: info: Loading plugin "spop"... Jul 18 11:34:45 office volumio[29570]: Forking 3 albumart workers Jul 18 11:34:46 office volumio[29570]: info: Loading plugin "volspotconnect2"... Jul 18 11:34:46 office volumio[29570]: Starting albumart workers Jul 18 11:34:46 office volumio[29570]: Starting albumart workers Jul 18 11:34:46 office volumio[29570]: Starting albumart workers Jul 18 11:34:47 office volumio[29570]: info: Loading i18n strings for locale en Jul 18 11:34:47 office volumio[29570]: Updating browse sources language Jul 18 11:34:47 office volumio[29570]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 18 11:34:47 office volumio[29570]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 18 11:34:47 office volumio[29570]: info: CoreCommandRouter::initPlayerControls Jul 18 11:34:47 office volumio[29570]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:47 office volumio[29570]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:47 office volumio[29570]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:47 office volumio[29570]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:47 office volumio[29570]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:47 office volumio[29570]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:47 office volumio[29570]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:47 office volumio[29570]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:47 office volumio[29570]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 18 11:34:47 office volumio[29570]: Express server listening on port 3000 Jul 18 11:34:47 office volumio[29570]: [Metrics] WebUI: 7s 665.70ms Jul 18 11:34:47 office volumio[29570]: info: CoreStateMachine::resetVolumioState Jul 18 11:34:47 office volumio[29570]: info: CoreStateMachine::getcurrentVolume Jul 18 11:34:47 office volumio[29570]: info: CoreCommandRouter::volumioRetrievevolume Jul 18 11:34:47 office mpd[29614]: client: [0] opened from local Jul 18 11:34:47 office volumio[29570]: info: MPD Permissions set Jul 18 11:34:47 office volumio-remote-updater[482]: [2024-07-18 11:34:47] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1721302483 101 Jul 18 11:34:47 office volumio[29570]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1 Jul 18 11:34:47 office mpd[29614]: exception: No database Jul 18 11:34:47 office mpd[29614]: exception: No database Jul 18 11:34:47 office volumio[29570]: info: MPD running with PID29614 Jul 18 11:34:47 office volumio[29570]: ,establishing connection Jul 18 11:34:47 office mpd[29614]: client: [1] opened from local Jul 18 11:34:47 office volumio[29570]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:47 office volumio[29570]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:47 office volumio[29570]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:47 office volumio[29570]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:47 office volumio[29570]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:47 office volumio[29570]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:47 office volumio[29570]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:47 office volumio[29570]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:47 office volumio[29570]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 18 11:34:47 office volumio[29570]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:47 office volumio[29570]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 18 11:34:47 office volumio[29570]: info: Reloading queue from file Jul 18 11:34:47 office volumio[29570]: error: updateQueue error: null Jul 18 11:34:47 office volumio[29570]: error: Failed LSINFO: Error: [50@0] {lsinfo} No database Jul 18 11:34:47 office volumio[29570]: info: CoreStateMachine::setRepeat null single undefined Jul 18 11:34:47 office volumio[29570]: info: CoreStateMachine::pushState Jul 18 11:34:47 office volumio[29570]: info: CorePlayQueue::getTrack 0 Jul 18 11:34:47 office volumio[29570]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 11:34:47 office volumio[29570]: info: CoreCommandRouter::volumioPushState Jul 18 11:34:47 office volumio[29570]: info: CoreStateMachine::setRandom true Jul 18 11:34:47 office volumio[29570]: info: CoreStateMachine::pushState Jul 18 11:34:47 office volumio[29570]: info: CorePlayQueue::getTrack 0 Jul 18 11:34:47 office volumio[29570]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 11:34:47 office volumio[29570]: info: CoreCommandRouter::volumioPushState Jul 18 11:34:47 office volumio[29570]: info: Setting Device type: Raspberry PI Jul 18 11:34:48 office volumio[29570]: info: Completed loading Core Plugins Jul 18 11:34:48 office volumio[29570]: info: Preparing to generate the ALSA configuration file Jul 18 11:34:48 office volumio[29570]: info: VolumeController:: Volume=56 Mute =false Jul 18 11:34:48 office volumio[29570]: info: CoreStateMachine::pushState Jul 18 11:34:48 office volumio[29570]: info: CorePlayQueue::getTrack 0 Jul 18 11:34:48 office volumio[29570]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 11:34:48 office volumio[29570]: info: CoreCommandRouter::volumioPushState Jul 18 11:34:48 office volumio[29570]: info: CoreStateMachine::updateTrackBlock Jul 18 11:34:48 office volumio[29570]: info: CorePlayQueue::getTrackBlock Jul 18 11:34:48 office volumio[29570]: info: CoreCommandRouter::volumioRetrievevolume Jul 18 11:34:48 office volumio[29570]: error: updateQueue error: null Jul 18 11:34:48 office volumio[29570]: info: Asound.conf file unchanged, so no further update is needed Jul 18 11:34:48 office volumio[29570]: info: Output device has changed, restarting MPD Jul 18 11:34:48 office sudo[29689]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 18 11:34:48 office sudo[29689]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 11:34:48 office volumio[29570]: info: Output device has changed, restarting Shairport Sync Jul 18 11:34:48 office sudo[29689]: pam_unix(sudo:session): session closed for user root Jul 18 11:34:48 office volumio[29570]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:48 office volumio[29570]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 18 11:34:48 office sudo[29691]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 18 11:34:48 office sudo[29691]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 11:34:48 office systemd[1]: Stopping Music Player Daemon... Jul 18 11:34:48 office volumio[29570]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 18 11:34:48 office wpa_supplicant[750]: wlan0: Failed to initiate sched scan Jul 18 11:34:48 office systemd[1]: mpd.service: Succeeded. Jul 18 11:34:48 office volumio[29570]: info: ___________ START PLUGINS ___________ Jul 18 11:34:48 office systemd[1]: Stopped Music Player Daemon. Jul 18 11:34:48 office systemd[1]: Starting Music Player Daemon... Jul 18 11:34:48 office volumio[29570]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 18 11:34:48 office volumio[29570]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 18 11:34:48 office volumio[29570]: info: [1721302488214] CoreMusicLibrary::Adding element Media Servers Jul 18 11:34:48 office volumio[29570]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 18 11:34:48 office volumio[29570]: Cannot find translation for sourceMedia Servers Jul 18 11:34:48 office volumio[29570]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:48 office volumio[29570]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 18 11:34:48 office volumio[29570]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 18 11:34:48 office volumio[29570]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:48 office volumio[29570]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 18 11:34:48 office volumio[29570]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 18 11:34:48 office volumio[29570]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 18 11:34:48 office volumio[29570]: info: [1721302488368] CoreMusicLibrary::Adding element Last_100 Jul 18 11:34:48 office volumio[29570]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 18 11:34:48 office volumio[29570]: Cannot find translation for sourceMedia Servers Jul 18 11:34:48 office volumio[29570]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 18 11:34:48 office volumio[29570]: info: [1721302488373] CoreMusicLibrary::Adding element Webradio Jul 18 11:34:48 office volumio[29570]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 18 11:34:48 office volumio[29570]: Cannot find translation for sourceMedia Servers Jul 18 11:34:48 office volumio[29570]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 18 11:34:48 office volumio[29570]: info: [squeezelite_mc] Starting proxy server... Jul 18 11:34:48 office volumio[29570]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 18 11:34:48 office volumio[29570]: [SpotifyConnect] Creating VLS config file Jul 18 11:34:48 office volumio[29570]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:48 office volumio[29570]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:48 office volumio[29570]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:48 office volumio[29570]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:48 office volumio[29570]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:48 office volumio[29570]: [SpotifyConnect] Starting metadata listener Jul 18 11:34:48 office sudo[29707]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hosts Jul 18 11:34:48 office sudo[29707]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 11:34:48 office sudo[29707]: pam_unix(sudo:session): session closed for user root Jul 18 11:34:48 office volumio[29570]: [SpotifyConnect] Creating VLS config file Jul 18 11:34:48 office volumio[29570]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:48 office volumio[29570]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:48 office volumio[29570]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:48 office volumio[29570]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:48 office volumio[29570]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:48 office volumio[29570]: [SpotifyConnect] Starting metadata listener Jul 18 11:34:48 office sudo[29710]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect.service Jul 18 11:34:48 office sudo[29710]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 11:34:48 office volumio[29570]: info: Volumio Calling Home Jul 18 11:34:48 office sudo[29710]: pam_unix(sudo:session): session closed for user root Jul 18 11:34:48 office volumio[29570]: info: [squeezelite_mc] Proxy server started on port 36385 Jul 18 11:34:48 office volumio[29570]: [SpotifyConnect] Error: bind EADDRINUSE 0.0.0.0:5030 Jul 18 11:34:48 office volumio[29570]: at dgram.js:337:20 Jul 18 11:34:48 office volumio[29570]: at processTicksAndRejections (internal/process/task_queues.js:81:21) Jul 18 11:34:48 office volumio[29570]: info: CoreCommandRouter::volumioRetrievevolume Jul 18 11:34:48 office volumio[29570]: Unhandled rejection Error: No sockets available, cannot start. Jul 18 11:34:48 office volumio[29570]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Jul 18 11:34:48 office volumio[29570]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Jul 18 11:34:48 office volumio[29570]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Jul 18 11:34:48 office volumio[29570]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Jul 18 11:34:48 office volumio[29570]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Jul 18 11:34:48 office volumio[29570]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Jul 18 11:34:48 office volumio[29570]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Jul 18 11:34:48 office volumio[29570]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Jul 18 11:34:48 office volumio[29570]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Jul 18 11:34:48 office volumio[29570]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Jul 18 11:34:48 office volumio[29570]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Jul 18 11:34:48 office volumio[29570]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Jul 18 11:34:48 office volumio[29570]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Jul 18 11:34:48 office volumio[29570]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Jul 18 11:34:48 office volumio[29570]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Jul 18 11:34:48 office volumio[29570]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jul 18 11:34:48 office volumio[29570]: info: Cannot mount NAS FLAC at system boot, trial number 2 ,retrying in 5 seconds Jul 18 11:34:48 office volumio[29570]: info: Cannot mount NAS MP3 at system boot, trial number 2 ,retrying in 5 seconds Jul 18 11:34:48 office volumio[29570]: [SpotifyConnect] Vollibrespot Daemon service started! Jul 18 11:34:48 office volumio[29570]: [Metrics] SpotifyConnect: 0s 282.37ms Jul 18 11:34:48 office volumio[29570]: info: MPD Permissions set Jul 18 11:34:48 office volumio[29570]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 Jul 18 11:34:48 office volumio[29570]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:48 office volumio[29570]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:48 office volumio[29570]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:48 office volumio[29570]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:48 office volumio[29570]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:48 office volumio[29570]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:48 office volumio[29570]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:48 office volumio[29570]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:48 office volumio[29570]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 18 11:34:48 office volumio[29570]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:48 office volumio[29570]: info: VolumeController:: Volume=56 Mute =false Jul 18 11:34:48 office volumio[29570]: info: CoreStateMachine::pushState Jul 18 11:34:48 office volumio[29570]: info: CorePlayQueue::getTrack 0 Jul 18 11:34:48 office volumio[29570]: info: CoreCommandRouter::volumioPushState Jul 18 11:34:48 office volumio[29570]: info: VolumeController:: Volume=56 Mute =false Jul 18 11:34:48 office volumio[29570]: info: CoreStateMachine::pushState Jul 18 11:34:48 office volumio[29570]: info: CorePlayQueue::getTrack 0 Jul 18 11:34:48 office volumio[29570]: info: CoreCommandRouter::volumioPushState Jul 18 11:34:49 office mpd[29706]: Jul 18 11:34 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 18 11:34:49 office mpd[29706]: Jul 18 11:34 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Jul 18 11:34:49 office mpd[29706]: Jul 18 11:34 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Jul 18 11:34:50 office ntpd[657]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Jul 18 11:34:50 office systemd[1]: Started Music Player Daemon. Jul 18 11:34:50 office sudo[29691]: pam_unix(sudo:session): session closed for user root Jul 18 11:34:50 office volumio[29570]: info: [squeezelite_mc] Server discovery started Jul 18 11:34:50 office volumio[29570]: info: [squeezelite_mc] Player finder started Jul 18 11:34:50 office volumio[29570]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:50 office volumio[29570]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:50 office volumio[29570]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:34:50 office volumio[29570]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 18 11:34:50 office volumio[29570]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true Jul 18 11:34:50 office sudo[29713]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect2.service Jul 18 11:34:50 office sudo[29713]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 11:34:50 office volumio[29570]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 18 11:34:50 office sudo[29713]: pam_unix(sudo:session): session closed for user root Jul 18 11:34:50 office volumio[29570]: Error: send ENETUNREACH 255.255.255.255:3483 Jul 18 11:34:50 office volumio[29570]: at doSend (dgram.js:692:16) Jul 18 11:34:50 office volumio[29570]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Jul 18 11:34:50 office volumio[29570]: at afterDns (dgram.js:638:5) Jul 18 11:34:50 office volumio[29570]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Jul 18 11:34:50 office volumio[29570]: errno: -101, Jul 18 11:34:50 office volumio[29570]: code: 'ENETUNREACH', Jul 18 11:34:50 office volumio[29570]: syscall: 'send', Jul 18 11:34:50 office volumio[29570]: address: '255.255.255.255', Jul 18 11:34:50 office volumio[29570]: port: 3483 Jul 18 11:34:50 office volumio[29570]: } Jul 18 11:34:50 office volumio[29570]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 18 11:34:50 office sudo[29738]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-18 11:33 Jul 18 11:34:50 office sudo[29738]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 11:34:50 office sudo[29738]: pam_unix(sudo:session): session closed for user root Jul 18 11:34:51 office volumio-remote-updater[482]: [2024-07-18 11:34:51] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jul 18 11:34:51 office volumio-remote-updater[482]: [2024-07-18 11:34:51] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jul 18 11:34:51 office systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jul 18 11:34:51 office systemd[1]: volumio.service: Failed with result 'exit-code'. Jul 18 11:34:51 office systemd[1]: Started dynamicswap service. Jul 18 11:34:51 office systemd[1]: dynamicswap.service: Succeeded. Jul 18 11:34:51 office systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jul 18 11:34:51 office systemd[1]: volumio.service: Scheduled restart job, restart counter is at 72. Jul 18 11:34:51 office systemd[1]: Stopped Volumio Backend Module. Jul 18 11:34:51 office systemd[1]: Started Volumio Backend Module. Jul 18 11:34:51 office systemd[1]: Started dynamicswap service. Jul 18 11:34:51 office systemd[1]: dynamicswap.service: Succeeded. Jul 18 11:34:53 office volumio[29751]: info: ------------------------------------------- Jul 18 11:34:53 office volumio[29751]: info: ----- Volumio3 ---- Jul 18 11:34:53 office volumio[29751]: info: ------------------------------------------- Jul 18 11:34:53 office volumio[29751]: info: ----- System startup ---- Jul 18 11:34:53 office volumio[29751]: info: ------------------------------------------- Jul 18 11:34:54 office volumio[29751]: info: MYVOLUMIO Environment detected Jul 18 11:34:54 office volumio[29751]: info: Plugin folders cleanup Jul 18 11:34:54 office volumio[29751]: info: Scanning into folder /volumio/app/plugins/ Jul 18 11:34:54 office volumio[29751]: info: Scanning category audio_interface Jul 18 11:34:54 office volumio[29751]: info: Scanning category miscellanea Jul 18 11:34:54 office volumio[29751]: info: Scanning category music_service Jul 18 11:34:54 office volumio[29751]: info: Scanning category plugins.json Jul 18 11:34:54 office volumio[29751]: info: Scanning category system_controller Jul 18 11:34:54 office volumio[29751]: info: Scanning category user_interface Jul 18 11:34:54 office volumio[29751]: info: Scanning into folder /data/plugins/ Jul 18 11:34:54 office volumio[29751]: info: Scanning category music_service Jul 18 11:34:54 office volumio[29751]: info: Plugin folders cleanup completed Jul 18 11:34:54 office volumio[29751]: info: ------------------------------------------- Jul 18 11:34:54 office volumio[29751]: info: ----- Core plugins startup ---- Jul 18 11:34:54 office volumio[29751]: info: ------------------------------------------- Jul 18 11:34:54 office volumio[29751]: info: Loading plugins from folder /volumio/app/plugins/ Jul 18 11:34:54 office volumio[29751]: info: Adding plugin upnp to MyMusic Plugins Jul 18 11:34:54 office volumio[29751]: info: Adding plugin airplay_emulation to MyMusic Plugins Jul 18 11:34:54 office volumio[29751]: info: Adding plugin upnp_browser to MyMusic Plugins Jul 18 11:34:54 office volumio[29751]: info: Loading plugins from folder /data/plugins/ Jul 18 11:34:54 office volumio[29751]: info: Loading plugin "system"... Jul 18 11:34:54 office volumio[29751]: info: Loading plugin "appearance"... Jul 18 11:34:55 office wpa_supplicant[750]: wlan0: Failed to initiate sched scan Jul 18 11:34:55 office volumio[29751]: info: Loading plugin "network"... Jul 18 11:34:55 office volumio[29751]: info: Refreshing Cached IP Addresses Jul 18 11:34:55 office sudo[29775]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 18 11:34:55 office sudo[29775]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 11:34:55 office sudo[29775]: pam_unix(sudo:session): session closed for user root Jul 18 11:34:55 office sudo[29777]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 18 11:34:55 office sudo[29777]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 11:34:55 office volumio[29751]: info: Loading plugin "services"... Jul 18 11:34:55 office volumio[29751]: info: Loading plugin "alsa_controller"... Jul 18 11:34:55 office sudo[29777]: pam_unix(sudo:session): session closed for user root Jul 18 11:34:55 office sudo[29780]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jul 18 11:34:55 office sudo[29780]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 11:34:55 office volumio[29751]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 18 11:34:55 office volumio[29751]: info: Loading plugin "wizard"... Jul 18 11:34:55 office volumio[29751]: info: Loading plugin "volumio_command_line_client"... Jul 18 11:34:55 office volumio[29751]: info: Loading plugin "upnp"... Jul 18 11:34:55 office volumio[29751]: info: [1721302495361] Starting Upmpd Daemon Jul 18 11:34:55 office volumio[29751]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 18 11:34:55 office volumio[29751]: info: Loading plugin "my_music"... Jul 18 11:34:55 office volumio[29751]: info: Loading plugin "mpd"... Jul 18 11:34:55 office volumio[29751]: info: Creating MPD Configuration file Jul 18 11:34:55 office volumio[29751]: info: Loading plugin "upnp_browser"... Jul 18 11:34:55 office sudo[29788]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 18 11:34:55 office sudo[29788]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 11:34:55 office sudo[29788]: pam_unix(sudo:session): session closed for user root Jul 18 11:34:55 office sudo[29790]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 18 11:34:55 office sudo[29790]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 11:34:55 office systemd[1]: Stopping Music Player Daemon... Jul 18 11:34:55 office systemd[1]: mpd.service: Succeeded. Jul 18 11:34:55 office systemd[1]: Stopped Music Player Daemon. Jul 18 11:34:55 office systemd[1]: Starting Music Player Daemon... Jul 18 11:34:55 office sudo[29780]: pam_unix(sudo:session): session closed for user root Jul 18 11:34:56 office volumio-remote-updater[482]: [2024-07-18 11:34:56] [connect] Successful connection Jul 18 11:34:56 office volumio[29751]: info: Loading plugin "networkfs"... Jul 18 11:34:56 office volumio[29751]: info: Cannot mount NAS FLAC at system boot, trial number 1 ,retrying in 5 seconds Jul 18 11:34:56 office volumio[29751]: info: Cannot mount NAS MP3 at system boot, trial number 1 ,retrying in 5 seconds Jul 18 11:34:56 office volumio[29751]: info: Starting Udev Watcher for removable devices Jul 18 11:34:56 office volumio[29751]: info: Ignoring mount for partition: boot Jul 18 11:34:56 office volumio[29751]: info: Ignoring mount for partition: volumio Jul 18 11:34:56 office volumio[29751]: info: Ignoring mount for partition: volumio_data Jul 18 11:34:56 office volumio[29751]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 18 11:34:56 office volumio[29751]: info: Loading plugin "alarm-clock"... Jul 18 11:34:56 office volumio[29751]: info: Loading plugin "airplay_emulation"... Jul 18 11:34:56 office volumio[29751]: info: Starting Shairport Sync Jul 18 11:34:56 office volumio[29751]: info: Loading plugin "last_100"... Jul 18 11:34:57 office volumio[29751]: info: Loading plugin "webradio"... Jul 18 11:34:57 office volumio[29751]: info: Loading plugin "i2s_dacs"... Jul 18 11:34:57 office volumio[29751]: info: Loading plugin "volumiodiscovery"... Jul 18 11:34:57 office volumio[29751]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 18 11:34:57 office volumio[29751]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 18 11:34:57 office volumio[29751]: *** WARNING *** For more information see Jul 18 11:34:57 office node[29751]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 18 11:34:57 office volumio[29751]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 18 11:34:57 office volumio[29751]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 18 11:34:57 office volumio[29751]: *** WARNING *** For more information see Jul 18 11:34:57 office node[29751]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 18 11:34:57 office node[29751]: *** WARNING *** For more information see Jul 18 11:34:57 office node[29751]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 18 11:34:57 office node[29751]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 18 11:34:57 office node[29751]: *** WARNING *** For more information see Jul 18 11:34:57 office volumio[29751]: info: Applying required configuration parameters for plugin volumiodiscovery Jul 18 11:34:57 office volumio[29751]: info: Discovery: Started advertising with name: Office Jul 18 11:34:57 office volumio[29751]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 18 11:34:57 office volumio[29751]: info: Loading plugin "squeezelite_mc"... Jul 18 11:34:57 office mpd[29795]: Jul 18 11:34 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 18 11:34:57 office mpd[29795]: Jul 18 11:34 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Jul 18 11:34:57 office mpd[29795]: Jul 18 11:34 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Jul 18 11:34:57 office systemd[1]: Started Music Player Daemon. Jul 18 11:34:57 office sudo[29790]: pam_unix(sudo:session): session closed for user root Jul 18 11:34:58 office volumio[29751]: info: Loading plugin "outputs"... Jul 18 11:34:58 office volumio[29751]: info: Loading plugin "albumart"... Jul 18 11:34:58 office volumio[29751]: info: Plugin example_plugin is not enabled Jul 18 11:34:58 office volumio[29751]: info: Loading plugin "inputs"... Jul 18 11:34:58 office volumio[29751]: info: Loading plugin "updater_comm"... Jul 18 11:34:58 office volumio[29751]: info: Plugin mpdemulation is not enabled Jul 18 11:34:58 office volumio[29751]: info: Loading plugin "rest_api"... Jul 18 11:34:58 office volumio[29751]: info: Loading plugin "websocket"... Jul 18 11:34:58 office volumio[29751]: info: Loading plugin "spop"... Jul 18 11:34:58 office volumio[29751]: Forking 3 albumart workers Jul 18 11:34:59 office volumio[29751]: info: Loading plugin "volspotconnect2"... Jul 18 11:34:59 office volumio[29751]: Starting albumart workers Jul 18 11:34:59 office volumio[29751]: Starting albumart workers Jul 18 11:34:59 office volumio[29751]: Starting albumart workers Jul 18 11:35:00 office volumio[29751]: info: Loading i18n strings for locale en Jul 18 11:35:00 office volumio[29751]: Updating browse sources language Jul 18 11:35:00 office volumio[29751]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 18 11:35:00 office volumio[29751]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 18 11:35:00 office volumio[29751]: info: CoreCommandRouter::initPlayerControls Jul 18 11:35:00 office volumio[29751]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:00 office volumio[29751]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:00 office volumio[29751]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:00 office volumio[29751]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:00 office volumio[29751]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:00 office volumio[29751]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:00 office volumio[29751]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:00 office volumio[29751]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:00 office volumio[29751]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 18 11:35:00 office volumio[29751]: Express server listening on port 3000 Jul 18 11:35:00 office volumio[29751]: [Metrics] WebUI: 7s 768.87ms Jul 18 11:35:00 office volumio[29751]: info: CoreStateMachine::resetVolumioState Jul 18 11:35:00 office volumio[29751]: info: CoreStateMachine::getcurrentVolume Jul 18 11:35:00 office volumio[29751]: info: CoreCommandRouter::volumioRetrievevolume Jul 18 11:35:00 office volumio[29751]: info: MPD Permissions set Jul 18 11:35:00 office volumio-remote-updater[482]: [2024-07-18 11:35:00] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1721302496 101 Jul 18 11:35:00 office volumio[29751]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1 Jul 18 11:35:00 office volumio[29751]: info: MPD running with PID29795 Jul 18 11:35:00 office volumio[29751]: ,establishing connection Jul 18 11:35:00 office wpa_supplicant[750]: wlan0: Failed to initiate sched scan Jul 18 11:35:00 office volumio[29751]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:00 office volumio[29751]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:00 office volumio[29751]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:00 office volumio[29751]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:00 office volumio[29751]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:00 office volumio[29751]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:00 office volumio[29751]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:00 office volumio[29751]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:00 office volumio[29751]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 18 11:35:00 office volumio[29751]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:00 office volumio[29751]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 18 11:35:00 office volumio[29751]: info: Reloading queue from file Jul 18 11:35:00 office volumio[29751]: error: updateQueue error: null Jul 18 11:35:00 office volumio[29751]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jul 18 11:35:00 office volumio[29751]: info: CoreStateMachine::setRepeat null single undefined Jul 18 11:35:00 office volumio[29751]: info: CoreStateMachine::pushState Jul 18 11:35:00 office volumio[29751]: info: CorePlayQueue::getTrack 0 Jul 18 11:35:00 office volumio[29751]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 11:35:00 office volumio[29751]: info: CoreCommandRouter::volumioPushState Jul 18 11:35:00 office volumio[29751]: info: CoreStateMachine::setRandom true Jul 18 11:35:00 office volumio[29751]: info: CoreStateMachine::pushState Jul 18 11:35:00 office volumio[29751]: info: CorePlayQueue::getTrack 0 Jul 18 11:35:00 office volumio[29751]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 11:35:00 office volumio[29751]: info: CoreCommandRouter::volumioPushState Jul 18 11:35:00 office volumio[29751]: info: Setting Device type: Raspberry PI Jul 18 11:35:00 office volumio[29751]: info: Completed loading Core Plugins Jul 18 11:35:00 office volumio[29751]: info: Preparing to generate the ALSA configuration file Jul 18 11:35:00 office volumio[29751]: info: VolumeController:: Volume=56 Mute =false Jul 18 11:35:00 office volumio[29751]: info: CoreStateMachine::pushState Jul 18 11:35:00 office volumio[29751]: info: CorePlayQueue::getTrack 0 Jul 18 11:35:00 office volumio[29751]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 11:35:00 office volumio[29751]: info: CoreCommandRouter::volumioPushState Jul 18 11:35:00 office volumio[29751]: info: CoreStateMachine::updateTrackBlock Jul 18 11:35:00 office volumio[29751]: info: CorePlayQueue::getTrackBlock Jul 18 11:35:00 office volumio[29751]: info: CoreCommandRouter::volumioRetrievevolume Jul 18 11:35:00 office volumio[29751]: info: Asound.conf file unchanged, so no further update is needed Jul 18 11:35:00 office volumio[29751]: info: Output device has changed, restarting MPD Jul 18 11:35:00 office sudo[29870]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 18 11:35:00 office sudo[29870]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 11:35:00 office volumio[29751]: info: Output device has changed, restarting Shairport Sync Jul 18 11:35:00 office volumio[29751]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:00 office volumio[29751]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 18 11:35:00 office sudo[29870]: pam_unix(sudo:session): session closed for user root Jul 18 11:35:00 office sudo[29872]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 18 11:35:00 office sudo[29872]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 11:35:00 office systemd[1]: Stopping Music Player Daemon... Jul 18 11:35:01 office volumio[29751]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 18 11:35:01 office volumio[29751]: info: ___________ START PLUGINS ___________ Jul 18 11:35:01 office volumio[29751]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 18 11:35:01 office volumio[29751]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 18 11:35:01 office volumio[29751]: info: [1721302501017] CoreMusicLibrary::Adding element Media Servers Jul 18 11:35:01 office volumio[29751]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 18 11:35:01 office volumio[29751]: Cannot find translation for sourceMedia Servers Jul 18 11:35:01 office volumio[29751]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:01 office volumio[29751]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 18 11:35:01 office volumio[29751]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 18 11:35:01 office volumio[29751]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:01 office volumio[29751]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 18 11:35:01 office volumio[29751]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 18 11:35:01 office volumio[29751]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 18 11:35:01 office volumio[29751]: info: [1721302501146] CoreMusicLibrary::Adding element Last_100 Jul 18 11:35:01 office volumio[29751]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 18 11:35:01 office volumio[29751]: Cannot find translation for sourceMedia Servers Jul 18 11:35:01 office volumio[29751]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 18 11:35:01 office volumio[29751]: info: [1721302501151] CoreMusicLibrary::Adding element Webradio Jul 18 11:35:01 office volumio[29751]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 18 11:35:01 office volumio[29751]: Cannot find translation for sourceMedia Servers Jul 18 11:35:01 office volumio[29751]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 18 11:35:01 office volumio[29751]: info: [squeezelite_mc] Starting proxy server... Jul 18 11:35:01 office volumio[29751]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 18 11:35:01 office volumio[29751]: [SpotifyConnect] Creating VLS config file Jul 18 11:35:01 office volumio[29751]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:01 office volumio[29751]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:01 office volumio[29751]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:01 office volumio[29751]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:01 office volumio[29751]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:01 office volumio[29751]: [SpotifyConnect] Starting metadata listener Jul 18 11:35:01 office volumio[29751]: [SpotifyConnect] Creating VLS config file Jul 18 11:35:01 office volumio[29751]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:01 office volumio[29751]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:01 office volumio[29751]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:01 office volumio[29751]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:01 office volumio[29751]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:01 office volumio[29751]: [SpotifyConnect] Starting metadata listener Jul 18 11:35:01 office volumio[29751]: info: Volumio Calling Home Jul 18 11:35:01 office systemd[1]: mpd.service: Succeeded. Jul 18 11:35:01 office systemd[1]: Stopped Music Player Daemon. Jul 18 11:35:01 office systemd[1]: Starting Music Player Daemon... Jul 18 11:35:01 office volumio[29751]: info: [squeezelite_mc] Proxy server started on port 44145 Jul 18 11:35:01 office volumio[29751]: [SpotifyConnect] Error: bind EADDRINUSE 0.0.0.0:5030 Jul 18 11:35:01 office volumio[29751]: at dgram.js:337:20 Jul 18 11:35:01 office volumio[29751]: at processTicksAndRejections (internal/process/task_queues.js:81:21) Jul 18 11:35:01 office volumio[29751]: info: CoreCommandRouter::volumioRetrievevolume Jul 18 11:35:01 office volumio[29751]: Unhandled rejection Error: No sockets available, cannot start. Jul 18 11:35:01 office volumio[29751]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Jul 18 11:35:01 office volumio[29751]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Jul 18 11:35:01 office volumio[29751]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Jul 18 11:35:01 office volumio[29751]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Jul 18 11:35:01 office volumio[29751]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Jul 18 11:35:01 office volumio[29751]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Jul 18 11:35:01 office volumio[29751]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Jul 18 11:35:01 office volumio[29751]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Jul 18 11:35:01 office volumio[29751]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Jul 18 11:35:01 office volumio[29751]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Jul 18 11:35:01 office volumio[29751]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Jul 18 11:35:01 office volumio[29751]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Jul 18 11:35:01 office volumio[29751]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Jul 18 11:35:01 office volumio[29751]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Jul 18 11:35:01 office volumio[29751]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Jul 18 11:35:01 office volumio[29751]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jul 18 11:35:01 office volumio[29751]: info: Cannot mount NAS FLAC at system boot, trial number 2 ,retrying in 5 seconds Jul 18 11:35:01 office volumio[29751]: info: Cannot mount NAS MP3 at system boot, trial number 2 ,retrying in 5 seconds Jul 18 11:35:01 office volumio[29751]: error: MPD error: Error: write EPIPE Jul 18 11:35:01 office volumio[29751]: error: write EPIPE {"errno":-32,"code":"EPIPE","syscall":"write"} Jul 18 11:35:01 office volumio[29751]: info: MPD Permissions set Jul 18 11:35:01 office volumio[29751]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:01 office volumio[29751]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:01 office volumio[29751]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:01 office volumio[29751]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:01 office volumio[29751]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:01 office volumio[29751]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:01 office volumio[29751]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:01 office volumio[29751]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:01 office volumio[29751]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 18 11:35:01 office volumio[29751]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:01 office volumio[29751]: info: VolumeController:: Volume=56 Mute =false Jul 18 11:35:01 office volumio[29751]: info: CoreStateMachine::pushState Jul 18 11:35:01 office volumio[29751]: info: CorePlayQueue::getTrack 0 Jul 18 11:35:01 office volumio[29751]: info: CoreCommandRouter::volumioPushState Jul 18 11:35:02 office sudo[29885]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hosts Jul 18 11:35:02 office sudo[29887]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect.service Jul 18 11:35:02 office sudo[29885]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 11:35:02 office sudo[29887]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 11:35:02 office sudo[29885]: pam_unix(sudo:session): session closed for user root Jul 18 11:35:02 office volumio[29751]: info: [squeezelite_mc] Server discovery started Jul 18 11:35:02 office volumio[29751]: info: [squeezelite_mc] Player finder started Jul 18 11:35:02 office sudo[29889]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect2.service Jul 18 11:35:02 office volumio[29751]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:02 office volumio[29751]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:02 office volumio[29751]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:02 office volumio[29751]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 18 11:35:02 office sudo[29889]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 11:35:02 office volumio[29751]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true Jul 18 11:35:02 office volumio[29751]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 18 11:35:02 office volumio[29751]: Error: send ENETUNREACH 255.255.255.255:3483 Jul 18 11:35:02 office volumio[29751]: at doSend (dgram.js:692:16) Jul 18 11:35:02 office volumio[29751]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Jul 18 11:35:02 office volumio[29751]: at afterDns (dgram.js:638:5) Jul 18 11:35:02 office volumio[29751]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Jul 18 11:35:02 office volumio[29751]: errno: -101, Jul 18 11:35:02 office volumio[29751]: code: 'ENETUNREACH', Jul 18 11:35:02 office volumio[29751]: syscall: 'send', Jul 18 11:35:02 office volumio[29751]: address: '255.255.255.255', Jul 18 11:35:02 office volumio[29751]: port: 3483 Jul 18 11:35:02 office volumio[29751]: } Jul 18 11:35:02 office volumio[29751]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 18 11:35:02 office sudo[29889]: pam_unix(sudo:session): session closed for user root Jul 18 11:35:02 office sudo[29887]: pam_unix(sudo:session): session closed for user root Jul 18 11:35:02 office mpd[29898]: Jul 18 11:35 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 18 11:35:03 office mpd[29898]: Jul 18 11:35 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Jul 18 11:35:03 office mpd[29898]: Jul 18 11:35 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Jul 18 11:35:03 office systemd[1]: Started Music Player Daemon. Jul 18 11:35:03 office sudo[29872]: pam_unix(sudo:session): session closed for user root Jul 18 11:35:03 office sudo[29919]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-18 11:34 Jul 18 11:35:03 office sudo[29919]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 11:35:03 office sudo[29919]: pam_unix(sudo:session): session closed for user root Jul 18 11:35:03 office volumio-remote-updater[482]: [2024-07-18 11:35:03] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jul 18 11:35:03 office volumio-remote-updater[482]: [2024-07-18 11:35:03] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jul 18 11:35:03 office systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jul 18 11:35:03 office systemd[1]: volumio.service: Failed with result 'exit-code'. Jul 18 11:35:03 office systemd[1]: Started dynamicswap service. Jul 18 11:35:03 office systemd[1]: dynamicswap.service: Succeeded. Jul 18 11:35:03 office systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jul 18 11:35:03 office systemd[1]: volumio.service: Scheduled restart job, restart counter is at 73. Jul 18 11:35:03 office systemd[1]: Stopped Volumio Backend Module. Jul 18 11:35:03 office systemd[1]: Started Volumio Backend Module. Jul 18 11:35:03 office systemd[1]: Started dynamicswap service. Jul 18 11:35:03 office systemd[1]: dynamicswap.service: Succeeded. Jul 18 11:35:05 office volumio[29932]: info: ------------------------------------------- Jul 18 11:35:05 office volumio[29932]: info: ----- Volumio3 ---- Jul 18 11:35:05 office volumio[29932]: info: ------------------------------------------- Jul 18 11:35:05 office volumio[29932]: info: ----- System startup ---- Jul 18 11:35:05 office volumio[29932]: info: ------------------------------------------- Jul 18 11:35:06 office volumio[29932]: info: MYVOLUMIO Environment detected Jul 18 11:35:06 office volumio[29932]: info: Plugin folders cleanup Jul 18 11:35:06 office volumio[29932]: info: Scanning into folder /volumio/app/plugins/ Jul 18 11:35:06 office volumio[29932]: info: Scanning category audio_interface Jul 18 11:35:06 office volumio[29932]: info: Scanning category miscellanea Jul 18 11:35:06 office volumio[29932]: info: Scanning category music_service Jul 18 11:35:06 office volumio[29932]: info: Scanning category plugins.json Jul 18 11:35:06 office volumio[29932]: info: Scanning category system_controller Jul 18 11:35:06 office volumio[29932]: info: Scanning category user_interface Jul 18 11:35:06 office volumio[29932]: info: Scanning into folder /data/plugins/ Jul 18 11:35:06 office volumio[29932]: info: Scanning category music_service Jul 18 11:35:06 office volumio[29932]: info: Plugin folders cleanup completed Jul 18 11:35:06 office volumio[29932]: info: ------------------------------------------- Jul 18 11:35:06 office volumio[29932]: info: ----- Core plugins startup ---- Jul 18 11:35:06 office volumio[29932]: info: ------------------------------------------- Jul 18 11:35:06 office volumio[29932]: info: Loading plugins from folder /volumio/app/plugins/ Jul 18 11:35:06 office volumio[29932]: info: Adding plugin upnp to MyMusic Plugins Jul 18 11:35:06 office volumio[29932]: info: Adding plugin airplay_emulation to MyMusic Plugins Jul 18 11:35:06 office volumio[29932]: info: Adding plugin upnp_browser to MyMusic Plugins Jul 18 11:35:06 office volumio[29932]: info: Loading plugins from folder /data/plugins/ Jul 18 11:35:06 office volumio[29932]: info: Loading plugin "system"... Jul 18 11:35:06 office volumio[29932]: info: Loading plugin "appearance"... Jul 18 11:35:07 office volumio[29932]: info: Loading plugin "network"... Jul 18 11:35:07 office volumio[29932]: info: Refreshing Cached IP Addresses Jul 18 11:35:07 office wpa_supplicant[750]: wlan0: Failed to initiate sched scan Jul 18 11:35:07 office sudo[29956]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 18 11:35:07 office sudo[29956]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 11:35:07 office sudo[29956]: pam_unix(sudo:session): session closed for user root Jul 18 11:35:07 office volumio[29932]: info: Loading plugin "services"... Jul 18 11:35:07 office sudo[29959]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 18 11:35:07 office sudo[29959]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 11:35:07 office volumio[29932]: info: Loading plugin "alsa_controller"... Jul 18 11:35:07 office sudo[29961]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jul 18 11:35:07 office sudo[29961]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 11:35:07 office sudo[29959]: pam_unix(sudo:session): session closed for user root Jul 18 11:35:07 office volumio[29932]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 18 11:35:07 office volumio[29932]: info: Loading plugin "wizard"... Jul 18 11:35:07 office volumio[29932]: info: Loading plugin "volumio_command_line_client"... Jul 18 11:35:07 office volumio[29932]: info: Loading plugin "upnp"... Jul 18 11:35:07 office volumio[29932]: info: [1721302507850] Starting Upmpd Daemon Jul 18 11:35:07 office volumio[29932]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 18 11:35:07 office volumio[29932]: info: Loading plugin "my_music"... Jul 18 11:35:07 office volumio[29932]: info: Loading plugin "mpd"... Jul 18 11:35:08 office volumio[29932]: info: Creating MPD Configuration file Jul 18 11:35:08 office volumio[29932]: info: Loading plugin "upnp_browser"... Jul 18 11:35:08 office sudo[29969]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 18 11:35:08 office sudo[29969]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 11:35:08 office sudo[29969]: pam_unix(sudo:session): session closed for user root Jul 18 11:35:08 office sudo[29971]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 18 11:35:08 office sudo[29971]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 11:35:08 office systemd[1]: Stopping Music Player Daemon... Jul 18 11:35:08 office systemd[1]: mpd.service: Succeeded. Jul 18 11:35:08 office systemd[1]: Stopped Music Player Daemon. Jul 18 11:35:08 office systemd[1]: Starting Music Player Daemon... Jul 18 11:35:08 office sudo[29961]: pam_unix(sudo:session): session closed for user root Jul 18 11:35:08 office volumio-remote-updater[482]: [2024-07-18 11:35:08] [connect] Successful connection Jul 18 11:35:08 office volumio[29932]: info: Loading plugin "networkfs"... Jul 18 11:35:08 office volumio[29932]: info: Cannot mount NAS FLAC at system boot, trial number 1 ,retrying in 5 seconds Jul 18 11:35:08 office volumio[29932]: info: Cannot mount NAS MP3 at system boot, trial number 1 ,retrying in 5 seconds Jul 18 11:35:08 office volumio[29932]: info: Starting Udev Watcher for removable devices Jul 18 11:35:09 office volumio[29932]: info: Ignoring mount for partition: boot Jul 18 11:35:09 office volumio[29932]: info: Ignoring mount for partition: volumio Jul 18 11:35:09 office volumio[29932]: info: Ignoring mount for partition: volumio_data Jul 18 11:35:09 office volumio[29932]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 18 11:35:09 office volumio[29932]: info: Loading plugin "alarm-clock"... Jul 18 11:35:09 office volumio[29932]: info: Loading plugin "airplay_emulation"... Jul 18 11:35:09 office volumio[29932]: info: Starting Shairport Sync Jul 18 11:35:09 office volumio[29932]: info: Loading plugin "last_100"... Jul 18 11:35:09 office volumio[29932]: info: Loading plugin "webradio"... Jul 18 11:35:09 office volumio[29932]: info: Loading plugin "i2s_dacs"... Jul 18 11:35:09 office volumio[29932]: info: Loading plugin "volumiodiscovery"... Jul 18 11:35:09 office volumio[29932]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 18 11:35:09 office volumio[29932]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 18 11:35:09 office volumio[29932]: *** WARNING *** For more information see Jul 18 11:35:09 office node[29932]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 18 11:35:09 office volumio[29932]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 18 11:35:09 office volumio[29932]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 18 11:35:09 office volumio[29932]: *** WARNING *** For more information see Jul 18 11:35:09 office node[29932]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 18 11:35:09 office node[29932]: *** WARNING *** For more information see Jul 18 11:35:09 office node[29932]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 18 11:35:09 office node[29932]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 18 11:35:09 office node[29932]: *** WARNING *** For more information see Jul 18 11:35:09 office volumio[29932]: info: Applying required configuration parameters for plugin volumiodiscovery Jul 18 11:35:09 office volumio[29932]: info: Discovery: Started advertising with name: Office Jul 18 11:35:09 office volumio[29932]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 18 11:35:09 office volumio[29932]: info: Loading plugin "squeezelite_mc"... Jul 18 11:35:09 office mpd[29976]: Jul 18 11:35 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 18 11:35:10 office mpd[29976]: Jul 18 11:35 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Jul 18 11:35:10 office mpd[29976]: Jul 18 11:35 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Jul 18 11:35:10 office systemd[1]: Started Music Player Daemon. Jul 18 11:35:10 office sudo[29971]: pam_unix(sudo:session): session closed for user root Jul 18 11:35:10 office volumio[29932]: info: Loading plugin "outputs"... Jul 18 11:35:10 office volumio[29932]: info: Loading plugin "albumart"... Jul 18 11:35:10 office volumio[29932]: info: Plugin example_plugin is not enabled Jul 18 11:35:10 office volumio[29932]: info: Loading plugin "inputs"... Jul 18 11:35:10 office volumio[29932]: info: Loading plugin "updater_comm"... Jul 18 11:35:10 office volumio[29932]: info: Plugin mpdemulation is not enabled Jul 18 11:35:10 office volumio[29932]: info: Loading plugin "rest_api"... Jul 18 11:35:10 office volumio[29932]: info: Loading plugin "websocket"... Jul 18 11:35:10 office volumio[29932]: info: Loading plugin "spop"... Jul 18 11:35:10 office volumio[29932]: Forking 3 albumart workers Jul 18 11:35:11 office volumio[29932]: info: Loading plugin "volspotconnect2"... Jul 18 11:35:12 office volumio[29932]: Starting albumart workers Jul 18 11:35:12 office volumio[29932]: Starting albumart workers Jul 18 11:35:12 office volumio[29932]: Starting albumart workers Jul 18 11:35:12 office volumio[29932]: info: Loading i18n strings for locale en Jul 18 11:35:12 office volumio[29932]: Updating browse sources language Jul 18 11:35:12 office volumio[29932]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 18 11:35:12 office volumio[29932]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 18 11:35:12 office volumio[29932]: info: CoreCommandRouter::initPlayerControls Jul 18 11:35:12 office volumio[29932]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:12 office volumio[29932]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:12 office volumio[29932]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:12 office volumio[29932]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:12 office volumio[29932]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:12 office volumio[29932]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:12 office volumio[29932]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:12 office volumio[29932]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:12 office volumio[29932]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 18 11:35:12 office volumio[29932]: Express server listening on port 3000 Jul 18 11:35:12 office volumio[29932]: [Metrics] WebUI: 7s 758.19ms Jul 18 11:35:12 office volumio[29932]: info: CoreStateMachine::resetVolumioState Jul 18 11:35:12 office volumio[29932]: info: CoreStateMachine::getcurrentVolume Jul 18 11:35:12 office volumio[29932]: info: CoreCommandRouter::volumioRetrievevolume Jul 18 11:35:13 office volumio[29932]: info: MPD Permissions set Jul 18 11:35:13 office volumio-remote-updater[482]: [2024-07-18 11:35:13] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1721302508 101 Jul 18 11:35:13 office volumio[29932]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1 Jul 18 11:35:13 office volumio[29932]: info: MPD running with PID29976 Jul 18 11:35:13 office volumio[29932]: ,establishing connection Jul 18 11:35:13 office volumio[29932]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:13 office volumio[29932]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:13 office volumio[29932]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:13 office volumio[29932]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:13 office volumio[29932]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:13 office volumio[29932]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:13 office volumio[29932]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:13 office volumio[29932]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:13 office wpa_supplicant[750]: wlan0: Failed to initiate sched scan Jul 18 11:35:13 office volumio[29932]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 18 11:35:13 office volumio[29932]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:13 office volumio[29932]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 18 11:35:13 office volumio[29932]: info: Reloading queue from file Jul 18 11:35:13 office volumio[29932]: error: updateQueue error: null Jul 18 11:35:13 office volumio[29932]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jul 18 11:35:13 office volumio[29932]: info: CoreStateMachine::setRepeat null single undefined Jul 18 11:35:13 office volumio[29932]: info: CoreStateMachine::pushState Jul 18 11:35:13 office volumio[29932]: info: CorePlayQueue::getTrack 0 Jul 18 11:35:13 office volumio[29932]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 11:35:13 office volumio[29932]: info: CoreCommandRouter::volumioPushState Jul 18 11:35:13 office volumio[29932]: info: CoreStateMachine::setRandom true Jul 18 11:35:13 office volumio[29932]: info: CoreStateMachine::pushState Jul 18 11:35:13 office volumio[29932]: info: CorePlayQueue::getTrack 0 Jul 18 11:35:13 office volumio[29932]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 11:35:13 office volumio[29932]: info: CoreCommandRouter::volumioPushState Jul 18 11:35:13 office volumio[29932]: info: Setting Device type: Raspberry PI Jul 18 11:35:13 office volumio[29932]: info: Completed loading Core Plugins Jul 18 11:35:13 office volumio[29932]: info: Preparing to generate the ALSA configuration file Jul 18 11:35:13 office volumio[29932]: info: VolumeController:: Volume=56 Mute =false Jul 18 11:35:13 office volumio[29932]: info: CoreStateMachine::pushState Jul 18 11:35:13 office volumio[29932]: info: CorePlayQueue::getTrack 0 Jul 18 11:35:13 office volumio[29932]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 11:35:13 office volumio[29932]: info: CoreCommandRouter::volumioPushState Jul 18 11:35:13 office volumio[29932]: info: CoreStateMachine::updateTrackBlock Jul 18 11:35:13 office volumio[29932]: info: CorePlayQueue::getTrackBlock Jul 18 11:35:13 office volumio[29932]: info: CoreCommandRouter::volumioRetrievevolume Jul 18 11:35:13 office volumio[29932]: info: Asound.conf file unchanged, so no further update is needed Jul 18 11:35:13 office volumio[29932]: info: Output device has changed, restarting MPD Jul 18 11:35:13 office sudo[30053]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 18 11:35:13 office sudo[30053]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 11:35:13 office volumio[29932]: info: Output device has changed, restarting Shairport Sync Jul 18 11:35:13 office volumio[29932]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:13 office volumio[29932]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 18 11:35:13 office sudo[30053]: pam_unix(sudo:session): session closed for user root Jul 18 11:35:13 office sudo[30055]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 18 11:35:13 office sudo[30055]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 11:35:13 office systemd[1]: Stopping Music Player Daemon... Jul 18 11:35:13 office volumio[29932]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 18 11:35:13 office volumio[29932]: info: ___________ START PLUGINS ___________ Jul 18 11:35:13 office volumio[29932]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 18 11:35:13 office volumio[29932]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 18 11:35:13 office volumio[29932]: info: [1721302513513] CoreMusicLibrary::Adding element Media Servers Jul 18 11:35:13 office volumio[29932]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 18 11:35:13 office volumio[29932]: Cannot find translation for sourceMedia Servers Jul 18 11:35:13 office volumio[29932]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:13 office volumio[29932]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 18 11:35:13 office volumio[29932]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 18 11:35:13 office volumio[29932]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:13 office volumio[29932]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 18 11:35:13 office volumio[29932]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 18 11:35:13 office volumio[29932]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 18 11:35:13 office volumio[29932]: info: [1721302513643] CoreMusicLibrary::Adding element Last_100 Jul 18 11:35:13 office volumio[29932]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 18 11:35:13 office volumio[29932]: Cannot find translation for sourceMedia Servers Jul 18 11:35:13 office volumio[29932]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 18 11:35:13 office volumio[29932]: info: [1721302513647] CoreMusicLibrary::Adding element Webradio Jul 18 11:35:13 office volumio[29932]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 18 11:35:13 office volumio[29932]: Cannot find translation for sourceMedia Servers Jul 18 11:35:13 office volumio[29932]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 18 11:35:13 office volumio[29932]: info: [squeezelite_mc] Starting proxy server... Jul 18 11:35:13 office volumio[29932]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 18 11:35:13 office volumio[29932]: [SpotifyConnect] Creating VLS config file Jul 18 11:35:13 office volumio[29932]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:13 office volumio[29932]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:13 office volumio[29932]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:13 office volumio[29932]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:13 office volumio[29932]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:13 office volumio[29932]: [SpotifyConnect] Starting metadata listener Jul 18 11:35:13 office volumio[29932]: [SpotifyConnect] Creating VLS config file Jul 18 11:35:13 office volumio[29932]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:13 office volumio[29932]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:13 office volumio[29932]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:13 office volumio[29932]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:13 office volumio[29932]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:13 office volumio[29932]: [SpotifyConnect] Starting metadata listener Jul 18 11:35:13 office systemd[1]: mpd.service: Succeeded. Jul 18 11:35:13 office systemd[1]: Stopped Music Player Daemon. Jul 18 11:35:13 office systemd[1]: Starting Music Player Daemon... Jul 18 11:35:13 office volumio[29932]: info: Volumio Calling Home Jul 18 11:35:13 office volumio[29932]: info: [squeezelite_mc] Proxy server started on port 45245 Jul 18 11:35:13 office volumio[29932]: [SpotifyConnect] Error: bind EADDRINUSE 0.0.0.0:5030 Jul 18 11:35:13 office volumio[29932]: at dgram.js:337:20 Jul 18 11:35:13 office volumio[29932]: at processTicksAndRejections (internal/process/task_queues.js:81:21) Jul 18 11:35:14 office volumio[29932]: info: CoreCommandRouter::volumioRetrievevolume Jul 18 11:35:14 office volumio[29932]: Unhandled rejection Error: No sockets available, cannot start. Jul 18 11:35:14 office volumio[29932]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Jul 18 11:35:14 office volumio[29932]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Jul 18 11:35:14 office volumio[29932]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Jul 18 11:35:14 office volumio[29932]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Jul 18 11:35:14 office volumio[29932]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Jul 18 11:35:14 office volumio[29932]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Jul 18 11:35:14 office volumio[29932]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Jul 18 11:35:14 office volumio[29932]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Jul 18 11:35:14 office volumio[29932]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Jul 18 11:35:14 office volumio[29932]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Jul 18 11:35:14 office volumio[29932]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Jul 18 11:35:14 office volumio[29932]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Jul 18 11:35:14 office volumio[29932]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Jul 18 11:35:14 office volumio[29932]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Jul 18 11:35:14 office volumio[29932]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Jul 18 11:35:14 office volumio[29932]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jul 18 11:35:14 office volumio[29932]: info: Cannot mount NAS FLAC at system boot, trial number 2 ,retrying in 5 seconds Jul 18 11:35:14 office volumio[29932]: info: Cannot mount NAS MP3 at system boot, trial number 2 ,retrying in 5 seconds Jul 18 11:35:14 office volumio[29932]: error: MPD error: Error: write EPIPE Jul 18 11:35:14 office volumio[29932]: error: write EPIPE {"errno":-32,"code":"EPIPE","syscall":"write"} Jul 18 11:35:14 office volumio[29932]: info: MPD Permissions set Jul 18 11:35:14 office volumio[29932]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:14 office volumio[29932]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:14 office volumio[29932]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:14 office volumio[29932]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:14 office volumio[29932]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:14 office volumio[29932]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:14 office volumio[29932]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:14 office volumio[29932]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:14 office volumio[29932]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 18 11:35:14 office volumio[29932]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:14 office volumio[29932]: info: VolumeController:: Volume=56 Mute =false Jul 18 11:35:14 office volumio[29932]: info: CoreStateMachine::pushState Jul 18 11:35:14 office volumio[29932]: info: CorePlayQueue::getTrack 0 Jul 18 11:35:14 office volumio[29932]: info: CoreCommandRouter::volumioPushState Jul 18 11:35:15 office sudo[30068]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hosts Jul 18 11:35:15 office sudo[30068]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 11:35:15 office sudo[30072]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect2.service Jul 18 11:35:15 office sudo[30072]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 11:35:15 office sudo[30070]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect.service Jul 18 11:35:15 office sudo[30070]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 11:35:15 office mpd[30079]: Jul 18 11:35 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 18 11:35:15 office volumio[29932]: info: [squeezelite_mc] Server discovery started Jul 18 11:35:15 office volumio[29932]: info: [squeezelite_mc] Player finder started Jul 18 11:35:15 office sudo[30068]: pam_unix(sudo:session): session closed for user root Jul 18 11:35:15 office volumio[29932]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:15 office volumio[29932]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:15 office volumio[29932]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:15 office volumio[29932]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 18 11:35:15 office volumio[29932]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true Jul 18 11:35:15 office sudo[30072]: pam_unix(sudo:session): session closed for user root Jul 18 11:35:15 office volumio[29932]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 18 11:35:15 office sudo[30070]: pam_unix(sudo:session): session closed for user root Jul 18 11:35:15 office volumio[29932]: Error: send ENETUNREACH 255.255.255.255:3483 Jul 18 11:35:15 office volumio[29932]: at doSend (dgram.js:692:16) Jul 18 11:35:15 office volumio[29932]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Jul 18 11:35:15 office volumio[29932]: at afterDns (dgram.js:638:5) Jul 18 11:35:15 office volumio[29932]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Jul 18 11:35:15 office volumio[29932]: errno: -101, Jul 18 11:35:15 office volumio[29932]: code: 'ENETUNREACH', Jul 18 11:35:15 office volumio[29932]: syscall: 'send', Jul 18 11:35:15 office volumio[29932]: address: '255.255.255.255', Jul 18 11:35:15 office volumio[29932]: port: 3483 Jul 18 11:35:15 office volumio[29932]: } Jul 18 11:35:15 office volumio[29932]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 18 11:35:15 office mpd[30079]: Jul 18 11:35 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Jul 18 11:35:15 office mpd[30079]: Jul 18 11:35 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Jul 18 11:35:15 office systemd[1]: Started Music Player Daemon. Jul 18 11:35:15 office sudo[30055]: pam_unix(sudo:session): session closed for user root Jul 18 11:35:15 office sudo[30102]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-18 11:34 Jul 18 11:35:15 office sudo[30102]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 11:35:16 office sudo[30102]: pam_unix(sudo:session): session closed for user root Jul 18 11:35:16 office volumio-remote-updater[482]: [2024-07-18 11:35:16] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jul 18 11:35:16 office volumio-remote-updater[482]: [2024-07-18 11:35:16] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jul 18 11:35:16 office systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jul 18 11:35:16 office systemd[1]: volumio.service: Failed with result 'exit-code'. Jul 18 11:35:16 office systemd[1]: Started dynamicswap service. Jul 18 11:35:16 office systemd[1]: dynamicswap.service: Succeeded. Jul 18 11:35:16 office systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jul 18 11:35:16 office systemd[1]: volumio.service: Scheduled restart job, restart counter is at 74. Jul 18 11:35:16 office systemd[1]: Stopped Volumio Backend Module. Jul 18 11:35:16 office systemd[1]: Started Volumio Backend Module. Jul 18 11:35:16 office systemd[1]: Started dynamicswap service. Jul 18 11:35:16 office systemd[1]: dynamicswap.service: Succeeded. Jul 18 11:35:18 office volumio[30115]: info: ------------------------------------------- Jul 18 11:35:18 office volumio[30115]: info: ----- Volumio3 ---- Jul 18 11:35:18 office volumio[30115]: info: ------------------------------------------- Jul 18 11:35:18 office volumio[30115]: info: ----- System startup ---- Jul 18 11:35:18 office volumio[30115]: info: ------------------------------------------- Jul 18 11:35:19 office volumio[30115]: info: MYVOLUMIO Environment detected Jul 18 11:35:19 office volumio[30115]: info: Plugin folders cleanup Jul 18 11:35:19 office volumio[30115]: info: Scanning into folder /volumio/app/plugins/ Jul 18 11:35:19 office volumio[30115]: info: Scanning category audio_interface Jul 18 11:35:19 office volumio[30115]: info: Scanning category miscellanea Jul 18 11:35:19 office volumio[30115]: info: Scanning category music_service Jul 18 11:35:19 office volumio[30115]: info: Scanning category plugins.json Jul 18 11:35:19 office volumio[30115]: info: Scanning category system_controller Jul 18 11:35:19 office volumio[30115]: info: Scanning category user_interface Jul 18 11:35:19 office volumio[30115]: info: Scanning into folder /data/plugins/ Jul 18 11:35:19 office volumio[30115]: info: Scanning category music_service Jul 18 11:35:19 office volumio[30115]: info: Plugin folders cleanup completed Jul 18 11:35:19 office volumio[30115]: info: ------------------------------------------- Jul 18 11:35:19 office volumio[30115]: info: ----- Core plugins startup ---- Jul 18 11:35:19 office volumio[30115]: info: ------------------------------------------- Jul 18 11:35:19 office volumio[30115]: info: Loading plugins from folder /volumio/app/plugins/ Jul 18 11:35:19 office volumio[30115]: info: Adding plugin upnp to MyMusic Plugins Jul 18 11:35:19 office volumio[30115]: info: Adding plugin airplay_emulation to MyMusic Plugins Jul 18 11:35:19 office volumio[30115]: info: Adding plugin upnp_browser to MyMusic Plugins Jul 18 11:35:19 office volumio[30115]: info: Loading plugins from folder /data/plugins/ Jul 18 11:35:19 office volumio[30115]: info: Loading plugin "system"... Jul 18 11:35:19 office volumio[30115]: info: Loading plugin "appearance"... Jul 18 11:35:20 office volumio[30115]: info: Loading plugin "network"... Jul 18 11:35:20 office volumio[30115]: info: Refreshing Cached IP Addresses Jul 18 11:35:20 office volumio[30115]: info: Loading plugin "services"... Jul 18 11:35:20 office volumio[30115]: info: Loading plugin "alsa_controller"... Jul 18 11:35:20 office wpa_supplicant[750]: wlan0: Failed to initiate sched scan Jul 18 11:35:20 office sudo[30141]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 18 11:35:20 office sudo[30141]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 11:35:20 office sudo[30139]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 18 11:35:20 office sudo[30141]: pam_unix(sudo:session): session closed for user root Jul 18 11:35:20 office sudo[30139]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 11:35:20 office sudo[30143]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jul 18 11:35:20 office sudo[30143]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 11:35:20 office sudo[30139]: pam_unix(sudo:session): session closed for user root Jul 18 11:35:20 office volumio[30115]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 18 11:35:20 office volumio[30115]: info: Loading plugin "wizard"... Jul 18 11:35:20 office volumio[30115]: info: Loading plugin "volumio_command_line_client"... Jul 18 11:35:20 office volumio[30115]: info: Loading plugin "upnp"... Jul 18 11:35:20 office volumio[30115]: info: [1721302520328] Starting Upmpd Daemon Jul 18 11:35:20 office volumio[30115]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 18 11:35:20 office volumio[30115]: info: Loading plugin "my_music"... Jul 18 11:35:20 office volumio[30115]: info: Loading plugin "mpd"... Jul 18 11:35:20 office volumio[30115]: info: Creating MPD Configuration file Jul 18 11:35:20 office volumio[30115]: info: Loading plugin "upnp_browser"... Jul 18 11:35:20 office sudo[30152]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 18 11:35:20 office sudo[30152]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 11:35:20 office sudo[30152]: pam_unix(sudo:session): session closed for user root Jul 18 11:35:20 office sudo[30154]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 18 11:35:20 office sudo[30154]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 11:35:20 office systemd[1]: Stopping Music Player Daemon... Jul 18 11:35:20 office systemd[1]: mpd.service: Succeeded. Jul 18 11:35:20 office systemd[1]: Stopped Music Player Daemon. Jul 18 11:35:20 office systemd[1]: Starting Music Player Daemon... Jul 18 11:35:20 office sudo[30143]: pam_unix(sudo:session): session closed for user root Jul 18 11:35:21 office volumio[30115]: info: Loading plugin "networkfs"... Jul 18 11:35:21 office volumio-remote-updater[482]: [2024-07-18 11:35:21] [connect] Successful connection Jul 18 11:35:21 office volumio[30115]: info: Cannot mount NAS FLAC at system boot, trial number 1 ,retrying in 5 seconds Jul 18 11:35:21 office volumio[30115]: info: Cannot mount NAS MP3 at system boot, trial number 1 ,retrying in 5 seconds Jul 18 11:35:21 office volumio[30115]: info: Starting Udev Watcher for removable devices Jul 18 11:35:21 office volumio[30115]: info: Ignoring mount for partition: boot Jul 18 11:35:21 office volumio[30115]: info: Ignoring mount for partition: volumio Jul 18 11:35:21 office volumio[30115]: info: Ignoring mount for partition: volumio_data Jul 18 11:35:21 office volumio[30115]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 18 11:35:21 office volumio[30115]: info: Loading plugin "alarm-clock"... Jul 18 11:35:21 office volumio[30115]: info: Loading plugin "airplay_emulation"... Jul 18 11:35:21 office volumio[30115]: info: Starting Shairport Sync Jul 18 11:35:21 office volumio[30115]: info: Loading plugin "last_100"... Jul 18 11:35:21 office volumio[30115]: info: Loading plugin "webradio"... Jul 18 11:35:22 office volumio[30115]: info: Loading plugin "i2s_dacs"... Jul 18 11:35:22 office volumio[30115]: info: Loading plugin "volumiodiscovery"... Jul 18 11:35:22 office volumio[30115]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 18 11:35:22 office volumio[30115]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 18 11:35:22 office volumio[30115]: *** WARNING *** For more information see Jul 18 11:35:22 office node[30115]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 18 11:35:22 office volumio[30115]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 18 11:35:22 office volumio[30115]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 18 11:35:22 office volumio[30115]: *** WARNING *** For more information see Jul 18 11:35:22 office node[30115]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 18 11:35:22 office node[30115]: *** WARNING *** For more information see Jul 18 11:35:22 office node[30115]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 18 11:35:22 office node[30115]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 18 11:35:22 office node[30115]: *** WARNING *** For more information see Jul 18 11:35:22 office volumio[30115]: info: Applying required configuration parameters for plugin volumiodiscovery Jul 18 11:35:22 office volumio[30115]: info: Discovery: Started advertising with name: Office Jul 18 11:35:22 office volumio[30115]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 18 11:35:22 office volumio[30115]: info: Loading plugin "squeezelite_mc"... Jul 18 11:35:22 office mpd[30159]: Jul 18 11:35 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 18 11:35:22 office mpd[30159]: Jul 18 11:35 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Jul 18 11:35:22 office mpd[30159]: Jul 18 11:35 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Jul 18 11:35:22 office systemd[1]: Started Music Player Daemon. Jul 18 11:35:22 office sudo[30154]: pam_unix(sudo:session): session closed for user root Jul 18 11:35:22 office volumio[30115]: info: Loading plugin "outputs"... Jul 18 11:35:22 office volumio[30115]: info: Loading plugin "albumart"... Jul 18 11:35:23 office volumio[30115]: info: Plugin example_plugin is not enabled Jul 18 11:35:23 office volumio[30115]: info: Loading plugin "inputs"... Jul 18 11:35:23 office volumio[30115]: info: Loading plugin "updater_comm"... Jul 18 11:35:23 office volumio[30115]: info: Plugin mpdemulation is not enabled Jul 18 11:35:23 office volumio[30115]: info: Loading plugin "rest_api"... Jul 18 11:35:23 office volumio[30115]: info: Loading plugin "websocket"... Jul 18 11:35:23 office volumio[30115]: info: Loading plugin "spop"... Jul 18 11:35:23 office volumio[30115]: Forking 3 albumart workers Jul 18 11:35:24 office volumio[30115]: info: Loading plugin "volspotconnect2"... Jul 18 11:35:24 office volumio[30115]: Starting albumart workers Jul 18 11:35:24 office volumio[30115]: Starting albumart workers Jul 18 11:35:24 office volumio[30115]: Starting albumart workers Jul 18 11:35:24 office volumio[30115]: info: Loading i18n strings for locale en Jul 18 11:35:24 office volumio[30115]: Updating browse sources language Jul 18 11:35:24 office volumio[30115]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 18 11:35:25 office volumio[30115]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 18 11:35:25 office volumio[30115]: info: CoreCommandRouter::initPlayerControls Jul 18 11:35:25 office volumio[30115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:25 office volumio[30115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:25 office volumio[30115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:25 office volumio[30115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:25 office volumio[30115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:25 office volumio[30115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:25 office volumio[30115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:25 office volumio[30115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:25 office volumio[30115]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 18 11:35:25 office volumio[30115]: Express server listening on port 3000 Jul 18 11:35:25 office volumio[30115]: [Metrics] WebUI: 7s 734.87ms Jul 18 11:35:25 office volumio[30115]: info: CoreStateMachine::resetVolumioState Jul 18 11:35:25 office volumio[30115]: info: CoreStateMachine::getcurrentVolume Jul 18 11:35:25 office volumio[30115]: info: CoreCommandRouter::volumioRetrievevolume Jul 18 11:35:25 office volumio[30115]: info: MPD Permissions set Jul 18 11:35:25 office volumio-remote-updater[482]: [2024-07-18 11:35:25] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1721302521 101 Jul 18 11:35:25 office volumio[30115]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1 Jul 18 11:35:25 office volumio[30115]: info: MPD running with PID30159 Jul 18 11:35:25 office volumio[30115]: ,establishing connection Jul 18 11:35:25 office volumio[30115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:25 office volumio[30115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:25 office volumio[30115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:25 office volumio[30115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:25 office volumio[30115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:25 office volumio[30115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:25 office volumio[30115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:25 office volumio[30115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:25 office volumio[30115]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 18 11:35:25 office volumio[30115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:25 office volumio[30115]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 18 11:35:25 office volumio[30115]: info: Reloading queue from file Jul 18 11:35:25 office volumio[30115]: error: updateQueue error: null Jul 18 11:35:25 office volumio[30115]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jul 18 11:35:25 office volumio[30115]: info: CoreStateMachine::setRepeat null single undefined Jul 18 11:35:25 office volumio[30115]: info: CoreStateMachine::pushState Jul 18 11:35:25 office volumio[30115]: info: CorePlayQueue::getTrack 0 Jul 18 11:35:25 office volumio[30115]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 11:35:25 office volumio[30115]: info: CoreCommandRouter::volumioPushState Jul 18 11:35:25 office volumio[30115]: info: CoreStateMachine::setRandom true Jul 18 11:35:25 office volumio[30115]: info: CoreStateMachine::pushState Jul 18 11:35:25 office volumio[30115]: info: CorePlayQueue::getTrack 0 Jul 18 11:35:25 office volumio[30115]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 11:35:25 office volumio[30115]: info: CoreCommandRouter::volumioPushState Jul 18 11:35:25 office volumio[30115]: info: Setting Device type: Raspberry PI Jul 18 11:35:25 office volumio[30115]: info: Completed loading Core Plugins Jul 18 11:35:25 office volumio[30115]: info: Preparing to generate the ALSA configuration file Jul 18 11:35:25 office volumio[30115]: info: VolumeController:: Volume=56 Mute =false Jul 18 11:35:25 office volumio[30115]: info: CoreStateMachine::pushState Jul 18 11:35:25 office volumio[30115]: info: CorePlayQueue::getTrack 0 Jul 18 11:35:25 office volumio[30115]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 11:35:25 office volumio[30115]: info: CoreCommandRouter::volumioPushState Jul 18 11:35:25 office wpa_supplicant[750]: wlan0: Failed to initiate sched scan Jul 18 11:35:25 office volumio[30115]: info: CoreStateMachine::updateTrackBlock Jul 18 11:35:25 office volumio[30115]: info: CorePlayQueue::getTrackBlock Jul 18 11:35:25 office volumio[30115]: info: CoreCommandRouter::volumioRetrievevolume Jul 18 11:35:25 office volumio[30115]: info: Asound.conf file unchanged, so no further update is needed Jul 18 11:35:25 office volumio[30115]: info: Output device has changed, restarting MPD Jul 18 11:35:25 office sudo[30234]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 18 11:35:25 office sudo[30234]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 11:35:25 office volumio[30115]: info: Output device has changed, restarting Shairport Sync Jul 18 11:35:25 office volumio[30115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:25 office volumio[30115]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 18 11:35:25 office sudo[30234]: pam_unix(sudo:session): session closed for user root Jul 18 11:35:25 office sudo[30236]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 18 11:35:25 office sudo[30236]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 11:35:25 office systemd[1]: Stopping Music Player Daemon... Jul 18 11:35:25 office volumio[30115]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 18 11:35:25 office volumio[30115]: info: ___________ START PLUGINS ___________ Jul 18 11:35:25 office volumio[30115]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 18 11:35:25 office volumio[30115]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 18 11:35:25 office volumio[30115]: info: [1721302525978] CoreMusicLibrary::Adding element Media Servers Jul 18 11:35:25 office volumio[30115]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 18 11:35:25 office volumio[30115]: Cannot find translation for sourceMedia Servers Jul 18 11:35:25 office volumio[30115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:25 office volumio[30115]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 18 11:35:26 office volumio[30115]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 18 11:35:26 office volumio[30115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:26 office volumio[30115]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 18 11:35:26 office volumio[30115]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 18 11:35:26 office volumio[30115]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 18 11:35:26 office volumio[30115]: info: [1721302526108] CoreMusicLibrary::Adding element Last_100 Jul 18 11:35:26 office volumio[30115]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 18 11:35:26 office volumio[30115]: Cannot find translation for sourceMedia Servers Jul 18 11:35:26 office volumio[30115]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 18 11:35:26 office volumio[30115]: info: [1721302526113] CoreMusicLibrary::Adding element Webradio Jul 18 11:35:26 office volumio[30115]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 18 11:35:26 office volumio[30115]: Cannot find translation for sourceMedia Servers Jul 18 11:35:26 office volumio[30115]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 18 11:35:26 office volumio[30115]: info: [squeezelite_mc] Starting proxy server... Jul 18 11:35:26 office volumio[30115]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 18 11:35:26 office volumio[30115]: [SpotifyConnect] Creating VLS config file Jul 18 11:35:26 office volumio[30115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:26 office volumio[30115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:26 office volumio[30115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:26 office volumio[30115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:26 office volumio[30115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:26 office volumio[30115]: [SpotifyConnect] Starting metadata listener Jul 18 11:35:26 office volumio[30115]: [SpotifyConnect] Creating VLS config file Jul 18 11:35:26 office volumio[30115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:26 office volumio[30115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:26 office volumio[30115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:26 office volumio[30115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:26 office volumio[30115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:26 office volumio[30115]: [SpotifyConnect] Starting metadata listener Jul 18 11:35:26 office systemd[1]: mpd.service: Succeeded. Jul 18 11:35:26 office systemd[1]: Stopped Music Player Daemon. Jul 18 11:35:26 office systemd[1]: Starting Music Player Daemon... Jul 18 11:35:26 office volumio[30115]: info: Volumio Calling Home Jul 18 11:35:26 office volumio[30115]: info: [squeezelite_mc] Proxy server started on port 46383 Jul 18 11:35:26 office volumio[30115]: [SpotifyConnect] Error: bind EADDRINUSE 0.0.0.0:5030 Jul 18 11:35:26 office volumio[30115]: at dgram.js:337:20 Jul 18 11:35:26 office volumio[30115]: at processTicksAndRejections (internal/process/task_queues.js:81:21) Jul 18 11:35:26 office volumio[30115]: info: CoreCommandRouter::volumioRetrievevolume Jul 18 11:35:26 office volumio[30115]: Unhandled rejection Error: No sockets available, cannot start. Jul 18 11:35:26 office volumio[30115]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Jul 18 11:35:26 office volumio[30115]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Jul 18 11:35:26 office volumio[30115]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Jul 18 11:35:26 office volumio[30115]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Jul 18 11:35:26 office volumio[30115]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Jul 18 11:35:26 office volumio[30115]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Jul 18 11:35:26 office volumio[30115]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Jul 18 11:35:26 office volumio[30115]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Jul 18 11:35:26 office volumio[30115]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Jul 18 11:35:26 office volumio[30115]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Jul 18 11:35:26 office volumio[30115]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Jul 18 11:35:26 office volumio[30115]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Jul 18 11:35:26 office volumio[30115]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Jul 18 11:35:26 office volumio[30115]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Jul 18 11:35:26 office volumio[30115]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Jul 18 11:35:26 office volumio[30115]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jul 18 11:35:26 office volumio[30115]: info: Cannot mount NAS FLAC at system boot, trial number 2 ,retrying in 5 seconds Jul 18 11:35:26 office volumio[30115]: info: Cannot mount NAS MP3 at system boot, trial number 2 ,retrying in 5 seconds Jul 18 11:35:26 office volumio[30115]: error: MPD error: Error: write EPIPE Jul 18 11:35:26 office volumio[30115]: error: write EPIPE {"errno":-32,"code":"EPIPE","syscall":"write"} Jul 18 11:35:26 office volumio[30115]: info: MPD Permissions set Jul 18 11:35:26 office ntpd[657]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Jul 18 11:35:26 office volumio[30115]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 Jul 18 11:35:26 office volumio[30115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:26 office volumio[30115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:26 office volumio[30115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:26 office volumio[30115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:26 office volumio[30115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:26 office volumio[30115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:26 office volumio[30115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:26 office volumio[30115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:26 office volumio[30115]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 18 11:35:26 office volumio[30115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:26 office volumio[30115]: info: VolumeController:: Volume=56 Mute =false Jul 18 11:35:26 office volumio[30115]: info: CoreStateMachine::pushState Jul 18 11:35:26 office volumio[30115]: info: CorePlayQueue::getTrack 0 Jul 18 11:35:26 office volumio[30115]: info: CoreCommandRouter::volumioPushState Jul 18 11:35:26 office volumio[30115]: info: VolumeController:: Volume=56 Mute =false Jul 18 11:35:26 office volumio[30115]: info: CoreStateMachine::pushState Jul 18 11:35:26 office volumio[30115]: info: CorePlayQueue::getTrack 0 Jul 18 11:35:26 office volumio[30115]: info: CoreCommandRouter::volumioPushState Jul 18 11:35:27 office mpd[30260]: exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 18 11:35:27 office mpd[30260]: output: No 'audio_output' defined in config file Jul 18 11:35:27 office mpd[30260]: output: Attempt to detect audio output device Jul 18 11:35:27 office mpd[30260]: output: Attempting to detect a alsa audio device Jul 18 11:35:27 office mpd[30260]: output: Successfully detected a alsa audio device Jul 18 11:35:27 office mpd[30260]: exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Jul 18 11:35:27 office mpd[30260]: exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Jul 18 11:35:27 office mpd[30260]: zeroconf: No global port, disabling zeroconf Jul 18 11:35:27 office systemd[1]: Started Music Player Daemon. Jul 18 11:35:27 office volumio[30115]: info: [squeezelite_mc] Server discovery started Jul 18 11:35:27 office volumio[30115]: info: [squeezelite_mc] Player finder started Jul 18 11:35:27 office volumio[30115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:27 office sudo[30236]: pam_unix(sudo:session): session closed for user root Jul 18 11:35:27 office volumio[30115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:27 office volumio[30115]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 18 11:35:27 office sudo[30255]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect2.service Jul 18 11:35:27 office sudo[30255]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 11:35:27 office volumio[30115]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true Jul 18 11:35:27 office sudo[30251]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect.service Jul 18 11:35:27 office sudo[30251]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 11:35:27 office sudo[30249]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hosts Jul 18 11:35:27 office sudo[30249]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 11:35:27 office sudo[30249]: pam_unix(sudo:session): session closed for user root Jul 18 11:35:27 office volumio[30115]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 18 11:35:27 office volumio[30115]: Error: send ENETUNREACH 255.255.255.255:3483 Jul 18 11:35:27 office volumio[30115]: at doSend (dgram.js:692:16) Jul 18 11:35:27 office volumio[30115]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Jul 18 11:35:27 office volumio[30115]: at afterDns (dgram.js:638:5) Jul 18 11:35:27 office volumio[30115]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Jul 18 11:35:27 office volumio[30115]: errno: -101, Jul 18 11:35:27 office volumio[30115]: code: 'ENETUNREACH', Jul 18 11:35:27 office volumio[30115]: syscall: 'send', Jul 18 11:35:27 office volumio[30115]: address: '255.255.255.255', Jul 18 11:35:27 office volumio[30115]: port: 3483 Jul 18 11:35:27 office volumio[30115]: } Jul 18 11:35:27 office volumio[30115]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 18 11:35:27 office sudo[30251]: pam_unix(sudo:session): session closed for user root Jul 18 11:35:27 office sudo[30255]: pam_unix(sudo:session): session closed for user root Jul 18 11:35:28 office sudo[30283]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-18 11:34 Jul 18 11:35:28 office sudo[30283]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 11:35:28 office sudo[30283]: pam_unix(sudo:session): session closed for user root Jul 18 11:35:28 office ntpd[657]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Jul 18 11:35:28 office volumio-remote-updater[482]: [2024-07-18 11:35:28] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jul 18 11:35:28 office volumio-remote-updater[482]: [2024-07-18 11:35:28] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jul 18 11:35:28 office systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jul 18 11:35:28 office systemd[1]: volumio.service: Failed with result 'exit-code'. Jul 18 11:35:28 office systemd[1]: Started dynamicswap service. Jul 18 11:35:28 office systemd[1]: dynamicswap.service: Succeeded. Jul 18 11:35:28 office systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jul 18 11:35:28 office systemd[1]: volumio.service: Scheduled restart job, restart counter is at 75. Jul 18 11:35:28 office systemd[1]: Stopped Volumio Backend Module. Jul 18 11:35:28 office systemd[1]: Started Volumio Backend Module. Jul 18 11:35:28 office systemd[1]: Started dynamicswap service. Jul 18 11:35:28 office systemd[1]: dynamicswap.service: Succeeded. Jul 18 11:35:29 office kernel: nfs: server 192.168.1.10 not responding, timed out Jul 18 11:35:30 office volumio[30296]: info: ------------------------------------------- Jul 18 11:35:30 office volumio[30296]: info: ----- Volumio3 ---- Jul 18 11:35:30 office volumio[30296]: info: ------------------------------------------- Jul 18 11:35:30 office volumio[30296]: info: ----- System startup ---- Jul 18 11:35:30 office volumio[30296]: info: ------------------------------------------- Jul 18 11:35:31 office volumio[30296]: info: MYVOLUMIO Environment detected Jul 18 11:35:31 office volumio[30296]: info: Plugin folders cleanup Jul 18 11:35:31 office volumio[30296]: info: Scanning into folder /volumio/app/plugins/ Jul 18 11:35:31 office volumio[30296]: info: Scanning category audio_interface Jul 18 11:35:31 office volumio[30296]: info: Scanning category miscellanea Jul 18 11:35:31 office volumio[30296]: info: Scanning category music_service Jul 18 11:35:31 office volumio[30296]: info: Scanning category plugins.json Jul 18 11:35:31 office volumio[30296]: info: Scanning category system_controller Jul 18 11:35:31 office volumio[30296]: info: Scanning category user_interface Jul 18 11:35:31 office volumio[30296]: info: Scanning into folder /data/plugins/ Jul 18 11:35:31 office volumio[30296]: info: Scanning category music_service Jul 18 11:35:31 office volumio[30296]: info: Plugin folders cleanup completed Jul 18 11:35:31 office volumio[30296]: info: ------------------------------------------- Jul 18 11:35:31 office volumio[30296]: info: ----- Core plugins startup ---- Jul 18 11:35:31 office volumio[30296]: info: ------------------------------------------- Jul 18 11:35:31 office volumio[30296]: info: Loading plugins from folder /volumio/app/plugins/ Jul 18 11:35:31 office volumio[30296]: info: Adding plugin upnp to MyMusic Plugins Jul 18 11:35:31 office volumio[30296]: info: Adding plugin airplay_emulation to MyMusic Plugins Jul 18 11:35:31 office volumio[30296]: info: Adding plugin upnp_browser to MyMusic Plugins Jul 18 11:35:31 office volumio[30296]: info: Loading plugins from folder /data/plugins/ Jul 18 11:35:31 office volumio[30296]: info: Loading plugin "system"... Jul 18 11:35:31 office volumio[30296]: info: Loading plugin "appearance"... Jul 18 11:35:32 office volumio[30296]: info: Loading plugin "network"... Jul 18 11:35:32 office volumio[30296]: info: Refreshing Cached IP Addresses Jul 18 11:35:32 office volumio[30296]: info: Loading plugin "services"... Jul 18 11:35:32 office volumio[30296]: info: Loading plugin "alsa_controller"... Jul 18 11:35:32 office sudo[30320]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 18 11:35:32 office wpa_supplicant[750]: wlan0: Failed to initiate sched scan Jul 18 11:35:32 office sudo[30322]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 18 11:35:32 office sudo[30322]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 11:35:32 office sudo[30320]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 11:35:32 office sudo[30322]: pam_unix(sudo:session): session closed for user root Jul 18 11:35:32 office sudo[30324]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jul 18 11:35:32 office sudo[30320]: pam_unix(sudo:session): session closed for user root Jul 18 11:35:32 office sudo[30324]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 11:35:32 office volumio[30296]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 18 11:35:32 office volumio[30296]: info: Loading plugin "wizard"... Jul 18 11:35:32 office volumio[30296]: info: Loading plugin "volumio_command_line_client"... Jul 18 11:35:32 office volumio[30296]: info: Loading plugin "upnp"... Jul 18 11:35:32 office volumio[30296]: info: [1721302532874] Starting Upmpd Daemon Jul 18 11:35:32 office volumio[30296]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 18 11:35:32 office volumio[30296]: info: Loading plugin "my_music"... Jul 18 11:35:32 office volumio[30296]: info: Loading plugin "mpd"... Jul 18 11:35:33 office volumio[30296]: info: Creating MPD Configuration file Jul 18 11:35:33 office volumio[30296]: info: Loading plugin "upnp_browser"... Jul 18 11:35:33 office sudo[30333]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 18 11:35:33 office sudo[30333]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 11:35:33 office sudo[30333]: pam_unix(sudo:session): session closed for user root Jul 18 11:35:33 office sudo[30335]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 18 11:35:33 office sudo[30335]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 11:35:33 office systemd[1]: Stopping Music Player Daemon... Jul 18 11:35:33 office systemd[1]: mpd.service: Succeeded. Jul 18 11:35:33 office systemd[1]: Stopped Music Player Daemon. Jul 18 11:35:33 office systemd[1]: Starting Music Player Daemon... Jul 18 11:35:33 office sudo[30324]: pam_unix(sudo:session): session closed for user root Jul 18 11:35:33 office volumio[30296]: info: Loading plugin "networkfs"... Jul 18 11:35:33 office volumio-remote-updater[482]: [2024-07-18 11:35:33] [connect] Successful connection Jul 18 11:35:33 office volumio[30296]: info: Cannot mount NAS FLAC at system boot, trial number 1 ,retrying in 5 seconds Jul 18 11:35:33 office volumio[30296]: info: Cannot mount NAS MP3 at system boot, trial number 1 ,retrying in 5 seconds Jul 18 11:35:33 office volumio[30296]: info: Starting Udev Watcher for removable devices Jul 18 11:35:34 office volumio[30296]: info: Ignoring mount for partition: boot Jul 18 11:35:34 office volumio[30296]: info: Ignoring mount for partition: volumio Jul 18 11:35:34 office volumio[30296]: info: Ignoring mount for partition: volumio_data Jul 18 11:35:34 office volumio[30296]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 18 11:35:34 office volumio[30296]: info: Loading plugin "alarm-clock"... Jul 18 11:35:34 office volumio[30296]: info: Loading plugin "airplay_emulation"... Jul 18 11:35:34 office volumio[30296]: info: Starting Shairport Sync Jul 18 11:35:34 office volumio[30296]: info: Loading plugin "last_100"... Jul 18 11:35:34 office kernel: nfs: server 192.168.1.10 not responding, timed out Jul 18 11:35:34 office volumio[30296]: info: Loading plugin "webradio"... Jul 18 11:35:34 office volumio[30296]: info: Loading plugin "i2s_dacs"... Jul 18 11:35:34 office volumio[30296]: info: Loading plugin "volumiodiscovery"... Jul 18 11:35:34 office volumio[30296]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 18 11:35:34 office volumio[30296]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 18 11:35:34 office volumio[30296]: *** WARNING *** For more information see Jul 18 11:35:34 office node[30296]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 18 11:35:34 office volumio[30296]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 18 11:35:34 office volumio[30296]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 18 11:35:34 office volumio[30296]: *** WARNING *** For more information see Jul 18 11:35:34 office node[30296]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 18 11:35:34 office node[30296]: *** WARNING *** For more information see Jul 18 11:35:34 office node[30296]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 18 11:35:34 office node[30296]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 18 11:35:34 office node[30296]: *** WARNING *** For more information see Jul 18 11:35:34 office volumio[30296]: info: Applying required configuration parameters for plugin volumiodiscovery Jul 18 11:35:34 office volumio[30296]: info: Discovery: Started advertising with name: Office Jul 18 11:35:34 office volumio[30296]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 18 11:35:34 office volumio[30296]: info: Loading plugin "squeezelite_mc"... Jul 18 11:35:34 office mpd[30340]: exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 18 11:35:34 office mpd[30340]: output: No 'audio_output' defined in config file Jul 18 11:35:34 office mpd[30340]: output: Attempt to detect audio output device Jul 18 11:35:34 office mpd[30340]: output: Attempting to detect a alsa audio device Jul 18 11:35:34 office mpd[30340]: output: Successfully detected a alsa audio device Jul 18 11:35:34 office mpd[30340]: exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Jul 18 11:35:34 office mpd[30340]: exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Jul 18 11:35:35 office mpd[30340]: zeroconf: No global port, disabling zeroconf Jul 18 11:35:35 office systemd[1]: Started Music Player Daemon. Jul 18 11:35:35 office sudo[30335]: pam_unix(sudo:session): session closed for user root Jul 18 11:35:35 office volumio[30296]: info: Loading plugin "outputs"... Jul 18 11:35:35 office volumio[30296]: info: Loading plugin "albumart"... Jul 18 11:35:35 office volumio[30296]: info: Plugin example_plugin is not enabled Jul 18 11:35:35 office volumio[30296]: info: Loading plugin "inputs"... Jul 18 11:35:35 office volumio[30296]: info: Loading plugin "updater_comm"... Jul 18 11:35:35 office volumio[30296]: info: Plugin mpdemulation is not enabled Jul 18 11:35:35 office volumio[30296]: info: Loading plugin "rest_api"... Jul 18 11:35:35 office volumio[30296]: info: Loading plugin "websocket"... Jul 18 11:35:35 office volumio[30296]: info: Loading plugin "spop"... Jul 18 11:35:35 office volumio[30296]: Forking 3 albumart workers Jul 18 11:35:36 office volumio[30296]: info: Loading plugin "volspotconnect2"... Jul 18 11:35:36 office volumio[30296]: Starting albumart workers Jul 18 11:35:37 office volumio[30296]: Starting albumart workers Jul 18 11:35:37 office volumio[30296]: Starting albumart workers Jul 18 11:35:37 office volumio[30296]: info: Loading i18n strings for locale en Jul 18 11:35:37 office volumio[30296]: Updating browse sources language Jul 18 11:35:37 office volumio[30296]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 18 11:35:37 office volumio[30296]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 18 11:35:37 office volumio[30296]: info: CoreCommandRouter::initPlayerControls Jul 18 11:35:37 office volumio[30296]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:37 office volumio[30296]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:37 office volumio[30296]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:37 office volumio[30296]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:37 office volumio[30296]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:37 office volumio[30296]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:37 office volumio[30296]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:37 office volumio[30296]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:38 office volumio[30296]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 18 11:35:38 office volumio[30296]: Express server listening on port 3000 Jul 18 11:35:38 office volumio[30296]: [Metrics] WebUI: 7s 830.88ms Jul 18 11:35:38 office volumio[30296]: info: CoreStateMachine::resetVolumioState Jul 18 11:35:38 office volumio[30296]: info: CoreStateMachine::getcurrentVolume Jul 18 11:35:38 office volumio[30296]: info: CoreCommandRouter::volumioRetrievevolume Jul 18 11:35:38 office mpd[30340]: client: [0] opened from local Jul 18 11:35:38 office volumio[30296]: info: MPD Permissions set Jul 18 11:35:38 office volumio-remote-updater[482]: [2024-07-18 11:35:38] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1721302533 101 Jul 18 11:35:38 office volumio[30296]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1 Jul 18 11:35:38 office mpd[30340]: exception: No database Jul 18 11:35:38 office mpd[30340]: exception: No database Jul 18 11:35:38 office volumio[30296]: info: MPD running with PID30340 Jul 18 11:35:38 office volumio[30296]: ,establishing connection Jul 18 11:35:38 office mpd[30340]: client: [1] opened from local Jul 18 11:35:38 office volumio[30296]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:38 office volumio[30296]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:38 office volumio[30296]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:38 office volumio[30296]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:38 office volumio[30296]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:38 office volumio[30296]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:38 office volumio[30296]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:38 office volumio[30296]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:38 office volumio[30296]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 18 11:35:38 office volumio[30296]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:38 office volumio[30296]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 18 11:35:38 office volumio[30296]: info: Reloading queue from file Jul 18 11:35:38 office volumio[30296]: error: updateQueue error: null Jul 18 11:35:38 office volumio[30296]: error: Failed LSINFO: Error: [50@0] {lsinfo} No database Jul 18 11:35:38 office volumio[30296]: info: CoreStateMachine::setRepeat null single undefined Jul 18 11:35:38 office volumio[30296]: info: CoreStateMachine::pushState Jul 18 11:35:38 office volumio[30296]: info: CorePlayQueue::getTrack 0 Jul 18 11:35:38 office volumio[30296]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 11:35:38 office volumio[30296]: info: CoreCommandRouter::volumioPushState Jul 18 11:35:38 office volumio[30296]: info: CoreStateMachine::setRandom true Jul 18 11:35:38 office volumio[30296]: info: CoreStateMachine::pushState Jul 18 11:35:38 office volumio[30296]: info: CorePlayQueue::getTrack 0 Jul 18 11:35:38 office volumio[30296]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 11:35:38 office volumio[30296]: info: CoreCommandRouter::volumioPushState Jul 18 11:35:38 office wpa_supplicant[750]: wlan0: Trying to associate with SSID 'SMART' Jul 18 11:35:38 office volumio[30296]: info: Setting Device type: Raspberry PI Jul 18 11:35:38 office volumio[30296]: info: Completed loading Core Plugins Jul 18 11:35:38 office volumio[30296]: info: Preparing to generate the ALSA configuration file Jul 18 11:35:38 office volumio[30296]: info: VolumeController:: Volume=56 Mute =false Jul 18 11:35:38 office volumio[30296]: info: CoreStateMachine::pushState Jul 18 11:35:38 office volumio[30296]: info: CorePlayQueue::getTrack 0 Jul 18 11:35:38 office volumio[30296]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 11:35:38 office volumio[30296]: info: CoreCommandRouter::volumioPushState Jul 18 11:35:38 office volumio[30296]: info: CoreStateMachine::updateTrackBlock Jul 18 11:35:38 office volumio[30296]: info: CorePlayQueue::getTrackBlock Jul 18 11:35:38 office volumio[30296]: info: CoreCommandRouter::volumioRetrievevolume Jul 18 11:35:38 office volumio[30296]: error: updateQueue error: null Jul 18 11:35:38 office volumio[30296]: info: Asound.conf file unchanged, so no further update is needed Jul 18 11:35:38 office volumio[30296]: info: Output device has changed, restarting MPD Jul 18 11:35:38 office sudo[30416]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 18 11:35:38 office sudo[30416]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 11:35:38 office volumio[30296]: info: Output device has changed, restarting Shairport Sync Jul 18 11:35:38 office volumio[30296]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:38 office volumio[30296]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 18 11:35:38 office sudo[30416]: pam_unix(sudo:session): session closed for user root Jul 18 11:35:38 office sudo[30418]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 18 11:35:38 office sudo[30418]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 11:35:38 office systemd[1]: Stopping Music Player Daemon... Jul 18 11:35:38 office volumio[30296]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 18 11:35:38 office volumio[30296]: info: ___________ START PLUGINS ___________ Jul 18 11:35:38 office systemd[1]: mpd.service: Succeeded. Jul 18 11:35:38 office systemd[1]: Stopped Music Player Daemon. Jul 18 11:35:38 office volumio[30296]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 18 11:35:38 office volumio[30296]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 18 11:35:38 office volumio[30296]: info: [1721302538566] CoreMusicLibrary::Adding element Media Servers Jul 18 11:35:38 office volumio[30296]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 18 11:35:38 office volumio[30296]: Cannot find translation for sourceMedia Servers Jul 18 11:35:38 office systemd[1]: Starting Music Player Daemon... Jul 18 11:35:38 office volumio[30296]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:38 office volumio[30296]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 18 11:35:38 office volumio[30296]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 18 11:35:38 office volumio[30296]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:38 office volumio[30296]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 18 11:35:38 office volumio[30296]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 18 11:35:38 office volumio[30296]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 18 11:35:38 office volumio[30296]: info: [1721302538737] CoreMusicLibrary::Adding element Last_100 Jul 18 11:35:38 office volumio[30296]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 18 11:35:38 office volumio[30296]: Cannot find translation for sourceMedia Servers Jul 18 11:35:38 office volumio[30296]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 18 11:35:38 office volumio[30296]: info: [1721302538742] CoreMusicLibrary::Adding element Webradio Jul 18 11:35:38 office volumio[30296]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 18 11:35:38 office volumio[30296]: Cannot find translation for sourceMedia Servers Jul 18 11:35:38 office volumio[30296]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 18 11:35:38 office volumio[30296]: info: [squeezelite_mc] Starting proxy server... Jul 18 11:35:38 office volumio[30296]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 18 11:35:38 office volumio[30296]: [SpotifyConnect] Creating VLS config file Jul 18 11:35:38 office volumio[30296]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:38 office volumio[30296]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:38 office volumio[30296]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:38 office volumio[30296]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:38 office volumio[30296]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:38 office volumio[30296]: [SpotifyConnect] Starting metadata listener Jul 18 11:35:38 office volumio[30296]: [SpotifyConnect] Creating VLS config file Jul 18 11:35:38 office volumio[30296]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:38 office volumio[30296]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:38 office volumio[30296]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:38 office volumio[30296]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:38 office volumio[30296]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:38 office volumio[30296]: [SpotifyConnect] Starting metadata listener Jul 18 11:35:38 office volumio[30296]: info: Volumio Calling Home Jul 18 11:35:39 office volumio[30296]: info: [squeezelite_mc] Proxy server started on port 37615 Jul 18 11:35:39 office volumio[30296]: [SpotifyConnect] Error: bind EADDRINUSE 0.0.0.0:5030 Jul 18 11:35:39 office volumio[30296]: at dgram.js:337:20 Jul 18 11:35:39 office volumio[30296]: at processTicksAndRejections (internal/process/task_queues.js:81:21) Jul 18 11:35:39 office volumio[30296]: info: CoreCommandRouter::volumioRetrievevolume Jul 18 11:35:39 office volumio[30296]: Unhandled rejection Error: No sockets available, cannot start. Jul 18 11:35:39 office volumio[30296]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Jul 18 11:35:39 office volumio[30296]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Jul 18 11:35:39 office volumio[30296]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Jul 18 11:35:39 office volumio[30296]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Jul 18 11:35:39 office volumio[30296]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Jul 18 11:35:39 office volumio[30296]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Jul 18 11:35:39 office volumio[30296]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Jul 18 11:35:39 office volumio[30296]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Jul 18 11:35:39 office volumio[30296]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Jul 18 11:35:39 office volumio[30296]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Jul 18 11:35:39 office volumio[30296]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Jul 18 11:35:39 office volumio[30296]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Jul 18 11:35:39 office volumio[30296]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Jul 18 11:35:39 office volumio[30296]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Jul 18 11:35:39 office volumio[30296]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Jul 18 11:35:39 office volumio[30296]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jul 18 11:35:39 office volumio[30296]: info: Cannot mount NAS FLAC at system boot, trial number 2 ,retrying in 5 seconds Jul 18 11:35:39 office volumio[30296]: info: Cannot mount NAS MP3 at system boot, trial number 2 ,retrying in 5 seconds Jul 18 11:35:39 office volumio[30296]: info: MPD Permissions set Jul 18 11:35:39 office volumio[30296]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:39 office volumio[30296]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:39 office volumio[30296]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:39 office volumio[30296]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:39 office volumio[30296]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:39 office volumio[30296]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:39 office volumio[30296]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:39 office volumio[30296]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:39 office volumio[30296]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 18 11:35:39 office volumio[30296]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:39 office volumio[30296]: info: VolumeController:: Volume=56 Mute =false Jul 18 11:35:39 office volumio[30296]: info: CoreStateMachine::pushState Jul 18 11:35:39 office volumio[30296]: info: CorePlayQueue::getTrack 0 Jul 18 11:35:39 office volumio[30296]: info: CoreCommandRouter::volumioPushState Jul 18 11:35:39 office kernel: nfs: server 192.168.1.10 not responding, timed out Jul 18 11:35:39 office mpd[30432]: Jul 18 11:35 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 18 11:35:40 office wpa_supplicant[750]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Jul 18 11:35:40 office volumio[30296]: info: [squeezelite_mc] Server discovery started Jul 18 11:35:40 office volumio[30296]: info: [squeezelite_mc] Player finder started Jul 18 11:35:40 office sudo[30438]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect2.service Jul 18 11:35:40 office sudo[30438]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 11:35:40 office volumio[30296]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:40 office volumio[30296]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:40 office volumio[30296]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 18 11:35:40 office sudo[30436]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect.service Jul 18 11:35:40 office sudo[30434]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hosts Jul 18 11:35:40 office sudo[30436]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 11:35:40 office sudo[30434]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 11:35:40 office volumio[30296]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true Jul 18 11:35:40 office sudo[30434]: pam_unix(sudo:session): session closed for user root Jul 18 11:35:40 office sudo[30436]: pam_unix(sudo:session): session closed for user root Jul 18 11:35:40 office sudo[30438]: pam_unix(sudo:session): session closed for user root Jul 18 11:35:40 office volumio[30296]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 18 11:35:40 office mpd[30432]: Jul 18 11:35 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Jul 18 11:35:40 office mpd[30432]: Jul 18 11:35 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Jul 18 11:35:40 office systemd[1]: Started Music Player Daemon. Jul 18 11:35:40 office sudo[30418]: pam_unix(sudo:session): session closed for user root Jul 18 11:35:40 office volumio[30296]: Error: send ENETUNREACH 255.255.255.255:3483 Jul 18 11:35:40 office volumio[30296]: at doSend (dgram.js:692:16) Jul 18 11:35:40 office volumio[30296]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Jul 18 11:35:40 office volumio[30296]: at afterDns (dgram.js:638:5) Jul 18 11:35:40 office volumio[30296]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Jul 18 11:35:40 office volumio[30296]: errno: -101, Jul 18 11:35:40 office volumio[30296]: code: 'ENETUNREACH', Jul 18 11:35:40 office volumio[30296]: syscall: 'send', Jul 18 11:35:40 office volumio[30296]: address: '255.255.255.255', Jul 18 11:35:40 office volumio[30296]: port: 3483 Jul 18 11:35:40 office volumio[30296]: } Jul 18 11:35:40 office volumio[30296]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 18 11:35:40 office ntpd[657]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Jul 18 11:35:40 office sudo[30465]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-18 11:34 Jul 18 11:35:40 office sudo[30465]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 11:35:41 office sudo[30465]: pam_unix(sudo:session): session closed for user root Jul 18 11:35:41 office volumio-remote-updater[482]: [2024-07-18 11:35:41] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jul 18 11:35:41 office volumio-remote-updater[482]: [2024-07-18 11:35:41] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jul 18 11:35:41 office systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jul 18 11:35:41 office systemd[1]: volumio.service: Failed with result 'exit-code'. Jul 18 11:35:41 office systemd[1]: Started dynamicswap service. Jul 18 11:35:41 office systemd[1]: dynamicswap.service: Succeeded. Jul 18 11:35:41 office systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jul 18 11:35:41 office systemd[1]: volumio.service: Scheduled restart job, restart counter is at 76. Jul 18 11:35:41 office systemd[1]: Started dynamicswap service. Jul 18 11:35:41 office systemd[1]: Stopped Volumio Backend Module. Jul 18 11:35:41 office systemd[1]: Started Volumio Backend Module. Jul 18 11:35:41 office systemd[1]: dynamicswap.service: Succeeded. Jul 18 11:35:42 office wpa_supplicant[750]: wlan0: Trying to associate with SSID 'SMART' Jul 18 11:35:43 office dhcpcd[772]: wlan0: carrier acquired Jul 18 11:35:43 office wpa_supplicant[750]: wlan0: Associated with 20:a6:cd:f8:cf:21 Jul 18 11:35:43 office wpa_supplicant[750]: wlan0: CTRL-EVENT-CONNECTED - Connection to 20:a6:cd:f8:cf:21 completed [id=1 id_str=] Jul 18 11:35:43 office wpa_supplicant[750]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jul 18 11:35:43 office dhcpcd[772]: wlan0: IAID eb:9e:88:84 Jul 18 11:35:43 office dhcpcd[772]: wlan0: carrier lost Jul 18 11:35:43 office dhcpcd[772]: wlan0: carrier acquired Jul 18 11:35:43 office dhcpcd[772]: wlan0: IAID eb:9e:88:84 Jul 18 11:35:43 office volumio[30479]: info: ------------------------------------------- Jul 18 11:35:43 office volumio[30479]: info: ----- Volumio3 ---- Jul 18 11:35:43 office volumio[30479]: info: ------------------------------------------- Jul 18 11:35:43 office volumio[30479]: info: ----- System startup ---- Jul 18 11:35:43 office volumio[30479]: info: ------------------------------------------- Jul 18 11:35:44 office dhcpcd[772]: wlan0: soliciting an IPv6 router Jul 18 11:35:44 office dhcpcd[772]: wlan0: rebinding lease of 172.16.16.11 Jul 18 11:35:44 office volumio[30479]: info: MYVOLUMIO Environment detected Jul 18 11:35:44 office volumio[30479]: info: Plugin folders cleanup Jul 18 11:35:44 office volumio[30479]: info: Scanning into folder /volumio/app/plugins/ Jul 18 11:35:44 office volumio[30479]: info: Scanning category audio_interface Jul 18 11:35:44 office volumio[30479]: info: Scanning category miscellanea Jul 18 11:35:44 office volumio[30479]: info: Scanning category music_service Jul 18 11:35:44 office volumio[30479]: info: Scanning category plugins.json Jul 18 11:35:44 office volumio[30479]: info: Scanning category system_controller Jul 18 11:35:44 office volumio[30479]: info: Scanning category user_interface Jul 18 11:35:44 office volumio[30479]: info: Scanning into folder /data/plugins/ Jul 18 11:35:44 office volumio[30479]: info: Scanning category music_service Jul 18 11:35:44 office volumio[30479]: info: Plugin folders cleanup completed Jul 18 11:35:44 office volumio[30479]: info: ------------------------------------------- Jul 18 11:35:44 office volumio[30479]: info: ----- Core plugins startup ---- Jul 18 11:35:44 office volumio[30479]: info: ------------------------------------------- Jul 18 11:35:44 office volumio[30479]: info: Loading plugins from folder /volumio/app/plugins/ Jul 18 11:35:44 office volumio[30479]: info: Adding plugin upnp to MyMusic Plugins Jul 18 11:35:44 office volumio[30479]: info: Adding plugin airplay_emulation to MyMusic Plugins Jul 18 11:35:44 office volumio[30479]: info: Adding plugin upnp_browser to MyMusic Plugins Jul 18 11:35:44 office volumio[30479]: info: Loading plugins from folder /data/plugins/ Jul 18 11:35:44 office volumio[30479]: info: Loading plugin "system"... Jul 18 11:35:44 office volumio[30479]: info: Loading plugin "appearance"... Jul 18 11:35:44 office kernel: nfs: server 192.168.1.10 not responding, timed out Jul 18 11:35:45 office volumio[30479]: info: Loading plugin "network"... Jul 18 11:35:45 office volumio[30479]: info: Refreshing Cached IP Addresses Jul 18 11:35:45 office sudo[30515]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 18 11:35:45 office sudo[30515]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 11:35:45 office sudo[30515]: pam_unix(sudo:session): session closed for user root Jul 18 11:35:45 office sudo[30517]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 18 11:35:45 office sudo[30517]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 11:35:45 office volumio[30479]: info: Loading plugin "services"... Jul 18 11:35:45 office sudo[30517]: pam_unix(sudo:session): session closed for user root Jul 18 11:35:45 office volumio[30479]: info: Loading plugin "alsa_controller"... Jul 18 11:35:45 office sudo[30521]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jul 18 11:35:45 office sudo[30521]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 11:35:45 office volumio[30479]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 18 11:35:45 office volumio[30479]: info: Loading plugin "wizard"... Jul 18 11:35:45 office volumio[30479]: info: Loading plugin "volumio_command_line_client"... Jul 18 11:35:45 office volumio[30479]: info: Loading plugin "upnp"... Jul 18 11:35:45 office volumio[30479]: info: [1721302545630] Starting Upmpd Daemon Jul 18 11:35:45 office volumio[30479]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 18 11:35:45 office volumio[30479]: info: Loading plugin "my_music"... Jul 18 11:35:45 office volumio[30479]: info: Loading plugin "mpd"... Jul 18 11:35:45 office volumio[30479]: info: Creating MPD Configuration file Jul 18 11:35:46 office volumio[30479]: info: Loading plugin "upnp_browser"... Jul 18 11:35:46 office sudo[30528]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 18 11:35:46 office sudo[30528]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 11:35:46 office sudo[30528]: pam_unix(sudo:session): session closed for user root Jul 18 11:35:46 office sudo[30530]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 18 11:35:46 office sudo[30530]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 11:35:46 office systemd[1]: Stopping Music Player Daemon... Jul 18 11:35:46 office systemd[1]: mpd.service: Succeeded. Jul 18 11:35:46 office systemd[1]: Stopped Music Player Daemon. Jul 18 11:35:46 office systemd[1]: Starting Music Player Daemon... Jul 18 11:35:46 office volumio[30479]: info: Loading plugin "networkfs"... Jul 18 11:35:46 office volumio[30479]: info: Cannot mount NAS FLAC at system boot, trial number 1 ,retrying in 5 seconds Jul 18 11:35:46 office volumio[30479]: info: Cannot mount NAS MP3 at system boot, trial number 1 ,retrying in 5 seconds Jul 18 11:35:46 office volumio[30479]: info: Starting Udev Watcher for removable devices Jul 18 11:35:46 office volumio[30479]: info: Ignoring mount for partition: boot Jul 18 11:35:46 office volumio[30479]: info: Ignoring mount for partition: volumio Jul 18 11:35:46 office volumio[30479]: info: Ignoring mount for partition: volumio_data Jul 18 11:35:46 office volumio[30479]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 18 11:35:46 office volumio[30479]: info: Loading plugin "alarm-clock"... Jul 18 11:35:47 office volumio[30479]: info: Loading plugin "airplay_emulation"... Jul 18 11:35:47 office volumio[30479]: info: Starting Shairport Sync Jul 18 11:35:47 office volumio[30479]: info: Loading plugin "last_100"... Jul 18 11:35:47 office volumio[30479]: info: Loading plugin "webradio"... Jul 18 11:35:47 office volumio[30479]: info: Loading plugin "i2s_dacs"... Jul 18 11:35:47 office volumio[30479]: info: Loading plugin "volumiodiscovery"... Jul 18 11:35:47 office volumio[30479]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 18 11:35:47 office volumio[30479]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 18 11:35:47 office node[30479]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 18 11:35:47 office volumio[30479]: *** WARNING *** For more information see Jul 18 11:35:47 office volumio[30479]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 18 11:35:47 office volumio[30479]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 18 11:35:47 office volumio[30479]: *** WARNING *** For more information see Jul 18 11:35:47 office node[30479]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 18 11:35:47 office node[30479]: *** WARNING *** For more information see Jul 18 11:35:47 office node[30479]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 18 11:35:47 office node[30479]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 18 11:35:47 office node[30479]: *** WARNING *** For more information see Jul 18 11:35:47 office volumio[30479]: info: Applying required configuration parameters for plugin volumiodiscovery Jul 18 11:35:47 office volumio[30479]: info: Discovery: Started advertising with name: Office Jul 18 11:35:47 office volumio[30479]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 18 11:35:47 office volumio[30479]: info: Loading plugin "squeezelite_mc"... Jul 18 11:35:47 office mpd[30535]: Jul 18 11:35 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 18 11:35:47 office volumio-remote-updater[482]: [2024-07-18 11:35:47] [connect] Successful connection Jul 18 11:35:47 office sudo[30521]: pam_unix(sudo:session): session closed for user root Jul 18 11:35:47 office mpd[30535]: Jul 18 11:35 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Jul 18 11:35:47 office mpd[30535]: Jul 18 11:35 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Jul 18 11:35:47 office systemd[1]: Started Music Player Daemon. Jul 18 11:35:47 office sudo[30530]: pam_unix(sudo:session): session closed for user root Jul 18 11:35:48 office volumio[30479]: info: Loading plugin "outputs"... Jul 18 11:35:48 office volumio[30479]: info: Loading plugin "albumart"... Jul 18 11:35:48 office volumio[30479]: info: Plugin example_plugin is not enabled Jul 18 11:35:48 office volumio[30479]: info: Loading plugin "inputs"... Jul 18 11:35:48 office volumio[30479]: info: Loading plugin "updater_comm"... Jul 18 11:35:48 office volumio[30479]: info: Plugin mpdemulation is not enabled Jul 18 11:35:48 office volumio[30479]: info: Loading plugin "rest_api"... Jul 18 11:35:48 office volumio[30479]: info: Loading plugin "websocket"... Jul 18 11:35:48 office volumio[30479]: info: Loading plugin "spop"... Jul 18 11:35:48 office volumio[30479]: Forking 3 albumart workers Jul 18 11:35:49 office dhcpcd[772]: wlan0: probing for an IPv4LL address Jul 18 11:35:49 office dhcpcd[772]: wlan0: DHCP lease expired Jul 18 11:35:49 office dhcpcd[772]: wlan0: soliciting a DHCP lease Jul 18 11:35:49 office dhcpcd[772]: wlan0: offered 172.16.16.11 from 172.16.16.16 Jul 18 11:35:49 office dhcpcd[772]: wlan0: probing address 172.16.16.11/24 Jul 18 11:35:49 office volumio[30479]: info: Loading plugin "volspotconnect2"... Jul 18 11:35:49 office volumio[30479]: Starting albumart workers Jul 18 11:35:49 office volumio[30479]: Starting albumart workers Jul 18 11:35:49 office volumio[30479]: Starting albumart workers Jul 18 11:35:50 office volumio[30479]: info: Loading i18n strings for locale en Jul 18 11:35:50 office volumio[30479]: Updating browse sources language Jul 18 11:35:50 office volumio[30479]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 18 11:35:50 office volumio[30479]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 18 11:35:50 office volumio[30479]: info: CoreCommandRouter::initPlayerControls Jul 18 11:35:50 office volumio[30479]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:50 office volumio[30479]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:50 office volumio[30479]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:50 office volumio[30479]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:50 office volumio[30479]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:50 office volumio[30479]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:50 office volumio[30479]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:50 office volumio[30479]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:50 office volumio[30479]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 18 11:35:50 office volumio[30479]: Express server listening on port 3000 Jul 18 11:35:50 office volumio[30479]: [Metrics] WebUI: 7s 846.42ms Jul 18 11:35:50 office volumio[30479]: info: CoreStateMachine::resetVolumioState Jul 18 11:35:50 office volumio[30479]: info: CoreStateMachine::getcurrentVolume Jul 18 11:35:50 office volumio[30479]: info: CoreCommandRouter::volumioRetrievevolume Jul 18 11:35:50 office volumio[30479]: info: MPD Permissions set Jul 18 11:35:50 office volumio-remote-updater[482]: [2024-07-18 11:35:50] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1721302546 101 Jul 18 11:35:50 office volumio[30479]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1 Jul 18 11:35:51 office volumio[30479]: info: MPD running with PID30535 Jul 18 11:35:51 office volumio[30479]: ,establishing connection Jul 18 11:35:51 office volumio[30479]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:51 office volumio[30479]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:51 office volumio[30479]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:51 office volumio[30479]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:51 office volumio[30479]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:51 office volumio[30479]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:51 office volumio[30479]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:51 office volumio[30479]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:51 office volumio[30479]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 18 11:35:51 office volumio[30479]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:51 office volumio[30479]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 18 11:35:51 office volumio[30479]: info: Reloading queue from file Jul 18 11:35:51 office volumio[30479]: error: updateQueue error: null Jul 18 11:35:51 office volumio[30479]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jul 18 11:35:51 office volumio[30479]: info: CoreStateMachine::setRepeat null single undefined Jul 18 11:35:51 office volumio[30479]: info: CoreStateMachine::pushState Jul 18 11:35:51 office volumio[30479]: info: CorePlayQueue::getTrack 0 Jul 18 11:35:51 office volumio[30479]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 11:35:51 office volumio[30479]: info: CoreCommandRouter::volumioPushState Jul 18 11:35:51 office volumio[30479]: info: CoreStateMachine::setRandom true Jul 18 11:35:51 office volumio[30479]: info: CoreStateMachine::pushState Jul 18 11:35:51 office volumio[30479]: info: CorePlayQueue::getTrack 0 Jul 18 11:35:51 office volumio[30479]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 11:35:51 office volumio[30479]: info: CoreCommandRouter::volumioPushState Jul 18 11:35:51 office volumio[30479]: info: Setting Device type: Raspberry PI Jul 18 11:35:51 office volumio[30479]: info: Completed loading Core Plugins Jul 18 11:35:51 office volumio[30479]: info: Preparing to generate the ALSA configuration file Jul 18 11:35:51 office volumio[30479]: info: VolumeController:: Volume=56 Mute =false Jul 18 11:35:51 office volumio[30479]: info: CoreStateMachine::pushState Jul 18 11:35:51 office volumio[30479]: info: CorePlayQueue::getTrack 0 Jul 18 11:35:51 office volumio[30479]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 11:35:51 office volumio[30479]: info: CoreCommandRouter::volumioPushState Jul 18 11:35:51 office volumio[30479]: info: CoreStateMachine::updateTrackBlock Jul 18 11:35:51 office volumio[30479]: info: CorePlayQueue::getTrackBlock Jul 18 11:35:51 office volumio[30479]: info: CoreCommandRouter::volumioRetrievevolume Jul 18 11:35:51 office volumio[30479]: info: Asound.conf file unchanged, so no further update is needed Jul 18 11:35:51 office volumio[30479]: info: Output device has changed, restarting MPD Jul 18 11:35:51 office sudo[30620]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 18 11:35:51 office sudo[30620]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 11:35:51 office volumio[30479]: info: Output device has changed, restarting Shairport Sync Jul 18 11:35:51 office volumio[30479]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:51 office volumio[30479]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 18 11:35:51 office sudo[30620]: pam_unix(sudo:session): session closed for user root Jul 18 11:35:51 office sudo[30622]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 18 11:35:51 office sudo[30622]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 11:35:51 office systemd[1]: Stopping Music Player Daemon... Jul 18 11:35:51 office volumio[30479]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 18 11:35:51 office volumio[30479]: info: ___________ START PLUGINS ___________ Jul 18 11:35:51 office volumio[30479]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 18 11:35:51 office volumio[30479]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 18 11:35:51 office volumio[30479]: info: [1721302551306] CoreMusicLibrary::Adding element Media Servers Jul 18 11:35:51 office volumio[30479]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 18 11:35:51 office volumio[30479]: Cannot find translation for sourceMedia Servers Jul 18 11:35:51 office systemd[1]: mpd.service: Succeeded. Jul 18 11:35:51 office systemd[1]: Stopped Music Player Daemon. Jul 18 11:35:51 office systemd[1]: Starting Music Player Daemon... Jul 18 11:35:52 office volumio[30479]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:52 office volumio[30479]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 18 11:35:52 office volumio[30479]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 18 11:35:52 office volumio[30479]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:52 office volumio[30479]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 18 11:35:52 office mpd[30630]: Jul 18 11:35 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 18 11:35:52 office volumio[30479]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 18 11:35:52 office volumio[30479]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 18 11:35:52 office volumio[30479]: info: [1721302552918] CoreMusicLibrary::Adding element Last_100 Jul 18 11:35:52 office volumio[30479]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 18 11:35:52 office volumio[30479]: Cannot find translation for sourceMedia Servers Jul 18 11:35:52 office volumio[30479]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 18 11:35:52 office volumio[30479]: info: [1721302552922] CoreMusicLibrary::Adding element Webradio Jul 18 11:35:52 office volumio[30479]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 18 11:35:52 office volumio[30479]: Cannot find translation for sourceMedia Servers Jul 18 11:35:52 office volumio[30479]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 18 11:35:52 office volumio[30479]: info: [squeezelite_mc] Starting proxy server... Jul 18 11:35:53 office volumio[30479]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 18 11:35:53 office volumio[30479]: [SpotifyConnect] Creating VLS config file Jul 18 11:35:53 office volumio[30479]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:53 office volumio[30479]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:53 office volumio[30479]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:53 office volumio[30479]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:53 office volumio[30479]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:53 office volumio[30479]: [SpotifyConnect] Starting metadata listener Jul 18 11:35:53 office sudo[30638]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hosts Jul 18 11:35:53 office sudo[30638]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 11:35:53 office sudo[30638]: pam_unix(sudo:session): session closed for user root Jul 18 11:35:53 office volumio[30479]: [SpotifyConnect] Creating VLS config file Jul 18 11:35:53 office volumio[30479]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:53 office volumio[30479]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:53 office volumio[30479]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:53 office volumio[30479]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:53 office volumio[30479]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:53 office volumio[30479]: [SpotifyConnect] Starting metadata listener Jul 18 11:35:53 office sudo[30641]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect.service Jul 18 11:35:53 office sudo[30641]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 11:35:53 office volumio[30479]: info: Volumio Calling Home Jul 18 11:35:53 office sudo[30641]: pam_unix(sudo:session): session closed for user root Jul 18 11:35:53 office sudo[30645]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect2.service Jul 18 11:35:53 office sudo[30645]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 11:35:53 office volumio[30479]: info: [squeezelite_mc] Proxy server started on port 40079 Jul 18 11:35:53 office sudo[30645]: pam_unix(sudo:session): session closed for user root Jul 18 11:35:53 office volumio[30479]: [SpotifyConnect] Error: bind EADDRINUSE 0.0.0.0:5030 Jul 18 11:35:53 office volumio[30479]: at dgram.js:337:20 Jul 18 11:35:53 office volumio[30479]: at processTicksAndRejections (internal/process/task_queues.js:81:21) Jul 18 11:35:53 office mpd[30630]: Jul 18 11:35 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Jul 18 11:35:53 office mpd[30630]: Jul 18 11:35 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Jul 18 11:35:53 office systemd[1]: Started Music Player Daemon. Jul 18 11:35:53 office sudo[30622]: pam_unix(sudo:session): session closed for user root Jul 18 11:35:53 office volumio[30479]: info: CoreCommandRouter::volumioRetrievevolume Jul 18 11:35:53 office volumio[30479]: info: Cannot mount NAS FLAC at system boot, trial number 2 ,retrying in 5 seconds Jul 18 11:35:53 office volumio[30479]: info: Cannot mount NAS MP3 at system boot, trial number 2 ,retrying in 5 seconds Jul 18 11:35:53 office volumio[30479]: Unhandled rejection Error: No sockets available, cannot start. Jul 18 11:35:53 office volumio[30479]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Jul 18 11:35:53 office volumio[30479]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Jul 18 11:35:53 office volumio[30479]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Jul 18 11:35:53 office volumio[30479]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Jul 18 11:35:53 office volumio[30479]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Jul 18 11:35:53 office volumio[30479]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Jul 18 11:35:53 office volumio[30479]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Jul 18 11:35:53 office volumio[30479]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Jul 18 11:35:53 office volumio[30479]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Jul 18 11:35:53 office volumio[30479]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Jul 18 11:35:53 office volumio[30479]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Jul 18 11:35:53 office volumio[30479]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Jul 18 11:35:53 office volumio[30479]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Jul 18 11:35:53 office volumio[30479]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Jul 18 11:35:53 office volumio[30479]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Jul 18 11:35:53 office volumio[30479]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jul 18 11:35:53 office volumio[30479]: error: MPD error: Error: write EPIPE Jul 18 11:35:53 office volumio[30479]: error: write EPIPE {"errno":-32,"code":"EPIPE","syscall":"write"} Jul 18 11:35:53 office volumio[30479]: [SpotifyConnect] Vollibrespot Daemon service started! Jul 18 11:35:53 office volumio[30479]: [Metrics] SpotifyConnect: 0s 305.16ms Jul 18 11:35:53 office volumio[30479]: [SpotifyConnect] Vollibrespot Daemon service started! Jul 18 11:35:53 office volumio[30479]: [Metrics] SpotifyConnect: 0s 307.19ms Jul 18 11:35:53 office volumio[30479]: info: MPD Permissions set Jul 18 11:35:53 office volumio[30479]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 Jul 18 11:35:53 office volumio[30479]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 3 Jul 18 11:35:53 office volumio[30479]: info: VolumeController:: Volume=56 Mute =false Jul 18 11:35:53 office volumio[30479]: info: CoreStateMachine::pushState Jul 18 11:35:53 office volumio[30479]: info: CorePlayQueue::getTrack 0 Jul 18 11:35:53 office volumio[30479]: info: CoreCommandRouter::volumioPushState Jul 18 11:35:53 office volumio[30479]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:53 office volumio[30479]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:53 office volumio[30479]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:53 office volumio[30479]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:53 office volumio[30479]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:53 office volumio[30479]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:53 office volumio[30479]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:53 office volumio[30479]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:53 office volumio[30479]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 18 11:35:53 office volumio[30479]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:53 office volumio[30479]: error: updateQueue error: null Jul 18 11:35:53 office volumio[30479]: info: VolumeController:: Volume=56 Mute =false Jul 18 11:35:53 office volumio[30479]: info: CoreStateMachine::pushState Jul 18 11:35:53 office volumio[30479]: info: CorePlayQueue::getTrack 0 Jul 18 11:35:53 office volumio[30479]: info: CoreCommandRouter::volumioPushState Jul 18 11:35:53 office volumio[30479]: info: [squeezelite_mc] Server discovery started Jul 18 11:35:53 office volumio[30479]: info: [squeezelite_mc] Player finder started Jul 18 11:35:53 office volumio[30479]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:53 office volumio[30479]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:53 office volumio[30479]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 11:35:53 office volumio[30479]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 18 11:35:53 office volumio[30479]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true Jul 18 11:35:53 office volumio[30479]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 18 11:35:53 office volumio[30479]: Error: send ENETUNREACH 255.255.255.255:3483 Jul 18 11:35:53 office volumio[30479]: at doSend (dgram.js:692:16) Jul 18 11:35:53 office volumio[30479]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Jul 18 11:35:53 office volumio[30479]: at afterDns (dgram.js:638:5) Jul 18 11:35:53 office volumio[30479]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Jul 18 11:35:53 office volumio[30479]: errno: -101, Jul 18 11:35:53 office volumio[30479]: code: 'ENETUNREACH', Jul 18 11:35:53 office volumio[30479]: syscall: 'send', Jul 18 11:35:53 office volumio[30479]: address: '255.255.255.255', Jul 18 11:35:53 office volumio[30479]: port: 3483 Jul 18 11:35:53 office volumio[30479]: } Jul 18 11:35:53 office volumio[30479]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 18 11:35:53 office kernel: nfs: server 192.168.1.10 not responding, timed out Jul 18 11:35:53 office dhcpcd[772]: wlan0: leased 172.16.16.11 for 7200 seconds Jul 18 11:35:55 office avahi-daemon[498]: Joining mDNS multicast group on interface wlan0.IPv4 with address 172.16.16.11. Jul 18 11:35:55 office dhcpcd[772]: wlan0: adding route to 172.16.16.0/24 Jul 18 11:35:55 office dhcpcd[772]: wlan0: adding default route via 172.16.16.16 Jul 18 11:35:55 office avahi-daemon[498]: New relevant interface wlan0.IPv4 for mDNS. Jul 18 11:35:55 office avahi-daemon[498]: Registering new address record for 172.16.16.11 on wlan0.IPv4. Jul 18 11:35:55 office sudo[30669]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-18 11:34 Jul 18 11:35:55 office sudo[30669]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="c87c6e2b1ae33dc5801986cbcbe970898b92e7b4" VOLUMIO_FE_VERSION="c433755e569617abac52264d17ad61be303ef463" VOLUMIO_FE3_VERSION="2419ddd8e895c5bdc456962163c9dc7c0f187aa7" VOLUMIO_BE_VERSION="b949d6001d7e656b4668f31c119d2247cdf45158" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 30 Mar 2023 05:45:03 PM CEST" VOLUMIO_VERSION="3.449" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="6428d8e2c06e98483f5c5d77e8f01989"