-- Logs begin at Sat 2025-06-14 08:42:20 UTC, end at Sat 2025-06-14 08:45:32 UTC. --
Jun 14 08:44:08 volumio firststart.sh[982]: Creating SSH2 RSA key; this may take some time ...
Jun 14 08:44:08 volumio systemd[1]: Started Samba SMB Daemon.
Jun 14 08:44:08 volumio smbd[1226]: [2025/06/14 08:44:08.399736, 0] ../lib/util/become_daemon.c:138(daemon_ready)
Jun 14 08:44:08 volumio firststart.sh[982]: 2048 SHA256:Fhi1j8XfNft8s3/GEsunh+gNveXj1C1QviuLb4kXbgo root@volumio (RSA)
Jun 14 08:44:08 volumio systemd[1]: Reached target Multi-User System.
Jun 14 08:44:08 volumio smbd[1226]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections
Jun 14 08:44:08 volumio systemd[1]: Reached target Graphical Interface.
Jun 14 08:44:08 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes...
Jun 14 08:44:08 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded.
Jun 14 08:44:08 volumio systemd[1]: Started Update UTMP about System Runlevel Changes.
Jun 14 08:44:08 volumio systemd[1]: Startup finished in 12.766s (firmware) + 5.626s (loader) + 19min 26.792s (kernel) + 2min 9.369s (userspace) = 21min 54.554s.
Jun 14 08:44:08 volumio firststart.sh[982]: Creating SSH2 DSA key; this may take some time ...
Jun 14 08:44:08 volumio firststart.sh[982]: 1024 SHA256:Tn4FWh+KnzVg94T0kXc31qV0jmwbXC9aqTLXXIEpaQE root@volumio (DSA)
Jun 14 08:44:08 volumio firststart.sh[982]: Creating SSH2 ECDSA key; this may take some time ...
Jun 14 08:44:08 volumio firststart.sh[982]: 256 SHA256:DytwtVMFm2uL2IJdXLI8t6guhvu3DN1olRqm7l8cyzE root@volumio (ECDSA)
Jun 14 08:44:09 volumio volumio[979]: info: -------------------------------------------
Jun 14 08:44:09 volumio volumio[979]: info: ----- Volumio3 ----
Jun 14 08:44:09 volumio volumio[979]: info: -------------------------------------------
Jun 14 08:44:09 volumio volumio[979]: info: ----- System startup ----
Jun 14 08:44:09 volumio volumio[979]: info: -------------------------------------------
Jun 14 08:44:09 volumio systemd[1]: Reloading.
Jun 14 08:44:09 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 14 08:44:10 volumio systemd[1]: getty@tty1.service: Current command vanished from the unit file, execution of the command list won't be resumed.
Jun 14 08:44:10 volumio firststart.sh[982]: rescue-ssh.target is a disabled or a static unit, not starting it.
Jun 14 08:44:10 volumio volumio[979]: info: File /data/configuration/plugins.json does not exist. Copying from Volumio
Jun 14 08:44:10 volumio volumio[979]: info: MYVOLUMIO Environment detected
Jun 14 08:44:10 volumio firststart.sh[982]: insserv: warning: current start runlevel(s) (empty) of script `ssh' overrides LSB defaults (2 3 4 5).
Jun 14 08:44:10 volumio firststart.sh[982]: insserv: warning: current stop runlevel(s) (2 3 4 5) of script `ssh' overrides LSB defaults (empty).
Jun 14 08:44:10 volumio systemd[1]: Reloading.
Jun 14 08:44:10 volumio volumio[979]: info: Plugin folders cleanup
Jun 14 08:44:10 volumio volumio[979]: info: Scanning into folder /volumio/app/plugins/
Jun 14 08:44:10 volumio volumio[979]: info: Scanning category audio_interface
Jun 14 08:44:10 volumio volumio[979]: info: Scanning category miscellanea
Jun 14 08:44:10 volumio volumio[979]: info: Scanning category music_service
Jun 14 08:44:10 volumio volumio[979]: info: Scanning category plugins.json
Jun 14 08:44:10 volumio volumio[979]: info: Scanning category system_controller
Jun 14 08:44:10 volumio volumio[979]: info: Scanning category user_interface
Jun 14 08:44:10 volumio volumio[979]: info: Scanning into folder /data/plugins/
Jun 14 08:44:10 volumio volumio[979]: info: Plugin folders cleanup completed
Jun 14 08:44:10 volumio volumio[979]: info: -------------------------------------------
Jun 14 08:44:10 volumio volumio[979]: info: ----- Core plugins startup ----
Jun 14 08:44:10 volumio volumio[979]: info: -------------------------------------------
Jun 14 08:44:10 volumio volumio[979]: info: Loading plugins from folder /volumio/app/plugins/
Jun 14 08:44:10 volumio volumio[979]: info: Adding plugin upnp to MyMusic Plugins
Jun 14 08:44:10 volumio volumio[979]: info: Adding plugin airplay_emulation to MyMusic Plugins
Jun 14 08:44:10 volumio volumio[979]: info: Adding plugin upnp_browser to MyMusic Plugins
Jun 14 08:44:10 volumio volumio[979]: info: Loading plugins from folder /data/plugins/
Jun 14 08:44:10 volumio volumio[979]: info: Loading plugin "system"...
Jun 14 08:44:10 volumio volumio[979]: info: No id defined. Creating one
Jun 14 08:44:10 volumio volumio[979]: info: Loading plugin "appearance"...
Jun 14 08:44:10 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 14 08:44:11 volumio firststart.sh[982]: Enabling SSH for first boot
Jun 14 08:44:11 volumio systemd[1]: Starting OpenBSD Secure Shell server...
Jun 14 08:44:11 volumio sshd[1517]: Server listening on 0.0.0.0 port 22.
Jun 14 08:44:11 volumio sshd[1517]: Server listening on :: port 22.
Jun 14 08:44:11 volumio systemd[1]: Started OpenBSD Secure Shell server.
Jun 14 08:44:11 volumio firststart.sh[982]: Disabling firststart service
Jun 14 08:44:11 volumio firststart.sh[982]: Removed /etc/systemd/system/multi-user.target.wants/firststart.service.
Jun 14 08:44:11 volumio systemd[1]: Reloading.
Jun 14 08:44: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 14 08:44:11 volumio volumio[979]: info: Loading plugin "network"...
Jun 14 08:44:11 volumio volumio[979]: info: Refreshing Cached IP Addresses
Jun 14 08:44:11 volumio sudo[1533]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Jun 14 08:44:11 volumio sudo[1533]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 14 08:44:11 volumio sudo[1535]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Jun 14 08:44:11 volumio sudo[1535]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 14 08:44:11 volumio sudo[1533]: pam_unix(sudo:session): session closed for user root
Jun 14 08:44:11 volumio firststart.sh[982]: Finalizing
Jun 14 08:44:11 volumio volumio[979]: info: Loading plugin "services"...
Jun 14 08:44:11 volumio sudo[1535]: pam_unix(sudo:session): session closed for user root
Jun 14 08:44:11 volumio volumio[979]: info: Loading plugin "alsa_controller"...
Jun 14 08:44:11 volumio sudo[1544]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
Jun 14 08:44:11 volumio sudo[1544]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 14 08:44:11 volumio sudo[1544]: pam_unix(sudo:session): session closed for user root
Jun 14 08:44:11 volumio volumio[979]: warn: The ALSA output card is not set, defaulting to card [object Object]
Jun 14 08:44:11 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Jun 14 08:44:11 volumio volumio[979]: info: Found match in Cards Database: setting mixer for card HDMI 0
Jun 14 08:44:11 volumio volumio[979]: info: Setting mixer IEC958 for card HDMI 0
Jun 14 08:44:11 volumio volumio[979]: info: CoreCommandRouter::volumioUpdateVolumeSettings
Jun 14 08:44:11 volumio volumio[979]: info: CoreCommandRouter::volumioUpdateVolumeSettings
Jun 14 08:44:11 volumio volumio[979]: info: CoreCommandRouter::volumioUpdateVolumeSettings
Jun 14 08:44:11 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Jun 14 08:44:11 volumio volumio[979]: info: Loading plugin "wizard"...
Jun 14 08:44:11 volumio volumio[979]: info: Loading plugin "networkfs"...
Jun 14 08:44:11 volumio volumio[979]: info: Starting Udev Watcher for removable devices
Jun 14 08:44:14 volumio volumio-remote-updater[727]: [2025-06-14 08:44:14] [connect] Successful connection
Jun 14 08:44:15 volumio volumio[979]: info: Ignoring mount for partition: DaphileBoot
Jun 14 08:44:15 volumio volumio[979]: info: Ignoring mount for partition: DaphileData
Jun 14 08:44:15 volumio volumio[979]: error: Cannot associate FS Label, not mounting
Jun 14 08:44:15 volumio volumio[979]: info: Ignoring mount for partition: music
Jun 14 08:44:15 volumio volumio[979]: info: Ignoring mount for partition: boot
Jun 14 08:44:15 volumio volumio[979]: info: Ignoring mount for partition: volumio
Jun 14 08:44:15 volumio volumio[979]: info: Ignoring mount for partition: volumio_data
Jun 14 08:44:15 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Jun 14 08:44:15 volumio volumio[979]: info: Loading plugin "volumio_command_line_client"...
Jun 14 08:44:15 volumio volumio[979]: info: Loading plugin "upnp"...
Jun 14 08:44:15 volumio volumio[979]: info: [1749890655708] Starting Upmpd Daemon
Jun 14 08:44:15 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Jun 14 08:44:15 volumio volumio[979]: info: Loading plugin "my_music"...
Jun 14 08:44:15 volumio volumio[979]: info: Loading plugin "mpd"...
Jun 14 08:44:19 volumio systemd[1]: firststart.service: Succeeded.
Jun 14 08:44:19 volumio volumio[979]: info: Loading plugin "upnp_browser"...
Jun 14 08:44:20 volumio volumio[979]: info: Loading plugin "alarm-clock"...
Jun 14 08:44:20 volumio volumio[979]: info: Loading plugin "airplay_emulation"...
Jun 14 08:44:20 volumio volumio[979]: info: Starting Shairport Sync
Jun 14 08:44:20 volumio volumio[979]: info: Loading plugin "last_100"...
Jun 14 08:44:20 volumio volumio[979]: info: Loading plugin "webradio"...
Jun 14 08:44:20 volumio volumio[979]: info: Loading plugin "i2s_dacs"...
Jun 14 08:44:20 volumio volumio[979]: info: I2S DAC not set, start Auto-detection
Jun 14 08:44:20 volumio volumio[979]: info: Loading plugin "volumiodiscovery"...
Jun 14 08:44:20 volumio volumio[979]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Jun 14 08:44:20 volumio volumio[979]: *** WARNING *** Please fix your application to use the native API of Avahi!
Jun 14 08:44:20 volumio volumio[979]: *** WARNING *** For more information see
Jun 14 08:44:20 volumio node[979]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Jun 14 08:44:20 volumio volumio[979]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Jun 14 08:44:20 volumio volumio[979]: *** WARNING *** Please fix your application to use the native API of Avahi!
Jun 14 08:44:20 volumio volumio[979]: *** WARNING *** For more information see
Jun 14 08:44:20 volumio node[979]: *** WARNING *** Please fix your application to use the native API of Avahi!
Jun 14 08:44:20 volumio node[979]: *** WARNING *** For more information see
Jun 14 08:44:20 volumio node[979]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Jun 14 08:44:20 volumio node[979]: *** WARNING *** Please fix your application to use the native API of Avahi!
Jun 14 08:44:20 volumio node[979]: *** WARNING *** For more information see
Jun 14 08:44:20 volumio volumio[979]: info: Discovery: Started advertising with name: Volumio
Jun 14 08:44:20 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Jun 14 08:44:20 volumio volumio[979]: info: Loading plugin "outputs"...
Jun 14 08:44:20 volumio volumio[979]: info: Loading plugin "albumart"...
Jun 14 08:44:20 volumio volumio[979]: info: Plugin example_plugin is not enabled
Jun 14 08:44:20 volumio volumio[979]: info: Loading plugin "inputs"...
Jun 14 08:44:20 volumio volumio[979]: info: Loading plugin "updater_comm"...
Jun 14 08:44:20 volumio volumio[979]: Forking 3 albumart workers
Jun 14 08:44:20 volumio volumio[979]: info: Plugin mpdemulation is not enabled
Jun 14 08:44:20 volumio volumio[979]: info: Loading plugin "rest_api"...
Jun 14 08:44:20 volumio volumio[979]: info: Loading plugin "websocket"...
Jun 14 08:44:20 volumio volumio[979]: info: Starting Socket.io Server version 2.3.0
Jun 14 08:44:20 volumio volumio[979]: info: Loading i18n strings for locale en
Jun 14 08:44:20 volumio volumio[979]: Updating browse sources language
Jun 14 08:44:20 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jun 14 08:44:20 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jun 14 08:44:20 volumio volumio[979]: info: CoreCommandRouter::initPlayerControls
Jun 14 08:44:20 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 14 08:44:20 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 14 08:44:20 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 14 08:44:20 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 14 08:44:20 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 14 08:44:20 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 14 08:44:20 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 14 08:44:20 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 14 08:44:20 volumio volumio[979]: Starting albumart workers
Jun 14 08:44:20 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jun 14 08:44:20 volumio volumio[979]: Express server listening on port 3000
Jun 14 08:44:20 volumio volumio[979]: [Metrics] WebUI: 52s 325.13ms
Jun 14 08:44:20 volumio volumio[979]: Starting albumart workers
Jun 14 08:44:20 volumio volumio[979]: Starting albumart workers
Jun 14 08:44:20 volumio volumio[979]: info: Setting Device type: x86
Jun 14 08:44:20 volumio volumio[979]: info: CoreStateMachine::resetVolumioState
Jun 14 08:44:20 volumio volumio[979]: info: CoreStateMachine::getcurrentVolume
Jun 14 08:44:20 volumio volumio[979]: info: CoreCommandRouter::volumioRetrievevolume
Jun 14 08:44:21 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 14 08:44:21 volumio volumio[979]: info: CoreCommandRouter::volumioRetrieveVolumeLevels
Jun 14 08:44:21 volumio volumio[979]: info: CoreStateMachine::getcurrentVolume
Jun 14 08:44:21 volumio volumio[979]: info: CoreCommandRouter::volumioRetrievevolume
Jun 14 08:44:21 volumio volumio[979]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt'
Jun 14 08:44:21 volumio volumio[979]: info: Completed loading Core Plugins
Jun 14 08:44:21 volumio volumio[979]: info: Preparing to generate the ALSA configuration file
Jun 14 08:44:21 volumio volumio[979]: info: Cannot read play queue from file
Jun 14 08:44:21 volumio volumio[979]: info: Volumio Network Manager: Network status updated: 1
Jun 14 08:44:21 volumio volumio[979]: Unable to parse:
Jun 14 08:44:21 volumio volumio[979]: Simple mixer control 'IEC958',0
Jun 14 08:44:21 volumio volumio[979]: Capabilities: pswitch pswitch-joined
Jun 14 08:44:21 volumio volumio[979]: Playback channels: Mono
Jun 14 08:44:21 volumio volumio[979]: Mono: Playback [off]
Jun 14 08:44:21 volumio volumio[979]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output
Jun 14 08:44:21 volumio volumio[979]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan
Jun 14 08:44:21 volumio volumio[979]: wlan0 Interface doesn't support scanning.
Jun 14 08:44:21 volumio volumio[979]: info: Cannot use regular scanning, forcing with ap-force
Jun 14 08:44:21 volumio sudo[1662]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force
Jun 14 08:44:21 volumio sudo[1662]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 14 08:44:21 volumio sudo[1662]: pam_unix(sudo:session): session closed for user root
Jun 14 08:44:21 volumio volumio[979]: command failed: No such device (-19)
Jun 14 08:44:21 volumio volumio[979]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force
Jun 14 08:44:21 volumio volumio[979]: command failed: No such device (-19)
Jun 14 08:44:21 volumio volumio[979]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision
Jun 14 08:44:21 volumio volumio[979]: Unable to parse:
Jun 14 08:44:21 volumio volumio[979]: Simple mixer control 'IEC958',0
Jun 14 08:44:21 volumio volumio[979]: Capabilities: pswitch pswitch-joined
Jun 14 08:44:21 volumio volumio[979]: Playback channels: Mono
Jun 14 08:44:21 volumio volumio[979]: Mono: Playback [off]
Jun 14 08:44:21 volumio volumio[979]: info: VolumeController:: Volume=undefined Mute =false
Jun 14 08:44:21 volumio volumio[979]: info: CoreStateMachine::pushState
Jun 14 08:44:21 volumio volumio[979]: info: CorePlayQueue::getTrack 0
Jun 14 08:44:21 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jun 14 08:44:21 volumio volumio[979]: info: CoreCommandRouter::volumioPushState
Jun 14 08:44:21 volumio volumio[979]: info: CoreStateMachine::updateTrackBlock
Jun 14 08:44:21 volumio volumio[979]: info: CorePlayQueue::getTrackBlock
Jun 14 08:44:21 volumio volumio[979]: info: CoreCommandRouter::volumioRetrievevolume
Jun 14 08:44:21 volumio volumio[979]: Unable to parse:
Jun 14 08:44:21 volumio volumio[979]: Simple mixer control 'IEC958',0
Jun 14 08:44:21 volumio volumio[979]: Capabilities: pswitch pswitch-joined
Jun 14 08:44:21 volumio volumio[979]: Playback channels: Mono
Jun 14 08:44:21 volumio volumio[979]: Mono: Playback [off]
Jun 14 08:44:21 volumio volumio[979]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output
Jun 14 08:44:21 volumio volumio[979]: Unable to parse:
Jun 14 08:44:21 volumio volumio[979]: Simple mixer control 'IEC958',0
Jun 14 08:44:21 volumio volumio[979]: Capabilities: pswitch pswitch-joined
Jun 14 08:44:21 volumio volumio[979]: Playback channels: Mono
Jun 14 08:44:21 volumio volumio[979]: Mono: Playback [off]
Jun 14 08:44:21 volumio volumio[979]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output
Jun 14 08:44:21 volumio volumio[979]: info: Asound.conf file written
Jun 14 08:44:21 volumio sudo[1699]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf
Jun 14 08:44:21 volumio sudo[1699]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 14 08:44:21 volumio sudo[1699]: pam_unix(sudo:session): session closed for user root
Jun 14 08:44:21 volumio volumio[979]: No state is present for card HDMI
Jun 14 08:44:21 volumio volumio[979]: Found hardware: "HDA-Intel" "ATI R6xx HDMI" "HDA:1002aa01,00aa0100,00100700" "0x103c" "0x8158"
Jun 14 08:44:21 volumio volumio[979]: Hardware is initialized using a generic method
Jun 14 08:44:21 volumio volumio[979]: No state is present for card HDMI
Jun 14 08:44:21 volumio volumio[979]: No state is present for card Generic
Jun 14 08:44:21 volumio volumio[979]: Found hardware: "HDA-Intel" "Realtek ALC255" "HDA:10ec0255,103c8158,00100002" "0x103c" "0x8158"
Jun 14 08:44:21 volumio volumio[979]: Hardware is initialized using a generic method
Jun 14 08:44:21 volumio volumio[979]: No state is present for card Generic
Jun 14 08:44:21 volumio volumio[979]: No state is present for card Amanero
Jun 14 08:44:21 volumio volumio[979]: Found hardware: "USB-Audio" "USB Mixer" "USB16d0:0a23" "" ""
Jun 14 08:44:21 volumio volumio[979]: Hardware is initialized using a generic method
Jun 14 08:44:21 volumio volumio[979]: No state is present for card Amanero
Jun 14 08:44:21 volumio volumio[979]: info: Output device has changed, restarting MPD
Jun 14 08:44:21 volumio sudo[1704]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Jun 14 08:44:21 volumio sudo[1704]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 14 08:44:21 volumio volumio[979]: info: Output device has changed, restarting Shairport Sync
Jun 14 08:44:21 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 14 08:44:21 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 14 08:44:21 volumio sudo[1706]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Jun 14 08:44:21 volumio sudo[1706]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 14 08:44:21 volumio sudo[1704]: pam_unix(sudo:session): session closed for user root
Jun 14 08:44:21 volumio systemd[1]: Stopping Music Player Daemon...
Jun 14 08:44:21 volumio volumio[979]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Jun 14 08:44:21 volumio volumio[979]: info: ___________ START PLUGINS ___________
Jun 14 08:44:21 volumio volumio[979]: info: Setting Hotspot Unique name on first start: Volumio-C594C
Jun 14 08:44:21 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: network , getConfigParam
Jun 14 08:44:21 volumio volumio[979]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getConfigParam] in plugin network
Jun 14 08:44:21 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: network , saveHotspotSettings
Jun 14 08:44:21 volumio volumio[979]: info: CoreCommandRouter::Reload Ui
Jun 14 08:44:21 volumio volumio[979]: info: ControllerMpd::onStart: Initializing MPD
Jun 14 08:44:21 volumio volumio[979]: info: Creating MPD Configuration file
Jun 14 08:44:21 volumio sudo[1713]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Jun 14 08:44:21 volumio sudo[1713]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 14 08:44:21 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jun 14 08:44:21 volumio volumio[979]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Jun 14 08:44:21 volumio volumio[979]: info: [1749890661515] CoreMusicLibrary::Adding element Media Servers
Jun 14 08:44:21 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jun 14 08:44:21 volumio sudo[1713]: pam_unix(sudo:session): session closed for user root
Jun 14 08:44:21 volumio sudo[1715]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Jun 14 08:44:21 volumio sudo[1715]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 14 08:44:21 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 14 08:44:21 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 14 08:44:21 volumio volumio[979]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Jun 14 08:44:21 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 14 08:44:21 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 14 08:44:21 volumio volumio[979]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Jun 14 08:44:21 volumio volumio[979]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Jun 14 08:44:21 volumio volumio[979]: info: [1749890661595] CoreMusicLibrary::Adding element Last_100
Jun 14 08:44:21 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jun 14 08:44:21 volumio volumio[979]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Jun 14 08:44:21 volumio volumio[979]: info: [1749890661597] CoreMusicLibrary::Adding element Webradio
Jun 14 08:44:21 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jun 14 08:44:21 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Jun 14 08:44:21 volumio volumio[979]: info: Initializing BBC Radios
Jun 14 08:44:21 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Jun 14 08:44:21 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 14 08:44:21 volumio volumio[979]: info: Volumio Calling Home
Jun 14 08:44:21 volumio systemd[1]: mpd.service: Succeeded.
Jun 14 08:44:21 volumio systemd[1]: Stopped Music Player Daemon.
Jun 14 08:44:21 volumio systemd[1]: Starting Music Player Daemon...
Jun 14 08:44:21 volumio sudo[1751]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Jun 14 08:44:21 volumio volumio[979]: Unable to parse:
Jun 14 08:44:21 volumio volumio[979]: Simple mixer control 'IEC958',0
Jun 14 08:44:21 volumio volumio[979]: Capabilities: pswitch pswitch-joined
Jun 14 08:44:21 volumio volumio[979]: Playback channels: Mono
Jun 14 08:44:21 volumio volumio[979]: Mono: Playback [off]
Jun 14 08:44:21 volumio volumio[979]: info: VolumeController:: Volume=undefined Mute =false
Jun 14 08:44:21 volumio volumio[979]: info: CoreStateMachine::pushState
Jun 14 08:44:21 volumio sudo[1751]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 14 08:44:21 volumio volumio[979]: info: CorePlayQueue::getTrack 0
Jun 14 08:44:21 volumio volumio[979]: info: CoreCommandRouter::volumioPushState
Jun 14 08:44:21 volumio volumio[979]: Unable to parse:
Jun 14 08:44:21 volumio volumio[979]: Simple mixer control 'IEC958',0
Jun 14 08:44:21 volumio volumio[979]: Capabilities: pswitch pswitch-joined
Jun 14 08:44:21 volumio volumio[979]: Playback channels: Mono
Jun 14 08:44:21 volumio volumio[979]: Mono: Playback [off]
Jun 14 08:44:21 volumio volumio[979]: info: VolumeController:: Volume=undefined Mute =false
Jun 14 08:44:21 volumio volumio[979]: info: CoreStateMachine::pushState
Jun 14 08:44:21 volumio volumio[979]: info: CorePlayQueue::getTrack 0
Jun 14 08:44:21 volumio volumio[979]: info: CoreCommandRouter::volumioPushState
Jun 14 08:44:21 volumio sudo[1751]: pam_unix(sudo:session): session closed for user root
Jun 14 08:44:21 volumio volumio[979]: info: CoreStateMachine::updateTrackBlock
Jun 14 08:44:21 volumio volumio[979]: info: CorePlayQueue::getTrackBlock
Jun 14 08:44:21 volumio volumio[979]: info: CoreCommandRouter::volumioRetrievevolume
Jun 14 08:44:21 volumio volumio[979]: info: MPD Permissions set
Jun 14 08:44:21 volumio volumio[979]: info: MPD Permissions set
Jun 14 08:44:21 volumio volumio[979]: Unable to parse:
Jun 14 08:44:21 volumio volumio[979]: Simple mixer control 'IEC958',0
Jun 14 08:44:21 volumio volumio[979]: Capabilities: pswitch pswitch-joined
Jun 14 08:44:21 volumio volumio[979]: Playback channels: Mono
Jun 14 08:44:21 volumio volumio[979]: Mono: Playback [off]
Jun 14 08:44:21 volumio volumio[979]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output
Jun 14 08:44:21 volumio volumio[979]: 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 14 08:44:21 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 14 08:44:21 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 14 08:44:21 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 14 08:44:21 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 14 08:44:21 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 14 08:44:21 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 14 08:44:21 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 14 08:44:21 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 14 08:44:21 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 14 08:44:21 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber
Jun 14 08:44:21 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 14 08:44:21 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 14 08:44:21 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 14 08:44:21 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 14 08:44:21 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 14 08:44:21 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 14 08:44:21 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 14 08:44:21 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 14 08:44:21 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 14 08:44:21 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 14 08:44:21 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber
Jun 14 08:44:21 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 14 08:44:21 volumio volumio[979]: Unable to parse:
Jun 14 08:44:21 volumio volumio[979]: Simple mixer control 'IEC958',0
Jun 14 08:44:21 volumio volumio[979]: Capabilities: pswitch pswitch-joined
Jun 14 08:44:21 volumio volumio[979]: Playback channels: Mono
Jun 14 08:44:21 volumio volumio[979]: Mono: Playback [off]
Jun 14 08:44:21 volumio volumio[979]: info: VolumeController:: Volume=undefined Mute =false
Jun 14 08:44:21 volumio volumio[979]: info: CoreStateMachine::pushState
Jun 14 08:44:21 volumio volumio[979]: info: CorePlayQueue::getTrack 0
Jun 14 08:44:21 volumio volumio[979]: info: CoreCommandRouter::volumioPushState
Jun 14 08:44:21 volumio volumio[979]: info: CorePlayQueue::getTrack 0
Jun 14 08:44:22 volumio volumio[979]: info: Starting Shairport Sync
Jun 14 08:44:22 volumio volumio[979]: info: Starting Shairport Sync
Jun 14 08:44:22 volumio volumio[979]: info: Starting Shairport Sync
Jun 14 08:44:22 volumio sudo[1773]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Jun 14 08:44:22 volumio sudo[1773]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 14 08:44:22 volumio sudo[1769]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Jun 14 08:44:22 volumio sudo[1769]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 14 08:44:22 volumio sudo[1771]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Jun 14 08:44:22 volumio sudo[1771]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 14 08:44:22 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Jun 14 08:44:22 volumio volumio[979]: info: CoreCommandRouter::volumioGetState
Jun 14 08:44:22 volumio volumio[979]: info: CorePlayQueue::getTrack 0
Jun 14 08:44:22 volumio mpd[1753]: Jun 14 08:44 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Jun 14 08:44:22 volumio systemd[1]: Started Music Player Daemon.
Jun 14 08:44:22 volumio sudo[1715]: pam_unix(sudo:session): session closed for user root
Jun 14 08:44:22 volumio systemd[1]: shairport-sync.service: Succeeded.
Jun 14 08:44:22 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Jun 14 08:44:22 volumio sudo[1706]: pam_unix(sudo:session): session closed for user root
Jun 14 08:44:22 volumio volumio[979]: info: Completed starting Core Plugins
Jun 14 08:44:22 volumio volumio[979]: info: -------------------------------------------
Jun 14 08:44:22 volumio volumio[979]: info: ----- MyVolumio plugins startup ----
Jun 14 08:44:22 volumio volumio[979]: info: -------------------------------------------
Jun 14 08:44:22 volumio volumio[979]: info: [MyVolumio PluginManager] Fetching plans data....
Jun 14 08:44:22 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Jun 14 08:44:22 volumio sudo[1771]: pam_unix(sudo:session): session closed for user root
Jun 14 08:44:22 volumio sudo[1769]: pam_unix(sudo:session): session closed for user root
Jun 14 08:44:22 volumio sudo[1773]: pam_unix(sudo:session): session closed for user root
Jun 14 08:44:22 volumio volumio[979]: error: MPD error: The expression evaluated to a falsy value:
Jun 14 08:44:22 volumio volumio[979]: assert.ok(self.idling)
Jun 14 08:44:22 volumio volumio[979]: error: The expression evaluated to a falsy value:
Jun 14 08:44:22 volumio volumio[979]: assert.ok(self.idling)
Jun 14 08:44:22 volumio volumio[979]: info: MPD running with PID1753
Jun 14 08:44:22 volumio volumio[979]: ,establishing connection
Jun 14 08:44:22 volumio volumio[979]: error: MPD error: The expression evaluated to a falsy value:
Jun 14 08:44:22 volumio volumio[979]: assert.ok(self.idling)
Jun 14 08:44:22 volumio volumio[979]: error: The expression evaluated to a falsy value:
Jun 14 08:44:22 volumio volumio[979]: assert.ok(self.idling)
Jun 14 08:44:22 volumio volumio[979]: info: Shairport-Sync Started
Jun 14 08:44:22 volumio volumio[979]: Error adding Membership: Error: addMembership EINVAL
Jun 14 08:44:22 volumio volumio[979]: info: Shairport-Sync Started
Jun 14 08:44:22 volumio volumio[979]: info: Shairport-Sync Started
Jun 14 08:44:22 volumio volumio[979]: error: updateQueue error: null
Jun 14 08:44:22 volumio sudo[1785]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hostapd/hostapd.conf
Jun 14 08:44:22 volumio sudo[1785]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 14 08:44:22 volumio sudo[1785]: pam_unix(sudo:session): session closed for user root
Jun 14 08:44:22 volumio volumio[979]: info: Permissions for /etc/hostapd/hostapd.conf
Jun 14 08:44:22 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart
Jun 14 08:44:22 volumio volumio[979]: info: Discovery: Restarting Advertising due to device name change
Jun 14 08:44:22 volumio sudo[1788]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service
Jun 14 08:44:22 volumio sudo[1788]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 14 08:44:22 volumio systemd[1]: Starting Wireless Services...
Jun 14 08:44:22 volumio wireless.js[1790]: WIRELESS: No wireless interface, exiting
Jun 14 08:44:22 volumio iw[1801]: command failed: No such device (-19)
Jun 14 08:44:22 volumio volumio[979]: info: Volumio called home
Jun 14 08:44:22 volumio iwconfig[1804]: Error for wireless request "Set Power Management" (8B2C) :
Jun 14 08:44:22 volumio iwconfig[1804]: SET failed on device wlan0 ; No such device.
Jun 14 08:44:22 volumio systemd[1]: wireless.service: Succeeded.
Jun 14 08:44:22 volumio systemd[1]: Started Wireless Services.
Jun 14 08:44:22 volumio sudo[1788]: pam_unix(sudo:session): session closed for user root
Jun 14 08:44:23 volumio volumio[979]: info: Discovery: A device disappeared from network
Jun 14 08:44:23 volumio volumio[979]: info: Discovery: Browse raised the following error TypeError: Cannot read property 'toLowerCase' of undefined
Jun 14 08:44:24 volumio volumio[979]: info: Discovery: adding 2946715c-f835-4e0d-bc50-8589054c2446
Jun 14 08:44:24 volumio volumio[979]: info: Discovery: Found device Volumio
Jun 14 08:44:24 volumio volumio[979]: info: CoreCommandRouter::volumioGetState
Jun 14 08:44:24 volumio volumio[979]: info: CorePlayQueue::getTrack 0
Jun 14 08:44:29 volumio volumio-remote-updater[727]: [2025-06-14 08:44:29] [connect] Successful connection
Jun 14 08:44:29 volumio sudo[1832]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Jun 14 08:44:29 volumio sudo[1832]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 14 08:44:29 volumio sudo[1830]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Jun 14 08:44:29 volumio sudo[1830]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 14 08:44:29 volumio sudo[1830]: pam_unix(sudo:session): session closed for user root
Jun 14 08:44:29 volumio volumio[979]: info: Discovery: Started advertising with name: Volumio
Jun 14 08:44:29 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: wizard , reportWirelessConnection
Jun 14 08:44:29 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessInfo
Jun 14 08:44:29 volumio sudo[1832]: pam_unix(sudo:session): session closed for user root
Jun 14 08:44:29 volumio volumio[979]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory
Jun 14 08:44:29 volumio sudo[1842]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Jun 14 08:44:29 volumio volumio-remote-updater[727]: [2025-06-14 08:44:29] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1749890669 101
Jun 14 08:44:29 volumio sudo[1842]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 14 08:44:29 volumio volumio[979]: 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 14 08:44:29 volumio sudo[1842]: pam_unix(sudo:session): session closed for user root
Jun 14 08:44:29 volumio sudo[1847]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service
Jun 14 08:44:29 volumio sudo[1847]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 14 08:44:29 volumio systemd[1]: Started UPnP Renderer front-end to MPD.
Jun 14 08:44:29 volumio sudo[1847]: pam_unix(sudo:session): session closed for user root
Jun 14 08:44:29 volumio volumio[979]: info: Upmpdcli Daemon Started
Jun 14 08:44:29 volumio volumio[1849]: Generating RSA private key, 4096 bit long modulus (2 primes)
Jun 14 08:44:31 volumio volumio[1849]: .....................................................................................................................++++
Jun 14 08:44:31 volumio volumio[1849]: .......++++
Jun 14 08:44:31 volumio volumio[1849]: e is 65537 (0x010001)
Jun 14 08:44:32 volumio volumio[1849]: writing RSA key
Jun 14 08:44:32 volumio volumio[979]: info: Discovery: this is already registered, 2946715c-f835-4e0d-bc50-8589054c2446
Jun 14 08:44:32 volumio volumio[979]: info: Discovery: Found device Volumio
Jun 14 08:44:32 volumio volumio[979]: info: CoreCommandRouter::volumioGetState
Jun 14 08:44:32 volumio volumio[979]: info: CorePlayQueue::getTrack 0
Jun 14 08:44:32 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart
Jun 14 08:44:32 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart
Jun 14 08:44:32 volumio volumio[979]: info: Refreshing Cached IP Addresses
Jun 14 08:44:37 volumio sudo[1886]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Jun 14 08:44:37 volumio sudo[1884]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Jun 14 08:44:37 volumio sudo[1884]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 14 08:44:37 volumio sudo[1886]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 14 08:44:37 volumio sudo[1882]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli
Jun 14 08:44:37 volumio sudo[1882]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 14 08:44:37 volumio sudo[1884]: pam_unix(sudo:session): session closed for user root
Jun 14 08:44:37 volumio sudo[1882]: pam_unix(sudo:session): session closed for user root
Jun 14 08:44:37 volumio sudo[1886]: pam_unix(sudo:session): session closed for user root
Jun 14 08:44:39 volumio volumio[979]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium
Jun 14 08:44:39 volumio volumio[979]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium
Jun 14 08:44:39 volumio volumio[979]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium
Jun 14 08:44:39 volumio volumio[979]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium
Jun 14 08:44:39 volumio volumio[979]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium
Jun 14 08:44:39 volumio volumio[979]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium
Jun 14 08:44:39 volumio volumio[979]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium
Jun 14 08:44:39 volumio volumio[979]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium
Jun 14 08:44:39 volumio volumio[979]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium
Jun 14 08:44:39 volumio volumio[979]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium
Jun 14 08:44:39 volumio volumio[979]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium
Jun 14 08:44:39 volumio volumio[979]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar
Jun 14 08:44:39 volumio volumio[979]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar
Jun 14 08:44:39 volumio volumio[979]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar
Jun 14 08:44:39 volumio volumio[979]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar
Jun 14 08:44:39 volumio volumio[979]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar
Jun 14 08:44:39 volumio volumio[979]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar
Jun 14 08:44:39 volumio volumio[979]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar
Jun 14 08:44:39 volumio volumio[979]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar
Jun 14 08:44:39 volumio volumio[979]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar
Jun 14 08:44:39 volumio volumio[979]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar
Jun 14 08:44:39 volumio volumio[979]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar
Jun 14 08:44:39 volumio volumio[979]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso
Jun 14 08:44:39 volumio volumio[979]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso
Jun 14 08:44:39 volumio volumio[979]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso
Jun 14 08:44:39 volumio volumio[979]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso
Jun 14 08:44:39 volumio volumio[979]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso
Jun 14 08:44:39 volumio volumio[979]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso
Jun 14 08:44:40 volumio volumio[979]: info: Adding plugin bluetooth to MyMusic Plugins
Jun 14 08:44:40 volumio volumio[979]: info: Adding plugin multiroom to MyMusic Plugins
Jun 14 08:44:40 volumio volumio[979]: info: Adding plugin metavolumio to MyMusic Plugins
Jun 14 08:44:40 volumio volumio[979]: info: Adding plugin cd_controller to MyMusic Plugins
Jun 14 08:44:40 volumio volumio[979]: info: Adding plugin qobuzconnect to MyMusic Plugins
Jun 14 08:44:40 volumio volumio[979]: info: Adding plugin smart_inputs to MyMusic Plugins
Jun 14 08:44:40 volumio volumio[979]: info: Adding plugin tidalconnect to MyMusic Plugins
Jun 14 08:44:40 volumio volumio[979]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"...
Jun 14 08:44:45 volumio volumio[979]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded
Jun 14 08:44:45 volumio volumio[979]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio
Jun 14 08:44:45 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 14 08:44:45 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 14 08:44:45 volumio volumio[979]: info: Starting MyVolumio Remote Streaming Endpoints
Jun 14 08:44:45 volumio volumio[979]: info: MyVolumio not started
Jun 14 08:44:45 volumio volumio[979]: info: Initializing device activation check
Jun 14 08:44:45 volumio volumio[979]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started
Jun 14 08:44:45 volumio volumio[979]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"...
Jun 14 08:44:47 volumio volumio[979]: info: Starting Streaming Service Transparent Proxy
Jun 14 08:44:47 volumio volumio[979]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded
Jun 14 08:44:47 volumio volumio[979]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services
Jun 14 08:44:47 volumio volumio[979]: info: Streaming services startup
Jun 14 08:44:47 volumio volumio[979]: info: Starting Streaming Daemon
Jun 14 08:44:47 volumio sudo[1965]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Jun 14 08:44:47 volumio sudo[1965]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 14 08:44:47 volumio sudo[1965]: pam_unix(sudo:session): session closed for user root
Jun 14 08:44:47 volumio volumio[979]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started
Jun 14 08:44:47 volumio sudo[1973]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Jun 14 08:44:47 volumio sudo[1973]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 14 08:44:47 volumio sudo[1973]: pam_unix(sudo:session): session closed for user root
Jun 14 08:44:47 volumio volumio[979]: error: Cannot start Volumio Streaming Daemon
Jun 14 08:44:47 volumio volumio[979]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Jun 14 08:44:47 volumio volumio[979]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Jun 14 08:44:47 volumio sudo[1975]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Jun 14 08:44:47 volumio sudo[1975]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 14 08:44:47 volumio sudo[1975]: pam_unix(sudo:session): session closed for user root
Jun 14 08:44:47 volumio sudo[1981]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service
Jun 14 08:44:47 volumio sudo[1981]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 14 08:44:47 volumio sudo[1981]: pam_unix(sudo:session): session closed for user root
Jun 14 08:44:47 volumio volumio[979]: info: Upmpdcli Daemon Started
Jun 14 08:44:47 volumio dbus-daemon[746]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by ':1.15' (uid=0 pid=1814 comm="/usr/lib/chromium/chromium --show-component-extens")
Jun 14 08:44:47 volumio systemd[1]: Starting Bluetooth service...
Jun 14 08:44:47 volumio volumio[979]: STREAMING PROXY: Starting server on port 3245
Jun 14 08:44:47 volumio volumio[979]: Node JS runtime: 14
Jun 14 08:44:51 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Jun 14 08:44:51 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 14 08:44:51 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Jun 14 08:44:51 volumio kernel: Bluetooth: Core ver 2.22
Jun 14 08:44:51 volumio kernel: NET: Registered PF_BLUETOOTH protocol family
Jun 14 08:44:51 volumio kernel: Bluetooth: HCI device and connection manager initialized
Jun 14 08:44:51 volumio kernel: Bluetooth: HCI socket layer initialized
Jun 14 08:44:51 volumio kernel: Bluetooth: L2CAP socket layer initialized
Jun 14 08:44:51 volumio kernel: Bluetooth: SCO socket layer initialized
Jun 14 08:44:52 volumio bluetoothd[1985]: Bluetooth daemon 5.50
Jun 14 08:44:52 volumio bluetoothd[1985]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf
Jun 14 08:44:52 volumio dbus-daemon[746]: [system] Successfully activated service 'org.bluez'
Jun 14 08:44:52 volumio systemd[1]: Started Bluetooth service.
Jun 14 08:44:52 volumio bluetoothd[1985]: Starting SDP server
Jun 14 08:44:52 volumio bluetoothd[1985]: Excluding (cli) sap
Jun 14 08:44:52 volumio kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3
Jun 14 08:44:52 volumio kernel: Bluetooth: BNEP filters: protocol multicast
Jun 14 08:44:52 volumio kernel: Bluetooth: BNEP socket layer initialized
Jun 14 08:44:52 volumio dbus-daemon[746]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.16' (uid=0 pid=1985 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ")
Jun 14 08:44:52 volumio bluetoothd[1985]: Bluetooth management interface 1.22 initialized
Jun 14 08:44:52 volumio systemd[1]: Starting Hostname Service...
Jun 14 08:44:52 volumio dbus-daemon[746]: [system] Successfully activated service 'org.freedesktop.hostname1'
Jun 14 08:44:52 volumio systemd[1]: Started Hostname Service.
Jun 14 08:44:53 volumio volumio[979]: 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: 3
Jun 14 08:44:54 volumio volumio[979]: info: CoreCommandRouter::volumioGetVisibleSources
Jun 14 08:44:54 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jun 14 08:44:54 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps
Jun 14 08:44:54 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode
Jun 14 08:44:54 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages
Jun 14 08:44:54 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones
Jun 14 08:44:54 volumio volumio[979]: info: Received Get System Info
Jun 14 08:44:54 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Jun 14 08:44:54 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Jun 14 08:44:54 volumio volumio[979]: info: Discovery: Getting this device information
Jun 14 08:44:54 volumio volumio[979]: info: CoreCommandRouter::volumioGetState
Jun 14 08:44:54 volumio volumio[979]: info: CorePlayQueue::getTrack 0
Jun 14 08:44:54 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jun 14 08:44:54 volumio volumio-remote-updater[727]: No test mode
Jun 14 08:44:54 volumio volumio-remote-updater[727]: No alpha test mode
Jun 14 08:44:54 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
Jun 14 08:44:54 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Jun 14 08:44:54 volumio volumio[979]: info: CoreCommandRouter::getUIConfigOnPlugin
Jun 14 08:44:54 volumio volumio[979]: info: Retrieving Cloud Streaming UI
Jun 14 08:44:54 volumio volumio[979]: info: Getting Tidal Cloud Configuration
Jun 14 08:44:54 volumio volumio[979]: info: CoreCommandRouter::getUIConfigOnPlugin
Jun 14 08:44:54 volumio volumio[979]: info: Getting Qobuz Cloud Configuration
Jun 14 08:44:54 volumio volumio[979]: info: Asking plugin for UI Config
Jun 14 08:44:54 volumio volumio[979]: info: CoreCommandRouter::getUIConfigOnPlugin
Jun 14 08:44:54 volumio volumio[979]: info: Getting Spotify Cloud Configuration
Jun 14 08:44:54 volumio volumio[979]: info: Asking plugin for UI Config
Jun 14 08:44:54 volumio volumio[979]: info: CoreCommandRouter::getUIConfigOnPlugin
Jun 14 08:44:54 volumio volumio[979]: info: Saving Spotify Acccount
Jun 14 08:44:54 volumio volumio[979]: info: Got Tidal Cloud Configuration
Jun 14 08:44:54 volumio volumio[979]: info: Got it
Jun 14 08:44:54 volumio volumio[979]: info: Got it
Jun 14 08:44:54 volumio volumio[979]: error: Could not retrieve Spotify Config from plugin Spotify: no section found
Jun 14 08:44:54 volumio volumio[979]: info: CoreCommandRouter::getUIConfigOnPlugin
Jun 14 08:44:54 volumio volumio[979]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined
Jun 14 08:44:59 volumio volumio[979]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"FIXES
- Various Fixes for QOBUZ Connect
- Various Fixes for Tidal Connect
","title":"Update v3.816","updateavailable":true}
Jun 14 08:44:59 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Jun 14 08:44:59 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage
Jun 14 08:44:59 volumio volumio[979]: info: Loading i18n strings for locale en
Jun 14 08:44:59 volumio volumio[979]: info: Fetching Streaming Services browse cache
Jun 14 08:45:03 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Jun 14 08:45:03 volumio volumio[979]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4
Jun 14 08:45:03 volumio volumio[979]: info: CoreCommandRouter::volumioGetState
Jun 14 08:45:03 volumio volumio[979]: info: CorePlayQueue::getTrack 0
Jun 14 08:45:03 volumio volumio[979]: Updating browse sources language
Jun 14 08:45:03 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jun 14 08:45:03 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone
Jun 14 08:45:03 volumio volumio[979]: info: Setting timezone to UTC
Jun 14 08:45:03 volumio sudo[2159]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime
Jun 14 08:45:03 volumio sudo[2159]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 14 08:45:03 volumio sudo[2159]: pam_unix(sudo:session): session closed for user root
Jun 14 08:45:03 volumio sudo[2162]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/UTC /etc/localtime
Jun 14 08:45:03 volumio sudo[2162]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 14 08:45:03 volumio sudo[2162]: pam_unix(sudo:session): session closed for user root
Jun 14 08:45:03 volumio sudo[2165]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime
Jun 14 08:45:03 volumio sudo[2165]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 14 08:45:07 volumio sudo[2165]: pam_unix(sudo:session): session closed for user root
Jun 14 08:45:07 volumio sudo[2168]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone UTC
Jun 14 08:45:07 volumio sudo[2168]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 14 08:45:07 volumio dbus-daemon[746]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.19' (uid=0 pid=2169 comm="/usr/bin/timedatectl set-timezone UTC ")
Jun 14 08:45:07 volumio systemd[1]: Starting Time & Date Service...
Jun 14 08:45:07 volumio dbus-daemon[746]: [system] Successfully activated service 'org.freedesktop.timedate1'
Jun 14 08:45:07 volumio systemd[1]: Started Time & Date Service.
Jun 14 08:45:07 volumio sudo[2168]: pam_unix(sudo:session): session closed for user root
Jun 14 08:45:07 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Jun 14 08:45:07 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 14 08:45:07 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam
Jun 14 08:45:07 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Jun 14 08:45:07 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject
Jun 14 08:45:07 volumio volumio[979]: info: CoreCommandRouter::volumioGetBrowseSources
Jun 14 08:45:07 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Jun 14 08:45:07 volumio volumio[979]: error: MyVolumio Plugin failed to authenticate in a timely fashion
Jun 14 08:45:07 volumio volumio[979]: info: Completed starting MyVolumio Plugin
Jun 14 08:45:07 volumio volumio[979]: [Metrics] CommandRouter: 58s 304.67ms
Jun 14 08:45:07 volumio volumio[979]: info: CoreCommandRouter::volumiosetStartupVolume
Jun 14 08:45:07 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 14 08:45:07 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 14 08:45:07 volumio volumio[979]: info: CoreCommandRouter::Close All Modals sent
Jun 14 08:45:07 volumio volumio[979]: info: CoreCommandRouter::Close All Modals sent
Jun 14 08:45:07 volumio volumio[979]: info: CoreCommandRouter::getUIConfigOnPlugin
Jun 14 08:45:07 volumio volumio[979]: info: Retrieving Cloud Streaming UI
Jun 14 08:45:07 volumio volumio[979]: info: CoreCommandRouter::getUIConfigOnPlugin
Jun 14 08:45:07 volumio volumio[979]: info: Retrieving Cloud Streaming UI
Jun 14 08:45:07 volumio volumio[979]: info: Getting Tidal Cloud Configuration
Jun 14 08:45:07 volumio volumio[979]: info: CoreCommandRouter::getUIConfigOnPlugin
Jun 14 08:45:07 volumio volumio[979]: info: Getting Qobuz Cloud Configuration
Jun 14 08:45:07 volumio volumio[979]: info: Asking plugin for UI Config
Jun 14 08:45:07 volumio volumio[979]: info: CoreCommandRouter::getUIConfigOnPlugin
Jun 14 08:45:07 volumio volumio[979]: info: Getting Spotify Cloud Configuration
Jun 14 08:45:07 volumio volumio[979]: info: Asking plugin for UI Config
Jun 14 08:45:07 volumio volumio[979]: info: CoreCommandRouter::getUIConfigOnPlugin
Jun 14 08:45:07 volumio volumio[979]: info: Saving Spotify Acccount
Jun 14 08:45:07 volumio volumio[979]: info: Got Tidal Cloud Configuration
Jun 14 08:45:07 volumio volumio[979]: info: Got it
Jun 14 08:45:07 volumio volumio[979]: info: Got it
Jun 14 08:45:07 volumio volumio[979]: error: Could not retrieve Spotify Config from plugin Spotify: no section found
Jun 14 08:45:07 volumio volumio[979]: info: Getting Tidal Cloud Configuration
Jun 14 08:45:07 volumio volumio[979]: info: CoreCommandRouter::getUIConfigOnPlugin
Jun 14 08:45:07 volumio volumio[979]: info: Getting Qobuz Cloud Configuration
Jun 14 08:45:07 volumio volumio[979]: info: Asking plugin for UI Config
Jun 14 08:45:07 volumio volumio[979]: info: CoreCommandRouter::getUIConfigOnPlugin
Jun 14 08:45:07 volumio volumio[979]: info: Getting Spotify Cloud Configuration
Jun 14 08:45:07 volumio volumio[979]: info: Asking plugin for UI Config
Jun 14 08:45:07 volumio volumio[979]: info: CoreCommandRouter::getUIConfigOnPlugin
Jun 14 08:45:07 volumio volumio[979]: info: Saving Spotify Acccount
Jun 14 08:45:07 volumio volumio[979]: info: Got Tidal Cloud Configuration
Jun 14 08:45:07 volumio volumio[979]: info: Got it
Jun 14 08:45:07 volumio volumio[979]: info: Got it
Jun 14 08:45:07 volumio volumio[979]: error: Could not retrieve Spotify Config from plugin Spotify: no section found
Jun 14 08:45:07 volumio volumio[979]: info: CoreCommandRouter::getUIConfigOnPlugin
Jun 14 08:45:07 volumio volumio[979]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined
Jun 14 08:45:07 volumio volumio[979]: info: CoreCommandRouter::getUIConfigOnPlugin
Jun 14 08:45:07 volumio volumio[979]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined
Jun 14 08:45:11 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable
Jun 14 08:45:11 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Jun 14 08:45:11 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect
Jun 14 08:45:11 volumio volumio[979]: info: Version has changed, forcing UI Reload
Jun 14 08:45:11 volumio volumio[979]: info: CoreCommandRouter::Reload Ui
Jun 14 08:45:11 volumio kernel: snd_hda_intel 0000:00:01.1: IRQ timing workaround is activated for card #0. Suggest a bigger bdl_pos_adj.
Jun 14 08:45:12 volumio sudo[2193]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Jun 14 08:45:12 volumio sudo[2193]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 14 08:45:12 volumio sudo[2193]: pam_unix(sudo:session): session closed for user root
Jun 14 08:45:12 volumio sudo[2196]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Jun 14 08:45:12 volumio sudo[2196]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 14 08:45:12 volumio sudo[2196]: pam_unix(sudo:session): session closed for user root
Jun 14 08:45:15 volumio volumio[979]: info: BOOT COMPLETED
Jun 14 08:45:15 volumio volumio[979]: 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 14 08:45:16 volumio volumio[979]: verbose: New Socket.io Connection to 192.168.3.187 from 192.168.3.68 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/126.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5
Jun 14 08:45:16 volumio volumio[979]: info: CoreCommandRouter::volumioGetVisibleSources
Jun 14 08:45:16 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jun 14 08:45:16 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps
Jun 14 08:45:16 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode
Jun 14 08:45:16 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages
Jun 14 08:45:16 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones
Jun 14 08:45:16 volumio volumio[979]: info: Received Get System Info
Jun 14 08:45:16 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Jun 14 08:45:16 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Jun 14 08:45:16 volumio volumio[979]: info: Discovery: Getting this device information
Jun 14 08:45:16 volumio volumio[979]: info: CoreCommandRouter::volumioGetState
Jun 14 08:45:16 volumio volumio[979]: info: CorePlayQueue::getTrack 0
Jun 14 08:45:16 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jun 14 08:45:16 volumio volumio-remote-updater[727]: No test mode
Jun 14 08:45:16 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
Jun 14 08:45:16 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Jun 14 08:45:16 volumio volumio-remote-updater[727]: No alpha test mode
Jun 14 08:45:16 volumio volumio[979]: info: CoreCommandRouter::volumioGetVisibleSources
Jun 14 08:45:16 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jun 14 08:45:16 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps
Jun 14 08:45:16 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode
Jun 14 08:45:16 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages
Jun 14 08:45:16 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones
Jun 14 08:45:16 volumio volumio[979]: info: Received Get System Info
Jun 14 08:45:16 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Jun 14 08:45:16 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Jun 14 08:45:16 volumio volumio[979]: info: Discovery: Getting this device information
Jun 14 08:45:16 volumio volumio[979]: info: CoreCommandRouter::volumioGetState
Jun 14 08:45:16 volumio volumio[979]: info: CorePlayQueue::getTrack 0
Jun 14 08:45:16 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jun 14 08:45:16 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Jun 14 08:45:16 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
Jun 14 08:45:16 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Jun 14 08:45:16 volumio volumio[979]: info: CoreCommandRouter::getUIConfigOnPlugin
Jun 14 08:45:16 volumio volumio[979]: info: Retrieving Cloud Streaming UI
Jun 14 08:45:16 volumio volumio[979]: info: CoreCommandRouter::getUIConfigOnPlugin
Jun 14 08:45:16 volumio volumio[979]: info: Retrieving Cloud Streaming UI
Jun 14 08:45:16 volumio volumio[979]: info: Getting Tidal Cloud Configuration
Jun 14 08:45:16 volumio volumio[979]: info: CoreCommandRouter::getUIConfigOnPlugin
Jun 14 08:45:16 volumio volumio[979]: info: Getting Qobuz Cloud Configuration
Jun 14 08:45:16 volumio volumio[979]: info: Asking plugin for UI Config
Jun 14 08:45:16 volumio volumio[979]: info: CoreCommandRouter::getUIConfigOnPlugin
Jun 14 08:45:16 volumio volumio[979]: info: Getting Spotify Cloud Configuration
Jun 14 08:45:16 volumio volumio[979]: info: Asking plugin for UI Config
Jun 14 08:45:16 volumio volumio[979]: info: CoreCommandRouter::getUIConfigOnPlugin
Jun 14 08:45:16 volumio volumio[979]: info: Saving Spotify Acccount
Jun 14 08:45:16 volumio volumio[979]: info: Got Tidal Cloud Configuration
Jun 14 08:45:16 volumio volumio[979]: info: Got it
Jun 14 08:45:16 volumio volumio[979]: info: Got it
Jun 14 08:45:16 volumio volumio[979]: error: Could not retrieve Spotify Config from plugin Spotify: no section found
Jun 14 08:45:16 volumio volumio[979]: info: Getting Tidal Cloud Configuration
Jun 14 08:45:16 volumio volumio[979]: info: CoreCommandRouter::getUIConfigOnPlugin
Jun 14 08:45:16 volumio volumio[979]: info: Getting Qobuz Cloud Configuration
Jun 14 08:45:16 volumio volumio[979]: info: Asking plugin for UI Config
Jun 14 08:45:16 volumio volumio[979]: info: CoreCommandRouter::getUIConfigOnPlugin
Jun 14 08:45:16 volumio volumio[979]: info: Getting Spotify Cloud Configuration
Jun 14 08:45:16 volumio volumio[979]: info: Asking plugin for UI Config
Jun 14 08:45:16 volumio volumio[979]: info: CoreCommandRouter::getUIConfigOnPlugin
Jun 14 08:45:16 volumio volumio[979]: info: Saving Spotify Acccount
Jun 14 08:45:16 volumio volumio[979]: info: Got Tidal Cloud Configuration
Jun 14 08:45:16 volumio volumio[979]: info: Got it
Jun 14 08:45:16 volumio volumio[979]: info: Got it
Jun 14 08:45:16 volumio volumio[979]: error: Could not retrieve Spotify Config from plugin Spotify: no section found
Jun 14 08:45:16 volumio volumio[979]: info: CoreCommandRouter::getUIConfigOnPlugin
Jun 14 08:45:16 volumio volumio[979]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined
Jun 14 08:45:16 volumio volumio[979]: info: CoreCommandRouter::getUIConfigOnPlugin
Jun 14 08:45:16 volumio volumio[979]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined
Jun 14 08:45:16 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Jun 14 08:45:17 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage
Jun 14 08:45:17 volumio volumio[979]: info: Loading i18n strings for locale zh
Jun 14 08:45:19 volumio volumio-remote-updater[727]: No test mode
Jun 14 08:45:19 volumio volumio-remote-updater[727]: No alpha test mode
Jun 14 08:45:19 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone
Jun 14 08:45:19 volumio volumio[979]: info: Setting timezone to Asia/Shanghai
Jun 14 08:45:19 volumio sudo[2380]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime
Jun 14 08:45:19 volumio sudo[2380]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 14 08:45:19 volumio sudo[2380]: pam_unix(sudo:session): session closed for user root
Jun 14 08:45:19 volumio sudo[2383]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Asia/Shanghai /etc/localtime
Jun 14 08:45:19 volumio sudo[2383]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 14 08:45:19 volumio sudo[2383]: pam_unix(sudo:session): session closed for user root
Jun 14 08:45:19 volumio sudo[2386]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime
Jun 14 08:45:19 volumio sudo[2386]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 14 08:45:19 volumio sudo[2386]: pam_unix(sudo:session): session closed for user root
Jun 14 08:45:20 volumio sudo[2389]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Asia/Shanghai
Jun 14 08:45:20 volumio sudo[2389]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 14 08:45:20 volumio systemd-timedated[2170]: Failed to set time zone: Permission denied
Jun 14 08:45:20 volumio sudo[2389]: pam_unix(sudo:session): session closed for user root
Jun 14 08:45:20 volumio volumio[979]: Failed to set time zone: Access denied
Jun 14 08:45:20 volumio volumio[979]: info: Could not set timezone, retrying
Jun 14 08:45:22 volumio systemd[1]: systemd-hostnamed.service: Succeeded.
Jun 14 08:45:27 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage
Jun 14 08:45:27 volumio volumio[979]: info: Loading i18n strings for locale zh
Jun 14 08:45:27 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone
Jun 14 08:45:27 volumio volumio[979]: info: Setting timezone to Asia/Shanghai
Jun 14 08:45:28 volumio sudo[2455]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime
Jun 14 08:45:28 volumio sudo[2455]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 14 08:45:28 volumio sudo[2455]: pam_unix(sudo:session): session closed for user root
Jun 14 08:45:28 volumio sudo[2458]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Asia/Shanghai /etc/localtime
Jun 14 08:45:28 volumio sudo[2458]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 14 08:45:28 volumio sudo[2458]: pam_unix(sudo:session): session closed for user root
Jun 14 08:45:28 volumio sudo[2461]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime
Jun 14 08:45:28 volumio sudo[2461]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 14 08:45:28 volumio sudo[2461]: pam_unix(sudo:session): session closed for user root
Jun 14 08:45:28 volumio sudo[2464]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Asia/Shanghai
Jun 14 08:45:28 volumio sudo[2464]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 14 08:45:28 volumio sudo[2464]: pam_unix(sudo:session): session closed for user root
Jun 14 08:45:28 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache
Jun 14 08:45:28 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks
Jun 14 08:45:28 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage
Jun 14 08:45:28 volumio volumio[979]: info: Loading i18n strings for locale en
Jun 14 08:45:28 volumio sudo[2467]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
Jun 14 08:45:28 volumio sudo[2467]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 14 08:45:28 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone
Jun 14 08:45:28 volumio volumio[979]: info: Setting timezone to UTC
Jun 14 08:45:28 volumio sudo[2467]: pam_unix(sudo:session): session closed for user root
Jun 14 08:45:28 volumio sudo[2472]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime
Jun 14 08:45:28 volumio sudo[2472]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 14 08:45:28 volumio sudo[2472]: pam_unix(sudo:session): session closed for user root
Jun 14 08:45:28 volumio sudo[2475]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/UTC /etc/localtime
Jun 14 08:45:28 volumio sudo[2475]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 14 08:45:28 volumio sudo[2475]: pam_unix(sudo:session): session closed for user root
Jun 14 08:45:28 volumio sudo[2478]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime
Jun 14 08:45:28 volumio sudo[2478]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 14 08:45:28 volumio sudo[2478]: pam_unix(sudo:session): session closed for user root
Jun 14 08:45:28 volumio sudo[2481]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone UTC
Jun 14 08:45:28 volumio sudo[2481]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 14 08:45:28 volumio systemd-timedated[2170]: Failed to set time zone: Permission denied
Jun 14 08:45:28 volumio sudo[2481]: pam_unix(sudo:session): session closed for user root
Jun 14 08:45:28 volumio volumio[979]: Failed to set time zone: Access denied
Jun 14 08:45:28 volumio volumio[979]: info: Could not set timezone, retrying
Jun 14 08:45:28 volumio volumio[979]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"FIXES
- Various Fixes for QOBUZ Connect
- Various Fixes for Tidal Connect
","title":"Update v3.816","updateavailable":true}
Jun 14 08:45:28 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Jun 14 08:45:28 volumio volumio[979]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"FIXES
- Various Fixes for QOBUZ Connect
- Various Fixes for Tidal Connect
","title":"Update v3.816","updateavailable":true}
Jun 14 08:45:28 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Jun 14 08:45:28 volumio volumio[979]: info: Fetching Streaming Services browse cache
Jun 14 08:45:32 volumio sudo[2502]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Asia/Shanghai
Jun 14 08:45:32 volumio sudo[2502]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 14 08:45:32 volumio systemd-timedated[2170]: Failed to set time zone: Permission denied
Jun 14 08:45:32 volumio sudo[2502]: pam_unix(sudo:session): session closed for user root
Jun 14 08:45:32 volumio volumio[979]: Failed to set time zone: Access denied
Jun 14 08:45:32 volumio volumio[979]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Jun 14 08:45:32 volumio volumio[979]: Error: Command failed: /usr/bin/sudo /usr/bin/timedatectl set-timezone 'Asia/Shanghai'
Jun 14 08:45:32 volumio volumio[979]: Failed to set time zone: Access denied
Jun 14 08:45:32 volumio volumio[979]: at checkExecSyncError (child_process.js:790:11)
Jun 14 08:45:32 volumio volumio[979]: at execSync (child_process.js:863:15)
Jun 14 08:45:32 volumio volumio[979]: at Timeout._onTimeout (/volumio/app/plugins/system_controller/system/index.js:1575:9)
Jun 14 08:45:32 volumio volumio[979]: at listOnTimeout (internal/timers.js:557:17)
Jun 14 08:45:32 volumio volumio[979]: at processTimers (internal/timers.js:500:7) {
Jun 14 08:45:32 volumio volumio[979]: status: 1,
Jun 14 08:45:32 volumio volumio[979]: signal: null,
Jun 14 08:45:32 volumio volumio[979]: output: [ null, '', 'Failed to set time zone: Access denied\n' ],
Jun 14 08:45:32 volumio volumio[979]: pid: 2501,
Jun 14 08:45:32 volumio volumio[979]: stdout: '',
Jun 14 08:45:32 volumio volumio[979]: stderr: 'Failed to set time zone: Access denied\n'
Jun 14 08:45:32 volumio volumio[979]: }
Jun 14 08:45:32 volumio volumio[979]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Jun 14 08:45:32 volumio sudo[2513]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-14 08:44
Jun 14 08:45:32 volumio sudo[2513]: 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="570c5791513f5bac7da274aba6690c1a961705de"
VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6"
VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a"
VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d"
VOLUMIO_ARCH="x64"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:50:12 PM CEST"
VOLUMIO_VERSION="3.812"
VOLUMIO_HARDWARE="x86_amd64"
VOLUMIO_DEVICENAME="x86_64"
VOLUMIO_HASH="39b0f8c200b2dcadf117e189b4b3632d"