-- Logs begin at Sat 2025-03-15 14:28:20 CST, end at Sat 2025-03-15 14:30:57 CST. -- Mar 15 14:29:02 volumio ntpd[1220]: receive: Unexpected origin timestamp 0xeb7f9cab.854f3f74 does not match aorg 0000000000.00000000 from server@193.182.111.142 xmt 0xeb7f9cae.04896521 Mar 15 14:29:02 volumio ntpd[1220]: receive: Unexpected origin timestamp 0xeb7f9cab.854b9c03 does not match aorg 0000000000.00000000 from server@193.182.111.143 xmt 0xeb7f9cae.0472afac Mar 15 14:29:02 volumio ntpd[1220]: receive: Unexpected origin timestamp 0xeb7f9cab.854cfdb4 does not match aorg 0000000000.00000000 from server@81.16.177.123 xmt 0xeb7f9cae.0b76e695 Mar 15 14:29:03 volumio firststart.sh[911]: Enabling SSH for first boot Mar 15 14:29:03 volumio volumio-remote-updater[677]: [2025-03-15 06:29:03] [connect] Successful connection Mar 15 14:29:04 volumio systemd[1]: Starting OpenBSD Secure Shell server... Mar 15 14:29:04 volumio sshd[1434]: Server listening on 0.0.0.0 port 22. Mar 15 14:29:04 volumio sshd[1434]: Server listening on :: port 22. Mar 15 14:29:04 volumio systemd[1]: Started OpenBSD Secure Shell server. Mar 15 14:29:04 volumio firststart.sh[911]: Disabling firststart service Mar 15 14:29:04 volumio firststart.sh[911]: Removed /etc/systemd/system/multi-user.target.wants/firststart.service. Mar 15 14:29:04 volumio systemd[1]: Reloading. Mar 15 14:29:05 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. Mar 15 14:29:05 volumio firststart.sh[911]: Finalizing Mar 15 14:29:13 volumio volumio[909]: info: Loading plugin "alarm-clock"... Mar 15 14:29:18 volumio volumio[909]: info: Loading plugin "airplay_emulation"... Mar 15 14:29:18 volumio volumio-remote-updater[677]: [2025-03-15 06:29:18] [connect] Successful connection Mar 15 14:29:18 volumio volumio[909]: info: Starting Shairport Sync Mar 15 14:29:18 volumio volumio[909]: info: Loading plugin "last_100"... Mar 15 14:29:19 volumio volumio[909]: info: Loading plugin "webradio"... Mar 15 14:29:24 volumio systemd[1]: firststart.service: Succeeded. Mar 15 14:29:25 volumio systemd[1]: systemd-timedated.service: Succeeded. Mar 15 14:29:26 volumio smbd[1408]: [2025/03/15 06:29:26.253412, 0] ../lib/util/become_daemon.c:138(daemon_ready) Mar 15 14:29:26 volumio smbd[1408]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Mar 15 14:29:26 volumio systemd[1]: Started Samba SMB Daemon. Mar 15 14:29:26 volumio systemd[1]: Reached target Multi-User System. Mar 15 14:29:26 volumio systemd[1]: Reached target Graphical Interface. Mar 15 14:29:26 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Mar 15 14:29:26 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Mar 15 14:29:26 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Mar 15 14:29:26 volumio systemd[1]: Startup finished in 50.663s (kernel) + 1min 7.156s (userspace) = 1min 57.819s. Mar 15 14:29:28 volumio volumio[909]: info: Loading plugin "i2s_dacs"... Mar 15 14:29:28 volumio volumio[909]: info: I2S DAC not set, start Auto-detection Mar 15 14:29:28 volumio volumio[909]: info: Loading plugin "volumiodiscovery"... Mar 15 14:29:28 volumio volumio[909]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 15 14:29:28 volumio volumio[909]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 15 14:29:28 volumio volumio[909]: *** WARNING *** For more information see Mar 15 14:29:28 volumio volumio[909]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 15 14:29:28 volumio volumio[909]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 15 14:29:28 volumio node[909]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 15 14:29:28 volumio volumio[909]: *** WARNING *** For more information see Mar 15 14:29:28 volumio node[909]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 15 14:29:28 volumio node[909]: *** WARNING *** For more information see Mar 15 14:29:28 volumio node[909]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 15 14:29:28 volumio node[909]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 15 14:29:28 volumio node[909]: *** WARNING *** For more information see Mar 15 14:29:28 volumio volumio[909]: info: Discovery: Started advertising with name: Volumio Mar 15 14:29:28 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 15 14:29:28 volumio volumio[909]: info: Loading plugin "outputs"... Mar 15 14:29:28 volumio volumio[909]: info: Loading plugin "albumart"... Mar 15 14:29:28 volumio volumio[909]: info: Plugin example_plugin is not enabled Mar 15 14:29:28 volumio volumio[909]: info: Loading plugin "inputs"... Mar 15 14:29:28 volumio volumio[909]: info: Loading plugin "updater_comm"... Mar 15 14:29:28 volumio volumio[909]: Forking 3 albumart workers Mar 15 14:29:28 volumio volumio[909]: Starting albumart workers Mar 15 14:29:28 volumio volumio[909]: Starting albumart workers Mar 15 14:29:28 volumio volumio[909]: Starting albumart workers Mar 15 14:29:29 volumio volumio[909]: info: Plugin mpdemulation is not enabled Mar 15 14:29:29 volumio volumio[909]: info: Loading plugin "rest_api"... Mar 15 14:29:29 volumio volumio[909]: info: Loading plugin "websocket"... Mar 15 14:29:29 volumio volumio[909]: info: Starting Socket.io Server version 2.3.0 Mar 15 14:29:29 volumio volumio[909]: info: Loading i18n strings for locale en Mar 15 14:29:29 volumio volumio[909]: Updating browse sources language Mar 15 14:29:29 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 15 14:29:33 volumio volumio-remote-updater[677]: [2025-03-15 06:29:33] [connect] Successful connection Mar 15 14:29:33 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 15 14:29:33 volumio volumio[909]: info: CoreCommandRouter::initPlayerControls Mar 15 14:29:33 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 14:29:33 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 14:29:33 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 14:29:33 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 14:29:33 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 14:29:33 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 14:29:33 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 14:29:33 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 14:29:33 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 15 14:29:33 volumio volumio[909]: Express server listening on port 3000 Mar 15 14:29:33 volumio volumio[909]: [Metrics] WebUI: 46s 714.20ms Mar 15 14:29:33 volumio volumio[909]: info: Setting Device type: x86 Mar 15 14:29:33 volumio volumio[909]: info: CoreStateMachine::resetVolumioState Mar 15 14:29:33 volumio volumio[909]: info: CoreStateMachine::getcurrentVolume Mar 15 14:29:33 volumio volumio[909]: info: CoreCommandRouter::volumioRetrievevolume Mar 15 14:29:33 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 15 14:29:34 volumio volumio[909]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Mar 15 14:29:34 volumio volumio[909]: info: CoreStateMachine::getcurrentVolume Mar 15 14:29:34 volumio volumio[909]: info: CoreCommandRouter::volumioRetrievevolume Mar 15 14:29:34 volumio volumio[909]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Mar 15 14:29:34 volumio volumio[909]: info: Completed loading Core Plugins Mar 15 14:29:34 volumio volumio[909]: info: Preparing to generate the ALSA configuration file Mar 15 14:29:34 volumio sudo[1612]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 15 14:29:34 volumio sudo[1612]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 14:29:34 volumio volumio[909]: info: Cannot read play queue from file Mar 15 14:29:34 volumio volumio[909]: info: Volumio Network Manager: Network status updated: 1 Mar 15 14:29:34 volumio volumio[909]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Mar 15 14:29:34 volumio volumio[909]: wlan0 Interface doesn't support scanning. Mar 15 14:29:34 volumio volumio[909]: info: Cannot use regular scanning, forcing with ap-force Mar 15 14:29:34 volumio sudo[1610]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 15 14:29:34 volumio sudo[1610]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 14:29:34 volumio sudo[1610]: pam_unix(sudo:session): session closed for user root Mar 15 14:29:34 volumio sudo[1618]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Mar 15 14:29:34 volumio sudo[1618]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 14:29:34 volumio sudo[1612]: pam_unix(sudo:session): session closed for user root Mar 15 14:29:34 volumio sudo[1618]: pam_unix(sudo:session): session closed for user root Mar 15 14:29:34 volumio volumio[909]: command failed: No such device (-19) Mar 15 14:29:34 volumio volumio[909]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Mar 15 14:29:34 volumio volumio[909]: command failed: No such device (-19) Mar 15 14:29:34 volumio volumio[909]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Mar 15 14:29:34 volumio volumio-remote-updater[677]: [2025-03-15 06:29:34] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1742020173 101 Mar 15 14:29:34 volumio volumio[909]: 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: 1 Mar 15 14:29:34 volumio volumio[909]: info: VolumeController:: Volume=41 Mute =false Mar 15 14:29:34 volumio volumio[909]: info: CoreStateMachine::pushState Mar 15 14:29:34 volumio volumio[909]: info: CorePlayQueue::getTrack 0 Mar 15 14:29:34 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 15 14:29:34 volumio volumio[909]: info: CoreCommandRouter::volumioPushState Mar 15 14:29:34 volumio volumio[909]: info: CoreStateMachine::updateTrackBlock Mar 15 14:29:34 volumio volumio[909]: info: CorePlayQueue::getTrackBlock Mar 15 14:29:34 volumio volumio[909]: info: CoreCommandRouter::volumioRetrievevolume Mar 15 14:29:34 volumio volumio[909]: info: Asound.conf file written Mar 15 14:29:34 volumio sudo[1639]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Mar 15 14:29:34 volumio sudo[1639]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 14:29:34 volumio sudo[1639]: pam_unix(sudo:session): session closed for user root Mar 15 14:29:34 volumio volumio[909]: No state is present for card MID Mar 15 14:29:34 volumio volumio[909]: Found hardware: "HDA-Intel" "Realtek ALC662 rev1" "HDA:10ec0662,10ec0662,00100101 HDA:80862804,80860101,00100000" "0x8086" "0x3b56" Mar 15 14:29:34 volumio volumio[909]: Hardware is initialized using a generic method Mar 15 14:29:34 volumio volumio[909]: No state is present for card MID Mar 15 14:29:34 volumio volumio[909]: info: Output device has changed, restarting MPD Mar 15 14:29:34 volumio sudo[1659]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 15 14:29:34 volumio sudo[1659]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 14:29:34 volumio volumio[909]: info: Output device has changed, restarting Shairport Sync Mar 15 14:29:34 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 14:29:34 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 15 14:29:34 volumio sudo[1661]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 15 14:29:34 volumio sudo[1661]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 14:29:34 volumio systemd[1]: Stopping Music Player Daemon... Mar 15 14:29:34 volumio volumio[909]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 15 14:29:34 volumio volumio[909]: info: ___________ START PLUGINS ___________ Mar 15 14:29:34 volumio volumio[909]: info: Setting Hotspot Unique name on first start: Volumio-D071B Mar 15 14:29:34 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: network , getConfigParam Mar 15 14:29:34 volumio volumio[909]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getConfigParam] in plugin network Mar 15 14:29:34 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: network , saveHotspotSettings Mar 15 14:29:34 volumio volumio[909]: info: CoreCommandRouter::Reload Ui Mar 15 14:29:34 volumio volumio[909]: info: ControllerMpd::onStart: Initializing MPD Mar 15 14:29:34 volumio volumio[909]: info: Creating MPD Configuration file Mar 15 14:29:34 volumio sudo[1668]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 15 14:29:34 volumio sudo[1668]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 14:29:34 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 15 14:29:34 volumio volumio[909]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 15 14:29:34 volumio volumio[909]: info: [1742020174773] CoreMusicLibrary::Adding element Media Servers Mar 15 14:29:34 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 15 14:29:34 volumio sudo[1670]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 15 14:29:34 volumio sudo[1670]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 14:29:34 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 14:29:34 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 15 14:29:34 volumio volumio[909]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 15 14:29:34 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 14:29:34 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 15 14:29:34 volumio volumio[909]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 15 14:29:34 volumio volumio[909]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 15 14:29:34 volumio volumio[909]: info: [1742020174819] CoreMusicLibrary::Adding element Last_100 Mar 15 14:29:34 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 15 14:29:34 volumio volumio[909]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 15 14:29:34 volumio volumio[909]: info: [1742020174820] CoreMusicLibrary::Adding element Webradio Mar 15 14:29:34 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 15 14:29:34 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 15 14:29:34 volumio volumio[909]: info: Initializing BBC Radios Mar 15 14:29:34 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 15 14:29:34 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 15 14:29:34 volumio volumio[909]: info: Volumio Calling Home Mar 15 14:29:35 volumio volumio[909]: info: VolumeController:: Volume=41 Mute =false Mar 15 14:29:35 volumio volumio[909]: info: CoreStateMachine::pushState Mar 15 14:29:35 volumio volumio[909]: info: CorePlayQueue::getTrack 0 Mar 15 14:29:35 volumio volumio[909]: info: CoreCommandRouter::volumioPushState Mar 15 14:29:35 volumio volumio[909]: info: CoreStateMachine::updateTrackBlock Mar 15 14:29:35 volumio volumio[909]: info: CorePlayQueue::getTrackBlock Mar 15 14:29:35 volumio volumio[909]: info: CoreCommandRouter::volumioRetrievevolume Mar 15 14:29:35 volumio volumio[909]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Mar 15 14:29:35 volumio sudo[1694]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Mar 15 14:29:35 volumio sudo[1694]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 14:29:35 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 14:29:35 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 14:29:35 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 14:29:35 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 14:29:35 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 14:29:35 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 14:29:35 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 14:29:35 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 14:29:35 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 15 14:29:35 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Mar 15 14:29:35 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Mar 15 14:29:35 volumio sudo[1694]: pam_unix(sudo:session): session closed for user root Mar 15 14:29:35 volumio sudo[1659]: pam_unix(sudo:session): session closed for user root Mar 15 14:29:35 volumio sudo[1668]: pam_unix(sudo:session): session closed for user root Mar 15 14:29:35 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 14:29:35 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 14:29:35 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 15 14:29:35 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Mar 15 14:29:35 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 14:29:35 volumio systemd[1]: mpd.service: Succeeded. Mar 15 14:29:35 volumio systemd[1]: Stopped Music Player Daemon. Mar 15 14:29:35 volumio volumio[909]: info: Discovery: adding e77adc32-effb-4fb8-8267-7aa66f53c7c8 Mar 15 14:29:35 volumio volumio[909]: info: Discovery: Found device Volumio Mar 15 14:29:35 volumio volumio[909]: info: CoreCommandRouter::volumioGetState Mar 15 14:29:35 volumio volumio[909]: info: CorePlayQueue::getTrack 0 Mar 15 14:29:35 volumio volumio[909]: info: CorePlayQueue::getTrack 0 Mar 15 14:29:35 volumio volumio[909]: info: VolumeController:: Volume=41 Mute =false Mar 15 14:29:35 volumio volumio[909]: info: CoreStateMachine::pushState Mar 15 14:29:35 volumio volumio[909]: info: CorePlayQueue::getTrack 0 Mar 15 14:29:35 volumio volumio[909]: info: CoreCommandRouter::volumioPushState Mar 15 14:29:35 volumio volumio[909]: info: MPD Permissions set Mar 15 14:29:35 volumio volumio[909]: info: MPD Permissions set Mar 15 14:29:35 volumio volumio[909]: info: Upmpdcli Daemon Started Mar 15 14:29:35 volumio systemd[1]: Starting Music Player Daemon... Mar 15 14:29:36 volumio volumio[909]: info: VolumeController:: Volume=41 Mute =false Mar 15 14:29:36 volumio volumio[909]: info: CoreStateMachine::pushState Mar 15 14:29:36 volumio volumio[909]: info: CorePlayQueue::getTrack 0 Mar 15 14:29:36 volumio volumio[909]: info: CoreCommandRouter::volumioPushState Mar 15 14:29:36 volumio volumio[909]: info: Starting Shairport Sync Mar 15 14:29:36 volumio sudo[1713]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hostapd/hostapd.conf Mar 15 14:29:36 volumio sudo[1713]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 14:29:36 volumio sudo[1710]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 15 14:29:36 volumio sudo[1710]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 14:29:36 volumio sudo[1710]: pam_unix(sudo:session): session closed for user root Mar 15 14:29:36 volumio volumio[909]: info: Starting Shairport Sync Mar 15 14:29:36 volumio sudo[1717]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 15 14:29:36 volumio sudo[1717]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 14:29:36 volumio sudo[1720]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 15 14:29:36 volumio sudo[1720]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 14:29:36 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 15 14:29:36 volumio systemd[1]: shairport-sync.service: Succeeded. Mar 15 14:29:36 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 15 14:29:36 volumio volumio[909]: info: CoreCommandRouter::volumioGetState Mar 15 14:29:36 volumio volumio[909]: info: CorePlayQueue::getTrack 0 Mar 15 14:29:36 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 15 14:29:36 volumio sudo[1720]: pam_unix(sudo:session): session closed for user root Mar 15 14:29:36 volumio sudo[1717]: pam_unix(sudo:session): session closed for user root Mar 15 14:29:36 volumio volumio[909]: info: Starting Shairport Sync Mar 15 14:29:36 volumio sudo[1725]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 15 14:29:36 volumio sudo[1725]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 14:29:36 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 15 14:29:36 volumio systemd[1]: shairport-sync.service: Succeeded. Mar 15 14:29:36 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 15 14:29:36 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 15 14:29:36 volumio sudo[1725]: pam_unix(sudo:session): session closed for user root Mar 15 14:29:36 volumio volumio[909]: info: Shairport-Sync Started Mar 15 14:29:36 volumio volumio[909]: Error adding Membership: Error: addMembership EINVAL Mar 15 14:29:36 volumio volumio[909]: info: Shairport-Sync Started Mar 15 14:29:36 volumio volumio[909]: info: Shairport-Sync Started Mar 15 14:29:36 volumio mpd[1718]: Mar 15 06:29 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 15 14:29:36 volumio systemd[1]: Started Music Player Daemon. Mar 15 14:29:36 volumio sudo[1661]: pam_unix(sudo:session): session closed for user root Mar 15 14:29:36 volumio sudo[1670]: pam_unix(sudo:session): session closed for user root Mar 15 14:29:36 volumio volumio[909]: info: Completed starting Core Plugins Mar 15 14:29:36 volumio volumio[909]: info: ------------------------------------------- Mar 15 14:29:36 volumio volumio[909]: info: ----- MyVolumio plugins startup ---- Mar 15 14:29:36 volumio volumio[909]: info: ------------------------------------------- Mar 15 14:29:36 volumio volumio[909]: info: [MyVolumio PluginManager] Fetching plans data.... Mar 15 14:29:36 volumio sudo[1713]: pam_unix(sudo:session): session closed for user root Mar 15 14:29:36 volumio volumio[909]: error: MPD error: The expression evaluated to a falsy value: Mar 15 14:29:36 volumio volumio[909]: assert.ok(self.idling) Mar 15 14:29:36 volumio volumio[909]: error: The expression evaluated to a falsy value: Mar 15 14:29:36 volumio volumio[909]: assert.ok(self.idling) Mar 15 14:29:36 volumio volumio[909]: error: Cannot initialize MPD Connection: MPD is not running Mar 15 14:29:36 volumio volumio[909]: info: Permissions for /etc/hostapd/hostapd.conf Mar 15 14:29:36 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Mar 15 14:29:36 volumio volumio[909]: info: Discovery: Restarting Advertising due to device name change Mar 15 14:29:36 volumio sudo[1749]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Mar 15 14:29:36 volumio sudo[1749]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 14:29:36 volumio volumio[909]: error: updateQueue error: null Mar 15 14:29:36 volumio volumio[1696]: Generating RSA private key, 4096 bit long modulus (2 primes) Mar 15 14:29:36 volumio systemd[1]: Starting Wireless Services... Mar 15 14:29:36 volumio volumio[909]: info: Volumio called home Mar 15 14:29:37 volumio wireless.js[1754]: WIRELESS: No wireless interface, exiting Mar 15 14:29:37 volumio iw[1766]: command failed: No such device (-19) Mar 15 14:29:37 volumio iwconfig[1769]: Error for wireless request "Set Power Management" (8B2C) : Mar 15 14:29:37 volumio iwconfig[1769]: SET failed on device wlan0 ; No such device. Mar 15 14:29:37 volumio systemd[1]: wireless.service: Succeeded. Mar 15 14:29:37 volumio systemd[1]: Started Wireless Services. Mar 15 14:29:37 volumio sudo[1749]: pam_unix(sudo:session): session closed for user root Mar 15 14:29:38 volumio volumio[1696]: ..............................................................................................++++ Mar 15 14:29:38 volumio volumio[909]: info: Discovery: A device disappeared from network Mar 15 14:29:38 volumio volumio[909]: info: Discovery: Device volumio disappeared from network Mar 15 14:29:38 volumio volumio[1696]: ...............................................++++ Mar 15 14:29:38 volumio volumio[1696]: e is 65537 (0x010001) Mar 15 14:29:38 volumio volumio[1696]: writing RSA key Mar 15 14:29:41 volumio volumio[909]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Mar 15 14:29:41 volumio volumio[909]: info: Discovery: Started advertising with name: Volumio Mar 15 14:29:42 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: wizard , reportWirelessConnection Mar 15 14:29:42 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessInfo Mar 15 14:29:42 volumio sudo[1789]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 15 14:29:42 volumio sudo[1789]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 14:29:42 volumio sudo[1789]: pam_unix(sudo:session): session closed for user root Mar 15 14:29:42 volumio volumio[909]: info: Discovery: adding e77adc32-effb-4fb8-8267-7aa66f53c7c8 Mar 15 14:29:42 volumio volumio[909]: info: Discovery: Found device Volumio Mar 15 14:29:42 volumio volumio[909]: info: CoreCommandRouter::volumioGetState Mar 15 14:29:42 volumio volumio[909]: info: CorePlayQueue::getTrack 0 Mar 15 14:29:46 volumio volumio[909]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Mar 15 14:29:46 volumio volumio[909]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Mar 15 14:29:46 volumio volumio[909]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Mar 15 14:29:46 volumio volumio[909]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Mar 15 14:29:46 volumio volumio[909]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Mar 15 14:29:46 volumio volumio[909]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Mar 15 14:29:46 volumio volumio[909]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Mar 15 14:29:46 volumio volumio[909]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Mar 15 14:29:46 volumio volumio[909]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Mar 15 14:29:46 volumio volumio[909]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Mar 15 14:29:46 volumio volumio[909]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Mar 15 14:29:46 volumio volumio[909]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Mar 15 14:29:46 volumio volumio[909]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Mar 15 14:29:46 volumio volumio[909]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Mar 15 14:29:46 volumio volumio[909]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Mar 15 14:29:46 volumio volumio[909]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Mar 15 14:29:46 volumio volumio[909]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Mar 15 14:29:46 volumio volumio[909]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Mar 15 14:29:46 volumio volumio[909]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Mar 15 14:29:46 volumio volumio[909]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Mar 15 14:29:46 volumio volumio[909]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Mar 15 14:29:46 volumio volumio[909]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Mar 15 14:29:46 volumio volumio[909]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Mar 15 14:29:46 volumio volumio[909]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Mar 15 14:29:46 volumio volumio[909]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Mar 15 14:29:46 volumio volumio[909]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Mar 15 14:29:46 volumio volumio[909]: info: Adding plugin bluetooth to MyMusic Plugins Mar 15 14:29:46 volumio volumio[909]: info: Adding plugin multiroom to MyMusic Plugins Mar 15 14:29:46 volumio volumio[909]: info: Adding plugin metavolumio to MyMusic Plugins Mar 15 14:29:46 volumio volumio[909]: info: Adding plugin cd_controller to MyMusic Plugins Mar 15 14:29:46 volumio volumio[909]: info: Adding plugin smart_inputs to MyMusic Plugins Mar 15 14:29:46 volumio volumio[909]: info: Adding plugin tidalconnect to MyMusic Plugins Mar 15 14:29:46 volumio volumio[909]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Mar 15 14:29:46 volumio dbus-daemon[678]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by ':1.16' (uid=0 pid=1415 comm="/usr/lib/chromium/chromium --show-component-extens") Mar 15 14:29:46 volumio systemd[1]: Starting Bluetooth service... Mar 15 14:29:46 volumio kernel: cryptd: max_cpu_qlen set to 1000 Mar 15 14:29:53 volumio bluetoothd[1820]: Bluetooth daemon 5.50 Mar 15 14:29:53 volumio kernel: Bluetooth: Core ver 2.22 Mar 15 14:29:53 volumio kernel: NET: Registered PF_BLUETOOTH protocol family Mar 15 14:29:53 volumio kernel: Bluetooth: HCI device and connection manager initialized Mar 15 14:29:53 volumio kernel: Bluetooth: HCI socket layer initialized Mar 15 14:29:53 volumio kernel: Bluetooth: L2CAP socket layer initialized Mar 15 14:29:53 volumio kernel: Bluetooth: SCO socket layer initialized Mar 15 14:29:56 volumio bluetoothd[1820]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Mar 15 14:29:56 volumio dbus-daemon[678]: [system] Successfully activated service 'org.bluez' Mar 15 14:29:56 volumio systemd[1]: Started Bluetooth service. Mar 15 14:29:58 volumio bluetoothd[1820]: Starting SDP server Mar 15 14:29:58 volumio bluetoothd[1820]: Excluding (cli) sap Mar 15 14:30:00 volumio kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Mar 15 14:30:00 volumio kernel: Bluetooth: BNEP filters: protocol multicast Mar 15 14:30:00 volumio kernel: Bluetooth: BNEP socket layer initialized Mar 15 14:30:00 volumio bluetoothd[1820]: Bluetooth management interface 1.22 initialized Mar 15 14:30:00 volumio dbus-daemon[678]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.17' (uid=0 pid=1820 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Mar 15 14:30:04 volumio volumio-remote-updater[677]: [2025-03-15 06:30:04] [disconnect] Disconnect close local:[1008,Pong timeout] remote:[1006] Mar 15 14:30:06 volumio systemd[1]: Starting Hostname Service... Mar 15 14:30:08 volumio dbus-daemon[678]: [system] Successfully activated service 'org.freedesktop.hostname1' Mar 15 14:30:08 volumio systemd[1]: Started Hostname Service. Mar 15 14:30:09 volumio volumio-remote-updater[677]: [2025-03-15 06:30:09] [connect] Successful connection Mar 15 14:30:22 volumio volumio-remote-updater[677]: [2025-03-15 06:30:22] [connect] Successful connection Mar 15 14:30:37 volumio volumio-remote-updater[677]: [2025-03-15 06:30:37] [connect] Successful connection Mar 15 14:30:38 volumio systemd[1]: systemd-hostnamed.service: Succeeded. Mar 15 14:30:43 volumio volumio[909]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Mar 15 14:30:43 volumio volumio[909]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Mar 15 14:30:43 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 15 14:30:43 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 15 14:30:43 volumio volumio[909]: info: Starting MyVolumio Remote Streaming Endpoints Mar 15 14:30:43 volumio volumio[909]: info: MyVolumio not started Mar 15 14:30:43 volumio volumio[909]: info: Initializing device activation check Mar 15 14:30:43 volumio volumio[909]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Mar 15 14:30:43 volumio volumio[909]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Mar 15 14:30:45 volumio volumio[909]: info: Starting Streaming Service Transparent Proxy Mar 15 14:30:45 volumio volumio[909]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Mar 15 14:30:45 volumio volumio[909]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Mar 15 14:30:45 volumio volumio[909]: info: Streaming services startup Mar 15 14:30:45 volumio volumio[909]: info: Starting Streaming Daemon Mar 15 14:30:45 volumio sudo[2107]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 15 14:30:45 volumio sudo[2107]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 14:30:45 volumio sudo[2107]: pam_unix(sudo:session): session closed for user root Mar 15 14:30:45 volumio volumio[909]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Mar 15 14:30:45 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart Mar 15 14:30:45 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart Mar 15 14:30:45 volumio volumio[909]: info: Refreshing Cached IP Addresses Mar 15 14:30:45 volumio sudo[2115]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Mar 15 14:30:45 volumio sudo[2115]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 14:30:45 volumio sudo[2117]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 15 14:30:45 volumio sudo[2117]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 14:30:45 volumio sudo[2115]: pam_unix(sudo:session): session closed for user root Mar 15 14:30:45 volumio sudo[2117]: pam_unix(sudo:session): session closed for user root Mar 15 14:30:45 volumio sudo[2121]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 15 14:30:45 volumio sudo[2121]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 14:30:45 volumio sudo[2121]: pam_unix(sudo:session): session closed for user root Mar 15 14:30:45 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 15 14:30:45 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 15 14:30:45 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 15 14:30:45 volumio volumio[909]: STREAMING PROXY: Starting server on port 3245 Mar 15 14:30:45 volumio volumio[909]: Node JS runtime: 14 Mar 15 14:30:45 volumio volumio[909]: error: Cannot start Volumio Streaming Daemon Mar 15 14:30:45 volumio volumio[909]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Mar 15 14:30:45 volumio volumio[909]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Mar 15 14:30:45 volumio systemd[1]: upmpdcli.service: Succeeded. Mar 15 14:30:46 volumio volumio[909]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Mar 15 14:30:50 volumio sudo[2130]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 15 14:30:50 volumio sudo[2130]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 14:30:50 volumio sudo[2130]: pam_unix(sudo:session): session closed for user root Mar 15 14:30:50 volumio sudo[2132]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 15 14:30:50 volumio sudo[2132]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 14:30:50 volumio sudo[2132]: pam_unix(sudo:session): session closed for user root Mar 15 14:30:50 volumio volumio[909]: verbose: New Socket.io Connection to 192.168.50.5 from 192.168.50.4 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/122.0.6261.95 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Mar 15 14:30:50 volumio volumio[909]: info: CoreCommandRouter::volumioGetVisibleSources Mar 15 14:30:50 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 15 14:30:50 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Mar 15 14:30:51 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Mar 15 14:30:51 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Mar 15 14:30:51 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Mar 15 14:30:51 volumio volumio[909]: info: Received Get System Info Mar 15 14:30:51 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 15 14:30:51 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 15 14:30:51 volumio volumio[909]: info: Discovery: Getting this device information Mar 15 14:30:51 volumio volumio[909]: info: CoreCommandRouter::volumioGetState Mar 15 14:30:51 volumio volumio[909]: info: CorePlayQueue::getTrack 0 Mar 15 14:30:51 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 15 14:30:51 volumio volumio[909]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 15 14:30:51 volumio volumio[909]: info: Retrieving Cloud Streaming UI Mar 15 14:30:51 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 15 14:30:51 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 15 14:30:52 volumio volumio-remote-updater[677]: [2025-03-15 06:30:52] [connect] Successful connection Mar 15 14:30:52 volumio volumio-remote-updater[677]: [2025-03-15 06:30:52] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1742020252 101 Mar 15 14:30:52 volumio volumio[909]: 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: 3 Mar 15 14:30:52 volumio volumio[909]: info: Getting Tidal Cloud Configuration Mar 15 14:30:52 volumio volumio[909]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 15 14:30:52 volumio volumio[909]: info: Getting Qobuz Cloud Configuration Mar 15 14:30:52 volumio volumio[909]: info: Asking plugin for UI Config Mar 15 14:30:52 volumio volumio[909]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 15 14:30:52 volumio volumio[909]: info: Getting Spotify Cloud Configuration Mar 15 14:30:52 volumio volumio[909]: info: Asking plugin for UI Config Mar 15 14:30:52 volumio volumio[909]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 15 14:30:52 volumio volumio[909]: info: Saving Spotify Acccount Mar 15 14:30:52 volumio volumio[909]: info: Got Tidal Cloud Configuration Mar 15 14:30:52 volumio volumio[909]: info: Got it Mar 15 14:30:52 volumio volumio[909]: info: Got it Mar 15 14:30:52 volumio volumio[909]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Mar 15 14:30:52 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 15 14:30:52 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Mar 15 14:30:52 volumio volumio[909]: info: Loading i18n strings for locale zh Mar 15 14:30:54 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Mar 15 14:30:54 volumio volumio[909]: info: Setting timezone to Asia/Shanghai Mar 15 14:30:54 volumio sudo[2141]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Mar 15 14:30:54 volumio sudo[2141]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 14:30:54 volumio sudo[2141]: pam_unix(sudo:session): session closed for user root Mar 15 14:30:54 volumio sudo[2144]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Asia/Shanghai /etc/localtime Mar 15 14:30:54 volumio sudo[2144]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 14:30:54 volumio sudo[2144]: pam_unix(sudo:session): session closed for user root Mar 15 14:30:54 volumio sudo[2147]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Mar 15 14:30:54 volumio sudo[2147]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 14:30:54 volumio sudo[2147]: pam_unix(sudo:session): session closed for user root Mar 15 14:30:54 volumio sudo[2150]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Asia/Shanghai Mar 15 14:30:54 volumio sudo[2150]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 14:30:54 volumio dbus-daemon[678]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.20' (uid=0 pid=2151 comm="/usr/bin/timedatectl set-timezone Asia/Shanghai ") Mar 15 14:30:54 volumio systemd[1]: Starting Time & Date Service... Mar 15 14:30:54 volumio dbus-daemon[678]: [system] Successfully activated service 'org.freedesktop.timedate1' Mar 15 14:30:54 volumio systemd[1]: Started Time & Date Service. Mar 15 14:30:54 volumio sudo[2150]: pam_unix(sudo:session): session closed for user root Mar 15 14:30:54 volumio volumio[909]: info: Fetching Streaming Services browse cache Mar 15 14:30:54 volumio volumio[909]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 15 14:30:54 volumio volumio[909]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Mar 15 14:30:54 volumio volumio[909]: Updating browse sources language Mar 15 14:30:54 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 15 14:30:54 volumio volumio[909]: 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 Mar 15 14:30:55 volumio volumio[909]: info: CoreCommandRouter::volumioGetVisibleSources Mar 15 14:30:55 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 15 14:30:55 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Mar 15 14:30:55 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Mar 15 14:30:55 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Mar 15 14:30:55 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Mar 15 14:30:55 volumio volumio[909]: info: Received Get System Info Mar 15 14:30:55 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 15 14:30:55 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 15 14:30:55 volumio volumio[909]: info: Discovery: Getting this device information Mar 15 14:30:55 volumio volumio[909]: info: CoreCommandRouter::volumioGetState Mar 15 14:30:55 volumio volumio[909]: info: CorePlayQueue::getTrack 0 Mar 15 14:30:55 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 15 14:30:55 volumio volumio-remote-updater[677]: No test mode Mar 15 14:30:55 volumio volumio-remote-updater[677]: No alpha test mode Mar 15 14:30:55 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 15 14:30:55 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 15 14:30:55 volumio volumio[909]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 15 14:30:55 volumio volumio[909]: info: Retrieving Cloud Streaming UI Mar 15 14:30:55 volumio volumio[909]: info: Getting Tidal Cloud Configuration Mar 15 14:30:55 volumio volumio[909]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 15 14:30:55 volumio volumio[909]: info: Getting Qobuz Cloud Configuration Mar 15 14:30:55 volumio volumio[909]: info: Asking plugin for UI Config Mar 15 14:30:55 volumio volumio[909]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 15 14:30:55 volumio volumio[909]: info: Getting Spotify Cloud Configuration Mar 15 14:30:55 volumio volumio[909]: info: Asking plugin for UI Config Mar 15 14:30:55 volumio volumio[909]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 15 14:30:55 volumio volumio[909]: info: Saving Spotify Acccount Mar 15 14:30:55 volumio volumio[909]: info: Got Tidal Cloud Configuration Mar 15 14:30:55 volumio volumio[909]: info: Got it Mar 15 14:30:55 volumio volumio[909]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Mar 15 14:30:55 volumio volumio[909]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 15 14:30:55 volumio volumio[909]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Mar 15 14:30:55 volumio sudo[2173]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 15 14:30:55 volumio sudo[2173]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 14:30:55 volumio sudo[2173]: pam_unix(sudo:session): session closed for user root Mar 15 14:30:55 volumio sudo[2175]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 15 14:30:55 volumio sudo[2175]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 14:30:55 volumio sudo[2175]: pam_unix(sudo:session): session closed for user root Mar 15 14:30:55 volumio sudo[2180]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Mar 15 14:30:55 volumio sudo[2180]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 14:30:56 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Mar 15 14:30:56 volumio sudo[2180]: pam_unix(sudo:session): session closed for user root Mar 15 14:30:56 volumio volumio[909]: info: Upmpdcli Daemon Started Mar 15 14:30:56 volumio volumio[2183]: writing RSA key Mar 15 14:30:56 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 15 14:30:56 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Mar 15 14:30:56 volumio volumio[909]: info: Loading i18n strings for locale en Mar 15 14:30:56 volumio volumio[909]: Updating browse sources language Mar 15 14:30:56 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 15 14:30:56 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Mar 15 14:30:56 volumio volumio[909]: info: Setting timezone to UTC Mar 15 14:30:56 volumio sudo[2214]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Mar 15 14:30:56 volumio sudo[2214]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 14:30:56 volumio sudo[2214]: pam_unix(sudo:session): session closed for user root Mar 15 14:30:56 volumio sudo[2217]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/UTC /etc/localtime Mar 15 14:30:56 volumio sudo[2217]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 14:30:56 volumio sudo[2217]: pam_unix(sudo:session): session closed for user root Mar 15 14:30:56 volumio sudo[2220]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Mar 15 14:30:56 volumio sudo[2220]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 14:30:56 volumio sudo[2220]: pam_unix(sudo:session): session closed for user root Mar 15 14:30:56 volumio sudo[2223]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone UTC Mar 15 14:30:56 volumio sudo[2223]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 14:30:56 volumio systemd-timedated[2152]: Failed to set time zone: Permission denied Mar 15 14:30:56 volumio sudo[2223]: pam_unix(sudo:session): session closed for user root Mar 15 14:30:56 volumio volumio[909]: Failed to set time zone: Access denied Mar 15 14:30:56 volumio volumio[909]: info: Could not set timezone, retrying Mar 15 14:30:56 volumio volumio[909]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Mar 15 14:30:56 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Mar 15 14:30:56 volumio volumio[909]: info: Fetching Streaming Services browse cache Mar 15 14:30:57 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Mar 15 14:30:57 volumio volumio[909]: info: Loading i18n strings for locale zh Mar 15 14:30:57 volumio volumio[909]: Updating browse sources language Mar 15 14:30:57 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 15 14:30:57 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Mar 15 14:30:57 volumio volumio[909]: info: Setting timezone to Asia/Shanghai Mar 15 14:30:57 volumio sudo[2226]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Mar 15 14:30:57 volumio sudo[2226]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 14:30:57 volumio sudo[2226]: pam_unix(sudo:session): session closed for user root Mar 15 14:30:57 volumio sudo[2229]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Asia/Shanghai /etc/localtime Mar 15 14:30:57 volumio sudo[2229]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 14:30:57 volumio sudo[2229]: pam_unix(sudo:session): session closed for user root Mar 15 14:30:57 volumio sudo[2232]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Mar 15 14:30:57 volumio sudo[2232]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 14:30:57 volumio sudo[2232]: pam_unix(sudo:session): session closed for user root Mar 15 14:30:57 volumio sudo[2235]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Asia/Shanghai Mar 15 14:30:57 volumio sudo[2235]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 14:30:57 volumio systemd-timedated[2152]: Failed to set time zone: Permission denied Mar 15 14:30:57 volumio sudo[2235]: pam_unix(sudo:session): session closed for user root Mar 15 14:30:57 volumio volumio[909]: Failed to set time zone: Access denied Mar 15 14:30:57 volumio volumio[909]: info: Could not set timezone, retrying Mar 15 14:30:57 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Mar 15 14:30:57 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Mar 15 14:30:57 volumio sudo[2238]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Mar 15 14:30:57 volumio sudo[2238]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 14:30:57 volumio sudo[2238]: pam_unix(sudo:session): session closed for user root Mar 15 14:30:57 volumio volumio[909]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Mar 15 14:30:57 volumio volumio[909]: wlan0 Interface doesn't support scanning. Mar 15 14:30:57 volumio volumio[909]: info: Cannot use regular scanning, forcing with ap-force Mar 15 14:30:57 volumio sudo[2243]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Mar 15 14:30:57 volumio sudo[2243]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 14:30:57 volumio sudo[2243]: pam_unix(sudo:session): session closed for user root Mar 15 14:30:57 volumio volumio[909]: command failed: No such device (-19) Mar 15 14:30:57 volumio volumio[909]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Mar 15 14:30:57 volumio volumio[909]: command failed: No such device (-19) Mar 15 14:30:57 volumio volumio[909]: info: Fetching Streaming Services browse cache Mar 15 14:30:57 volumio sudo[2248]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone UTC Mar 15 14:30:57 volumio sudo[2248]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 14:30:57 volumio systemd-timedated[2152]: Failed to set time zone: Permission denied Mar 15 14:30:57 volumio sudo[2248]: pam_unix(sudo:session): session closed for user root Mar 15 14:30:57 volumio volumio[909]: Failed to set time zone: Access denied Mar 15 14:30:57 volumio volumio[909]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 15 14:30:57 volumio volumio[909]: Error: Command failed: /usr/bin/sudo /usr/bin/timedatectl set-timezone 'UTC' Mar 15 14:30:57 volumio volumio[909]: Failed to set time zone: Access denied Mar 15 14:30:57 volumio volumio[909]: at checkExecSyncError (child_process.js:790:11) Mar 15 14:30:57 volumio volumio[909]: at execSync (child_process.js:863:15) Mar 15 14:30:57 volumio volumio[909]: at Timeout._onTimeout (/volumio/app/plugins/system_controller/system/index.js:1575:9) Mar 15 14:30:57 volumio volumio[909]: at listOnTimeout (internal/timers.js:557:17) Mar 15 14:30:57 volumio volumio[909]: at processTimers (internal/timers.js:500:7) { Mar 15 14:30:57 volumio volumio[909]: status: 1, Mar 15 14:30:57 volumio volumio[909]: signal: null, Mar 15 14:30:57 volumio volumio[909]: output: [ null, '', 'Failed to set time zone: Access denied\n' ], Mar 15 14:30:57 volumio volumio[909]: pid: 2247, Mar 15 14:30:57 volumio volumio[909]: stdout: '', Mar 15 14:30:57 volumio volumio[909]: stderr: 'Failed to set time zone: Access denied\n' Mar 15 14:30:57 volumio volumio[909]: } Mar 15 14:30:57 volumio volumio[909]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 15 14:30:57 volumio sudo[2259]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-15 14:29 Mar 15 14:30:57 volumio sudo[2259]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="a01a833dd28483f88cb390596f5090b7187992af" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 27 Feb 2025 12:15:38 PM CET" VOLUMIO_VERSION="3.795" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="9e0e048b157c8fb0f19240808f3da58b"