-- Logs begin at Mon 2024-12-02 21:09:49 UTC, end at Mon 2024-12-02 21:11:30 UTC. -- Dec 02 21:10:00 volumio volumio[1026]: info: File /data/configuration/plugins.json does not exist. Copying from Volumio Dec 02 21:10:00 volumio ntpd[1364]: Soliciting pool server 131.111.8.63 Dec 02 21:10:00 volumio volumio[1026]: info: MYVOLUMIO Environment detected Dec 02 21:10:00 volumio volumio[1026]: info: Plugin folders cleanup Dec 02 21:10:00 volumio volumio[1026]: info: Scanning into folder /volumio/app/plugins/ Dec 02 21:10:00 volumio volumio[1026]: info: Scanning category audio_interface Dec 02 21:10:00 volumio volumio[1026]: info: Scanning category miscellanea Dec 02 21:10:00 volumio volumio[1026]: info: Scanning category music_service Dec 02 21:10:00 volumio volumio[1026]: info: Scanning category plugins.json Dec 02 21:10:00 volumio volumio[1026]: info: Scanning category system_controller Dec 02 21:10:00 volumio volumio[1026]: info: Scanning category user_interface Dec 02 21:10:00 volumio volumio[1026]: info: Scanning into folder /data/plugins/ Dec 02 21:10:00 volumio volumio[1026]: info: Plugin folders cleanup completed Dec 02 21:10:00 volumio volumio[1026]: info: ------------------------------------------- Dec 02 21:10:00 volumio volumio[1026]: info: ----- Core plugins startup ---- Dec 02 21:10:00 volumio volumio[1026]: info: ------------------------------------------- Dec 02 21:10:00 volumio volumio[1026]: info: Loading plugins from folder /volumio/app/plugins/ Dec 02 21:10:00 volumio volumio[1026]: info: Adding plugin upnp to MyMusic Plugins Dec 02 21:10:00 volumio volumio[1026]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 02 21:10:00 volumio volumio[1026]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 02 21:10:00 volumio volumio[1026]: info: Loading plugins from folder /data/plugins/ Dec 02 21:10:00 volumio volumio[1026]: info: Loading plugin "system"... Dec 02 21:10:00 volumio volumio[1026]: info: No id defined. Creating one Dec 02 21:10:00 volumio volumio[1026]: info: Loading plugin "appearance"... Dec 02 21:10:00 volumio systemd[1]: Reloading. Dec 02 21:10:00 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. Dec 02 21:10:00 volumio systemd[1]: getty@tty1.service: Current command vanished from the unit file, execution of the command list won't be resumed. Dec 02 21:10:00 volumio firststart.sh[1027]: rescue-ssh.target is a disabled or a static unit, not starting it. Dec 02 21:10:00 volumio volumio[1026]: info: Loading plugin "network"... Dec 02 21:10:00 volumio firststart.sh[1027]: insserv: warning: current start runlevel(s) (empty) of script `ssh' overrides LSB defaults (2 3 4 5). Dec 02 21:10:00 volumio firststart.sh[1027]: insserv: warning: current stop runlevel(s) (2 3 4 5) of script `ssh' overrides LSB defaults (empty). Dec 02 21:10:00 volumio volumio[1026]: info: Refreshing Cached IP Addresses Dec 02 21:10:00 volumio systemd[1]: Reloading. Dec 02 21:10:00 volumio sudo[1506]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 02 21:10:00 volumio sudo[1506]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 21:10:00 volumio sudo[1506]: pam_unix(sudo:session): session closed for user root Dec 02 21:10:00 volumio sudo[1508]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 02 21:10:00 volumio sudo[1508]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 21:10:00 volumio sudo[1508]: pam_unix(sudo:session): session closed for user root Dec 02 21:10:00 volumio volumio[1026]: info: Loading plugin "services"... Dec 02 21:10:00 volumio volumio[1026]: info: Loading plugin "alsa_controller"... Dec 02 21:10:00 volumio sudo[1522]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 02 21:10:00 volumio sudo[1522]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 21:10:00 volumio sudo[1522]: pam_unix(sudo:session): session closed for user root Dec 02 21:10:00 volumio volumio[1026]: warn: The ALSA output card is not set, defaulting to card [object Object] Dec 02 21:10:01 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 02 21:10:01 volumio volumio[1026]: info: Setting mixer Master for card HDA NVidia Dec 02 21:10:01 volumio volumio[1026]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 02 21:10:01 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. Dec 02 21:10:01 volumio volumio[1026]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 02 21:10:01 volumio volumio[1026]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 02 21:10:01 volumio ntpd[1364]: Soliciting pool server 193.57.144.50 Dec 02 21:10:01 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 02 21:10:01 volumio volumio[1026]: info: Loading plugin "wizard"... Dec 02 21:10:01 volumio ntpd[1364]: Soliciting pool server 131.111.8.60 Dec 02 21:10:01 volumio volumio[1026]: info: Loading plugin "networkfs"... Dec 02 21:10:01 volumio volumio[1026]: info: Starting Udev Watcher for removable devices Dec 02 21:10:01 volumio volumio[1026]: info: Ignoring mount for partition: boot Dec 02 21:10:01 volumio volumio[1026]: info: Ignoring mount for partition: volumio Dec 02 21:10:01 volumio volumio[1026]: info: Ignoring mount for partition: volumio_data Dec 02 21:10:01 volumio volumio[1026]: info: Ignoring mount for partition: boot Dec 02 21:10:01 volumio volumio[1026]: info: Ignoring mount for partition: volumio Dec 02 21:10:01 volumio volumio[1026]: info: Ignoring mount for partition: volumio_data Dec 02 21:10:01 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 02 21:10:01 volumio volumio[1026]: info: Loading plugin "volumio_command_line_client"... Dec 02 21:10:01 volumio volumio[1026]: info: Loading plugin "upnp"... Dec 02 21:10:01 volumio volumio[1026]: info: [1733173801179] Starting Upmpd Daemon Dec 02 21:10:01 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 02 21:10:01 volumio volumio[1026]: info: Loading plugin "my_music"... Dec 02 21:10:01 volumio volumio[1026]: info: Loading plugin "mpd"... Dec 02 21:10:01 volumio volumio[1026]: info: Loading plugin "upnp_browser"... Dec 02 21:10:01 volumio firststart.sh[1027]: Enabling SSH for first boot Dec 02 21:10:01 volumio systemd[1]: Starting OpenBSD Secure Shell server... Dec 02 21:10:01 volumio sshd[1567]: Server listening on 0.0.0.0 port 22. Dec 02 21:10:01 volumio sshd[1567]: Server listening on :: port 22. Dec 02 21:10:01 volumio systemd[1]: Started OpenBSD Secure Shell server. Dec 02 21:10:01 volumio firststart.sh[1027]: Disabling firststart service Dec 02 21:10:01 volumio firststart.sh[1027]: Removed /etc/systemd/system/multi-user.target.wants/firststart.service. Dec 02 21:10:01 volumio systemd[1]: Reloading. Dec 02 21:10:01 volumio volumio[1026]: info: Loading plugin "alarm-clock"... Dec 02 21:10:01 volumio volumio[1026]: info: Loading plugin "airplay_emulation"... Dec 02 21:10:01 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. Dec 02 21:10:01 volumio volumio[1026]: info: Starting Shairport Sync Dec 02 21:10:01 volumio volumio[1026]: info: Loading plugin "last_100"... Dec 02 21:10:01 volumio volumio[1026]: info: Loading plugin "webradio"... Dec 02 21:10:01 volumio volumio[1026]: info: Loading plugin "i2s_dacs"... Dec 02 21:10:01 volumio volumio[1026]: info: I2S DAC not set, start Auto-detection Dec 02 21:10:01 volumio volumio[1026]: info: Loading plugin "volumiodiscovery"... Dec 02 21:10:01 volumio volumio[1026]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 02 21:10:01 volumio node[1026]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 02 21:10:01 volumio volumio[1026]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 02 21:10:01 volumio node[1026]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 02 21:10:01 volumio volumio[1026]: *** WARNING *** For more information see Dec 02 21:10:01 volumio node[1026]: *** WARNING *** For more information see Dec 02 21:10:01 volumio volumio[1026]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 02 21:10:01 volumio volumio[1026]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 02 21:10:01 volumio volumio[1026]: *** WARNING *** For more information see Dec 02 21:10:01 volumio node[1026]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 02 21:10:01 volumio node[1026]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 02 21:10:01 volumio node[1026]: *** WARNING *** For more information see Dec 02 21:10:01 volumio volumio[1026]: info: Discovery: Started advertising with name: Volumio Dec 02 21:10:01 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 02 21:10:01 volumio volumio[1026]: info: Loading plugin "outputs"... Dec 02 21:10:01 volumio volumio[1026]: info: Loading plugin "albumart"... Dec 02 21:10:01 volumio volumio-time-update[728]: volumio-time-update-util: Fetching time from Volumio... Dec 02 21:10:01 volumio volumio[1026]: info: Plugin example_plugin is not enabled Dec 02 21:10:01 volumio volumio[1026]: info: Loading plugin "inputs"... Dec 02 21:10:01 volumio volumio[1026]: info: Loading plugin "updater_comm"... Dec 02 21:10:01 volumio volumio-time-update[728]: volumio-time-update-util: Setting system time to: 2024-12-02 21:10:52 Dec 02 21:10:01 volumio firststart.sh[1027]: Finalizing Dec 02 21:10:01 volumio sudo[1596]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2024-12-02 21:10:52 Dec 02 21:10:01 volumio sudo[1596]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 21:10:01 volumio dbus-daemon[736]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.11' (uid=0 pid=1597 comm="timedatectl set-time 2024-12-02 21:10:52 ") Dec 02 21:10:01 volumio systemd[1]: firststart.service: Succeeded. Dec 02 21:10:01 volumio volumio[1026]: info: Plugin mpdemulation is not enabled Dec 02 21:10:02 volumio volumio[1026]: info: Loading plugin "rest_api"... Dec 02 21:10:02 volumio systemd[1]: Starting Time & Date Service... Dec 02 21:10:02 volumio volumio[1026]: Forking 1 albumart workers Dec 02 21:10:02 volumio volumio[1026]: info: Loading plugin "websocket"... Dec 02 21:10:02 volumio volumio[1026]: info: Starting Socket.io Server version 2.3.0 Dec 02 21:10:02 volumio volumio[1026]: info: Loading i18n strings for locale en Dec 02 21:10:02 volumio volumio[1026]: Updating browse sources language Dec 02 21:10:02 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 02 21:10:02 volumio dbus-daemon[736]: [system] Successfully activated service 'org.freedesktop.timedate1' Dec 02 21:10:02 volumio systemd[1]: Started Time & Date Service. Dec 02 21:10:52 volumio systemd-timedated[1599]: Changed local time to Mon Dec 2 21:10:52 2024 Dec 02 21:10:52 volumio sudo[1596]: pam_unix(sudo:session): session closed for user root Dec 02 21:10:52 volumio volumio-time-update[728]: volumio-time-update-util: System time updated successfully. Dec 02 21:10:52 volumio systemd[1]: Started Volumio Time Update Utility. Dec 02 21:10:52 volumio systemd[1]: Reached target Multi-User System. Dec 02 21:10:52 volumio systemd[1]: Reached target Graphical Interface. Dec 02 21:10:52 volumio ntpd[1364]: Soliciting pool server 213.244.206.130 Dec 02 21:10:52 volumio ntpd[1364]: Soliciting pool server 77.104.162.218 Dec 02 21:10:52 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Dec 02 21:10:52 volumio ntpd[1364]: Soliciting pool server 162.159.200.123 Dec 02 21:10:52 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Dec 02 21:10:52 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Dec 02 21:10:52 volumio systemd[1]: Startup finished in 12.986s (kernel) + 14.265s (userspace) = 27.251s. Dec 02 21:10:52 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 02 21:10:52 volumio volumio[1026]: info: CoreCommandRouter::initPlayerControls Dec 02 21:10:52 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 21:10:52 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 21:10:52 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 21:10:52 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 21:10:52 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 21:10:52 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 21:10:52 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 21:10:52 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 21:10:52 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 02 21:10:52 volumio volumio[1026]: Express server listening on port 3000 Dec 02 21:10:52 volumio volumio[1026]: [Metrics] WebUI: 3s 405.46ms Dec 02 21:10:52 volumio volumio[1026]: info: Setting Device type: x86 Dec 02 21:10:52 volumio volumio[1026]: info: CoreStateMachine::resetVolumioState Dec 02 21:10:52 volumio volumio[1026]: info: CoreStateMachine::getcurrentVolume Dec 02 21:10:52 volumio volumio[1026]: info: CoreCommandRouter::volumioRetrievevolume Dec 02 21:10:52 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 02 21:10:52 volumio volumio[1026]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Dec 02 21:10:52 volumio volumio[1026]: info: Completed loading Core Plugins Dec 02 21:10:52 volumio volumio[1026]: info: Preparing to generate the ALSA configuration file Dec 02 21:10:52 volumio volumio[1026]: info: Cannot read play queue from file Dec 02 21:10:52 volumio volumio[1026]: info: Volumio Network Manager: Network status updated: 1 Dec 02 21:10:52 volumio volumio[1026]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Dec 02 21:10:52 volumio volumio[1026]: wlan0 Interface doesn't support scanning : Operation not supported Dec 02 21:10:52 volumio volumio[1026]: info: Cannot use regular scanning, forcing with ap-force Dec 02 21:10:52 volumio sudo[1610]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Dec 02 21:10:52 volumio sudo[1610]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 21:10:52 volumio kernel: brcmsmac bcma0:1: brcms_ops_bss_info_changed: Beacon enabled: false Dec 02 21:10:52 volumio volumio[1026]: Starting albumart workers Dec 02 21:10:52 volumio dhcpcd[830]: wlan0: probing for an IPv4LL address Dec 02 21:10:53 volumio ntpd[1364]: Soliciting pool server 82.219.4.30 Dec 02 21:10:53 volumio ntpd[1364]: Soliciting pool server 80.87.128.222 Dec 02 21:10:53 volumio ntpd[1364]: Soliciting pool server 195.171.43.12 Dec 02 21:10:53 volumio ntpd[1364]: Soliciting pool server 131.111.8.61 Dec 02 21:10:54 volumio ntpd[1364]: Soliciting pool server 176.58.115.34 Dec 02 21:10:54 volumio ntpd[1364]: Soliciting pool server 162.159.200.1 Dec 02 21:10:54 volumio ntpd[1364]: Soliciting pool server 51.89.151.183 Dec 02 21:10:54 volumio volumio-remote-updater[751]: [2024-12-02 21:10:54] [connect] Successful connection Dec 02 21:10:55 volumio ntpd[1364]: Soliciting pool server 193.150.34.2 Dec 02 21:10:55 volumio ntpd[1364]: Soliciting pool server 44.31.52.36 Dec 02 21:10:55 volumio sudo[1610]: pam_unix(sudo:session): session closed for user root Dec 02 21:10:55 volumio kernel: brcmsmac bcma0:1: brcms_ops_bss_info_changed: Beacon enabled: true Dec 02 21:10:55 volumio volumio[1026]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Dec 02 21:10:55 volumio volumio[1026]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Dec 02 21:10:55 volumio volumio[1026]: info: CoreStateMachine::getcurrentVolume Dec 02 21:10:55 volumio volumio[1026]: info: CoreCommandRouter::volumioRetrievevolume Dec 02 21:10:55 volumio volumio[1026]: verbose: New Socket.io Connection to 192.168.1.212 from 192.168.1.111 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.1.1 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 1 Dec 02 21:10:55 volumio volumio[1026]: verbose: New Socket.io Connection to 192.168.1.212 from 192.168.1.111 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.1.1 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 2 Dec 02 21:10:55 volumio volumio[1026]: verbose: New Socket.io Connection to 192.168.1.212 from 192.168.1.111 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.1.1 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 3 Dec 02 21:10:55 volumio volumio-remote-updater[751]: [2024-12-02 21:10:55] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1733173854 101 Dec 02 21:10:55 volumio volumio[1026]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 4 Dec 02 21:10:55 volumio volumio[1026]: info: VolumeController:: Volume=41 Mute =false Dec 02 21:10:55 volumio volumio[1026]: info: CoreStateMachine::pushState Dec 02 21:10:55 volumio volumio[1026]: info: CorePlayQueue::getTrack 0 Dec 02 21:10:55 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 21:10:55 volumio volumio[1026]: info: CoreCommandRouter::volumioPushState Dec 02 21:10:55 volumio volumio[1026]: info: CoreStateMachine::updateTrackBlock Dec 02 21:10:55 volumio volumio[1026]: info: CorePlayQueue::getTrackBlock Dec 02 21:10:55 volumio volumio[1026]: info: CoreCommandRouter::volumioRetrievevolume Dec 02 21:10:55 volumio volumio[1026]: info: Asound.conf file written Dec 02 21:10:55 volumio sudo[1632]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 02 21:10:55 volumio sudo[1632]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 21:10:55 volumio sudo[1632]: pam_unix(sudo:session): session closed for user root Dec 02 21:10:55 volumio volumio[1026]: No state is present for card NVidia Dec 02 21:10:55 volumio volumio[1026]: Found hardware: "HDA-Intel" "Cirrus Logic CS4206" "HDA:10134206,106b0300,00100301 HDA:10de000c,10de0101,00100200" "0x10de" "0xcb89" Dec 02 21:10:55 volumio volumio[1026]: Hardware is initialized using a generic method Dec 02 21:10:55 volumio volumio[1026]: No state is present for card NVidia Dec 02 21:10:55 volumio volumio[1026]: info: Output device has changed, restarting MPD Dec 02 21:10:55 volumio sudo[1637]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 02 21:10:55 volumio sudo[1637]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 21:10:55 volumio volumio[1026]: info: Output device has changed, restarting Shairport Sync Dec 02 21:10:55 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 21:10:55 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 02 21:10:55 volumio sudo[1637]: pam_unix(sudo:session): session closed for user root Dec 02 21:10:55 volumio sudo[1640]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 02 21:10:55 volumio sudo[1640]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 21:10:55 volumio systemd[1]: Stopping Music Player Daemon... Dec 02 21:10:55 volumio volumio[1026]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 02 21:10:55 volumio volumio[1026]: info: ___________ START PLUGINS ___________ Dec 02 21:10:55 volumio volumio[1026]: info: Setting Hotspot Unique name on first start: Volumio-A9783 Dec 02 21:10:55 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: network , getConfigParam Dec 02 21:10:55 volumio volumio[1026]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getConfigParam] in plugin network Dec 02 21:10:55 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: network , saveHotspotSettings Dec 02 21:10:55 volumio volumio[1026]: info: CoreCommandRouter::Reload Ui Dec 02 21:10:55 volumio volumio[1026]: info: ControllerMpd::onStart: Initializing MPD Dec 02 21:10:55 volumio volumio[1026]: info: Creating MPD Configuration file Dec 02 21:10:55 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 02 21:10:55 volumio volumio[1026]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 02 21:10:55 volumio volumio[1026]: info: [1733173855551] CoreMusicLibrary::Adding element Media Servers Dec 02 21:10:55 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 02 21:10:55 volumio sudo[1646]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 02 21:10:55 volumio sudo[1646]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 21:10:55 volumio systemd[1]: mpd.service: Succeeded. Dec 02 21:10:55 volumio sudo[1648]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 02 21:10:55 volumio systemd[1]: Stopped Music Player Daemon. Dec 02 21:10:55 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 21:10:55 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 02 21:10:55 volumio sudo[1646]: pam_unix(sudo:session): session closed for user root Dec 02 21:10:55 volumio sudo[1648]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 21:10:55 volumio systemd[1]: Starting Music Player Daemon... Dec 02 21:10:55 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Dec 02 21:10:55 volumio systemd[1]: mpd.service: Succeeded. Dec 02 21:10:55 volumio systemd[1]: Stopped Music Player Daemon. Dec 02 21:10:55 volumio systemd[1]: Starting Music Player Daemon... Dec 02 21:10:55 volumio volumio[1026]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 02 21:10:55 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 21:10:55 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 02 21:10:55 volumio volumio[1026]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 02 21:10:55 volumio volumio[1026]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 02 21:10:55 volumio volumio[1026]: info: [1733173855630] CoreMusicLibrary::Adding element Last_100 Dec 02 21:10:55 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 02 21:10:55 volumio volumio[1026]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 02 21:10:55 volumio volumio[1026]: info: [1733173855632] CoreMusicLibrary::Adding element Webradio Dec 02 21:10:55 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 02 21:10:55 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 02 21:10:55 volumio sudo[1655]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 02 21:10:55 volumio sudo[1655]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 21:10:55 volumio volumio[1026]: info: Initializing BBC Radios Dec 02 21:10:55 volumio sudo[1655]: pam_unix(sudo:session): session closed for user root Dec 02 21:10:55 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 02 21:10:55 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 02 21:10:55 volumio volumio[1026]: info: Volumio Calling Home Dec 02 21:10:55 volumio volumio[1026]: verbose: New Socket.io Connection to 192.168.1.212 from 192.168.1.111 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.1.1 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 5 Dec 02 21:10:55 volumio volumio[1026]: verbose: New Socket.io Connection to 192.168.1.212 from 192.168.1.111 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.1.1 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 6 Dec 02 21:10:55 volumio volumio[1026]: info: VolumeController:: Volume=41 Mute =false Dec 02 21:10:55 volumio volumio[1026]: info: CoreStateMachine::pushState Dec 02 21:10:55 volumio volumio[1026]: info: CorePlayQueue::getTrack 0 Dec 02 21:10:55 volumio volumio[1026]: info: CoreCommandRouter::volumioPushState Dec 02 21:10:55 volumio volumio[1026]: info: CoreStateMachine::updateTrackBlock Dec 02 21:10:55 volumio volumio[1026]: info: CorePlayQueue::getTrackBlock Dec 02 21:10:55 volumio volumio[1026]: info: CoreCommandRouter::volumioRetrievevolume Dec 02 21:10:55 volumio volumio[1026]: verbose: New Socket.io Connection to 192.168.1.212 from 192.168.1.111 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.1.1 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 7 Dec 02 21:10:55 volumio volumio[1026]: info: MPD Permissions set Dec 02 21:10:55 volumio volumio[1026]: info: MPD Permissions set Dec 02 21:10:55 volumio volumio[1026]: info: Volumio called home Dec 02 21:10:55 volumio volumio[1026]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Dec 02 21:10:55 volumio volumio[1026]: info: VolumeController:: Volume=41 Mute =false Dec 02 21:10:55 volumio volumio[1026]: info: CoreStateMachine::pushState Dec 02 21:10:55 volumio volumio[1026]: info: CorePlayQueue::getTrack 0 Dec 02 21:10:55 volumio volumio[1026]: info: CoreCommandRouter::volumioPushState Dec 02 21:10:55 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 21:10:55 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 02 21:10:55 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 02 21:10:55 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 21:10:55 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 21:10:55 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 21:10:55 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 21:10:55 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 21:10:55 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 21:10:55 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 21:10:55 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 02 21:10:55 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 02 21:10:56 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 21:10:56 volumio volumio[1026]: info: Discovery: adding 05909108-b3c9-4c9b-b245-60da228438a4 Dec 02 21:10:56 volumio volumio[1026]: info: Discovery: Found device Volumio Dec 02 21:10:56 volumio volumio[1026]: info: CoreCommandRouter::volumioGetState Dec 02 21:10:56 volumio volumio[1026]: info: CorePlayQueue::getTrack 0 Dec 02 21:10:56 volumio ntpd[1364]: Soliciting pool server 129.250.35.251 Dec 02 21:10:56 volumio ntpd[1364]: Soliciting pool server 2a00:fd80:aaaa:ffff::eeee:ff3 Dec 02 21:10:56 volumio volumio[1026]: info: VolumeController:: Volume=41 Mute =false Dec 02 21:10:56 volumio volumio[1026]: info: CoreStateMachine::pushState Dec 02 21:10:56 volumio volumio[1026]: info: CorePlayQueue::getTrack 0 Dec 02 21:10:56 volumio volumio[1026]: info: CoreCommandRouter::volumioPushState Dec 02 21:10:56 volumio volumio[1026]: info: Discovery: this is already registered, 05909108-b3c9-4c9b-b245-60da228438a4 Dec 02 21:10:56 volumio volumio[1026]: info: Discovery: Found device Volumio Dec 02 21:10:56 volumio volumio[1026]: info: CoreCommandRouter::volumioGetState Dec 02 21:10:56 volumio volumio[1026]: info: CorePlayQueue::getTrack 0 Dec 02 21:10:56 volumio volumio[1026]: info: CorePlayQueue::getTrack 0 Dec 02 21:10:56 volumio mpd[1660]: Dec 02 21:10 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 02 21:10:56 volumio systemd[1]: Started Music Player Daemon. Dec 02 21:10:56 volumio sudo[1648]: pam_unix(sudo:session): session closed for user root Dec 02 21:10:56 volumio sudo[1640]: pam_unix(sudo:session): session closed for user root Dec 02 21:10:56 volumio volumio[1026]: info: Completed starting Core Plugins Dec 02 21:10:56 volumio volumio[1026]: info: ------------------------------------------- Dec 02 21:10:56 volumio volumio[1026]: info: ----- MyVolumio plugins startup ---- Dec 02 21:10:56 volumio volumio[1026]: info: ------------------------------------------- Dec 02 21:10:56 volumio volumio[1026]: info: [MyVolumio PluginManager] Fetching plans data.... Dec 02 21:10:56 volumio volumio[1026]: info: Starting Shairport Sync Dec 02 21:10:56 volumio volumio[1026]: info: Starting Shairport Sync Dec 02 21:10:56 volumio sudo[1696]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 02 21:10:56 volumio sudo[1696]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 21:10:56 volumio volumio[1026]: info: Starting Shairport Sync Dec 02 21:10:56 volumio sudo[1699]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 02 21:10:56 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 02 21:10:56 volumio sudo[1699]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 21:10:56 volumio systemd[1]: shairport-sync.service: Succeeded. Dec 02 21:10:56 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 02 21:10:56 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 02 21:10:56 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 02 21:10:56 volumio sudo[1696]: pam_unix(sudo:session): session closed for user root Dec 02 21:10:56 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Dec 02 21:10:56 volumio systemd[1]: shairport-sync.service: Succeeded. Dec 02 21:10:56 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 02 21:10:56 volumio sudo[1703]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 02 21:10:56 volumio sudo[1703]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 21:10:56 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 02 21:10:56 volumio sudo[1699]: pam_unix(sudo:session): session closed for user root Dec 02 21:10:56 volumio volumio[1026]: info: CoreCommandRouter::volumioGetState Dec 02 21:10:56 volumio volumio[1026]: info: CorePlayQueue::getTrack 0 Dec 02 21:10:56 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 02 21:10:56 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Dec 02 21:10:56 volumio systemd[1]: shairport-sync.service: Succeeded. Dec 02 21:10:56 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 02 21:10:56 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 02 21:10:56 volumio sudo[1703]: pam_unix(sudo:session): session closed for user root Dec 02 21:10:56 volumio volumio[1026]: error: MPD error: The expression evaluated to a falsy value: Dec 02 21:10:56 volumio volumio[1026]: assert.ok(self.idling) Dec 02 21:10:56 volumio volumio[1026]: error: The expression evaluated to a falsy value: Dec 02 21:10:56 volumio volumio[1026]: assert.ok(self.idling) Dec 02 21:10:56 volumio volumio[1026]: info: Shairport-Sync Started Dec 02 21:10:56 volumio volumio[1026]: Error adding Membership: Error: addMembership EINVAL Dec 02 21:10:56 volumio volumio[1026]: info: Shairport-Sync Started Dec 02 21:10:56 volumio volumio[1026]: info: MPD running with PID1660 Dec 02 21:10:56 volumio volumio[1026]: ,establishing connection Dec 02 21:10:56 volumio volumio[1026]: error: updateQueue error: null Dec 02 21:10:56 volumio volumio[1026]: info: Shairport-Sync Started Dec 02 21:10:56 volumio volumio[1026]: error: updateQueue error: null Dec 02 21:10:56 volumio sudo[1711]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hostapd/hostapd.conf Dec 02 21:10:56 volumio sudo[1711]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 21:10:56 volumio sudo[1711]: pam_unix(sudo:session): session closed for user root Dec 02 21:10:56 volumio volumio[1026]: info: Permissions for /etc/hostapd/hostapd.conf Dec 02 21:10:56 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Dec 02 21:10:56 volumio volumio[1026]: info: Discovery: Restarting Advertising due to device name change Dec 02 21:10:56 volumio sudo[1714]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Dec 02 21:10:56 volumio sudo[1714]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 21:10:56 volumio systemd[1]: Starting Wireless Services... Dec 02 21:10:56 volumio wireless.js[1716]: Cleaning previous... Dec 02 21:10:56 volumio systemd[1]: Stopping dnsmasq - A lightweight DHCP and caching DNS server... Dec 02 21:10:56 volumio dnsmasq[1056]: exiting on receipt of SIGTERM Dec 02 21:10:56 volumio systemd[1]: dnsmasq.service: Succeeded. Dec 02 21:10:56 volumio systemd[1]: Stopped dnsmasq - A lightweight DHCP and caching DNS server. Dec 02 21:10:56 volumio systemd[1]: Stopping Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Dec 02 21:10:56 volumio dhcpcd[830]: wlan0: carrier lost Dec 02 21:10:56 volumio kernel: brcmsmac bcma0:1: brcms_ops_bss_info_changed: Beacon enabled: false Dec 02 21:10:56 volumio avahi-daemon[735]: Interface wlan0.IPv4 no longer relevant for mDNS. Dec 02 21:10:56 volumio avahi-daemon[735]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Dec 02 21:10:56 volumio avahi-daemon[735]: Withdrawing address record for 192.168.211.1 on wlan0. Dec 02 21:10:56 volumio kernel: brcmsmac bcma0:1: brcms_ops_bss_info_changed: qos enabled: false (implement) Dec 02 21:10:56 volumio kernel: brcmsmac bcma0:1: brcms_ops_config: change power-save mode: false (implement) Dec 02 21:10:56 volumio avahi-daemon[735]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Dec 02 21:10:56 volumio systemd[1]: hostapd.service: Succeeded. Dec 02 21:10:56 volumio avahi-daemon[735]: New relevant interface wlan0.IPv4 for mDNS. Dec 02 21:10:56 volumio avahi-daemon[735]: Registering new address record for 192.168.211.1 on wlan0.IPv4. Dec 02 21:10:56 volumio volumio[1026]: info: Discovery: A device disappeared from network Dec 02 21:10:56 volumio volumio[1026]: info: Discovery: Device volumio disappeared from network Dec 02 21:10:56 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Dec 02 21:10:56 volumio sudo[1764]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Dec 02 21:10:56 volumio sudo[1764]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 21:10:56 volumio avahi-daemon[735]: Withdrawing address record for 192.168.211.1 on wlan0. Dec 02 21:10:56 volumio avahi-daemon[735]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Dec 02 21:10:56 volumio avahi-daemon[735]: Interface wlan0.IPv4 no longer relevant for mDNS. Dec 02 21:10:56 volumio sudo[1764]: pam_unix(sudo:session): session closed for user root Dec 02 21:10:56 volumio sudo[1766]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Dec 02 21:10:56 volumio sudo[1766]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 21:10:56 volumio sudo[1766]: pam_unix(sudo:session): session closed for user root Dec 02 21:10:56 volumio wireless.js[1716]: Stopped aP Dec 02 21:10:57 volumio sudo[1773]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Dec 02 21:10:57 volumio sudo[1773]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 21:10:57 volumio kernel: brcmsmac bcma0:1: brcms_ops_bss_info_changed: qos enabled: false (implement) Dec 02 21:10:57 volumio kernel: brcmsmac bcma0:1: brcms_ops_config: change power-save mode: false (implement) Dec 02 21:10:57 volumio sudo[1773]: pam_unix(sudo:session): session closed for user root Dec 02 21:10:57 volumio sudo[1775]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Dec 02 21:10:57 volumio sudo[1775]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 21:10:57 volumio sudo[1775]: pam_unix(sudo:session): session closed for user root Dec 02 21:10:57 volumio sudo[1782]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Dec 02 21:10:57 volumio sudo[1782]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 21:10:57 volumio sudo[1782]: pam_unix(sudo:session): session closed for user root Dec 02 21:10:57 volumio sudo[1784]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Dec 02 21:10:57 volumio sudo[1784]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 21:10:57 volumio volumio[1026]: info: Discovery: A device disappeared from network Dec 02 21:10:58 volumio ntpd[1364]: receive: Unexpected origin timestamp 0xeaf8a0e2.0504a786 does not match aorg 0000000000.00000000 from server@162.159.200.123 xmt 0xeaf8a0e2.6c90d3d2 Dec 02 21:10:58 volumio ntpd[1364]: receive: Unexpected origin timestamp 0xeaf8a0e2.04fe3e99 does not match aorg 0000000000.00000000 from server@193.150.34.2 xmt 0xeaf8a0e2.6d19ac1b Dec 02 21:10:58 volumio ntpd[1364]: receive: Unexpected origin timestamp 0xeaf8a0e2.05007b7d does not match aorg 0000000000.00000000 from server@131.111.8.61 xmt 0xeaf8a0e2.6d85a32d Dec 02 21:10:58 volumio ntpd[1364]: receive: Unexpected origin timestamp 0xeaf8a0e2.0502988f does not match aorg 0000000000.00000000 from server@195.171.43.12 xmt 0xeaf8a0e2.6dd0dfec Dec 02 21:10:58 volumio ntpd[1364]: receive: Unexpected origin timestamp 0xeaf8a0e2.04fd305f does not match aorg 0000000000.00000000 from server@44.31.52.36 xmt 0xeaf8a0e2.6d4d9b2e Dec 02 21:10:58 volumio ntpd[1364]: receive: Unexpected origin timestamp 0xeaf8a0e2.05018511 does not match aorg 0000000000.00000000 from server@82.219.4.30 xmt 0xeaf8a0e2.6deb40c9 Dec 02 21:10:58 volumio ntpd[1364]: receive: Unexpected origin timestamp 0xeaf8a0e2.0503992c does not match aorg 0000000000.00000000 from server@213.244.206.130 xmt 0xeaf8a0e2.832fe280 Dec 02 21:10:59 volumio ntpd[1364]: Deleting interface #4 wlan0, 192.168.211.1#123, interface stats: received=0, sent=0, dropped=0, active_time=10 secs Dec 02 21:11:00 volumio sudo[1784]: pam_unix(sudo:session): session closed for user root Dec 02 21:11:00 volumio wireless.js[1716]: SETTING APPROPRIATE REG DOMAIN: GB Dec 02 21:11:00 volumio sudo[1789]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Dec 02 21:11:00 volumio sudo[1789]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 21:11:00 volumio sudo[1789]: pam_unix(sudo:session): session closed for user root Dec 02 21:11:00 volumio sudo[1791]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set GB Dec 02 21:11:00 volumio sudo[1791]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 21:11:00 volumio sudo[1791]: pam_unix(sudo:session): session closed for user root Dec 02 21:11:00 volumio wireless.js[1716]: SUCCESSFULLY SET NEW REGDOMAIN: GB Dec 02 21:11:00 volumio sudo[1798]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Dec 02 21:11:00 volumio sudo[1798]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 21:11:00 volumio sudo[1798]: pam_unix(sudo:session): session closed for user root Dec 02 21:11:00 volumio sudo[1800]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Dec 02 21:11:00 volumio sudo[1800]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 21:11:00 volumio sudo[1800]: pam_unix(sudo:session): session closed for user root Dec 02 21:11:00 volumio kernel: brcmsmac bcma0:1: brcms_ops_bss_info_changed: qos enabled: false (implement) Dec 02 21:11:00 volumio kernel: brcmsmac bcma0:1: brcms_ops_config: change power-save mode: false (implement) Dec 02 21:11:00 volumio avahi-daemon[735]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Dec 02 21:11:00 volumio avahi-daemon[735]: New relevant interface wlan0.IPv4 for mDNS. Dec 02 21:11:00 volumio avahi-daemon[735]: Registering new address record for 192.168.211.1 on wlan0.IPv4. Dec 02 21:11:00 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Dec 02 21:11:00 volumio hostapd[1806]: Configuration file: /etc/hostapd/hostapd.conf Dec 02 21:11:00 volumio avahi-daemon[735]: Interface wlan0.IPv4 no longer relevant for mDNS. Dec 02 21:11:00 volumio avahi-daemon[735]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Dec 02 21:11:00 volumio avahi-daemon[735]: Withdrawing address record for 192.168.211.1 on wlan0. Dec 02 21:11:00 volumio avahi-daemon[735]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Dec 02 21:11:00 volumio avahi-daemon[735]: New relevant interface wlan0.IPv4 for mDNS. Dec 02 21:11:00 volumio avahi-daemon[735]: Registering new address record for 192.168.211.1 on wlan0.IPv4. Dec 02 21:11:00 volumio hostapd[1806]: Using interface wlan0 with hwaddr 58:b0:35:86:fb:bf and ssid "Volumio-A9783" Dec 02 21:11:00 volumio hostapd[1806]: wlan0: interface state UNINITIALIZED->ENABLED Dec 02 21:11:00 volumio hostapd[1806]: wlan0: AP-ENABLED Dec 02 21:11:00 volumio kernel: brcmsmac bcma0:1: brcms_ops_bss_info_changed: qos enabled: true (implement) Dec 02 21:11:00 volumio kernel: brcmsmac bcma0:1: brcms_ops_config: change power-save mode: false (implement) Dec 02 21:11:00 volumio kernel: brcmsmac bcma0:1: brcms_ops_bss_info_changed: Beacon enabled: true Dec 02 21:11:00 volumio kernel: brcmsmac bcma0:1: brcms_ops_bss_info_changed: qos enabled: true (implement) Dec 02 21:11:00 volumio kernel: brcmsmac bcma0:1: brcms_ops_bss_info_changed: qos enabled: true (implement) Dec 02 21:11:00 volumio kernel: brcmsmac bcma0:1: brcms_ops_bss_info_changed: qos enabled: true (implement) Dec 02 21:11:00 volumio kernel: brcmsmac bcma0:1: brcms_ops_bss_info_changed: qos enabled: true (implement) Dec 02 21:11:00 volumio dhcpcd[830]: wlan0: carrier acquired Dec 02 21:11:00 volumio systemd[1]: Started Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Dec 02 21:11:00 volumio dhcpcd[830]: wlan0: IAID 35:86:fb:bf Dec 02 21:11:00 volumio systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Dec 02 21:11:00 volumio dnsmasq[1809]: dnsmasq: syntax check OK. Dec 02 21:11:00 volumio systemd[1]: wireless.service: Succeeded. Dec 02 21:11:00 volumio iwconfig[1812]: Error for wireless request "Set Power Management" (8B2C) : Dec 02 21:11:00 volumio iwconfig[1812]: SET failed on device wlan0 ; Invalid argument. Dec 02 21:11:00 volumio systemd[1]: Started Wireless Services. Dec 02 21:11:00 volumio sudo[1714]: pam_unix(sudo:session): session closed for user root Dec 02 21:11:00 volumio dnsmasq[1817]: started, version 2.80 cachesize 150 Dec 02 21:11:00 volumio dnsmasq[1817]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect inotify dumpfile Dec 02 21:11:00 volumio dnsmasq-dhcp[1817]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Dec 02 21:11:00 volumio dnsmasq[1817]: reading /etc/resolv.conf Dec 02 21:11:00 volumio dnsmasq[1817]: using nameserver 192.168.1.254#53 Dec 02 21:11:00 volumio dnsmasq[1817]: using nameserver 208.67.222.222#53 Dec 02 21:11:00 volumio dnsmasq[1817]: using nameserver 208.67.220.220#53 Dec 02 21:11:00 volumio dnsmasq[1817]: read /etc/hosts - 5 addresses Dec 02 21:11:00 volumio systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Dec 02 21:11:00 volumio dhcpcd[830]: wlan0: soliciting a DHCP lease Dec 02 21:11:01 volumio sudo[1838]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 02 21:11:01 volumio sudo[1838]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 21:11:01 volumio sudo[1838]: pam_unix(sudo:session): session closed for user root Dec 02 21:11:01 volumio sudo[1841]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 02 21:11:01 volumio sudo[1841]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 21:11:01 volumio sudo[1841]: pam_unix(sudo:session): session closed for user root Dec 02 21:11:01 volumio sudo[1844]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 02 21:11:01 volumio sudo[1844]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 21:11:01 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Dec 02 21:11:01 volumio sudo[1844]: pam_unix(sudo:session): session closed for user root Dec 02 21:11:01 volumio volumio[1026]: info: Upmpdcli Daemon Started Dec 02 21:11:01 volumio dhcpcd[830]: wlan0: soliciting an IPv6 router Dec 02 21:11:01 volumio volumio[1026]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Dec 02 21:11:01 volumio volumio[1846]: Generating RSA private key, 4096 bit long modulus (2 primes) Dec 02 21:11:01 volumio volumio[1846]: .............++++ Dec 02 21:11:01 volumio volumio[1026]: info: Discovery: Started advertising with name: Volumio Dec 02 21:11:02 volumio ntpd[1364]: Listen normally on 5 wlan0 192.168.211.1:123 Dec 02 21:11:02 volumio ntpd[1364]: new interface(s) found: waking up resolver Dec 02 21:11:02 volumio volumio[1026]: info: Discovery: adding 05909108-b3c9-4c9b-b245-60da228438a4 Dec 02 21:11:02 volumio volumio[1026]: info: Discovery: Found device Volumio Dec 02 21:11:02 volumio volumio[1026]: info: CoreCommandRouter::volumioGetState Dec 02 21:11:02 volumio volumio[1026]: info: CorePlayQueue::getTrack 0 Dec 02 21:11:02 volumio volumio[1026]: info: Discovery: this is already registered, 05909108-b3c9-4c9b-b245-60da228438a4 Dec 02 21:11:02 volumio volumio[1026]: info: Discovery: Found device Volumio Dec 02 21:11:02 volumio volumio[1026]: info: CoreCommandRouter::volumioGetState Dec 02 21:11:02 volumio volumio[1026]: info: CorePlayQueue::getTrack 0 Dec 02 21:11:03 volumio volumio[1846]: .........................................................................................................................++++ Dec 02 21:11:03 volumio volumio[1846]: e is 65537 (0x010001) Dec 02 21:11:03 volumio volumio[1846]: writing RSA key Dec 02 21:11:04 volumio volumio[1026]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Dec 02 21:11:04 volumio volumio[1026]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Dec 02 21:11:04 volumio volumio[1026]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Dec 02 21:11:04 volumio volumio[1026]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Dec 02 21:11:04 volumio volumio[1026]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Dec 02 21:11:04 volumio volumio[1026]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Dec 02 21:11:04 volumio volumio[1026]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Dec 02 21:11:04 volumio volumio[1026]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Dec 02 21:11:04 volumio volumio[1026]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Dec 02 21:11:04 volumio volumio[1026]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Dec 02 21:11:04 volumio volumio[1026]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Dec 02 21:11:04 volumio volumio[1026]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Dec 02 21:11:04 volumio volumio[1026]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Dec 02 21:11:04 volumio volumio[1026]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Dec 02 21:11:04 volumio volumio[1026]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Dec 02 21:11:04 volumio volumio[1026]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Dec 02 21:11:04 volumio volumio[1026]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Dec 02 21:11:04 volumio volumio[1026]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Dec 02 21:11:04 volumio volumio[1026]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Dec 02 21:11:04 volumio volumio[1026]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Dec 02 21:11:04 volumio volumio[1026]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Dec 02 21:11:04 volumio volumio[1026]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Dec 02 21:11:04 volumio volumio[1026]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Dec 02 21:11:04 volumio volumio[1026]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Dec 02 21:11:04 volumio volumio[1026]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Dec 02 21:11:04 volumio volumio[1026]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Dec 02 21:11:04 volumio volumio[1026]: info: Adding plugin bluetooth to MyMusic Plugins Dec 02 21:11:04 volumio volumio[1026]: info: Adding plugin multiroom to MyMusic Plugins Dec 02 21:11:04 volumio volumio[1026]: info: Adding plugin metavolumio to MyMusic Plugins Dec 02 21:11:04 volumio volumio[1026]: info: Adding plugin cd_controller to MyMusic Plugins Dec 02 21:11:04 volumio volumio[1026]: info: Adding plugin smart_inputs to MyMusic Plugins Dec 02 21:11:04 volumio volumio[1026]: info: Adding plugin tidalconnect to MyMusic Plugins Dec 02 21:11:04 volumio volumio[1026]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Dec 02 21:11:05 volumio volumio[1026]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Dec 02 21:11:05 volumio volumio[1026]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Dec 02 21:11:05 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 02 21:11:05 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 02 21:11:05 volumio volumio[1026]: info: Starting MyVolumio Remote Streaming Endpoints Dec 02 21:11:05 volumio volumio[1026]: info: MyVolumio not started Dec 02 21:11:05 volumio volumio[1026]: info: Initializing device activation check Dec 02 21:11:05 volumio volumio[1026]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Dec 02 21:11:05 volumio volumio[1026]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Dec 02 21:11:05 volumio dhcpcd[830]: wlan0: probing for an IPv4LL address Dec 02 21:11:06 volumio volumio[1026]: info: Starting Streaming Service Transparent Proxy Dec 02 21:11:06 volumio volumio[1026]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Dec 02 21:11:06 volumio volumio[1026]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Dec 02 21:11:06 volumio volumio[1026]: info: Streaming services startup Dec 02 21:11:06 volumio volumio[1026]: info: Starting Streaming Daemon Dec 02 21:11:06 volumio sudo[1915]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 02 21:11:06 volumio sudo[1915]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 21:11:06 volumio volumio[1026]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Dec 02 21:11:06 volumio sudo[1915]: pam_unix(sudo:session): session closed for user root Dec 02 21:11:06 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: wizard , reportWirelessConnection Dec 02 21:11:06 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessInfo Dec 02 21:11:06 volumio sudo[1921]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 02 21:11:06 volumio sudo[1921]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 21:11:06 volumio sudo[1921]: pam_unix(sudo:session): session closed for user root Dec 02 21:11:06 volumio volumio[1026]: error: Cannot start Volumio Streaming Daemon Dec 02 21:11:06 volumio volumio[1026]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 02 21:11:06 volumio volumio[1026]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 02 21:11:07 volumio volumio[1026]: STREAMING PROXY: Starting server on port 3245 Dec 02 21:11:07 volumio volumio[1026]: Node JS runtime: 14 Dec 02 21:11:10 volumio dhcpcd[830]: wlan0: using IPv4LL address 169.254.192.249 Dec 02 21:11:10 volumio dhcpcd[830]: wlan0: adding route to 169.254.0.0/16 Dec 02 21:11:10 volumio avahi-daemon[735]: Registering new address record for 169.254.192.249 on wlan0.IPv4. Dec 02 21:11:10 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart Dec 02 21:11:10 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart Dec 02 21:11:10 volumio volumio[1026]: info: Refreshing Cached IP Addresses Dec 02 21:11:10 volumio sudo[2037]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Dec 02 21:11:10 volumio sudo[2037]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 21:11:10 volumio sudo[2037]: pam_unix(sudo:session): session closed for user root Dec 02 21:11:10 volumio sudo[2040]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 02 21:11:10 volumio sudo[2040]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 21:11:10 volumio sudo[2040]: pam_unix(sudo:session): session closed for user root Dec 02 21:11:10 volumio sudo[2043]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 02 21:11:10 volumio sudo[2043]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 21:11:10 volumio sudo[2043]: pam_unix(sudo:session): session closed for user root Dec 02 21:11:10 volumio systemd[1]: upmpdcli.service: Succeeded. Dec 02 21:11:10 volumio volumio[1026]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Dec 02 21:11:11 volumio volumio[1026]: info: CoreCommandRouter::volumioGetVisibleSources Dec 02 21:11:11 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 02 21:11:11 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Dec 02 21:11:11 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Dec 02 21:11:11 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Dec 02 21:11:11 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Dec 02 21:11:11 volumio volumio[1026]: info: Received Get System Info Dec 02 21:11:11 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 02 21:11:11 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 02 21:11:11 volumio volumio[1026]: info: Discovery: Getting this device information Dec 02 21:11:11 volumio volumio[1026]: info: CoreCommandRouter::volumioGetState Dec 02 21:11:11 volumio volumio[1026]: info: CorePlayQueue::getTrack 0 Dec 02 21:11:11 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 02 21:11:11 volumio volumio-remote-updater[751]: No test mode Dec 02 21:11:11 volumio volumio-remote-updater[751]: No alpha test mode Dec 02 21:11:11 volumio volumio[1026]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Dec 02 21:11:11 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Dec 02 21:11:11 volumio systemd[1]: systemd-fsckd.service: Succeeded. Dec 02 21:11:11 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 02 21:11:11 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 02 21:11:11 volumio volumio[1026]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 02 21:11:11 volumio volumio[1026]: info: Retrieving Cloud Streaming UI Dec 02 21:11:11 volumio volumio[1026]: info: Getting Tidal Cloud Configuration Dec 02 21:11:11 volumio volumio[1026]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 02 21:11:11 volumio volumio[1026]: info: Getting Qobuz Cloud Configuration Dec 02 21:11:11 volumio volumio[1026]: info: Asking plugin for UI Config Dec 02 21:11:11 volumio volumio[1026]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 02 21:11:11 volumio volumio[1026]: info: Getting Spotify Cloud Configuration Dec 02 21:11:11 volumio volumio[1026]: info: Asking plugin for UI Config Dec 02 21:11:11 volumio volumio[1026]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 02 21:11:11 volumio volumio[1026]: info: Saving Spotify Acccount Dec 02 21:11:11 volumio volumio[1026]: info: Got Tidal Cloud Configuration Dec 02 21:11:11 volumio volumio[1026]: info: Got it Dec 02 21:11:11 volumio volumio[1026]: info: Got it Dec 02 21:11:11 volumio volumio[1026]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 02 21:11:11 volumio volumio[1026]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 02 21:11:11 volumio volumio[1026]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Dec 02 21:11:12 volumio systemd[1]: systemd-hostnamed.service: Succeeded. Dec 02 21:11:12 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Dec 02 21:11:12 volumio volumio[1026]: info: Loading i18n strings for locale en Dec 02 21:11:12 volumio ntpd[1364]: Listen normally on 6 wlan0 169.254.192.249:123 Dec 02 21:11:12 volumio ntpd[1364]: new interface(s) found: waking up resolver Dec 02 21:11:12 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Dec 02 21:11:12 volumio volumio[1026]: info: Setting timezone to UTC Dec 02 21:11:12 volumio sudo[2066]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Dec 02 21:11:12 volumio sudo[2066]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 21:11:12 volumio sudo[2066]: pam_unix(sudo:session): session closed for user root Dec 02 21:11:12 volumio sudo[2069]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/UTC /etc/localtime Dec 02 21:11:12 volumio sudo[2069]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 21:11:12 volumio sudo[2069]: pam_unix(sudo:session): session closed for user root Dec 02 21:11:12 volumio sudo[2072]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Dec 02 21:11:12 volumio sudo[2072]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 21:11:12 volumio sudo[2072]: pam_unix(sudo:session): session closed for user root Dec 02 21:11:12 volumio sudo[2075]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone UTC Dec 02 21:11:12 volumio sudo[2075]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 21:11:12 volumio systemd-timedated[1599]: Failed to set time zone: Permission denied Dec 02 21:11:12 volumio sudo[2075]: pam_unix(sudo:session): session closed for user root Dec 02 21:11:12 volumio volumio[1026]: Failed to set time zone: Access denied Dec 02 21:11:12 volumio volumio[1026]: info: Could not set timezone, retrying Dec 02 21:11:12 volumio volumio[1026]: Updating browse sources language Dec 02 21:11:12 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 02 21:11:12 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 02 21:11:12 volumio volumio[1026]: info: Fetching Streaming Services browse cache Dec 02 21:11:13 volumio sudo[2249]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone UTC Dec 02 21:11:13 volumio sudo[2249]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 21:11:13 volumio sudo[2249]: pam_unix(sudo:session): session closed for user root Dec 02 21:11:18 volumio volumio[1026]: verbose: New Socket.io Connection to 192.168.1.212 from 192.168.1.111 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.1.1 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 10 Dec 02 21:11:18 volumio volumio[1026]: info: CoreCommandRouter::volumioGetState Dec 02 21:11:18 volumio volumio[1026]: info: CorePlayQueue::getTrack 0 Dec 02 21:11:18 volumio volumio[1026]: info: Received Get System Info Dec 02 21:11:18 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 02 21:11:18 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 02 21:11:18 volumio volumio[1026]: info: Discovery: Getting this device information Dec 02 21:11:18 volumio volumio[1026]: info: CoreCommandRouter::volumioGetState Dec 02 21:11:18 volumio volumio[1026]: info: CorePlayQueue::getTrack 0 Dec 02 21:11:18 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 02 21:11:18 volumio volumio[1026]: info: CoreCommandRouter::volumioGetState Dec 02 21:11:18 volumio volumio[1026]: info: CorePlayQueue::getTrack 0 Dec 02 21:11:18 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 02 21:11:18 volumio volumio[1026]: info: CoreCommandRouter::volumioGetVisibleSources Dec 02 21:11:18 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 02 21:11:18 volumio volumio[1026]: info: CoreCommandRouter::volumioGetState Dec 02 21:11:18 volumio volumio[1026]: info: CorePlayQueue::getTrack 0 Dec 02 21:11:18 volumio volumio[1026]: info: CoreCommandRouter::volumioGetState Dec 02 21:11:18 volumio volumio[1026]: info: CorePlayQueue::getTrack 0 Dec 02 21:11:18 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 02 21:11:18 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 02 21:11:18 volumio volumio[1026]: info: Received Get System Info Dec 02 21:11:18 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 02 21:11:18 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 02 21:11:18 volumio volumio[1026]: info: Discovery: Getting this device information Dec 02 21:11:18 volumio volumio[1026]: info: CoreCommandRouter::volumioGetState Dec 02 21:11:18 volumio volumio[1026]: info: CorePlayQueue::getTrack 0 Dec 02 21:11:18 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 02 21:11:18 volumio volumio[1026]: info: CoreCommandRouter::volumioGetState Dec 02 21:11:18 volumio volumio[1026]: info: CorePlayQueue::getTrack 0 Dec 02 21:11:18 volumio volumio[1026]: info: Listing playlists Dec 02 21:11:18 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 02 21:11:18 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 02 21:11:20 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 02 21:11:20 volumio volumio[1026]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Dec 02 21:11:20 volumio volumio[1026]: info: CoreCommandRouter::volumioGetState Dec 02 21:11:20 volumio volumio[1026]: info: CorePlayQueue::getTrack 0 Dec 02 21:11:20 volumio sudo[2256]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 02 21:11:20 volumio sudo[2256]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 21:11:20 volumio sudo[2256]: pam_unix(sudo:session): session closed for user root Dec 02 21:11:20 volumio sudo[2259]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 02 21:11:20 volumio sudo[2259]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 21:11:20 volumio sudo[2259]: pam_unix(sudo:session): session closed for user root Dec 02 21:11:20 volumio sudo[2262]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 02 21:11:20 volumio sudo[2262]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 21:11:20 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Dec 02 21:11:20 volumio sudo[2262]: pam_unix(sudo:session): session closed for user root Dec 02 21:11:20 volumio volumio[1026]: info: Upmpdcli Daemon Started Dec 02 21:11:20 volumio volumio[2264]: writing RSA key Dec 02 21:11:23 volumio volumio[1026]: info: CoreCommandRouter::Close All Modals sent Dec 02 21:11:23 volumio volumio[1026]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 02 21:11:23 volumio volumio[1026]: info: Retrieving Cloud Streaming UI Dec 02 21:11:23 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: appearance , setTOSAccepted Dec 02 21:11:23 volumio volumio[1026]: info: Getting Tidal Cloud Configuration Dec 02 21:11:23 volumio volumio[1026]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 02 21:11:23 volumio volumio[1026]: info: Getting Qobuz Cloud Configuration Dec 02 21:11:23 volumio volumio[1026]: info: Asking plugin for UI Config Dec 02 21:11:23 volumio volumio[1026]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 02 21:11:23 volumio volumio[1026]: info: Getting Spotify Cloud Configuration Dec 02 21:11:23 volumio volumio[1026]: info: Asking plugin for UI Config Dec 02 21:11:23 volumio volumio[1026]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 02 21:11:23 volumio volumio[1026]: info: Saving Spotify Acccount Dec 02 21:11:23 volumio volumio[1026]: info: Got Tidal Cloud Configuration Dec 02 21:11:23 volumio volumio[1026]: info: Got it Dec 02 21:11:23 volumio volumio[1026]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 02 21:11:23 volumio volumio[1026]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 02 21:11:23 volumio volumio[1026]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Dec 02 21:11:25 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 21:11:25 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 21:11:25 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Dec 02 21:11:25 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 02 21:11:25 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 02 21:11:25 volumio volumio[1026]: info: CoreCommandRouter::volumioGetBrowseSources Dec 02 21:11:25 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 02 21:11:26 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 02 21:11:26 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 02 21:11:26 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 02 21:11:26 volumio volumio-remote-updater[751]: No test mode Dec 02 21:11:26 volumio volumio-remote-updater[751]: No alpha test mode Dec 02 21:11:26 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Dec 02 21:11:26 volumio volumio[1026]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Dec 02 21:11:26 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Dec 02 21:11:26 volumio volumio[1026]: error: MyVolumio Plugin failed to authenticate in a timely fashion Dec 02 21:11:26 volumio volumio[1026]: info: Completed starting MyVolumio Plugin Dec 02 21:11:26 volumio volumio[1026]: [Metrics] CommandRouter: 36s 741.89ms Dec 02 21:11:26 volumio volumio[1026]: info: CoreCommandRouter::volumiosetStartupVolume Dec 02 21:11:26 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 21:11:26 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 02 21:11:26 volumio volumio[1026]: info: CoreCommandRouter::Close All Modals sent Dec 02 21:11:26 volumio volumio[1026]: info: CoreCommandRouter::Close All Modals sent Dec 02 21:11:26 volumio volumio[1026]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 02 21:11:26 volumio volumio[1026]: info: Retrieving Cloud Streaming UI Dec 02 21:11:26 volumio volumio[1026]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 02 21:11:26 volumio volumio[1026]: info: Retrieving Cloud Streaming UI Dec 02 21:11:26 volumio volumio[1026]: info: Getting Tidal Cloud Configuration Dec 02 21:11:26 volumio volumio[1026]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 02 21:11:26 volumio volumio[1026]: info: Getting Qobuz Cloud Configuration Dec 02 21:11:26 volumio volumio[1026]: info: Asking plugin for UI Config Dec 02 21:11:26 volumio volumio[1026]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 02 21:11:26 volumio volumio[1026]: info: Getting Spotify Cloud Configuration Dec 02 21:11:26 volumio volumio[1026]: info: Asking plugin for UI Config Dec 02 21:11:26 volumio volumio[1026]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 02 21:11:26 volumio volumio[1026]: info: Saving Spotify Acccount Dec 02 21:11:26 volumio volumio[1026]: info: Got Tidal Cloud Configuration Dec 02 21:11:26 volumio volumio[1026]: info: Got it Dec 02 21:11:26 volumio volumio[1026]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 02 21:11:26 volumio volumio[1026]: info: Getting Tidal Cloud Configuration Dec 02 21:11:26 volumio volumio[1026]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 02 21:11:26 volumio volumio[1026]: info: Getting Qobuz Cloud Configuration Dec 02 21:11:26 volumio volumio[1026]: info: Asking plugin for UI Config Dec 02 21:11:26 volumio volumio[1026]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 02 21:11:26 volumio volumio[1026]: info: Getting Spotify Cloud Configuration Dec 02 21:11:26 volumio volumio[1026]: info: Asking plugin for UI Config Dec 02 21:11:26 volumio volumio[1026]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 02 21:11:26 volumio volumio[1026]: info: Saving Spotify Acccount Dec 02 21:11:26 volumio volumio[1026]: info: Got Tidal Cloud Configuration Dec 02 21:11:26 volumio volumio[1026]: info: Got it Dec 02 21:11:26 volumio volumio[1026]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 02 21:11:26 volumio volumio[1026]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 02 21:11:26 volumio volumio[1026]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Dec 02 21:11:26 volumio volumio[1026]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 02 21:11:26 volumio volumio[1026]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Dec 02 21:11:27 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Dec 02 21:11:27 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 02 21:11:27 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Dec 02 21:11:27 volumio volumio[1026]: info: Version has changed, forcing UI Reload Dec 02 21:11:27 volumio volumio[1026]: info: CoreCommandRouter::Reload Ui Dec 02 21:11:27 volumio sudo[2322]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 02 21:11:27 volumio sudo[2322]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 21:11:27 volumio sudo[2322]: pam_unix(sudo:session): session closed for user root Dec 02 21:11:27 volumio sudo[2325]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 02 21:11:27 volumio sudo[2325]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 21:11:27 volumio sudo[2325]: pam_unix(sudo:session): session closed for user root Dec 02 21:11:27 volumio volumio[1026]: verbose: New Socket.io Connection to 192.168.1.212 from 192.168.1.111 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.1.1 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 4 Dec 02 21:11:27 volumio volumio[1026]: info: CoreCommandRouter::volumioGetVisibleSources Dec 02 21:11:27 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 02 21:11:27 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Dec 02 21:11:27 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Dec 02 21:11:28 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Dec 02 21:11:28 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Dec 02 21:11:28 volumio volumio[1026]: info: Received Get System Info Dec 02 21:11:28 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 02 21:11:28 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 02 21:11:28 volumio volumio[1026]: info: Discovery: Getting this device information Dec 02 21:11:28 volumio volumio[1026]: info: CoreCommandRouter::volumioGetState Dec 02 21:11:28 volumio volumio[1026]: info: CorePlayQueue::getTrack 0 Dec 02 21:11:28 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 02 21:11:28 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 02 21:11:28 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 02 21:11:28 volumio volumio-remote-updater[751]: No test mode Dec 02 21:11:28 volumio volumio-remote-updater[751]: No alpha test mode Dec 02 21:11:28 volumio volumio[1026]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 02 21:11:28 volumio volumio[1026]: info: Retrieving Cloud Streaming UI Dec 02 21:11:28 volumio volumio[1026]: info: Getting Tidal Cloud Configuration Dec 02 21:11:28 volumio volumio[1026]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 02 21:11:28 volumio volumio[1026]: info: Getting Qobuz Cloud Configuration Dec 02 21:11:28 volumio volumio[1026]: info: Asking plugin for UI Config Dec 02 21:11:28 volumio volumio[1026]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 02 21:11:28 volumio volumio[1026]: info: Getting Spotify Cloud Configuration Dec 02 21:11:28 volumio volumio[1026]: info: Asking plugin for UI Config Dec 02 21:11:28 volumio volumio[1026]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 02 21:11:28 volumio volumio[1026]: info: Saving Spotify Acccount Dec 02 21:11:28 volumio volumio[1026]: info: Got Tidal Cloud Configuration Dec 02 21:11:28 volumio volumio[1026]: info: Got it Dec 02 21:11:28 volumio volumio[1026]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 02 21:11:28 volumio volumio[1026]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 02 21:11:28 volumio volumio[1026]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Dec 02 21:11:28 volumio volumio[1026]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Dec 02 21:11:28 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Dec 02 21:11:28 volumio volumio[1026]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Dec 02 21:11:28 volumio volumio[1026]: info: CoreCommandRouter::volumioGetVisibleSources Dec 02 21:11:28 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 02 21:11:28 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Dec 02 21:11:28 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Dec 02 21:11:28 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Dec 02 21:11:28 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Dec 02 21:11:28 volumio volumio[1026]: info: Received Get System Info Dec 02 21:11:28 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 02 21:11:28 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 02 21:11:28 volumio volumio[1026]: info: Discovery: Getting this device information Dec 02 21:11:28 volumio volumio[1026]: info: CoreCommandRouter::volumioGetState Dec 02 21:11:28 volumio volumio[1026]: info: CorePlayQueue::getTrack 0 Dec 02 21:11:28 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 02 21:11:28 volumio volumio-remote-updater[751]: No test mode Dec 02 21:11:28 volumio volumio-remote-updater[751]: No alpha test mode Dec 02 21:11:28 volumio volumio[1026]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Dec 02 21:11:28 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Dec 02 21:11:28 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 02 21:11:28 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 02 21:11:28 volumio volumio[1026]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 02 21:11:28 volumio volumio[1026]: info: Retrieving Cloud Streaming UI Dec 02 21:11:28 volumio volumio[1026]: info: Getting Tidal Cloud Configuration Dec 02 21:11:28 volumio volumio[1026]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 02 21:11:28 volumio volumio[1026]: info: Getting Qobuz Cloud Configuration Dec 02 21:11:28 volumio volumio[1026]: info: Asking plugin for UI Config Dec 02 21:11:28 volumio volumio[1026]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 02 21:11:28 volumio volumio[1026]: info: Getting Spotify Cloud Configuration Dec 02 21:11:28 volumio volumio[1026]: info: Asking plugin for UI Config Dec 02 21:11:28 volumio volumio[1026]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 02 21:11:28 volumio volumio[1026]: info: Saving Spotify Acccount Dec 02 21:11:28 volumio volumio[1026]: info: Got Tidal Cloud Configuration Dec 02 21:11:28 volumio volumio[1026]: info: Got it Dec 02 21:11:28 volumio volumio[1026]: info: Got it Dec 02 21:11:28 volumio volumio[1026]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 02 21:11:28 volumio volumio[1026]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 02 21:11:28 volumio volumio[1026]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Dec 02 21:11:29 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Dec 02 21:11:29 volumio volumio[1026]: info: Loading i18n strings for locale en Dec 02 21:11:29 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Dec 02 21:11:29 volumio volumio[1026]: info: Setting timezone to Europe/London Dec 02 21:11:29 volumio sudo[2336]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Dec 02 21:11:29 volumio sudo[2336]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 21:11:29 volumio sudo[2336]: pam_unix(sudo:session): session closed for user root Dec 02 21:11:29 volumio sudo[2339]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Europe/London /etc/localtime Dec 02 21:11:29 volumio sudo[2339]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 21:11:29 volumio sudo[2339]: pam_unix(sudo:session): session closed for user root Dec 02 21:11:29 volumio sudo[2342]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Dec 02 21:11:29 volumio sudo[2342]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 21:11:29 volumio sudo[2342]: pam_unix(sudo:session): session closed for user root Dec 02 21:11:29 volumio sudo[2345]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Europe/London Dec 02 21:11:29 volumio sudo[2345]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 21:11:29 volumio systemd-timedated[1599]: Failed to set time zone: Permission denied Dec 02 21:11:29 volumio sudo[2345]: pam_unix(sudo:session): session closed for user root Dec 02 21:11:29 volumio volumio[1026]: Failed to set time zone: Access denied Dec 02 21:11:29 volumio volumio[1026]: info: Could not set timezone, retrying Dec 02 21:11:29 volumio volumio[1026]: Updating browse sources language Dec 02 21:11:29 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 02 21:11:29 volumio volumio[1026]: info: Fetching Streaming Services browse cache Dec 02 21:11:29 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Dec 02 21:11:29 volumio volumio[1026]: info: Loading i18n strings for locale en Dec 02 21:11:29 volumio volumio[1026]: Updating browse sources language Dec 02 21:11:29 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 02 21:11:29 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Dec 02 21:11:29 volumio volumio[1026]: info: Setting timezone to UTC Dec 02 21:11:29 volumio sudo[2348]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Dec 02 21:11:29 volumio sudo[2348]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 21:11:29 volumio sudo[2348]: pam_unix(sudo:session): session closed for user root Dec 02 21:11:29 volumio sudo[2351]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/UTC /etc/localtime Dec 02 21:11:29 volumio sudo[2351]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 21:11:29 volumio sudo[2351]: pam_unix(sudo:session): session closed for user root Dec 02 21:11:29 volumio sudo[2354]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Dec 02 21:11:29 volumio sudo[2354]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 21:11:29 volumio sudo[2354]: pam_unix(sudo:session): session closed for user root Dec 02 21:11:29 volumio sudo[2357]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone UTC Dec 02 21:11:29 volumio sudo[2357]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 21:11:29 volumio systemd-timedated[1599]: Failed to set time zone: Permission denied Dec 02 21:11:29 volumio sudo[2357]: pam_unix(sudo:session): session closed for user root Dec 02 21:11:29 volumio volumio[1026]: Failed to set time zone: Access denied Dec 02 21:11:29 volumio volumio[1026]: info: Could not set timezone, retrying Dec 02 21:11:29 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 02 21:11:30 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 02 21:11:30 volumio volumio[1026]: info: Fetching Streaming Services browse cache Dec 02 21:11:30 volumio sudo[2360]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Europe/London Dec 02 21:11:30 volumio sudo[2360]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 21:11:30 volumio systemd-timedated[1599]: Failed to set time zone: Permission denied Dec 02 21:11:30 volumio sudo[2360]: pam_unix(sudo:session): session closed for user root Dec 02 21:11:30 volumio volumio[1026]: Failed to set time zone: Access denied Dec 02 21:11:30 volumio volumio[1026]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 02 21:11:30 volumio volumio[1026]: Error: Command failed: /usr/bin/sudo /usr/bin/timedatectl set-timezone 'Europe/London' Dec 02 21:11:30 volumio volumio[1026]: Failed to set time zone: Access denied Dec 02 21:11:30 volumio volumio[1026]: at checkExecSyncError (child_process.js:790:11) Dec 02 21:11:30 volumio volumio[1026]: at execSync (child_process.js:863:15) Dec 02 21:11:30 volumio volumio[1026]: at Timeout._onTimeout (/volumio/app/plugins/system_controller/system/index.js:1575:9) Dec 02 21:11:30 volumio volumio[1026]: at listOnTimeout (internal/timers.js:557:17) Dec 02 21:11:30 volumio volumio[1026]: at processTimers (internal/timers.js:500:7) { Dec 02 21:11:30 volumio volumio[1026]: status: 1, Dec 02 21:11:30 volumio volumio[1026]: signal: null, Dec 02 21:11:30 volumio volumio[1026]: output: [ null, '', 'Failed to set time zone: Access denied\n' ], Dec 02 21:11:30 volumio volumio[1026]: pid: 2359, Dec 02 21:11:30 volumio volumio[1026]: stdout: '', Dec 02 21:11:30 volumio volumio[1026]: stderr: 'Failed to set time zone: Access denied\n' Dec 02 21:11:30 volumio volumio[1026]: } Dec 02 21:11:30 volumio volumio[1026]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 02 21:11:30 volumio sudo[2371]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-12-02 21:10 Dec 02 21:11:30 volumio sudo[2371]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 07:25:16 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="66c567362bdbb3dc1644a18f7879afe7"