-- Logs begin at Thu 2019-02-14 10:11:59 UTC, end at Sat 2024-06-01 10:30:31 UTC. -- Jun 01 10:29:00 volumio wireless.js[451]: trying... Jun 01 10:29:00 volumio sudo[735]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 01 10:29:00 volumio sudo[735]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 10:29:00 volumio sudo[735]: pam_unix(sudo:session): session closed for user root Jun 01 10:29:00 volumio volumio-remote-updater[448]: [2024-06-01 10:29:00] [info] asio async_connect error: system:111 (Connection refused) Jun 01 10:29:00 volumio volumio-remote-updater[448]: [2024-06-01 10:29:00] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jun 01 10:29:00 volumio volumio-remote-updater[448]: [2024-06-01 10:29:00] [error] handle_connect error: Underlying Transport Error Jun 01 10:29:01 volumio wireless.js[451]: trying... Jun 01 10:29:01 volumio sudo[738]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 01 10:29:01 volumio sudo[738]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 10:29:01 volumio sudo[738]: pam_unix(sudo:session): session closed for user root Jun 01 10:29:02 volumio wpa_supplicant[668]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Jun 01 10:29:02 volumio wireless.js[451]: trying... Jun 01 10:29:02 volumio sudo[741]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 01 10:29:02 volumio sudo[741]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 10:29:02 volumio sudo[741]: pam_unix(sudo:session): session closed for user root Jun 01 10:29:03 volumio wireless.js[451]: trying... Jun 01 10:29:03 volumio sudo[744]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 01 10:29:03 volumio sudo[744]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 10:29:03 volumio sudo[744]: pam_unix(sudo:session): session closed for user root Jun 01 10:29:04 volumio wireless.js[451]: trying... Jun 01 10:29:04 volumio sudo[747]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 01 10:29:04 volumio sudo[747]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 10:29:04 volumio sudo[747]: pam_unix(sudo:session): session closed for user root Jun 01 10:29:05 volumio wpa_supplicant[668]: wlan0: Trying to associate with SSID 'HL-NETWORK' Jun 01 10:29:05 volumio wireless.js[451]: trying... Jun 01 10:29:05 volumio sudo[750]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 01 10:29:05 volumio sudo[750]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 10:29:05 volumio sudo[750]: pam_unix(sudo:session): session closed for user root Jun 01 10:29:06 volumio wireless.js[451]: trying... Jun 01 10:29:06 volumio sudo[753]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 01 10:29:06 volumio sudo[753]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 10:29:06 volumio sudo[753]: pam_unix(sudo:session): session closed for user root Jun 01 10:29:07 volumio wireless.js[451]: trying... Jun 01 10:29:07 volumio sudo[756]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 01 10:29:07 volumio sudo[756]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 10:29:07 volumio sudo[756]: pam_unix(sudo:session): session closed for user root Jun 01 10:29:07 volumio wpa_supplicant[668]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Jun 01 10:29:08 volumio wireless.js[451]: trying... Jun 01 10:29:08 volumio sudo[759]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 01 10:29:08 volumio sudo[759]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 10:29:08 volumio sudo[759]: pam_unix(sudo:session): session closed for user root Jun 01 10:29:09 volumio wireless.js[451]: trying... Jun 01 10:29:09 volumio sudo[762]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 01 10:29:09 volumio sudo[762]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 10:29:09 volumio sudo[762]: pam_unix(sudo:session): session closed for user root Jun 01 10:29:10 volumio wireless.js[451]: trying... Jun 01 10:29:10 volumio sudo[765]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 01 10:29:10 volumio sudo[765]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 10:29:10 volumio sudo[765]: pam_unix(sudo:session): session closed for user root Jun 01 10:29:10 volumio wpa_supplicant[668]: wlan0: Trying to associate with SSID 'HL-NETWORK' Jun 01 10:29:10 volumio volumio-remote-updater[448]: [2024-06-01 10:29:10] [info] asio async_connect error: system:111 (Connection refused) Jun 01 10:29:10 volumio volumio-remote-updater[448]: [2024-06-01 10:29:10] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jun 01 10:29:10 volumio volumio-remote-updater[448]: [2024-06-01 10:29:10] [error] handle_connect error: Underlying Transport Error Jun 01 10:29:11 volumio wireless.js[451]: trying... Jun 01 10:29:11 volumio sudo[768]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 01 10:29:11 volumio sudo[768]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 10:29:11 volumio sudo[768]: pam_unix(sudo:session): session closed for user root Jun 01 10:29:12 volumio wireless.js[451]: Overtime, starting plan B Jun 01 10:29:12 volumio systemd[1]: Started Wireless Services. Jun 01 10:29:12 volumio systemd[1]: Started Volumio Backend Module. Jun 01 10:29:12 volumio systemd[1]: Started Volumio Streaming Daemon. Jun 01 10:29:12 volumio volumio-streaming-daemon[773]: ############################ Jun 01 10:29:12 volumio volumio-streaming-daemon[773]: # Volumio Streaming Daemon # Jun 01 10:29:12 volumio volumio-streaming-daemon[773]: # Running on port 7777 # Jun 01 10:29:12 volumio volumio-streaming-daemon[773]: ############################ Jun 01 10:29:12 volumio volumio-streaming-daemon[773]: Environment: production Jun 01 10:29:12 volumio wpa_supplicant[668]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Jun 01 10:29:14 volumio volumio[772]: info: ------------------------------------------- Jun 01 10:29:14 volumio volumio[772]: info: ----- Volumio3 ---- Jun 01 10:29:14 volumio volumio[772]: info: ------------------------------------------- Jun 01 10:29:14 volumio volumio[772]: info: ----- System startup ---- Jun 01 10:29:14 volumio volumio[772]: info: ------------------------------------------- Jun 01 10:29:14 volumio volumio[772]: info: MYVOLUMIO Environment detected Jun 01 10:29:14 volumio volumio[772]: info: Plugin folders cleanup Jun 01 10:29:14 volumio volumio[772]: info: Scanning into folder /volumio/app/plugins/ Jun 01 10:29:14 volumio volumio[772]: info: Scanning category audio_interface Jun 01 10:29:14 volumio volumio[772]: info: Scanning category miscellanea Jun 01 10:29:14 volumio volumio[772]: info: Scanning category music_service Jun 01 10:29:14 volumio volumio[772]: info: Scanning category plugins.json Jun 01 10:29:14 volumio volumio[772]: info: Scanning category system_controller Jun 01 10:29:14 volumio volumio[772]: info: Scanning category user_interface Jun 01 10:29:14 volumio volumio[772]: info: Scanning into folder /data/plugins/ Jun 01 10:29:14 volumio volumio[772]: info: Scanning category miscellanea Jun 01 10:29:14 volumio volumio[772]: info: Scanning category music_service Jun 01 10:29:14 volumio volumio[772]: info: Scanning category user_interface Jun 01 10:29:14 volumio volumio[772]: info: Plugin folders cleanup completed Jun 01 10:29:14 volumio volumio[772]: info: ------------------------------------------- Jun 01 10:29:14 volumio volumio[772]: info: ----- Core plugins startup ---- Jun 01 10:29:14 volumio volumio[772]: info: ------------------------------------------- Jun 01 10:29:14 volumio volumio[772]: info: Loading plugins from folder /volumio/app/plugins/ Jun 01 10:29:14 volumio volumio[772]: info: Adding plugin upnp to MyMusic Plugins Jun 01 10:29:14 volumio volumio[772]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 01 10:29:14 volumio volumio[772]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 01 10:29:14 volumio volumio[772]: info: Loading plugins from folder /data/plugins/ Jun 01 10:29:15 volumio volumio[772]: info: Loading plugin "system"... Jun 01 10:29:15 volumio volumio[772]: info: Loading plugin "appearance"... Jun 01 10:29:15 volumio wpa_supplicant[668]: wlan0: Trying to associate with SSID 'HL-NETWORK' Jun 01 10:29:15 volumio volumio[772]: info: Loading plugin "network"... Jun 01 10:29:16 volumio volumio[772]: info: Refreshing Cached IP Addresses Jun 01 10:29:16 volumio sudo[796]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 01 10:29:16 volumio sudo[796]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 10:29:16 volumio sudo[796]: pam_unix(sudo:session): session closed for user root Jun 01 10:29:16 volumio sudo[798]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 01 10:29:16 volumio volumio[772]: info: Loading plugin "peppy_screensaver"... Jun 01 10:29:16 volumio sudo[798]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 10:29:16 volumio sudo[798]: pam_unix(sudo:session): session closed for user root Jun 01 10:29:16 volumio sudo[801]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 01 10:29:16 volumio sudo[801]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 10:29:16 volumio sudo[801]: pam_unix(sudo:session): session closed for user root Jun 01 10:29:16 volumio kernel: ieee80211 phy0: brcmf_cfg80211_scan: Connecting: status (3) Jun 01 10:29:16 volumio systemd[1]: systemd-fsckd.service: Succeeded. Jun 01 10:29:16 volumio volumio[772]: info: Applying required configuration parameters for plugin peppy_screensaver Jun 01 10:29:16 volumio volumio[772]: info: Loading plugin "services"... Jun 01 10:29:16 volumio volumio[772]: info: Loading plugin "alsa_controller"... Jun 01 10:29:16 volumio volumio[772]: info: Loading plugin "wizard"... Jun 01 10:29:16 volumio volumio[772]: info: Loading plugin "volumio_command_line_client"... Jun 01 10:29:16 volumio volumio[772]: info: Loading plugin "upnp"... Jun 01 10:29:16 volumio volumio[772]: info: [1717237756796] Starting Upmpd Daemon Jun 01 10:29:16 volumio volumio[772]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 01 10:29:16 volumio volumio[772]: info: Loading plugin "my_music"... Jun 01 10:29:16 volumio volumio[772]: info: Loading plugin "mpd"... Jun 01 10:29:17 volumio volumio[772]: info: Creating MPD Configuration file Jun 01 10:29:17 volumio volumio[772]: info: Loading plugin "upnp_browser"... Jun 01 10:29:17 volumio sudo[809]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 01 10:29:17 volumio sudo[809]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 10:29:17 volumio sudo[809]: pam_unix(sudo:session): session closed for user root Jun 01 10:29:17 volumio sudo[811]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 01 10:29:17 volumio sudo[811]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 10:29:17 volumio systemd[1]: Stopping Music Player Daemon... Jun 01 10:29:17 volumio sh[367]: timed out Jun 01 10:29:17 volumio dhcpcd[418]: timed out Jun 01 10:29:17 volumio sh[367]: dhcpcd exited Jun 01 10:29:17 volumio dhcpcd[418]: dhcpcd exited Jun 01 10:29:17 volumio sh[367]: ifup: failed to bring up eth0 Jun 01 10:29:17 volumio systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Jun 01 10:29:17 volumio systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Jun 01 10:29:17 volumio systemd[1]: mpd.service: Succeeded. Jun 01 10:29:17 volumio systemd[1]: Stopped Music Player Daemon. Jun 01 10:29:17 volumio systemd[1]: Starting Music Player Daemon... Jun 01 10:29:17 volumio volumio[772]: info: Loading plugin "networkfs"... Jun 01 10:29:17 volumio volumio[772]: info: Starting Udev Watcher for removable devices Jun 01 10:29:17 volumio sudo[820]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=hubert.lefranc@gmail.com,password=,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=1.0,iocharset=utf8 //192.168.1.254/sdd02\audio /mnt/NAS/sdd02 Jun 01 10:29:17 volumio sudo[820]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 10:29:17 volumio kernel: FS-Cache: Netfs 'cifs' registered for caching Jun 01 10:29:17 volumio kernel: Key type cifs.spnego registered Jun 01 10:29:17 volumio kernel: Key type cifs.idmap registered Jun 01 10:29:17 volumio sudo[820]: pam_unix(sudo:session): session closed for user root Jun 01 10:29:17 volumio kernel: CIFS: Attempting to mount //192.168.1.254/sdd02\audio Jun 01 10:29:17 volumio kernel: CIFS VFS: Error connecting to socket. Aborting operation. Jun 01 10:29:17 volumio kernel: CIFS VFS: cifs_mount failed w/return code = -2 Jun 01 10:29:18 volumio volumio[772]: info: Ignoring mount for partition: boot Jun 01 10:29:18 volumio volumio[772]: info: Ignoring mount for partition: volumio Jun 01 10:29:18 volumio volumio[772]: info: Ignoring mount for partition: volumio_data Jun 01 10:29:18 volumio volumio[772]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 01 10:29:18 volumio volumio[772]: info: Loading plugin "alarm-clock"... Jun 01 10:29:18 volumio mpd[817]: Jun 01 10:29 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 01 10:29:18 volumio mpd[817]: Jun 01 10:29 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Jun 01 10:29:18 volumio mpd[817]: Jun 01 10:29 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Jun 01 10:29:18 volumio systemd[1]: Started Music Player Daemon. Jun 01 10:29:18 volumio sudo[811]: pam_unix(sudo:session): session closed for user root Jun 01 10:29:18 volumio volumio[772]: info: Loading plugin "airplay_emulation"... Jun 01 10:29:18 volumio volumio[772]: info: Starting Shairport Sync Jun 01 10:29:18 volumio volumio[772]: info: Loading plugin "last_100"... Jun 01 10:29:18 volumio volumio[772]: info: Loading plugin "webradio"... Jun 01 10:29:18 volumio volumio[772]: info: Loading plugin "i2s_dacs"... Jun 01 10:29:18 volumio volumio[772]: info: Loading plugin "volumiodiscovery"... Jun 01 10:29:18 volumio volumio[772]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 01 10:29:18 volumio node[772]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 01 10:29:18 volumio volumio[772]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 01 10:29:18 volumio node[772]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 01 10:29:18 volumio volumio[772]: *** WARNING *** For more information see Jun 01 10:29:18 volumio node[772]: *** WARNING *** For more information see Jun 01 10:29:18 volumio volumio[772]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 01 10:29:18 volumio node[772]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 01 10:29:18 volumio volumio[772]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 01 10:29:18 volumio node[772]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 01 10:29:18 volumio volumio[772]: *** WARNING *** For more information see Jun 01 10:29:18 volumio node[772]: *** WARNING *** For more information see Jun 01 10:29:18 volumio volumio[772]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 01 10:29:18 volumio volumio[772]: info: Discovery: Started advertising with name: Volumio Jun 01 10:29:18 volumio volumio[772]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 01 10:29:18 volumio volumio[772]: info: Loading plugin "now_playing"... Jun 01 10:29:18 volumio wpa_supplicant[668]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Jun 01 10:29:18 volumio volumio[772]: info: Loading plugin "outputs"... Jun 01 10:29:18 volumio volumio[772]: info: Loading plugin "albumart"... Jun 01 10:29:18 volumio volumio[772]: info: Plugin example_plugin is not enabled Jun 01 10:29:18 volumio volumio[772]: info: Loading plugin "inputs"... Jun 01 10:29:18 volumio volumio[772]: info: Loading plugin "updater_comm"... Jun 01 10:29:19 volumio volumio[772]: info: Plugin mpdemulation is not enabled Jun 01 10:29:19 volumio volumio[772]: info: Loading plugin "rest_api"... Jun 01 10:29:19 volumio volumio[772]: info: Loading plugin "websocket"... Jun 01 10:29:19 volumio volumio[772]: info: Plugin spop is not enabled Jun 01 10:29:19 volumio volumio[772]: info: Loading plugin "Systeminfo"... Jun 01 10:29:19 volumio volumio[772]: Forking 3 albumart workers Jun 01 10:29:19 volumio volumio[772]: info: Loading plugin "touch_display"... Jun 01 10:29:19 volumio volumio[772]: Starting albumart workers Jun 01 10:29:19 volumio volumio[772]: Starting albumart workers Jun 01 10:29:19 volumio volumio[772]: Starting albumart workers Jun 01 10:29:20 volumio volumio[772]: info: Applying required configuration parameters for plugin touch_display Jun 01 10:29:20 volumio volumio[772]: info: Loading i18n strings for locale en Jun 01 10:29:20 volumio volumio[772]: Updating browse sources language Jun 01 10:29:20 volumio volumio[772]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 01 10:29:20 volumio volumio[772]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 01 10:29:20 volumio volumio[772]: info: CoreCommandRouter::initPlayerControls Jun 01 10:29:20 volumio volumio[772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 10:29:20 volumio volumio[772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 10:29:20 volumio volumio[772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 10:29:20 volumio volumio[772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 10:29:20 volumio volumio[772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 10:29:20 volumio volumio[772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 10:29:20 volumio volumio[772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 10:29:20 volumio volumio[772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 10:29:20 volumio volumio[772]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 01 10:29:20 volumio volumio[772]: Express server listening on port 3000 Jun 01 10:29:20 volumio volumio[772]: [Metrics] WebUI: 6s 899.37ms Jun 01 10:29:20 volumio volumio[772]: info: CoreStateMachine::resetVolumioState Jun 01 10:29:20 volumio volumio[772]: info: CoreStateMachine::getcurrentVolume Jun 01 10:29:20 volumio volumio[772]: info: CoreCommandRouter::volumioRetrievevolume Jun 01 10:29:20 volumio volumio[772]: info: Cannot mount NAS sdd02 at system boot, trial number 1 ,retrying in 5 seconds Jun 01 10:29:20 volumio volumio[772]: info: MPD Permissions set Jun 01 10:29:20 volumio volumio[772]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Jun 01 10:29:20 volumio volumio[772]: wlan0 Interface doesn't support scanning : Resource temporarily unavailable Jun 01 10:29:20 volumio volumio[772]: info: Cannot use regular scanning, forcing with ap-force Jun 01 10:29:20 volumio sudo[896]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Jun 01 10:29:20 volumio sudo[896]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 10:29:20 volumio sudo[896]: pam_unix(sudo:session): session closed for user root Jun 01 10:29:20 volumio volumio[772]: command failed: Device or resource busy (-16) Jun 01 10:29:20 volumio volumio[772]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Jun 01 10:29:20 volumio volumio[772]: command failed: Device or resource busy (-16) Jun 01 10:29:20 volumio volumio[772]: info: MPD running with PID817 Jun 01 10:29:20 volumio volumio[772]: ,establishing connection Jun 01 10:29:20 volumio volumio[772]: error: MPD error: The expression evaluated to a falsy value: Jun 01 10:29:20 volumio volumio[772]: assert.ok(self.idling) Jun 01 10:29:20 volumio volumio[772]: error: MPD error: The expression evaluated to a falsy value: Jun 01 10:29:20 volumio volumio[772]: assert.ok(self.idling) Jun 01 10:29:20 volumio volumio[772]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 1 Jun 01 10:29:20 volumio volumio[772]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 Jun 01 10:29:20 volumio volumio[772]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 10:29:20 volumio volumio[772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 10:29:20 volumio volumio[772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 10:29:20 volumio volumio[772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 10:29:20 volumio volumio[772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 10:29:20 volumio volumio[772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 10:29:20 volumio volumio[772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 10:29:20 volumio volumio[772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 10:29:20 volumio volumio[772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 10:29:20 volumio volumio[772]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 10:29:20 volumio volumio[772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 10:29:20 volumio volumio[772]: info: Reloading queue from file Jun 01 10:29:20 volumio volumio[772]: info: VolumeController:: Volume=54 Mute =false Jun 01 10:29:20 volumio volumio[772]: info: CoreStateMachine::pushState Jun 01 10:29:20 volumio volumio[772]: info: CorePlayQueue::getTrack 0 Jun 01 10:29:20 volumio volumio[772]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 10:29:20 volumio volumio[772]: info: CoreCommandRouter::volumioPushState Jun 01 10:29:20 volumio volumio[772]: info: CoreStateMachine::updateTrackBlock Jun 01 10:29:20 volumio volumio[772]: info: CorePlayQueue::getTrackBlock Jun 01 10:29:20 volumio volumio[772]: info: CoreCommandRouter::volumioRetrievevolume Jun 01 10:29:20 volumio volumio[772]: info: Setting Device type: Raspberry PI Jun 01 10:29:20 volumio volumio[772]: info: CoreStateMachine::setRepeat true single undefined Jun 01 10:29:20 volumio volumio[772]: info: CoreStateMachine::pushState Jun 01 10:29:20 volumio volumio[772]: info: CorePlayQueue::getTrack 0 Jun 01 10:29:20 volumio volumio[772]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 10:29:20 volumio volumio[772]: info: CoreCommandRouter::volumioPushState Jun 01 10:29:20 volumio volumio[772]: info: CoreStateMachine::setRandom false Jun 01 10:29:20 volumio volumio[772]: info: CoreStateMachine::pushState Jun 01 10:29:20 volumio volumio[772]: info: CorePlayQueue::getTrack 0 Jun 01 10:29:20 volumio volumio[772]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 10:29:20 volumio volumio[772]: info: CoreCommandRouter::volumioPushState Jun 01 10:29:20 volumio volumio[772]: error: updateQueue error: null Jun 01 10:29:20 volumio volumio[772]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jun 01 10:29:20 volumio volumio[772]: info: Completed loading Core Plugins Jun 01 10:29:20 volumio volumio[772]: info: Preparing to generate the ALSA configuration file Jun 01 10:29:20 volumio volumio[772]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Jun 01 10:29:20 volumio volumio[772]: info: Reading ALSA contributions from plugins. Jun 01 10:29:20 volumio volumio[772]: info: VolumeController:: Volume=54 Mute =false Jun 01 10:29:20 volumio volumio[772]: info: CoreStateMachine::pushState Jun 01 10:29:20 volumio volumio[772]: info: CorePlayQueue::getTrack 0 Jun 01 10:29:20 volumio volumio[772]: info: CoreCommandRouter::volumioPushState Jun 01 10:29:20 volumio volumio[772]: info: Asound.conf file unchanged, so no further update is needed Jun 01 10:29:20 volumio volumio[772]: info: Output device has changed, restarting MPD Jun 01 10:29:20 volumio sudo[910]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 01 10:29:20 volumio sudo[910]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 10:29:20 volumio volumio[772]: info: Output device has changed, restarting Shairport Sync Jun 01 10:29:20 volumio volumio[772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 10:29:20 volumio volumio[772]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 10:29:20 volumio sudo[910]: pam_unix(sudo:session): session closed for user root Jun 01 10:29:20 volumio sudo[912]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 01 10:29:20 volumio sudo[912]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 10:29:20 volumio systemd[1]: Stopping Music Player Daemon... Jun 01 10:29:20 volumio volumio[772]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 01 10:29:20 volumio volumio[772]: info: ___________ START PLUGINS ___________ Jun 01 10:29:20 volumio volumio[772]: info: Loading i18n strings for locale en Jun 01 10:29:20 volumio systemd[1]: mpd.service: Succeeded. Jun 01 10:29:20 volumio systemd[1]: Stopped Music Player Daemon. Jun 01 10:29:20 volumio systemd[1]: Starting Music Player Daemon... Jun 01 10:29:20 volumio volumio[772]: info: /tmp/myfifo created Jun 01 10:29:20 volumio volumio-remote-updater[448]: [2024-06-01 10:29:20] [connect] Successful connection Jun 01 10:29:20 volumio sudo[922]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd-dummy index=7 pcm_substreams=1 Jun 01 10:29:20 volumio sudo[922]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 10:29:20 volumio sudo[922]: pam_unix(sudo:session): session closed for user root Jun 01 10:29:20 volumio volumio[772]: info: snd-dummy loaded Jun 01 10:29:20 volumio volumio[772]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 10:29:21 volumio volumio[772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 10:29:21 volumio volumio[772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 10:29:21 volumio volumio[772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jun 01 10:29:21 volumio volumio[772]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 01 10:29:21 volumio volumio[772]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 01 10:29:21 volumio volumio[772]: info: [1717237761021] CoreMusicLibrary::Adding element Media Servers Jun 01 10:29:21 volumio volumio[772]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 01 10:29:21 volumio volumio[772]: Cannot find translation for sourceMedia Servers Jun 01 10:29:21 volumio volumio[772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 10:29:21 volumio volumio[772]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 10:29:21 volumio sudo[930]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount --bind /data/configuration/miscellanea/peppy_screensaver/mpd.conf.tmpl /volumio/app/plugins/music_service/mpd/mpd.conf.tmpl Jun 01 10:29:21 volumio sudo[930]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 10:29:21 volumio sudo[930]: pam_unix(sudo:session): session closed for user root Jun 01 10:29:21 volumio volumio[772]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 01 10:29:21 volumio volumio[772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 10:29:21 volumio volumio[772]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 10:29:21 volumio volumio[772]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 01 10:29:21 volumio volumio[772]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 01 10:29:21 volumio volumio[772]: info: [1717237761123] CoreMusicLibrary::Adding element Last_100 Jun 01 10:29:21 volumio volumio[772]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 01 10:29:21 volumio volumio[772]: Cannot find translation for sourceMedia Servers Jun 01 10:29:21 volumio volumio[772]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 01 10:29:21 volumio volumio[772]: info: [1717237761126] CoreMusicLibrary::Adding element Webradio Jun 01 10:29:21 volumio volumio[772]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 01 10:29:21 volumio volumio[772]: Cannot find translation for sourceMedia Servers Jun 01 10:29:21 volumio volumio[772]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 01 10:29:21 volumio volumio[772]: info: Loading i18n strings for locale en Jun 01 10:29:21 volumio volumio[772]: info: Volumio Calling Home Jun 01 10:29:21 volumio sudo[940]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Jun 01 10:29:21 volumio sudo[940]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 10:29:21 volumio volumio[772]: info: Preparing to generate the ALSA configuration file Jun 01 10:29:21 volumio systemd[1]: Reloading. Jun 01 10:29:21 volumio volumio[772]: info: [now-playing-app] App is listening on port 4004. Jun 01 10:29:21 volumio volumio[772]: Unhandled rejection Error: No sockets available, cannot start. Jun 01 10:29:21 volumio volumio[772]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Jun 01 10:29:21 volumio volumio[772]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Jun 01 10:29:21 volumio volumio[772]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Jun 01 10:29:21 volumio volumio[772]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Jun 01 10:29:21 volumio volumio[772]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Jun 01 10:29:21 volumio volumio[772]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Jun 01 10:29:21 volumio volumio[772]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Jun 01 10:29:21 volumio volumio[772]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Jun 01 10:29:21 volumio volumio[772]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Jun 01 10:29:21 volumio volumio[772]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:392:24) Jun 01 10:29:21 volumio volumio[772]: at HashMap. (/volumio/app/pluginmanager.js:490:31) Jun 01 10:29:21 volumio volumio[772]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Jun 01 10:29:21 volumio volumio[772]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Jun 01 10:29:21 volumio volumio[772]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:489:20) Jun 01 10:29:21 volumio volumio[772]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Jun 01 10:29:21 volumio volumio[772]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jun 01 10:29:21 volumio volumio[772]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Jun 01 10:29:21 volumio volumio[772]: info: Reading ALSA contributions from plugins. Jun 01 10:29:21 volumio volumio[772]: info: CoreCommandRouter::volumioGetState Jun 01 10:29:21 volumio volumio[772]: info: CorePlayQueue::getTrack 0 Jun 01 10:29:21 volumio volumio[772]: info: MPD Permissions set Jun 01 10:29:21 volumio volumio-remote-updater[448]: [2024-06-01 10:29:21] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1717237760 101 Jun 01 10:29:21 volumio volumio[772]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 3 Jun 01 10:29:21 volumio volumio[772]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 4 Jun 01 10:29:21 volumio volumio[772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 10:29:21 volumio volumio[772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 10:29:21 volumio volumio[772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 10:29:21 volumio volumio[772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 10:29:21 volumio volumio[772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 10:29:21 volumio volumio[772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 10:29:21 volumio volumio[772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 10:29:21 volumio volumio[772]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 10:29:21 volumio volumio[772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 10:29:21 volumio volumio[772]: info: Starting Shairport Sync Jun 01 10:29:21 volumio volumio[772]: info: Starting Shairport Sync Jun 01 10:29:21 volumio sudo[965]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 01 10:29:21 volumio sudo[965]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 10:29:21 volumio volumio[772]: info: Starting Shairport Sync Jun 01 10:29:21 volumio sudo[968]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 01 10:29:21 volumio sudo[968]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 10:29:21 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Jun 01 10:29:21 volumio sudo[971]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 01 10:29:21 volumio sudo[971]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 10:29:21 volumio volumio[772]: info: Asound.conf file unchanged, so no further update is needed Jun 01 10:29:21 volumio volumio[772]: info: Output device has changed, restarting MPD Jun 01 10:29:21 volumio volumio[772]: info: Output device has changed, restarting Shairport Sync Jun 01 10:29:21 volumio volumio[772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 10:29:21 volumio sudo[974]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 01 10:29:21 volumio volumio[772]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 10:29:21 volumio sudo[974]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 10:29:21 volumio sudo[974]: pam_unix(sudo:session): session closed for user root Jun 01 10:29:21 volumio sudo[978]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 01 10:29:21 volumio sudo[978]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 10:29:21 volumio volumio[772]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 01 10:29:21 volumio volumio[772]: info: MPD Permissions set Jun 01 10:29:21 volumio volumio[772]: info: CoreCommandRouter::volumioGetState Jun 01 10:29:21 volumio volumio[772]: info: CorePlayQueue::getTrack 0 Jun 01 10:29:21 volumio volumio[772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 10:29:21 volumio volumio[772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 10:29:21 volumio volumio[772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 10:29:21 volumio volumio[772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 10:29:21 volumio volumio[772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 10:29:21 volumio volumio[772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 10:29:21 volumio volumio[772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 10:29:21 volumio volumio[772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 10:29:21 volumio volumio[772]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 10:29:21 volumio volumio[772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 10:29:21 volumio mpd[932]: Jun 01 10:29 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 01 10:29:21 volumio volumio[772]: info: Starting Shairport Sync Jun 01 10:29:21 volumio systemd[1]: getty@tty1.service: Current command vanished from the unit file, execution of the command list won't be resumed. Jun 01 10:29:21 volumio sudo[987]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 01 10:29:21 volumio sudo[987]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 10:29:21 volumio mpd[932]: Jun 01 10:29 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Jun 01 10:29:21 volumio mpd[932]: Jun 01 10:29 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Jun 01 10:29:21 volumio systemd[1]: Started Music Player Daemon. Jun 01 10:29:21 volumio sudo[912]: pam_unix(sudo:session): session closed for user root Jun 01 10:29:21 volumio sudo[940]: pam_unix(sudo:session): session closed for user root Jun 01 10:29:21 volumio volumio[772]: info: touch_display: systemctl daemon-reload succeeded. Jun 01 10:29:21 volumio volumio[772]: error: updateQueue error: null Jun 01 10:29:21 volumio sudo[992]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Jun 01 10:29:21 volumio sudo[992]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 10:29:21 volumio systemd[1]: Started Volumio Kiosk. Jun 01 10:29:21 volumio systemd[1]: Stopping Music Player Daemon... Jun 01 10:29:21 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 01 10:29:21 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 01 10:29:21 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 01 10:29:21 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 01 10:29:21 volumio sudo[971]: pam_unix(sudo:session): session closed for user root Jun 01 10:29:21 volumio sudo[987]: pam_unix(sudo:session): session closed for user root Jun 01 10:29:21 volumio sudo[965]: pam_unix(sudo:session): session closed for user root Jun 01 10:29:21 volumio volumio[772]: info: Shairport-Sync Started Jun 01 10:29:21 volumio sudo[968]: pam_unix(sudo:session): session closed for user root Jun 01 10:29:21 volumio volumio[772]: Error adding Membership: Error: addMembership EINVAL Jun 01 10:29:21 volumio volumio[772]: info: Shairport-Sync Started Jun 01 10:29:21 volumio volumio[772]: info: Shairport-Sync Started Jun 01 10:29:21 volumio sudo[992]: pam_unix(sudo:session): session closed for user root Jun 01 10:29:21 volumio systemd[1]: mpd.service: Succeeded. Jun 01 10:29:21 volumio systemd[1]: Stopped Music Player Daemon. Jun 01 10:29:21 volumio volumio[772]: info: Shairport-Sync Started Jun 01 10:29:21 volumio volumio[772]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Jun 01 10:29:21 volumio volumio[772]: info: touch_display: Volumio Kiosk started Jun 01 10:29:21 volumio volumio[772]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 01 10:29:21 volumio volumio[772]: info: CoreCommandRouter::volumioGetState Jun 01 10:29:21 volumio volumio[772]: info: CorePlayQueue::getTrack 0 Jun 01 10:29:22 volumio systemd[1]: Starting Music Player Daemon... Jun 01 10:29:22 volumio wpa_supplicant[668]: wlan0: Trying to associate with SSID 'HL-NETWORK' Jun 01 10:29:22 volumio volumio[772]: info: Completed starting Core Plugins Jun 01 10:29:22 volumio volumio[772]: info: ------------------------------------------- Jun 01 10:29:22 volumio volumio[772]: info: ----- MyVolumio plugins startup ---- Jun 01 10:29:22 volumio volumio[772]: info: ------------------------------------------- Jun 01 10:29:22 volumio volumio[772]: info: [MyVolumio PluginManager] Fetching plans data.... Jun 01 10:29:22 volumio volumio[772]: info: CoreCommandRouter::volumioGetState Jun 01 10:29:22 volumio volumio[772]: info: CorePlayQueue::getTrack 0 Jun 01 10:29:22 volumio volumio[772]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Jun 01 10:29:22 volumio volumio[772]: info: touch_display: Using /boot/config.txt instead of /boot/userconfig.txt. Jun 01 10:29:22 volumio volumio[772]: info: touch_display: Un-/commenting gpu_mem settings in /boot/config.txt. Jun 01 10:29:22 volumio volumio[772]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X Jun 01 10:29:22 volumio sudo[1024]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sed -i -e /Option "TransformationMatrix"/d /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Jun 01 10:29:22 volumio sudo[1024]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 10:29:22 volumio sudo[1024]: pam_unix(sudo:session): session closed for user root Jun 01 10:29:22 volumio volumio[772]: info: touch_display: Touchscreen transformation matrix removed. Jun 01 10:29:22 volumio volumio[772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 10:29:22 volumio volumio[772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 10:29:22 volumio volumio[772]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Jun 01 10:29:22 volumio startx[994]: X.Org X Server 1.20.4 Jun 01 10:29:22 volumio startx[994]: X Protocol Version 11, Revision 0 Jun 01 10:29:22 volumio startx[994]: Build Operating System: Linux 5.10.17-v8+ aarch64 Raspbian Jun 01 10:29:22 volumio startx[994]: Current Operating System: Linux volumio 5.4.83-v7l+ #1379 SMP Mon Dec 14 13:11:54 GMT 2020 armv7l Jun 01 10:29:22 volumio startx[994]: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_compat_alsa=0 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=1024 bcm2708_fb.fbheight=600 bcm2708_fb.fbswap=1 smsc95xx.macaddr=DC:A6:32:C9:7B:7D vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyS0,115200 console=tty1 imgpart=/dev/mmcblk0p2 imgfile=/volumio_current.sqsh rootwait bootdelay=5 elevator=noop logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd-bcm2835.enable_compat_alsa=0 snd_bcm2835.enable_hdmi=1 loglevel=0 Jun 01 10:29:22 volumio startx[994]: Build Date: 19 April 2021 03:23:36PM Jun 01 10:29:22 volumio startx[994]: xorg-server 2:1.20.4-1+rpt3+deb10u3 (https://www.debian.org/support) Jun 01 10:29:22 volumio startx[994]: Current version of pixman: 0.36.0 Jun 01 10:29:22 volumio startx[994]: Before reporting problems, check http://wiki.x.org Jun 01 10:29:22 volumio startx[994]: to make sure that you have the latest version. Jun 01 10:29:22 volumio startx[994]: Markers: (--) probed, (**) from config file, (==) default setting, Jun 01 10:29:22 volumio startx[994]: (++) from command line, (!!) notice, (II) informational, Jun 01 10:29:22 volumio startx[994]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Jun 01 10:29:22 volumio startx[994]: (==) Log file: "/var/log/Xorg.0.log", Time: Sat Jun 1 10:29:22 2024 Jun 01 10:29:22 volumio startx[994]: (==) Using config directory: "/etc/X11/xorg.conf.d" Jun 01 10:29:22 volumio startx[994]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Jun 01 10:29:22 volumio volumio[772]: info: CoreCommandRouter::volumioGetState Jun 01 10:29:22 volumio volumio[772]: info: CorePlayQueue::getTrack 0 Jun 01 10:29:22 volumio volumio[772]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Jun 01 10:29:22 volumio mpd[1027]: Jun 01 10:29 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 01 10:29:22 volumio mpd[1027]: Jun 01 10:29 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Jun 01 10:29:22 volumio mpd[1027]: Jun 01 10:29 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Jun 01 10:29:22 volumio systemd[1]: Started Music Player Daemon. Jun 01 10:29:22 volumio sudo[978]: pam_unix(sudo:session): session closed for user root Jun 01 10:29:22 volumio volumio[772]: error: updateQueue error: null Jun 01 10:29:23 volumio startx[994]: [1B blob data] Jun 01 10:29:23 volumio volumio[772]: info: touch_display: Setting screensaver timeout to 60 seconds. Jun 01 10:29:23 volumio volumio[772]: info: CoreCommandRouter::volumioGetState Jun 01 10:29:23 volumio volumio[772]: info: CorePlayQueue::getTrack 0 Jun 01 10:29:23 volumio volumio[772]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Jun 01 10:29:23 volumio volumio[772]: info: touch_display: Setting screensaver timeout to 60 seconds. Jun 01 10:29:23 volumio volumio[772]: info: CoreCommandRouter::volumioGetState Jun 01 10:29:23 volumio volumio[772]: info: CorePlayQueue::getTrack 0 Jun 01 10:29:23 volumio volumio[772]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Jun 01 10:29:23 volumio startx[994]: waiting for X server to begin accepting connections . Jun 01 10:29:23 volumio volumio[772]: info: touch_display: Setting screensaver timeout to 60 seconds. Jun 01 10:29:24 volumio systemd[1]: systemd-hostnamed.service: Succeeded. Jun 01 10:29:24 volumio wpa_supplicant[668]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Jun 01 10:29:24 volumio wpa_supplicant[668]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=0 ssid="HL-NETWORK" auth_failures=1 duration=10 reason=CONN_FAILED Jun 01 10:29:25 volumio sudo[1148]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=hubert.lefranc@gmail.com,password=,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=1.0,iocharset=utf8 //192.168.1.254/sdd02\audio /mnt/NAS/sdd02 Jun 01 10:29:25 volumio sudo[1148]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 10:29:25 volumio kernel: CIFS: Attempting to mount //192.168.1.254/sdd02\audio Jun 01 10:29:25 volumio kernel: CIFS VFS: Error connecting to socket. Aborting operation. Jun 01 10:29:25 volumio kernel: CIFS VFS: cifs_mount failed w/return code = -2 Jun 01 10:29:25 volumio sudo[1148]: pam_unix(sudo:session): session closed for user root Jun 01 10:29:25 volumio volumio[772]: info: Cannot mount NAS sdd02 at system boot, trial number 2 ,retrying in 5 seconds Jun 01 10:29:25 volumio volumio[772]: error: MPD error: The expression evaluated to a falsy value: Jun 01 10:29:25 volumio volumio[772]: assert.ok(self.idling) Jun 01 10:29:25 volumio volumio[772]: error: updateQueue error: null Jun 01 10:29:26 volumio sudo[1162]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 01 10:29:26 volumio sudo[1162]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 10:29:26 volumio sudo[1162]: pam_unix(sudo:session): session closed for user root Jun 01 10:29:26 volumio sudo[1164]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 01 10:29:26 volumio sudo[1164]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 10:29:26 volumio sudo[1164]: pam_unix(sudo:session): session closed for user root Jun 01 10:29:26 volumio sudo[1168]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jun 01 10:29:26 volumio sudo[1168]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 10:29:26 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Jun 01 10:29:26 volumio sudo[1168]: pam_unix(sudo:session): session closed for user root Jun 01 10:29:26 volumio volumio[772]: info: Upmpdcli Daemon Started Jun 01 10:29:30 volumio sudo[1258]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=hubert.lefranc@gmail.com,password=,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=1.0,iocharset=utf8 //192.168.1.254/sdd02\audio /mnt/NAS/sdd02 Jun 01 10:29:30 volumio sudo[1258]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 10:29:30 volumio sudo[1258]: pam_unix(sudo:session): session closed for user root Jun 01 10:29:30 volumio volumio[772]: info: Cannot mount NAS sdd02 at system boot, trial number 3 ,retrying in 5 seconds Jun 01 10:29:30 volumio kernel: CIFS: Attempting to mount //192.168.1.254/sdd02\audio Jun 01 10:29:30 volumio kernel: CIFS VFS: Error connecting to socket. Aborting operation. Jun 01 10:29:30 volumio kernel: CIFS VFS: cifs_mount failed w/return code = -2 Jun 01 10:29:30 volumio volumio[772]: error: updateQueue error: null Jun 01 10:29:31 volumio volumio[772]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=3.173&uuid=00073bab4323721709315160cb043cbe" http://updates.volumio.org/downloader-v1/track-device Jun 01 10:29:31 volumio volumio[772]: % Total % Received % Xferd Average Speed Time Time Time Current Jun 01 10:29:31 volumio volumio[772]: Dload Upload Total Spent Left Speed Jun 01 10:29:31 volumio volumio[772]: [132B blob data] Jun 01 10:29:31 volumio volumio[772]: retrying in 5 seconds, trial 0 Jun 01 10:29:31 volumio volumio[772]: info: Volumio Calling Home Jun 01 10:29:32 volumio wpa_supplicant[668]: wlan0: Trying to associate with SSID 'HL-NETWORK' Jun 01 10:29:33 volumio volumio[772]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Total Clients: 5 Jun 01 10:29:34 volumio wpa_supplicant[668]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Jun 01 10:29:35 volumio volumio[772]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Total Clients: 6 Jun 01 10:29:35 volumio sudo[1481]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=hubert.lefranc@gmail.com,password=,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=1.0,iocharset=utf8 //192.168.1.254/sdd02\audio /mnt/NAS/sdd02 Jun 01 10:29:35 volumio sudo[1481]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 10:29:35 volumio kernel: CIFS: Attempting to mount //192.168.1.254/sdd02\audio Jun 01 10:29:35 volumio kernel: CIFS VFS: Error connecting to socket. Aborting operation. Jun 01 10:29:35 volumio kernel: CIFS VFS: cifs_mount failed w/return code = -2 Jun 01 10:29:35 volumio sudo[1481]: pam_unix(sudo:session): session closed for user root Jun 01 10:29:35 volumio volumio[772]: info: Cannot mount NAS sdd02 at system boot, trial number 4 ,retrying in 5 seconds Jun 01 10:29:36 volumio volumio[772]: info: CoreCommandRouter::volumioGetState Jun 01 10:29:36 volumio volumio[772]: info: CorePlayQueue::getTrack 0 Jun 01 10:29:36 volumio volumio[772]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 01 10:29:36 volumio volumio[772]: info: CoreCommandRouter::volumioGetVisibleSources Jun 01 10:29:36 volumio volumio[772]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 01 10:29:36 volumio volumio[772]: info: Listing playlists Jun 01 10:29:36 volumio volumio[772]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 01 10:29:36 volumio volumio[772]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 01 10:29:36 volumio volumio[772]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 01 10:29:36 volumio volumio[772]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 01 10:29:38 volumio wpa_supplicant[668]: wlan0: CTRL-EVENT-SSID-REENABLED id=0 ssid="HL-NETWORK" Jun 01 10:29:38 volumio wpa_supplicant[668]: wlan0: Trying to associate with SSID 'HL-NETWORK' Jun 01 10:29:39 volumio volumio[772]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 01 10:29:40 volumio sudo[1490]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=hubert.lefranc@gmail.com,password=,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=1.0,iocharset=utf8 //192.168.1.254/sdd02\audio /mnt/NAS/sdd02 Jun 01 10:29:40 volumio sudo[1490]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 10:29:40 volumio sudo[1490]: pam_unix(sudo:session): session closed for user root Jun 01 10:29:40 volumio volumio[772]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Jun 01 10:29:40 volumio kernel: CIFS: Attempting to mount //192.168.1.254/sdd02\audio Jun 01 10:29:40 volumio kernel: CIFS VFS: Error connecting to socket. Aborting operation. Jun 01 10:29:40 volumio kernel: CIFS VFS: cifs_mount failed w/return code = -2 Jun 01 10:29:41 volumio wpa_supplicant[668]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Jun 01 10:29:41 volumio wpa_supplicant[668]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=0 ssid="HL-NETWORK" auth_failures=2 duration=23 reason=CONN_FAILED Jun 01 10:29:41 volumio volumio[772]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=3.173&uuid=00073bab4323721709315160cb043cbe" http://updates.volumio.org/downloader-v1/track-device Jun 01 10:29:41 volumio volumio[772]: % Total % Received % Xferd Average Speed Time Time Time Current Jun 01 10:29:41 volumio volumio[772]: Dload Upload Total Spent Left Speed Jun 01 10:29:41 volumio volumio[772]: [132B blob data] Jun 01 10:29:41 volumio volumio[772]: retrying in 5 seconds, trial 1 Jun 01 10:29:41 volumio volumio[772]: info: Volumio Calling Home Jun 01 10:29:42 volumio volumio[772]: error: MyVolumio Plugin failed to start in a timely fashion Jun 01 10:29:42 volumio volumio[772]: info: BOOT COMPLETED Jun 01 10:29:42 volumio volumio[772]: [Metrics] CommandRouter: 27s 971.78ms Jun 01 10:29:42 volumio volumio[772]: info: CoreCommandRouter::volumiosetStartupVolume Jun 01 10:29:42 volumio volumio[772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 10:29:42 volumio volumio[772]: info: VolumeController:: Setting startup Volume 50 Jun 01 10:29:42 volumio volumio[772]: info: VolumeController::SetAlsaVolume50 Jun 01 10:29:42 volumio volumio[772]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 10:29:42 volumio volumio[772]: info: CoreCommandRouter::Close All Modals sent Jun 01 10:29:42 volumio volumio[772]: info: CoreCommandRouter::Close All Modals sent Jun 01 10:29:42 volumio volumio[772]: info: CoreStateMachine::pushState Jun 01 10:29:42 volumio volumio[772]: info: CorePlayQueue::getTrack 0 Jun 01 10:29:42 volumio volumio[772]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 10:29:42 volumio volumio[772]: info: CoreCommandRouter::volumioPushState Jun 01 10:29:43 volumio volumio[772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jun 01 10:29:43 volumio volumio[772]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 01 10:29:43 volumio volumio[772]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jun 01 10:29:48 volumio wpa_supplicant[668]: wlan0: Trying to associate with SSID 'HL-NETWORK' Jun 01 10:29:51 volumio volumio[772]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 01 10:29:51 volumio volumio[772]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 10:29:51 volumio dhcpcd[680]: wlan0: carrier acquired Jun 01 10:29:51 volumio wpa_supplicant[668]: wlan0: Associated with 70:fc:8f:4f:ad:55 Jun 01 10:29:51 volumio wpa_supplicant[668]: wlan0: CTRL-EVENT-CONNECTED - Connection to 70:fc:8f:4f:ad:55 completed [id=1 id_str=] Jun 01 10:29:51 volumio dhcpcd[680]: wlan0: connected to Access Point `HL-NETWORK' Jun 01 10:29:51 volumio wpa_supplicant[668]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jun 01 10:29:51 volumio dhcpcd[680]: DUID 00:01:00:01:29:43:53:00:dc:a6:32:c9:7b:7d Jun 01 10:29:51 volumio dhcpcd[680]: wlan0: IAID 32:c9:7b:7e Jun 01 10:29:51 volumio dhcpcd[680]: wlan0: adding address fe80::4712:f774:b6e8:14d4 Jun 01 10:29:51 volumio dhcpcd[680]: ipv6_addaddr1: Permission denied Jun 01 10:29:51 volumio dhcpcd[680]: wlan0: carrier lost Jun 01 10:29:51 volumio dhcpcd[680]: wlan0: carrier acquired Jun 01 10:29:51 volumio dhcpcd[680]: wlan0: IAID 32:c9:7b:7e Jun 01 10:29:51 volumio dhcpcd[680]: wlan0: soliciting an IPv6 router Jun 01 10:29:51 volumio volumio[772]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=3.173&uuid=00073bab4323721709315160cb043cbe" http://updates.volumio.org/downloader-v1/track-device Jun 01 10:29:51 volumio volumio[772]: % Total % Received % Xferd Average Speed Time Time Time Current Jun 01 10:29:51 volumio volumio[772]: Dload Upload Total Spent Left Speed Jun 01 10:29:51 volumio volumio[772]: [132B blob data] Jun 01 10:29:51 volumio volumio[772]: retrying in 5 seconds, trial 2 Jun 01 10:29:51 volumio volumio[772]: info: Volumio Calling Home Jun 01 10:29:52 volumio dhcpcd[680]: wlan0: soliciting a DHCP lease Jun 01 10:29:52 volumio dhcpcd[680]: wlan0: offered 192.168.1.153 from 192.168.1.254 Jun 01 10:29:52 volumio dhcpcd[680]: wlan0: probing address 192.168.1.153/24 Jun 01 10:29:55 volumio ntpd[614]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Jun 01 10:29:56 volumio ntpd[614]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Jun 01 10:29:56 volumio ntpd[614]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Jun 01 10:29:57 volumio dhcpcd[680]: wlan0: leased 192.168.1.153 for 43200 seconds Jun 01 10:29:57 volumio avahi-daemon[467]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.153. Jun 01 10:29:57 volumio dhcpcd[680]: wlan0: adding route to 192.168.1.0/24 Jun 01 10:29:57 volumio dhcpcd[680]: wlan0: adding default route via 192.168.1.254 Jun 01 10:29:57 volumio avahi-daemon[467]: New relevant interface wlan0.IPv4 for mDNS. Jun 01 10:29:57 volumio avahi-daemon[467]: Registering new address record for 192.168.1.153 on wlan0.IPv4. Jun 01 10:29:58 volumio ntpd[614]: Soliciting pool server 176.137.36.37 Jun 01 10:29:58 volumio volumio[772]: info: Discovery: adding 84fd2f9f-7a69-404b-83d2-7ba5112048de Jun 01 10:29:58 volumio volumio[772]: info: mDNS: Found device Volumio Jun 01 10:29:58 volumio volumio[772]: info: CoreCommandRouter::volumioGetState Jun 01 10:29:58 volumio volumio[772]: info: CorePlayQueue::getTrack 0 Jun 01 10:29:59 volumio nmbd[587]: [2024/06/01 10:29:59.112327, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jun 01 10:29:59 volumio systemd[1]: Started Samba NMB Daemon. Jun 01 10:29:59 volumio nmbd[587]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Jun 01 10:29:59 volumio systemd[1]: Starting Samba Winbind Daemon... Jun 01 10:29:59 volumio winbindd[1540]: [2024/06/01 10:29:59.300720, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Jun 01 10:29:59 volumio winbindd[1540]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Jun 01 10:29:59 volumio winbindd[1540]: [2024/06/01 10:29:59.328545, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jun 01 10:29:59 volumio winbindd[1540]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Jun 01 10:29:59 volumio systemd[1]: Started Samba Winbind Daemon. Jun 01 10:29:59 volumio systemd[1]: Starting Samba SMB Daemon... Jun 01 10:29:59 volumio ntpd[614]: Listen normally on 3 wlan0 192.168.1.153:123 Jun 01 10:29:59 volumio ntpd[614]: new interface(s) found: waking up resolver Jun 01 10:29:59 volumio smbd[1545]: [2024/06/01 10:29:59.722684, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jun 01 10:29:59 volumio systemd[1]: Started Samba SMB Daemon. Jun 01 10:29:59 volumio systemd[1]: Reached target Multi-User System. Jun 01 10:29:59 volumio systemd[1]: Reached target Graphical Interface. Jun 01 10:29:59 volumio smbd[1545]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Jun 01 10:29:59 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Jun 01 10:29:59 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jun 01 10:29:59 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Jun 01 10:29:59 volumio systemd[1]: Startup finished in 10.875s (kernel) + 1min 18.432s (userspace) = 1min 29.308s. Jun 01 10:30:01 volumio volumio[1170]: Generating RSA private key, 4096 bit long modulus (2 primes) Jun 01 10:30:12 volumio volumio[1170]: ...................................................................................................................................++++ Jun 01 10:30:29 volumio volumio[772]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 01 10:30:29 volumio volumio[772]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 01 10:30:29 volumio volumio[772]: info: Discovery: Getting this device information Jun 01 10:30:29 volumio volumio[772]: info: CoreCommandRouter::volumioGetState Jun 01 10:30:29 volumio volumio[772]: info: CorePlayQueue::getTrack 0 Jun 01 10:30:29 volumio volumio[772]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Jun 01 10:30:29 volumio volumio[772]: verbose: New Socket.io Connection to 192.168.1.153:3000 from 192.168.1.95 UA: Dart/3.4 (dart:io) Total Clients: 7 Jun 01 10:30:30 volumio volumio[772]: info: CoreCommandRouter::volumioGetState Jun 01 10:30:30 volumio volumio[772]: info: CorePlayQueue::getTrack 0 Jun 01 10:30:30 volumio volumio[772]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 01 10:30:30 volumio volumio[772]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 01 10:30:30 volumio volumio[772]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 01 10:30:30 volumio volumio[772]: Error: certificate is not yet valid Jun 01 10:30:30 volumio volumio[772]: at TLSSocket.onConnectSecure (_tls_wrap.js:1497:34) Jun 01 10:30:30 volumio volumio[772]: at TLSSocket.emit (events.js:315:20) Jun 01 10:30:30 volumio volumio[772]: at TLSSocket._finishInit (_tls_wrap.js:932:8) Jun 01 10:30:30 volumio volumio[772]: at TLSWrap.ssl.onhandshakedone (_tls_wrap.js:706:12) { Jun 01 10:30:30 volumio volumio[772]: code: 'CERT_NOT_YET_VALID' Jun 01 10:30:30 volumio volumio[772]: } Jun 01 10:30:30 volumio volumio[772]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 01 10:30:31 volumio sudo[1571]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-06-01 10:29 Jun 01 10:30:31 volumio sudo[1571]: 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="543f2de901f774399af6eae9d76f06fe4162e5dd" VOLUMIO_FE_VERSION="dff19baf69ee85af3381c73e932cb6852dfe38e0" VOLUMIO_FE3_VERSION="081f1f7a36b167d793d831ee89ac02e7f8f3ee4b" VOLUMIO_BE_VERSION="1eeee4d17c356c3967f07f80a7dfdbc12de2e066" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 08 Dec 2021 12:21:52 PM CET" VOLUMIO_VERSION="3.173" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="1c9433a7294bad3fb7682386561b54d4"