-- Logs begin at Sat 2025-06-28 17:41:35 +07, end at Sat 2025-06-28 17:43:05 +07. -- Jun 28 17:42:00 volumio firststart.sh[983]: debconf: unable to initialize frontend: Dialog Jun 28 17:42:00 volumio firststart.sh[983]: debconf: (TERM is not set, so the dialog frontend is not usable.) Jun 28 17:42:00 volumio firststart.sh[983]: debconf: falling back to frontend: Readline Jun 28 17:42:00 volumio firststart.sh[983]: debconf: unable to initialize frontend: Readline Jun 28 17:42:00 volumio firststart.sh[983]: debconf: (This frontend requires a controlling tty.) Jun 28 17:42:00 volumio firststart.sh[983]: debconf: falling back to frontend: Teletype Jun 28 17:42:01 volumio dhcpcd[852]: eth0: leased 192.168.3.44 for 604800 seconds Jun 28 17:42:01 volumio dhcpcd[852]: eth0: adding route to 192.168.3.0/24 Jun 28 17:42:01 volumio dhcpcd[852]: eth0: adding default route via 192.168.3.1 Jun 28 17:42:01 volumio avahi-daemon[734]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.3.44. Jun 28 17:42:01 volumio avahi-daemon[734]: New relevant interface eth0.IPv4 for mDNS. Jun 28 17:42:01 volumio avahi-daemon[734]: Registering new address record for 192.168.3.44 on eth0.IPv4. Jun 28 17:42:01 volumio dhcpcd[852]: eth0: dhcp_envoption 213: Operation not supported Jun 28 17:42:01 volumio dhcpcd[852]: eth0: dhcp_envoption 213: Operation not supported Jun 28 17:42:01 volumio ntpd[958]: ntpd exiting on signal 15 (Terminated) Jun 28 17:42:01 volumio systemd[1]: Stopping Network Time Service... Jun 28 17:42:01 volumio systemd[1]: ntp.service: Succeeded. Jun 28 17:42:01 volumio systemd[1]: Stopped Network Time Service. Jun 28 17:42:01 volumio volumio-time-update[731]: volumio-time-update-util: Fetching time from Volumio... Jun 28 17:42:02 volumio systemd[1]: Starting Network Time Service... Jun 28 17:42:02 volumio ntpd[1215]: ntpd 4.2.8p12@1.3728-o (1): Starting Jun 28 17:42:02 volumio ntpd[1215]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Jun 28 17:42:02 volumio systemd[1]: Started Network Time Service. Jun 28 17:42:02 volumio ntpd[1222]: proto: precision = 0.143 usec (-23) Jun 28 17:42:02 volumio ntpd[1222]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jun 28 17:42:02 volumio ntpd[1222]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Jun 28 17:42:02 volumio ntpd[1222]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 914 days ago Jun 28 17:42:02 volumio ntpd[1222]: Listen and drop on 0 v6wildcard [::]:123 Jun 28 17:42:02 volumio ntpd[1222]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jun 28 17:42:02 volumio ntpd[1222]: Listen normally on 2 lo 127.0.0.1:123 Jun 28 17:42:02 volumio ntpd[1222]: Listen normally on 3 eth0 192.168.3.44:123 Jun 28 17:42:02 volumio ntpd[1222]: Listening on routing socket on fd #20 for interface updates Jun 28 17:42:02 volumio ntpd[1222]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jun 28 17:42:02 volumio ntpd[1222]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jun 28 17:42:03 volumio volumio-time-update[731]: volumio-time-update-util: Setting system time to: 2025-06-28 10:42:02 Jun 28 17:42:03 volumio sudo[1289]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-06-28 10:42:02 Jun 28 17:42:03 volumio sudo[1289]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 17:42:03 volumio dbus-daemon[743]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.6' (uid=0 pid=1293 comm="timedatectl set-time 2025-06-28 10:42:02 ") Jun 28 17:42:03 volumio systemd[1]: Starting Time & Date Service... Jun 28 17:42:03 volumio dbus-daemon[743]: [system] Successfully activated service 'org.freedesktop.timedate1' Jun 28 17:42:03 volumio systemd[1]: Started Time & Date Service. Jun 28 17:42:02 volumio systemd-timedated[1310]: Changed local time to Sat Jun 28 10:42:02 2025 Jun 28 17:42:02 volumio sudo[1289]: pam_unix(sudo:session): session closed for user root Jun 28 17:42:02 volumio volumio-time-update[731]: volumio-time-update-util: System time updated successfully. Jun 28 17:42:02 volumio systemd[1]: Started Volumio Time Update Utility. Jun 28 17:42:02 volumio firststart.sh[983]: Creating SSH2 RSA key; this may take some time ... Jun 28 17:42:02 volumio firststart.sh[983]: 2048 SHA256:yowBehmJwrq2DljAPxT5yeOHqz2PIsZBUhUh5yxxRy0 root@volumio (RSA) Jun 28 17:42:02 volumio volumio[981]: info: ------------------------------------------- Jun 28 17:42:02 volumio volumio[981]: info: ----- Volumio3 ---- Jun 28 17:42:02 volumio volumio[981]: info: ------------------------------------------- Jun 28 17:42:02 volumio volumio[981]: info: ----- System startup ---- Jun 28 17:42:02 volumio volumio[981]: info: ------------------------------------------- Jun 28 17:42:02 volumio firststart.sh[983]: Creating SSH2 DSA key; this may take some time ... Jun 28 17:42:02 volumio firststart.sh[983]: 1024 SHA256:ET0T0kJEJScBKxjaYo5aPSE5G2xp8yzJPayrCerrpIM root@volumio (DSA) Jun 28 17:42:02 volumio ntpd[1222]: Soliciting pool server 103.70.115.65 Jun 28 17:42:02 volumio firststart.sh[983]: Creating SSH2 ECDSA key; this may take some time ... Jun 28 17:42:02 volumio firststart.sh[983]: 256 SHA256:ygBwin7h31jll5fOvyzXVpI6Pe1tIT1HfBo9QULRkhc root@volumio (ECDSA) Jun 28 17:42:02 volumio nmbd[896]: [2025/06/28 10:42:02.765479, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jun 28 17:42:02 volumio systemd[1]: Started Samba NMB Daemon. Jun 28 17:42:02 volumio nmbd[896]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Jun 28 17:42:02 volumio systemd[1]: Starting Samba Winbind Daemon... Jun 28 17:42:03 volumio ntpd[1222]: Soliciting pool server 115.165.161.155 Jun 28 17:42:03 volumio ntpd[1222]: Soliciting pool server 103.184.124.254 Jun 28 17:42:04 volumio ntpd[1222]: Soliciting pool server 103.188.82.179 Jun 28 17:42:04 volumio ntpd[1222]: Soliciting pool server 103.199.19.135 Jun 28 17:42:04 volumio ntpd[1222]: Soliciting pool server 27.71.27.209 Jun 28 17:42:04 volumio winbindd[1376]: [2025/06/28 10:42:04.390113, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Jun 28 17:42:04 volumio winbindd[1376]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Jun 28 17:42:04 volumio volumio-remote-updater[733]: [2025-06-28 10:42:04] [connect] Successful connection Jun 28 17:42:05 volumio ntpd[1222]: Soliciting pool server 2400:e920:0:5::14 Jun 28 17:42:05 volumio winbindd[1376]: [2025/06/28 10:42:05.952813, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jun 28 17:42:05 volumio systemd[1]: Started Samba Winbind Daemon. Jun 28 17:42:05 volumio winbindd[1376]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Jun 28 17:42:05 volumio systemd[1]: Starting Samba SMB Daemon... Jun 28 17:42:06 volumio systemd[1]: Reloading. Jun 28 17:42:06 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 28 17:42:06 volumio volumio[981]: info: File /data/configuration/plugins.json does not exist. Copying from Volumio Jun 28 17:42:06 volumio systemd[1]: getty@tty1.service: Current command vanished from the unit file, execution of the command list won't be resumed. Jun 28 17:42:06 volumio volumio[981]: info: MYVOLUMIO Environment detected Jun 28 17:42:06 volumio volumio[981]: info: Plugin folders cleanup Jun 28 17:42:06 volumio volumio[981]: info: Scanning into folder /volumio/app/plugins/ Jun 28 17:42:06 volumio volumio[981]: info: Scanning category audio_interface Jun 28 17:42:06 volumio firststart.sh[983]: rescue-ssh.target is a disabled or a static unit, not starting it. Jun 28 17:42:06 volumio volumio[981]: info: Scanning category miscellanea Jun 28 17:42:06 volumio volumio[981]: info: Scanning category music_service Jun 28 17:42:06 volumio volumio[981]: info: Scanning category plugins.json Jun 28 17:42:06 volumio volumio[981]: info: Scanning category system_controller Jun 28 17:42:06 volumio volumio[981]: info: Scanning category user_interface Jun 28 17:42:06 volumio volumio[981]: info: Scanning into folder /data/plugins/ Jun 28 17:42:06 volumio volumio[981]: info: Plugin folders cleanup completed Jun 28 17:42:06 volumio volumio[981]: info: ------------------------------------------- Jun 28 17:42:06 volumio volumio[981]: info: ----- Core plugins startup ---- Jun 28 17:42:06 volumio volumio[981]: info: ------------------------------------------- Jun 28 17:42:06 volumio volumio[981]: info: Loading plugins from folder /volumio/app/plugins/ Jun 28 17:42:06 volumio volumio[981]: info: Adding plugin upnp to MyMusic Plugins Jun 28 17:42:06 volumio volumio[981]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 28 17:42:06 volumio volumio[981]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 28 17:42:06 volumio volumio[981]: info: Loading plugins from folder /data/plugins/ Jun 28 17:42:06 volumio volumio[981]: info: Loading plugin "system"... Jun 28 17:42:06 volumio volumio[981]: info: No id defined. Creating one Jun 28 17:42:06 volumio volumio[981]: info: Loading plugin "appearance"... Jun 28 17:42:07 volumio firststart.sh[983]: insserv: warning: current start runlevel(s) (empty) of script `ssh' overrides LSB defaults (2 3 4 5). Jun 28 17:42:07 volumio firststart.sh[983]: insserv: warning: current stop runlevel(s) (2 3 4 5) of script `ssh' overrides LSB defaults (empty). Jun 28 17:42:08 volumio systemd[1]: Reloading. Jun 28 17:42:11 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 28 17:42:11 volumio smbd[1392]: [2025/06/28 10:42:11.805895, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jun 28 17:42:11 volumio smbd[1392]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Jun 28 17:42:12 volumio systemd[1]: Started Samba SMB Daemon. Jun 28 17:42:12 volumio systemd[1]: Reached target Multi-User System. Jun 28 17:42:12 volumio systemd[1]: Reached target Graphical Interface. Jun 28 17:42:12 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Jun 28 17:42:12 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jun 28 17:42:12 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Jun 28 17:42:12 volumio systemd[1]: Startup finished in 11.774s (firmware) + 9.935s (loader) + 2min 41.022s (kernel) + 39.547s (userspace) = 3min 42.280s. Jun 28 17:42:12 volumio firststart.sh[983]: Enabling SSH for first boot Jun 28 17:42:12 volumio systemd[1]: Starting OpenBSD Secure Shell server... Jun 28 17:42:12 volumio sshd[1462]: Server listening on 0.0.0.0 port 22. Jun 28 17:42:12 volumio sshd[1462]: Server listening on :: port 22. Jun 28 17:42:12 volumio systemd[1]: Started OpenBSD Secure Shell server. Jun 28 17:42:12 volumio firststart.sh[983]: Disabling firststart service Jun 28 17:42:12 volumio firststart.sh[983]: Removed /etc/systemd/system/multi-user.target.wants/firststart.service. Jun 28 17:42:12 volumio systemd[1]: Reloading. Jun 28 17:42:12 volumio volumio[981]: info: Loading plugin "network"... Jun 28 17:42:12 volumio volumio[981]: info: Refreshing Cached IP Addresses Jun 28 17:42:12 volumio sudo[1478]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 28 17:42:12 volumio sudo[1478]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 17:42:12 volumio sudo[1480]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 28 17:42:12 volumio sudo[1480]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 17:42:12 volumio sudo[1478]: pam_unix(sudo:session): session closed for user root Jun 28 17:42:12 volumio sudo[1480]: pam_unix(sudo:session): session closed for user root Jun 28 17:42:12 volumio volumio[981]: info: Loading plugin "services"... Jun 28 17:42:12 volumio volumio[981]: info: Loading plugin "alsa_controller"... Jun 28 17:42:12 volumio sudo[1491]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 28 17:42:12 volumio sudo[1491]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 17:42:12 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 28 17:42:12 volumio sudo[1491]: pam_unix(sudo:session): session closed for user root Jun 28 17:42:12 volumio volumio[981]: warn: The ALSA output card is not set, defaulting to card [object Object] Jun 28 17:42:12 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 28 17:42:12 volumio volumio[981]: info: Found match in Cards Database: setting mixer for card HDMI 0 Jun 28 17:42:12 volumio volumio[981]: info: Setting mixer IEC958 for card HDMI 0 Jun 28 17:42:12 volumio volumio[981]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jun 28 17:42:12 volumio volumio[981]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jun 28 17:42:12 volumio volumio[981]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jun 28 17:42:12 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 28 17:42:12 volumio volumio[981]: info: Loading plugin "wizard"... Jun 28 17:42:12 volumio volumio[981]: info: Loading plugin "networkfs"... Jun 28 17:42:12 volumio volumio[981]: info: Starting Udev Watcher for removable devices Jun 28 17:42:12 volumio volumio[981]: info: Ignoring mount for partition: boot Jun 28 17:42:12 volumio volumio[981]: info: Ignoring mount for partition: volumio Jun 28 17:42:12 volumio volumio[981]: info: Ignoring mount for partition: volumio_data Jun 28 17:42:12 volumio volumio[981]: info: Ignoring mount for partition: boot Jun 28 17:42:12 volumio volumio[981]: info: Ignoring mount for partition: volumio Jun 28 17:42:12 volumio volumio[981]: info: Ignoring mount for partition: volumio_data Jun 28 17:42:12 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 28 17:42:12 volumio firststart.sh[983]: Finalizing Jun 28 17:42:12 volumio volumio[981]: info: Loading plugin "volumio_command_line_client"... Jun 28 17:42:12 volumio volumio[981]: info: Loading plugin "upnp"... Jun 28 17:42:12 volumio volumio[981]: info: [1751107332921] Starting Upmpd Daemon Jun 28 17:42:12 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 28 17:42:12 volumio volumio[981]: info: Loading plugin "my_music"... Jun 28 17:42:12 volumio volumio[981]: info: Loading plugin "mpd"... Jun 28 17:42:18 volumio volumio[981]: info: Loading plugin "upnp_browser"... Jun 28 17:42:20 volumio systemd[1]: systemd-fsckd.service: Succeeded. Jun 28 17:42:20 volumio volumio-remote-updater[733]: [2025-06-28 10:42:20] [connect] Successful connection Jun 28 17:42:31 volumio systemd[1]: firststart.service: Succeeded. Jun 28 17:42:33 volumio systemd[1]: systemd-timedated.service: Succeeded. Jun 28 17:42:35 volumio volumio-remote-updater[733]: [2025-06-28 10:42:35] [connect] Successful connection Jun 28 17:42:36 volumio volumio[981]: info: Loading plugin "alarm-clock"... Jun 28 17:42:36 volumio volumio[981]: info: Loading plugin "airplay_emulation"... Jun 28 17:42:36 volumio volumio[981]: info: Starting Shairport Sync Jun 28 17:42:36 volumio volumio[981]: info: Loading plugin "last_100"... Jun 28 17:42:36 volumio volumio[981]: info: Loading plugin "webradio"... Jun 28 17:42:38 volumio volumio[981]: info: Loading plugin "i2s_dacs"... Jun 28 17:42:38 volumio volumio[981]: info: I2S DAC not set, start Auto-detection Jun 28 17:42:38 volumio volumio[981]: info: Loading plugin "volumiodiscovery"... Jun 28 17:42:38 volumio volumio[981]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 28 17:42:38 volumio volumio[981]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 28 17:42:38 volumio volumio[981]: *** WARNING *** For more information see Jun 28 17:42:38 volumio node[981]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 28 17:42:38 volumio volumio[981]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 28 17:42:38 volumio volumio[981]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 28 17:42:38 volumio volumio[981]: *** WARNING *** For more information see Jun 28 17:42:38 volumio node[981]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 28 17:42:38 volumio node[981]: *** WARNING *** For more information see Jun 28 17:42:38 volumio node[981]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 28 17:42:38 volumio node[981]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 28 17:42:38 volumio node[981]: *** WARNING *** For more information see Jun 28 17:42:38 volumio volumio[981]: info: Discovery: Started advertising with name: Volumio Jun 28 17:42:38 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 28 17:42:38 volumio volumio[981]: info: Loading plugin "outputs"... Jun 28 17:42:38 volumio volumio[981]: info: Loading plugin "albumart"... Jun 28 17:42:38 volumio volumio[981]: info: Plugin example_plugin is not enabled Jun 28 17:42:38 volumio volumio[981]: info: Loading plugin "inputs"... Jun 28 17:42:38 volumio volumio[981]: info: Loading plugin "updater_comm"... Jun 28 17:42:39 volumio volumio[981]: Forking 3 albumart workers Jun 28 17:42:39 volumio volumio[981]: Starting albumart workers Jun 28 17:42:39 volumio volumio[981]: Starting albumart workers Jun 28 17:42:39 volumio volumio[981]: Starting albumart workers Jun 28 17:42:40 volumio volumio[981]: info: Plugin mpdemulation is not enabled Jun 28 17:42:40 volumio volumio[981]: info: Loading plugin "rest_api"... Jun 28 17:42:40 volumio volumio[981]: info: Loading plugin "websocket"... Jun 28 17:42:40 volumio volumio[981]: info: Starting Socket.io Server version 2.3.0 Jun 28 17:42:40 volumio volumio[981]: info: Loading i18n strings for locale en Jun 28 17:42:40 volumio volumio[981]: Updating browse sources language Jun 28 17:42:40 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 28 17:42:41 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 28 17:42:41 volumio volumio[981]: info: CoreCommandRouter::initPlayerControls Jun 28 17:42:41 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 28 17:42:41 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 28 17:42:41 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 28 17:42:41 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 28 17:42:41 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 28 17:42:41 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 28 17:42:41 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 28 17:42:41 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 28 17:42:41 volumio volumio[981]: Express server listening on port 3000 Jun 28 17:42:41 volumio volumio[981]: [Metrics] WebUI: 41s 631.94ms Jun 28 17:42:41 volumio volumio[981]: info: Setting Device type: x86 Jun 28 17:42:41 volumio volumio[981]: info: CoreStateMachine::resetVolumioState Jun 28 17:42:41 volumio volumio[981]: info: CoreStateMachine::getcurrentVolume Jun 28 17:42:41 volumio volumio[981]: info: CoreCommandRouter::volumioRetrievevolume Jun 28 17:42:41 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 28 17:42:41 volumio volumio[981]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Jun 28 17:42:41 volumio volumio[981]: info: CoreStateMachine::getcurrentVolume Jun 28 17:42:41 volumio volumio[981]: info: CoreCommandRouter::volumioRetrievevolume Jun 28 17:42:41 volumio sudo[1637]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 28 17:42:41 volumio sudo[1637]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 17:42:41 volumio sudo[1637]: pam_unix(sudo:session): session closed for user root Jun 28 17:42:41 volumio sudo[1640]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 28 17:42:41 volumio sudo[1640]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 17:42:41 volumio volumio[981]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Jun 28 17:42:41 volumio volumio[981]: info: Completed loading Core Plugins Jun 28 17:42:41 volumio volumio[981]: info: Preparing to generate the ALSA configuration file Jun 28 17:42:41 volumio sudo[1640]: pam_unix(sudo:session): session closed for user root Jun 28 17:42:41 volumio volumio[981]: info: Cannot read play queue from file Jun 28 17:42:41 volumio volumio[981]: info: Volumio Network Manager: Network status updated: 1 Jun 28 17:42:41 volumio volumio[981]: Unable to parse: Jun 28 17:42:41 volumio volumio[981]: Simple mixer control 'IEC958',0 Jun 28 17:42:41 volumio volumio[981]: Capabilities: pswitch pswitch-joined Jun 28 17:42:41 volumio volumio[981]: Playback channels: Mono Jun 28 17:42:41 volumio volumio[981]: Mono: Playback [off] Jun 28 17:42:41 volumio volumio[981]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Jun 28 17:42:41 volumio volumio[981]: Unable to parse: Jun 28 17:42:41 volumio volumio[981]: Simple mixer control 'IEC958',0 Jun 28 17:42:41 volumio volumio[981]: Capabilities: pswitch pswitch-joined Jun 28 17:42:41 volumio volumio[981]: Playback channels: Mono Jun 28 17:42:41 volumio volumio[981]: Mono: Playback [off] Jun 28 17:42:41 volumio volumio[981]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Jun 28 17:42:41 volumio volumio[981]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Jun 28 17:42:41 volumio volumio[981]: wlan0 Interface doesn't support scanning. Jun 28 17:42:41 volumio volumio[981]: info: Cannot use regular scanning, forcing with ap-force Jun 28 17:42:41 volumio sudo[1647]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Jun 28 17:42:41 volumio sudo[1647]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 17:42:41 volumio sudo[1647]: pam_unix(sudo:session): session closed for user root Jun 28 17:42:41 volumio volumio[981]: command failed: No such device (-19) Jun 28 17:42:41 volumio volumio[981]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Jun 28 17:42:41 volumio volumio[981]: command failed: No such device (-19) Jun 28 17:42:41 volumio volumio[981]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Jun 28 17:42:42 volumio volumio[981]: Unable to parse: Jun 28 17:42:42 volumio volumio[981]: Simple mixer control 'IEC958',0 Jun 28 17:42:42 volumio volumio[981]: Capabilities: pswitch pswitch-joined Jun 28 17:42:42 volumio volumio[981]: Playback channels: Mono Jun 28 17:42:42 volumio volumio[981]: Mono: Playback [off] Jun 28 17:42:42 volumio volumio[981]: info: VolumeController:: Volume=undefined Mute =false Jun 28 17:42:42 volumio volumio[981]: info: CoreStateMachine::pushState Jun 28 17:42:42 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Jun 28 17:42:42 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 17:42:42 volumio volumio[981]: info: CoreCommandRouter::volumioPushState Jun 28 17:42:42 volumio volumio[981]: info: CoreStateMachine::updateTrackBlock Jun 28 17:42:42 volumio volumio[981]: info: CorePlayQueue::getTrackBlock Jun 28 17:42:42 volumio volumio[981]: info: CoreCommandRouter::volumioRetrievevolume Jun 28 17:42:42 volumio volumio[981]: Unable to parse: Jun 28 17:42:42 volumio volumio[981]: Simple mixer control 'IEC958',0 Jun 28 17:42:42 volumio volumio[981]: Capabilities: pswitch pswitch-joined Jun 28 17:42:42 volumio volumio[981]: Playback channels: Mono Jun 28 17:42:42 volumio volumio[981]: Mono: Playback [off] Jun 28 17:42:42 volumio volumio[981]: info: VolumeController:: Volume=undefined Mute =false Jun 28 17:42:42 volumio volumio[981]: info: CoreStateMachine::pushState Jun 28 17:42:42 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Jun 28 17:42:42 volumio volumio[981]: info: CoreCommandRouter::volumioPushState Jun 28 17:42:42 volumio volumio[981]: info: CoreStateMachine::updateTrackBlock Jun 28 17:42:42 volumio volumio[981]: info: CorePlayQueue::getTrackBlock Jun 28 17:42:42 volumio volumio[981]: info: CoreCommandRouter::volumioRetrievevolume Jun 28 17:42:42 volumio volumio[981]: Unable to parse: Jun 28 17:42:42 volumio volumio[981]: Simple mixer control 'IEC958',0 Jun 28 17:42:42 volumio volumio[981]: Capabilities: pswitch pswitch-joined Jun 28 17:42:42 volumio volumio[981]: Playback channels: Mono Jun 28 17:42:42 volumio volumio[981]: Mono: Playback [off] Jun 28 17:42:42 volumio volumio[981]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Jun 28 17:42:42 volumio volumio[981]: Unable to parse: Jun 28 17:42:42 volumio volumio[981]: Simple mixer control 'IEC958',0 Jun 28 17:42:42 volumio volumio[981]: Capabilities: pswitch pswitch-joined Jun 28 17:42:42 volumio volumio[981]: Playback channels: Mono Jun 28 17:42:42 volumio volumio[981]: Mono: Playback [off] Jun 28 17:42:42 volumio volumio[981]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Jun 28 17:42:42 volumio volumio[981]: info: Asound.conf file written Jun 28 17:42:42 volumio sudo[1661]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jun 28 17:42:42 volumio sudo[1661]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 17:42:42 volumio sudo[1661]: pam_unix(sudo:session): session closed for user root Jun 28 17:42:42 volumio volumio[981]: No state is present for card HDMI Jun 28 17:42:42 volumio volumio[981]: Found hardware: "HDA-Intel" "ATI R6xx HDMI" "HDA:1002aa01,00aa0100,00100500" "0x1734" "0x1202" Jun 28 17:42:42 volumio volumio[981]: Hardware is initialized using a generic method Jun 28 17:42:42 volumio volumio[981]: No state is present for card HDMI Jun 28 17:42:42 volumio volumio[981]: No state is present for card Generic Jun 28 17:42:42 volumio volumio[981]: Found hardware: "HDA-Intel" "Realtek ALC671" "HDA:10ec0671,17341203,00100001" "0x1734" "0x1203" Jun 28 17:42:42 volumio volumio[981]: Hardware is initialized using a generic method Jun 28 17:42:42 volumio volumio[981]: No state is present for card Generic Jun 28 17:42:42 volumio volumio[981]: No state is present for card l20 Jun 28 17:42:42 volumio volumio[981]: Found hardware: "USB-Audio" "USB Mixer" "USB20b1:3001" "" "" Jun 28 17:42:42 volumio volumio[981]: Hardware is initialized using a generic method Jun 28 17:42:42 volumio volumio[981]: No state is present for card l20 Jun 28 17:42:42 volumio volumio[981]: info: Output device has changed, restarting MPD Jun 28 17:42:42 volumio sudo[1675]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 28 17:42:42 volumio sudo[1675]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 17:42:42 volumio volumio[981]: info: Output device has changed, restarting Shairport Sync Jun 28 17:42:42 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 28 17:42:42 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 28 17:42:42 volumio sudo[1677]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 28 17:42:42 volumio sudo[1677]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 17:42:42 volumio systemd[1]: Stopping Music Player Daemon... Jun 28 17:42:42 volumio sudo[1675]: pam_unix(sudo:session): session closed for user root Jun 28 17:42:42 volumio volumio[981]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 28 17:42:42 volumio volumio[981]: info: ___________ START PLUGINS ___________ Jun 28 17:42:42 volumio volumio[981]: info: Setting Hotspot Unique name on first start: Volumio-5ECA0 Jun 28 17:42:42 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: network , getConfigParam Jun 28 17:42:42 volumio volumio[981]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getConfigParam] in plugin network Jun 28 17:42:42 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: network , saveHotspotSettings Jun 28 17:42:42 volumio volumio[981]: info: CoreCommandRouter::Reload Ui Jun 28 17:42:42 volumio volumio[981]: info: ControllerMpd::onStart: Initializing MPD Jun 28 17:42:42 volumio volumio[981]: info: Creating MPD Configuration file Jun 28 17:42:42 volumio sudo[1684]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 28 17:42:42 volumio sudo[1684]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 17:42:42 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 28 17:42:42 volumio volumio[981]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 28 17:42:42 volumio volumio[981]: info: [1751107362397] CoreMusicLibrary::Adding element Media Servers Jun 28 17:42:42 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 28 17:42:42 volumio sudo[1684]: pam_unix(sudo:session): session closed for user root Jun 28 17:42:42 volumio sudo[1686]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 28 17:42:42 volumio sudo[1686]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 17:42:42 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 28 17:42:42 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 28 17:42:42 volumio volumio[981]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 28 17:42:42 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 28 17:42:42 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 28 17:42:42 volumio systemd[1]: mpd.service: Succeeded. Jun 28 17:42:42 volumio systemd[1]: Stopped Music Player Daemon. Jun 28 17:42:42 volumio volumio[981]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 28 17:42:42 volumio volumio[981]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 28 17:42:42 volumio volumio[981]: info: [1751107362491] CoreMusicLibrary::Adding element Last_100 Jun 28 17:42:42 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 28 17:42:42 volumio volumio[981]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 28 17:42:42 volumio volumio[981]: info: [1751107362493] CoreMusicLibrary::Adding element Webradio Jun 28 17:42:42 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 28 17:42:42 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 28 17:42:42 volumio volumio[981]: info: Initializing BBC Radios Jun 28 17:42:42 volumio systemd[1]: Starting Music Player Daemon... Jun 28 17:42:42 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 28 17:42:42 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 28 17:42:42 volumio volumio[981]: info: Volumio Calling Home Jun 28 17:42:42 volumio sudo[1695]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 28 17:42:42 volumio sudo[1695]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 17:42:42 volumio sudo[1695]: pam_unix(sudo:session): session closed for user root Jun 28 17:42:42 volumio volumio[981]: info: MPD Permissions set Jun 28 17:42:42 volumio volumio[981]: info: MPD Permissions set Jun 28 17:42:42 volumio volumio[981]: Unable to parse: Jun 28 17:42:42 volumio volumio[981]: Simple mixer control 'IEC958',0 Jun 28 17:42:42 volumio volumio[981]: Capabilities: pswitch pswitch-joined Jun 28 17:42:42 volumio volumio[981]: Playback channels: Mono Jun 28 17:42:42 volumio volumio[981]: Mono: Playback [off] Jun 28 17:42:42 volumio volumio[981]: info: VolumeController:: Volume=undefined Mute =false Jun 28 17:42:42 volumio volumio[981]: info: CoreStateMachine::pushState Jun 28 17:42:42 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Jun 28 17:42:42 volumio volumio[981]: info: CoreCommandRouter::volumioPushState Jun 28 17:42:42 volumio volumio[981]: Unable to parse: Jun 28 17:42:42 volumio volumio[981]: Simple mixer control 'IEC958',0 Jun 28 17:42:42 volumio volumio[981]: Capabilities: pswitch pswitch-joined Jun 28 17:42:42 volumio volumio[981]: Playback channels: Mono Jun 28 17:42:42 volumio volumio[981]: Mono: Playback [off] Jun 28 17:42:42 volumio volumio[981]: info: VolumeController:: Volume=undefined Mute =false Jun 28 17:42:42 volumio volumio[981]: info: CoreStateMachine::pushState Jun 28 17:42:42 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Jun 28 17:42:42 volumio volumio[981]: info: CoreCommandRouter::volumioPushState Jun 28 17:42:42 volumio sudo[1712]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jun 28 17:42:42 volumio sudo[1712]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 17:42:42 volumio volumio[981]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Jun 28 17:42:42 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 28 17:42:42 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 28 17:42:42 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Jun 28 17:42:42 volumio sudo[1712]: pam_unix(sudo:session): session closed for user root Jun 28 17:42:42 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 28 17:42:42 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 28 17:42:42 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 28 17:42:42 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 28 17:42:42 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 28 17:42:42 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 28 17:42:42 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 28 17:42:42 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 28 17:42:42 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 28 17:42:42 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 28 17:42:42 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 28 17:42:42 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 28 17:42:42 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 28 17:42:42 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 28 17:42:42 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Jun 28 17:42:42 volumio volumio[981]: info: Discovery: adding 1336ce62-f932-4288-924c-99f7fe883ab4 Jun 28 17:42:42 volumio volumio[981]: info: Discovery: Found device Volumio Jun 28 17:42:42 volumio volumio[981]: info: CoreCommandRouter::volumioGetState Jun 28 17:42:42 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Jun 28 17:42:42 volumio volumio[981]: info: Upmpdcli Daemon Started Jun 28 17:42:42 volumio volumio[981]: info: CoreCommandRouter::volumioGetState Jun 28 17:42:42 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Jun 28 17:42:43 volumio volumio[981]: info: Volumio called home Jun 28 17:42:43 volumio volumio[981]: info: Starting Shairport Sync Jun 28 17:42:43 volumio mpd[1710]: Jun 28 10:42 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 28 17:42:43 volumio systemd[1]: Started Music Player Daemon. Jun 28 17:42:43 volumio volumio[981]: info: Starting Shairport Sync Jun 28 17:42:43 volumio sudo[1677]: pam_unix(sudo:session): session closed for user root Jun 28 17:42:43 volumio sudo[1686]: pam_unix(sudo:session): session closed for user root Jun 28 17:42:43 volumio volumio[981]: info: Starting Shairport Sync Jun 28 17:42:43 volumio sudo[1742]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 28 17:42:43 volumio sudo[1742]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 17:42:43 volumio sudo[1746]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 28 17:42:43 volumio sudo[1746]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 17:42:43 volumio sudo[1749]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 28 17:42:43 volumio sudo[1749]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 17:42:43 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 28 17:42:43 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 28 17:42:43 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 28 17:42:43 volumio volumio[981]: info: Completed starting Core Plugins Jun 28 17:42:43 volumio volumio[981]: info: ------------------------------------------- Jun 28 17:42:43 volumio volumio[981]: info: ----- MyVolumio plugins startup ---- Jun 28 17:42:43 volumio volumio[981]: info: ------------------------------------------- Jun 28 17:42:43 volumio volumio[981]: info: [MyVolumio PluginManager] Fetching plans data.... Jun 28 17:42:43 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 28 17:42:43 volumio sudo[1746]: pam_unix(sudo:session): session closed for user root Jun 28 17:42:43 volumio sudo[1742]: pam_unix(sudo:session): session closed for user root Jun 28 17:42:43 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 28 17:42:43 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jun 28 17:42:43 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 28 17:42:43 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 28 17:42:43 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 28 17:42:43 volumio sudo[1749]: pam_unix(sudo:session): session closed for user root Jun 28 17:42:44 volumio volumio[981]: error: MPD error: The expression evaluated to a falsy value: Jun 28 17:42:44 volumio volumio[981]: assert.ok(self.idling) Jun 28 17:42:44 volumio volumio[981]: error: The expression evaluated to a falsy value: Jun 28 17:42:44 volumio volumio[981]: assert.ok(self.idling) Jun 28 17:42:47 volumio volumio[981]: error: updateQueue error: null Jun 28 17:42:47 volumio volumio[981]: info: Shairport-Sync Started Jun 28 17:42:47 volumio volumio[981]: Error adding Membership: Error: addMembership EINVAL Jun 28 17:42:47 volumio volumio[981]: info: Shairport-Sync Started Jun 28 17:42:47 volumio volumio[981]: info: Shairport-Sync Started Jun 28 17:42:47 volumio volumio[981]: info: MPD running with PID1710 Jun 28 17:42:47 volumio volumio[981]: ,establishing connection Jun 28 17:42:48 volumio volumio[981]: error: MPD error: The expression evaluated to a falsy value: Jun 28 17:42:48 volumio volumio[981]: assert.ok(self.idling) Jun 28 17:42:48 volumio volumio[981]: error: The expression evaluated to a falsy value: Jun 28 17:42:48 volumio volumio[981]: assert.ok(self.idling) Jun 28 17:42:48 volumio volumio[981]: error: MPD error: The expression evaluated to a falsy value: Jun 28 17:42:48 volumio volumio[981]: assert.ok(self.idling) Jun 28 17:42:48 volumio volumio[981]: error: The expression evaluated to a falsy value: Jun 28 17:42:48 volumio volumio[981]: assert.ok(self.idling) Jun 28 17:42:49 volumio sudo[1760]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hostapd/hostapd.conf Jun 28 17:42:49 volumio sudo[1760]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 17:42:49 volumio volumio[981]: Upnp client error: Error: This socket has been ended by the other party Jun 28 17:42:49 volumio volumio[981]: Upnp client error: Error: This socket has been ended by the other party Jun 28 17:42:49 volumio volumio[981]: error: updateQueue error: null Jun 28 17:42:49 volumio volumio[981]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jun 28 17:42:49 volumio sudo[1760]: pam_unix(sudo:session): session closed for user root Jun 28 17:42:49 volumio volumio[981]: info: Permissions for /etc/hostapd/hostapd.conf Jun 28 17:42:49 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Jun 28 17:42:49 volumio volumio[981]: info: Discovery: Restarting Advertising due to device name change Jun 28 17:42:49 volumio sudo[1764]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Jun 28 17:42:49 volumio sudo[1764]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 17:42:49 volumio systemd[1]: Starting Wireless Services... Jun 28 17:42:49 volumio wireless.js[1766]: WIRELESS: No wireless interface, exiting Jun 28 17:42:50 volumio volumio[981]: info: Discovery: A device disappeared from network Jun 28 17:42:50 volumio volumio[981]: info: Discovery: Device volumio disappeared from network Jun 28 17:42:50 volumio volumio-remote-updater[733]: [2025-06-28 10:42:50] [connect] Successful connection Jun 28 17:42:50 volumio volumio-remote-updater[733]: [2025-06-28 10:42:50] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1751107370 101 Jun 28 17:42:50 volumio volumio[981]: 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: 2 Jun 28 17:42:50 volumio iw[1777]: command failed: No such device (-19) Jun 28 17:42:50 volumio iwconfig[1780]: Error for wireless request "Set Power Management" (8B2C) : Jun 28 17:42:50 volumio iwconfig[1780]: SET failed on device wlan0 ; No such device. Jun 28 17:42:50 volumio systemd[1]: wireless.service: Succeeded. Jun 28 17:42:50 volumio systemd[1]: Started Wireless Services. Jun 28 17:42:50 volumio sudo[1764]: pam_unix(sudo:session): session closed for user root Jun 28 17:42:52 volumio volumio[981]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jun 28 17:42:52 volumio volumio[981]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jun 28 17:42:52 volumio volumio[981]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jun 28 17:42:52 volumio volumio[981]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jun 28 17:42:52 volumio volumio[981]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jun 28 17:42:52 volumio volumio[981]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jun 28 17:42:52 volumio volumio[981]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jun 28 17:42:52 volumio volumio[981]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jun 28 17:42:52 volumio volumio[981]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jun 28 17:42:52 volumio volumio[981]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jun 28 17:42:52 volumio volumio[981]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jun 28 17:42:52 volumio volumio[981]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jun 28 17:42:52 volumio volumio[981]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jun 28 17:42:52 volumio volumio[981]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jun 28 17:42:52 volumio volumio[981]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jun 28 17:42:52 volumio volumio[981]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jun 28 17:42:52 volumio volumio[981]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jun 28 17:42:52 volumio volumio[981]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jun 28 17:42:52 volumio volumio[981]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jun 28 17:42:52 volumio volumio[981]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jun 28 17:42:52 volumio volumio[981]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jun 28 17:42:52 volumio volumio[981]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jun 28 17:42:52 volumio volumio[981]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jun 28 17:42:52 volumio volumio[981]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jun 28 17:42:52 volumio volumio[981]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jun 28 17:42:52 volumio volumio[981]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jun 28 17:42:52 volumio volumio[981]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jun 28 17:42:52 volumio volumio[981]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jun 28 17:42:52 volumio volumio[981]: info: Adding plugin bluetooth to MyMusic Plugins Jun 28 17:42:52 volumio volumio[981]: info: Adding plugin multiroom to MyMusic Plugins Jun 28 17:42:52 volumio volumio[981]: info: Adding plugin metavolumio to MyMusic Plugins Jun 28 17:42:52 volumio volumio[981]: info: Adding plugin cd_controller to MyMusic Plugins Jun 28 17:42:52 volumio volumio[981]: info: Adding plugin qobuzconnect to MyMusic Plugins Jun 28 17:42:52 volumio volumio[981]: info: Adding plugin smart_inputs to MyMusic Plugins Jun 28 17:42:52 volumio volumio[981]: info: Adding plugin tidalconnect to MyMusic Plugins Jun 28 17:42:52 volumio volumio[981]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jun 28 17:42:54 volumio dbus-daemon[743]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by ':1.13' (uid=0 pid=1529 comm="/usr/lib/chromium/chromium --show-component-extens") Jun 28 17:42:54 volumio systemd[1]: Starting Bluetooth service... Jun 28 17:42:55 volumio volumio[981]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jun 28 17:42:55 volumio volumio[981]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jun 28 17:42:55 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 28 17:42:55 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 28 17:42:55 volumio volumio[981]: info: Starting MyVolumio Remote Streaming Endpoints Jun 28 17:42:55 volumio volumio[981]: info: MyVolumio not started Jun 28 17:42:55 volumio volumio[981]: info: Initializing device activation check Jun 28 17:42:55 volumio volumio[981]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jun 28 17:42:55 volumio volumio[981]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jun 28 17:42:59 volumio kernel: Bluetooth: Core ver 2.22 Jun 28 17:42:59 volumio kernel: NET: Registered PF_BLUETOOTH protocol family Jun 28 17:42:59 volumio kernel: Bluetooth: HCI device and connection manager initialized Jun 28 17:42:59 volumio kernel: Bluetooth: HCI socket layer initialized Jun 28 17:42:59 volumio kernel: Bluetooth: L2CAP socket layer initialized Jun 28 17:42:59 volumio kernel: Bluetooth: SCO socket layer initialized Jun 28 17:42:59 volumio bluetoothd[1859]: Bluetooth daemon 5.50 Jun 28 17:42:59 volumio bluetoothd[1859]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Jun 28 17:42:59 volumio dbus-daemon[743]: [system] Successfully activated service 'org.bluez' Jun 28 17:42:59 volumio systemd[1]: Started Bluetooth service. Jun 28 17:42:59 volumio bluetoothd[1859]: Starting SDP server Jun 28 17:42:59 volumio bluetoothd[1859]: Excluding (cli) sap Jun 28 17:42:59 volumio kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Jun 28 17:42:59 volumio kernel: Bluetooth: BNEP filters: protocol multicast Jun 28 17:42:59 volumio kernel: Bluetooth: BNEP socket layer initialized Jun 28 17:42:59 volumio dbus-daemon[743]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.14' (uid=0 pid=1859 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Jun 28 17:42:59 volumio bluetoothd[1859]: Bluetooth management interface 1.22 initialized Jun 28 17:42:59 volumio systemd[1]: Starting Hostname Service... Jun 28 17:42:59 volumio dbus-daemon[743]: [system] Successfully activated service 'org.freedesktop.hostname1' Jun 28 17:42:59 volumio systemd[1]: Started Hostname Service. Jun 28 17:43:00 volumio volumio[981]: info: Starting Streaming Service Transparent Proxy Jun 28 17:43:00 volumio volumio[981]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jun 28 17:43:00 volumio volumio[981]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jun 28 17:43:00 volumio volumio[981]: info: Streaming services startup Jun 28 17:43:00 volumio volumio[981]: info: Starting Streaming Daemon Jun 28 17:43:00 volumio sudo[2004]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 28 17:43:00 volumio sudo[2004]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 17:43:00 volumio sudo[2004]: pam_unix(sudo:session): session closed for user root Jun 28 17:43:00 volumio volumio[981]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jun 28 17:43:00 volumio volumio[981]: info: Discovery: Started advertising with name: Volumio Jun 28 17:43:00 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: wizard , reportWirelessConnection Jun 28 17:43:00 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessInfo Jun 28 17:43:00 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart Jun 28 17:43:00 volumio sudo[2012]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 28 17:43:00 volumio sudo[2012]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 17:43:00 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart Jun 28 17:43:00 volumio volumio[981]: info: Refreshing Cached IP Addresses Jun 28 17:43:00 volumio sudo[2012]: pam_unix(sudo:session): session closed for user root Jun 28 17:43:00 volumio sudo[2016]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Jun 28 17:43:00 volumio sudo[2016]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 17:43:00 volumio sudo[2016]: pam_unix(sudo:session): session closed for user root Jun 28 17:43:00 volumio systemd[1]: upmpdcli.service: Main process exited, code=killed, status=15/TERM Jun 28 17:43:00 volumio sudo[2020]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 28 17:43:00 volumio sudo[2020]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 17:43:00 volumio systemd[1]: upmpdcli.service: Succeeded. Jun 28 17:43:00 volumio sudo[2020]: pam_unix(sudo:session): session closed for user root Jun 28 17:43:00 volumio sudo[2023]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 28 17:43:00 volumio sudo[2023]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 17:43:00 volumio volumio[981]: Upnp client error: Error: read ECONNRESET Jun 28 17:43:00 volumio volumio[981]: error: Cannot start Volumio Streaming Daemon Jun 28 17:43:00 volumio volumio[981]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 28 17:43:00 volumio volumio[981]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 28 17:43:00 volumio sudo[2023]: pam_unix(sudo:session): session closed for user root Jun 28 17:43:01 volumio volumio[981]: STREAMING PROXY: Starting server on port 3245 Jun 28 17:43:01 volumio volumio[981]: Node JS runtime: 14 Jun 28 17:43:01 volumio sudo[2029]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 28 17:43:01 volumio sudo[2029]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 17:43:01 volumio sudo[2029]: pam_unix(sudo:session): session closed for user root Jun 28 17:43:01 volumio sudo[2032]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 28 17:43:01 volumio sudo[2032]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 17:43:01 volumio sudo[2032]: pam_unix(sudo:session): session closed for user root Jun 28 17:43:01 volumio volumio[981]: verbose: New Socket.io Connection to 192.168.3.44 from 192.168.3.87 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.5 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 3 Jun 28 17:43:01 volumio volumio[981]: info: Discovery: adding 1336ce62-f932-4288-924c-99f7fe883ab4 Jun 28 17:43:01 volumio volumio[981]: info: Discovery: Found device Volumio Jun 28 17:43:01 volumio volumio[981]: info: CoreCommandRouter::volumioGetState Jun 28 17:43:01 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Jun 28 17:43:01 volumio volumio[981]: 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: 4 Jun 28 17:43:01 volumio volumio[981]: info: CoreCommandRouter::volumioGetVisibleSources Jun 28 17:43:01 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 28 17:43:01 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Jun 28 17:43:01 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Jun 28 17:43:01 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Jun 28 17:43:01 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Jun 28 17:43:01 volumio volumio[981]: info: Received Get System Info Jun 28 17:43:01 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 28 17:43:01 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 28 17:43:01 volumio volumio[981]: info: Discovery: Getting this device information Jun 28 17:43:01 volumio volumio[981]: info: CoreCommandRouter::volumioGetState Jun 28 17:43:01 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Jun 28 17:43:01 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 28 17:43:01 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 28 17:43:01 volumio volumio-remote-updater[733]: No test mode Jun 28 17:43:01 volumio volumio-remote-updater[733]: No alpha test mode Jun 28 17:43:01 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 28 17:43:01 volumio volumio[981]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 28 17:43:01 volumio volumio[981]: info: Retrieving Cloud Streaming UI Jun 28 17:43:01 volumio volumio[981]: info: Getting Tidal Cloud Configuration Jun 28 17:43:01 volumio volumio[981]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 28 17:43:01 volumio volumio[981]: info: Getting Qobuz Cloud Configuration Jun 28 17:43:01 volumio volumio[981]: info: Asking plugin for UI Config Jun 28 17:43:01 volumio volumio[981]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 28 17:43:01 volumio volumio[981]: info: Getting Spotify Cloud Configuration Jun 28 17:43:01 volumio volumio[981]: info: Asking plugin for UI Config Jun 28 17:43:01 volumio volumio[981]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 28 17:43:01 volumio volumio[981]: info: Saving Spotify Acccount Jun 28 17:43:01 volumio volumio[981]: info: Got Tidal Cloud Configuration Jun 28 17:43:01 volumio volumio[981]: info: Got it Jun 28 17:43:01 volumio volumio[981]: info: Got it Jun 28 17:43:01 volumio volumio[981]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jun 28 17:43:01 volumio volumio[981]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 28 17:43:01 volumio volumio[981]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Jun 28 17:43:02 volumio volumio[981]: info: CoreCommandRouter::volumioGetVisibleSources Jun 28 17:43:02 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 28 17:43:02 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Jun 28 17:43:02 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Jun 28 17:43:02 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Jun 28 17:43:02 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Jun 28 17:43:02 volumio volumio[981]: info: Received Get System Info Jun 28 17:43:02 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 28 17:43:02 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 28 17:43:02 volumio volumio[981]: info: Discovery: Getting this device information Jun 28 17:43:02 volumio volumio[981]: info: CoreCommandRouter::volumioGetState Jun 28 17:43:02 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Jun 28 17:43:02 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 28 17:43:02 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 28 17:43:02 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 28 17:43:02 volumio volumio[981]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 28 17:43:02 volumio volumio[981]: info: Retrieving Cloud Streaming UI Jun 28 17:43:02 volumio volumio[981]: info: Getting Tidal Cloud Configuration Jun 28 17:43:02 volumio volumio[981]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 28 17:43:02 volumio volumio[981]: info: Getting Qobuz Cloud Configuration Jun 28 17:43:02 volumio volumio[981]: info: Asking plugin for UI Config Jun 28 17:43:02 volumio volumio[981]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 28 17:43:02 volumio volumio[981]: info: Getting Spotify Cloud Configuration Jun 28 17:43:02 volumio volumio[981]: info: Asking plugin for UI Config Jun 28 17:43:02 volumio volumio[981]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 28 17:43:02 volumio volumio[981]: info: Saving Spotify Acccount Jun 28 17:43:02 volumio volumio[981]: info: Got Tidal Cloud Configuration Jun 28 17:43:02 volumio volumio[981]: info: Got it Jun 28 17:43:02 volumio volumio[981]: info: Got it Jun 28 17:43:02 volumio volumio[981]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jun 28 17:43:02 volumio volumio[981]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 28 17:43:02 volumio volumio[981]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Jun 28 17:43:02 volumio volumio-remote-updater[733]: No test mode Jun 28 17:43:02 volumio volumio-remote-updater[733]: No alpha test mode Jun 28 17:43:02 volumio volumio[981]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jun 28 17:43:02 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jun 28 17:43:02 volumio volumio[981]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jun 28 17:43:02 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jun 28 17:43:03 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Jun 28 17:43:03 volumio volumio[981]: info: Loading i18n strings for locale en Jun 28 17:43:03 volumio volumio[981]: Updating browse sources language Jun 28 17:43:03 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 28 17:43:03 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Jun 28 17:43:03 volumio volumio[981]: info: Setting timezone to Asia/Saigon Jun 28 17:43:03 volumio sudo[2060]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Jun 28 17:43:03 volumio sudo[2060]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 17:43:03 volumio sudo[2060]: pam_unix(sudo:session): session closed for user root Jun 28 17:43:03 volumio sudo[2063]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Asia/Saigon /etc/localtime Jun 28 17:43:03 volumio sudo[2063]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 17:43:03 volumio sudo[2063]: pam_unix(sudo:session): session closed for user root Jun 28 17:43:03 volumio sudo[2066]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Jun 28 17:43:03 volumio sudo[2066]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 17:43:03 volumio sudo[2066]: pam_unix(sudo:session): session closed for user root Jun 28 17:43:03 volumio sudo[2069]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Asia/Saigon Jun 28 17:43:03 volumio sudo[2069]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 17:43:03 volumio dbus-daemon[743]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.19' (uid=0 pid=2070 comm="/usr/bin/timedatectl set-timezone Asia/Saigon ") Jun 28 17:43:03 volumio systemd[1]: Starting Time & Date Service... Jun 28 17:43:03 volumio dbus-daemon[743]: [system] Successfully activated service 'org.freedesktop.timedate1' Jun 28 17:43:03 volumio systemd[1]: Started Time & Date Service. Jun 28 17:43:03 volumio sudo[2069]: pam_unix(sudo:session): session closed for user root Jun 28 17:43:03 volumio volumio[981]: info: Fetching Streaming Services browse cache Jun 28 17:43:03 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 28 17:43:03 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 28 17:43:03 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Jun 28 17:43:03 volumio volumio[981]: info: Loading i18n strings for locale en Jun 28 17:43:03 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Jun 28 17:43:03 volumio volumio[981]: info: Setting timezone to UTC Jun 28 17:43:03 volumio sudo[2074]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Jun 28 17:43:03 volumio sudo[2074]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 17:43:03 volumio sudo[2074]: pam_unix(sudo:session): session closed for user root Jun 28 17:43:03 volumio sudo[2077]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/UTC /etc/localtime Jun 28 17:43:03 volumio sudo[2077]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 17:43:03 volumio sudo[2077]: pam_unix(sudo:session): session closed for user root Jun 28 17:43:03 volumio sudo[2080]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Jun 28 17:43:03 volumio sudo[2080]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 17:43:03 volumio sudo[2080]: pam_unix(sudo:session): session closed for user root Jun 28 17:43:03 volumio sudo[2083]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone UTC Jun 28 17:43:03 volumio sudo[2083]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 17:43:03 volumio systemd-timedated[2071]: Failed to set time zone: Permission denied Jun 28 17:43:03 volumio sudo[2083]: pam_unix(sudo:session): session closed for user root Jun 28 17:43:03 volumio volumio[981]: Failed to set time zone: Access denied Jun 28 17:43:03 volumio volumio[981]: info: Could not set timezone, retrying Jun 28 17:43:03 volumio volumio[981]: Updating browse sources language Jun 28 17:43:03 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 28 17:43:04 volumio volumio[981]: info: Fetching Streaming Services browse cache Jun 28 17:43:04 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Jun 28 17:43:04 volumio volumio[981]: info: MyVolumio login type: Token Jun 28 17:43:04 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Jun 28 17:43:04 volumio volumio[981]: info: Loading i18n strings for locale en Jun 28 17:43:04 volumio volumio[981]: Updating browse sources language Jun 28 17:43:04 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 28 17:43:04 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Jun 28 17:43:04 volumio volumio[981]: info: Setting timezone to Asia/Saigon Jun 28 17:43:04 volumio sudo[2087]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Jun 28 17:43:04 volumio sudo[2087]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 17:43:04 volumio sudo[2087]: pam_unix(sudo:session): session closed for user root Jun 28 17:43:04 volumio sudo[2090]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Asia/Saigon /etc/localtime Jun 28 17:43:04 volumio sudo[2090]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 17:43:04 volumio sudo[2090]: pam_unix(sudo:session): session closed for user root Jun 28 17:43:04 volumio sudo[2093]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Jun 28 17:43:04 volumio sudo[2093]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 17:43:04 volumio sudo[2093]: pam_unix(sudo:session): session closed for user root Jun 28 17:43:04 volumio sudo[2096]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Asia/Saigon Jun 28 17:43:04 volumio sudo[2096]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 17:43:04 volumio systemd-timedated[2071]: Failed to set time zone: Permission denied Jun 28 17:43:04 volumio sudo[2096]: pam_unix(sudo:session): session closed for user root Jun 28 17:43:04 volumio volumio[981]: Failed to set time zone: Access denied Jun 28 17:43:04 volumio volumio[981]: info: Could not set timezone, retrying Jun 28 17:43:04 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Jun 28 17:43:04 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Jun 28 17:43:04 volumio sudo[2099]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 28 17:43:04 volumio sudo[2099]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 17:43:04 volumio sudo[2099]: pam_unix(sudo:session): session closed for user root Jun 28 17:43:04 volumio volumio[981]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Jun 28 17:43:04 volumio volumio[981]: wlan0 Interface doesn't support scanning. Jun 28 17:43:04 volumio volumio[981]: info: Cannot use regular scanning, forcing with ap-force Jun 28 17:43:04 volumio sudo[2104]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Jun 28 17:43:04 volumio sudo[2104]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 17:43:04 volumio sudo[2104]: pam_unix(sudo:session): session closed for user root Jun 28 17:43:04 volumio volumio[981]: command failed: No such device (-19) Jun 28 17:43:04 volumio volumio[981]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Jun 28 17:43:04 volumio volumio[981]: command failed: No such device (-19) Jun 28 17:43:04 volumio sudo[2109]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone UTC Jun 28 17:43:04 volumio sudo[2109]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 17:43:05 volumio systemd-timedated[2071]: Failed to set time zone: Permission denied Jun 28 17:43:05 volumio sudo[2109]: pam_unix(sudo:session): session closed for user root Jun 28 17:43:05 volumio volumio[981]: Failed to set time zone: Access denied Jun 28 17:43:05 volumio volumio[981]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 28 17:43:05 volumio volumio[981]: Error: Command failed: /usr/bin/sudo /usr/bin/timedatectl set-timezone 'UTC' Jun 28 17:43:05 volumio volumio[981]: Failed to set time zone: Access denied Jun 28 17:43:05 volumio volumio[981]: at checkExecSyncError (child_process.js:790:11) Jun 28 17:43:05 volumio volumio[981]: at execSync (child_process.js:863:15) Jun 28 17:43:05 volumio volumio[981]: at Timeout._onTimeout (/volumio/app/plugins/system_controller/system/index.js:1680:9) Jun 28 17:43:05 volumio volumio[981]: at listOnTimeout (internal/timers.js:557:17) Jun 28 17:43:05 volumio volumio[981]: at processTimers (internal/timers.js:500:7) { Jun 28 17:43:05 volumio volumio[981]: status: 1, Jun 28 17:43:05 volumio volumio[981]: signal: null, Jun 28 17:43:05 volumio volumio[981]: output: [ null, '', 'Failed to set time zone: Access denied\n' ], Jun 28 17:43:05 volumio volumio[981]: pid: 2108, Jun 28 17:43:05 volumio volumio[981]: stdout: '', Jun 28 17:43:05 volumio volumio[981]: stderr: 'Failed to set time zone: Access denied\n' Jun 28 17:43:05 volumio volumio[981]: } Jun 28 17:43:05 volumio volumio[981]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 28 17:43:05 volumio sudo[2120]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-28 17:42 Jun 28 17:43:05 volumio sudo[2120]: 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 05:26:48 PM CEST" VOLUMIO_VERSION="3.816" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="a63f40325536655a08b14de2b295ce76"