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