-- Logs begin at Sat 2025-04-19 07:51:48 PDT, end at Sat 2025-04-19 07:54:50 PDT. -- Apr 19 07:53:01 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 07:53:01 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 07:53:01 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 19 07:53:01 volumio volumio[889]: Express server listening on port 3000 Apr 19 07:53:01 volumio volumio[889]: [Metrics] WebUI: 45s 457.46ms Apr 19 07:53:01 volumio volumio[889]: info: Setting Device type: x86 Apr 19 07:53:01 volumio volumio[889]: info: CoreStateMachine::resetVolumioState Apr 19 07:53:01 volumio volumio[889]: info: CoreStateMachine::getcurrentVolume Apr 19 07:53:01 volumio volumio[889]: info: CoreCommandRouter::volumioRetrievevolume Apr 19 07:53:01 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 07:53:01 volumio volumio[889]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Apr 19 07:53:01 volumio volumio[889]: info: CoreStateMachine::getcurrentVolume Apr 19 07:53:01 volumio volumio[889]: info: CoreCommandRouter::volumioRetrievevolume Apr 19 07:53:01 volumio sudo[1548]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 19 07:53:01 volumio volumio[889]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Apr 19 07:53:01 volumio volumio[889]: info: Completed loading Core Plugins Apr 19 07:53:01 volumio sudo[1548]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 07:53:01 volumio volumio[889]: info: Preparing to generate the ALSA configuration file Apr 19 07:53:01 volumio sudo[1548]: pam_unix(sudo:session): session closed for user root Apr 19 07:53:01 volumio sudo[1550]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 19 07:53:01 volumio sudo[1550]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 07:53:01 volumio volumio[889]: info: Cannot read play queue from file Apr 19 07:53:01 volumio volumio[889]: info: Volumio Network Manager: Network status updated: 1 Apr 19 07:53:01 volumio volumio[889]: Unable to parse: Apr 19 07:53:01 volumio volumio[889]: Simple mixer control 'IEC958',0 Apr 19 07:53:01 volumio volumio[889]: Capabilities: pswitch pswitch-joined Apr 19 07:53:01 volumio volumio[889]: Playback channels: Mono Apr 19 07:53:01 volumio volumio[889]: Mono: Playback [off] Apr 19 07:53:01 volumio volumio[889]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Apr 19 07:53:01 volumio volumio[889]: Unable to parse: Apr 19 07:53:01 volumio volumio[889]: Simple mixer control 'IEC958',0 Apr 19 07:53:01 volumio volumio[889]: Capabilities: pswitch pswitch-joined Apr 19 07:53:01 volumio volumio[889]: Playback channels: Mono Apr 19 07:53:01 volumio volumio[889]: Mono: Playback [off] Apr 19 07:53:01 volumio volumio[889]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Apr 19 07:53:01 volumio volumio[889]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Apr 19 07:53:01 volumio volumio[889]: wlan0 Interface doesn't support scanning. Apr 19 07:53:01 volumio volumio[889]: info: Cannot use regular scanning, forcing with ap-force Apr 19 07:53:01 volumio sudo[1557]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Apr 19 07:53:01 volumio sudo[1557]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 07:53:02 volumio sudo[1550]: pam_unix(sudo:session): session closed for user root Apr 19 07:53:02 volumio sudo[1557]: pam_unix(sudo:session): session closed for user root Apr 19 07:53:02 volumio volumio[889]: command failed: No such device (-19) Apr 19 07:53:02 volumio volumio[889]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Apr 19 07:53:02 volumio volumio[889]: command failed: No such device (-19) Apr 19 07:53:02 volumio volumio[889]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Apr 19 07:53:02 volumio volumio[889]: Unable to parse: Apr 19 07:53:02 volumio volumio[889]: Simple mixer control 'IEC958',0 Apr 19 07:53:02 volumio volumio[889]: Capabilities: pswitch pswitch-joined Apr 19 07:53:02 volumio volumio[889]: Playback channels: Mono Apr 19 07:53:02 volumio volumio[889]: Mono: Playback [off] Apr 19 07:53:02 volumio volumio[889]: info: VolumeController:: Volume=undefined Mute =false Apr 19 07:53:02 volumio volumio[889]: info: CoreStateMachine::pushState Apr 19 07:53:02 volumio volumio[889]: info: CorePlayQueue::getTrack 0 Apr 19 07:53:02 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 07:53:02 volumio volumio[889]: info: CoreCommandRouter::volumioPushState Apr 19 07:53:02 volumio volumio[889]: info: CoreStateMachine::updateTrackBlock Apr 19 07:53:02 volumio volumio[889]: info: CorePlayQueue::getTrackBlock Apr 19 07:53:02 volumio volumio[889]: info: CoreCommandRouter::volumioRetrievevolume Apr 19 07:53:02 volumio volumio[889]: Unable to parse: Apr 19 07:53:02 volumio volumio[889]: Simple mixer control 'IEC958',0 Apr 19 07:53:02 volumio volumio[889]: Capabilities: pswitch pswitch-joined Apr 19 07:53:02 volumio volumio[889]: Playback channels: Mono Apr 19 07:53:02 volumio volumio[889]: Mono: Playback [off] Apr 19 07:53:02 volumio volumio[889]: info: VolumeController:: Volume=undefined Mute =false Apr 19 07:53:02 volumio volumio[889]: info: CoreStateMachine::pushState Apr 19 07:53:02 volumio volumio[889]: info: CorePlayQueue::getTrack 0 Apr 19 07:53:02 volumio volumio[889]: info: CoreCommandRouter::volumioPushState Apr 19 07:53:02 volumio volumio[889]: info: CoreStateMachine::updateTrackBlock Apr 19 07:53:02 volumio volumio[889]: info: CorePlayQueue::getTrackBlock Apr 19 07:53:02 volumio volumio[889]: info: CoreCommandRouter::volumioRetrievevolume Apr 19 07:53:03 volumio volumio[889]: info: Asound.conf file written Apr 19 07:53:03 volumio sudo[1570]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Apr 19 07:53:03 volumio sudo[1570]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 07:53:03 volumio sudo[1570]: pam_unix(sudo:session): session closed for user root Apr 19 07:53:03 volumio volumio[889]: No state is present for card Generic Apr 19 07:53:03 volumio volumio[889]: Found hardware: "HDA-Intel" "ATI R6xx HDMI" "HDA:1002aa01,00aa0100,00100200" "0x1002" "0x1314" Apr 19 07:53:03 volumio volumio[889]: Hardware is initialized using a generic method Apr 19 07:53:03 volumio volumio[889]: No state is present for card Generic Apr 19 07:53:03 volumio volumio[889]: No state is present for card SB Apr 19 07:53:03 volumio volumio[889]: Found hardware: "HDA-Intel" "Realtek ALC268" "HDA:10ec0268,10ec0268,00100101" "0x1002" "0x4383" Apr 19 07:53:03 volumio volumio[889]: Hardware is initialized using a generic method Apr 19 07:53:03 volumio volumio[889]: No state is present for card SB Apr 19 07:53:03 volumio volumio[889]: info: Output device has changed, restarting MPD Apr 19 07:53:03 volumio volumio[889]: info: Output device has changed, restarting Shairport Sync Apr 19 07:53:03 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 07:53:03 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 07:53:03 volumio sudo[1575]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 19 07:53:03 volumio sudo[1575]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 07:53:03 volumio sudo[1577]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 19 07:53:03 volumio sudo[1577]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 07:53:03 volumio systemd[1]: Stopping Music Player Daemon... Apr 19 07:53:03 volumio sudo[1575]: pam_unix(sudo:session): session closed for user root Apr 19 07:53:03 volumio volumio[889]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 19 07:53:03 volumio volumio[889]: info: ___________ START PLUGINS ___________ Apr 19 07:53:03 volumio volumio[889]: info: Setting Hotspot Unique name on first start: Volumio-C558D Apr 19 07:53:03 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: network , getConfigParam Apr 19 07:53:03 volumio volumio[889]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getConfigParam] in plugin network Apr 19 07:53:03 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: network , saveHotspotSettings Apr 19 07:53:03 volumio volumio[889]: info: CoreCommandRouter::Reload Ui Apr 19 07:53:03 volumio volumio[889]: info: ControllerMpd::onStart: Initializing MPD Apr 19 07:53:03 volumio volumio[889]: info: Creating MPD Configuration file Apr 19 07:53:03 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 19 07:53:03 volumio volumio[889]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 19 07:53:03 volumio volumio[889]: info: [1745074383879] CoreMusicLibrary::Adding element Media Servers Apr 19 07:53:03 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 19 07:53:03 volumio sudo[1586]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 19 07:53:03 volumio sudo[1586]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 07:53:03 volumio sudo[1584]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 19 07:53:03 volumio sudo[1584]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 07:53:03 volumio sudo[1584]: pam_unix(sudo:session): session closed for user root Apr 19 07:53:03 volumio systemd[1]: mpd.service: Succeeded. Apr 19 07:53:03 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 07:53:03 volumio systemd[1]: Stopped Music Player Daemon. Apr 19 07:53:03 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 07:53:04 volumio systemd[1]: Starting Music Player Daemon... Apr 19 07:53:04 volumio volumio[889]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 19 07:53:04 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 07:53:04 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 07:53:04 volumio sudo[1589]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 19 07:53:04 volumio sudo[1589]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 07:53:04 volumio sudo[1589]: pam_unix(sudo:session): session closed for user root Apr 19 07:53:04 volumio volumio[889]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 19 07:53:04 volumio volumio[889]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 19 07:53:04 volumio volumio[889]: info: [1745074384514] CoreMusicLibrary::Adding element Last_100 Apr 19 07:53:04 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 19 07:53:04 volumio volumio[889]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 19 07:53:04 volumio volumio[889]: info: [1745074384520] CoreMusicLibrary::Adding element Webradio Apr 19 07:53:04 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 19 07:53:04 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 19 07:53:04 volumio volumio[889]: info: Initializing BBC Radios Apr 19 07:53:04 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 19 07:53:04 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 07:53:04 volumio volumio[889]: info: Volumio Calling Home Apr 19 07:53:05 volumio volumio[889]: Unable to parse: Apr 19 07:53:05 volumio volumio[889]: Simple mixer control 'IEC958',0 Apr 19 07:53:05 volumio volumio[889]: Capabilities: pswitch pswitch-joined Apr 19 07:53:05 volumio volumio[889]: Playback channels: Mono Apr 19 07:53:05 volumio volumio[889]: Mono: Playback [off] Apr 19 07:53:05 volumio volumio[889]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Apr 19 07:53:05 volumio volumio[889]: Unable to parse: Apr 19 07:53:05 volumio volumio[889]: Simple mixer control 'IEC958',0 Apr 19 07:53:05 volumio volumio[889]: Capabilities: pswitch pswitch-joined Apr 19 07:53:05 volumio volumio[889]: Playback channels: Mono Apr 19 07:53:05 volumio volumio[889]: Mono: Playback [off] Apr 19 07:53:05 volumio volumio[889]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Apr 19 07:53:05 volumio volumio[889]: info: MPD Permissions set Apr 19 07:53:05 volumio volumio[889]: info: MPD Permissions set Apr 19 07:53:05 volumio volumio[889]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Apr 19 07:53:05 volumio sudo[1614]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hostapd/hostapd.conf Apr 19 07:53:05 volumio sudo[1616]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Apr 19 07:53:05 volumio sudo[1616]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 07:53:05 volumio sudo[1614]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 07:53:05 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Apr 19 07:53:05 volumio sudo[1616]: pam_unix(sudo:session): session closed for user root Apr 19 07:53:05 volumio sudo[1614]: pam_unix(sudo:session): session closed for user root Apr 19 07:53:05 volumio volumio[889]: info: Discovery: adding 441af139-b8c2-4279-b920-b22811dd2222 Apr 19 07:53:05 volumio volumio[889]: info: Discovery: Found device Volumio Apr 19 07:53:05 volumio volumio[889]: info: CoreCommandRouter::volumioGetState Apr 19 07:53:05 volumio volumio[889]: info: CorePlayQueue::getTrack 0 Apr 19 07:53:06 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 07:53:06 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 07:53:06 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 07:53:06 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 07:53:06 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 19 07:53:06 volumio mpd[1597]: Apr 19 14:53 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 19 07:53:06 volumio systemd[1]: Started Music Player Daemon. Apr 19 07:53:06 volumio sudo[1577]: pam_unix(sudo:session): session closed for user root Apr 19 07:53:06 volumio sudo[1586]: pam_unix(sudo:session): session closed for user root Apr 19 07:53:06 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 07:53:06 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 07:53:06 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 07:53:06 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 07:53:06 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 19 07:53:06 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 07:53:06 volumio volumio[889]: info: Permissions for /etc/hostapd/hostapd.conf Apr 19 07:53:06 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Apr 19 07:53:06 volumio volumio[889]: info: Discovery: Restarting Advertising due to device name change Apr 19 07:53:06 volumio volumio[889]: Unable to parse: Apr 19 07:53:06 volumio volumio[889]: Simple mixer control 'IEC958',0 Apr 19 07:53:06 volumio volumio[889]: Capabilities: pswitch pswitch-joined Apr 19 07:53:06 volumio volumio[889]: Playback channels: Mono Apr 19 07:53:06 volumio volumio[889]: Mono: Playback [off] Apr 19 07:53:06 volumio volumio[889]: info: VolumeController:: Volume=undefined Mute =false Apr 19 07:53:06 volumio volumio[889]: info: CoreStateMachine::pushState Apr 19 07:53:06 volumio volumio[889]: info: CorePlayQueue::getTrack 0 Apr 19 07:53:06 volumio volumio[889]: info: CoreCommandRouter::volumioPushState Apr 19 07:53:06 volumio volumio[889]: info: Upmpdcli Daemon Started Apr 19 07:53:06 volumio volumio[889]: info: Volumio called home Apr 19 07:53:06 volumio volumio[889]: Unable to parse: Apr 19 07:53:06 volumio volumio[889]: Simple mixer control 'IEC958',0 Apr 19 07:53:06 volumio volumio[889]: Capabilities: pswitch pswitch-joined Apr 19 07:53:06 volumio volumio[889]: Playback channels: Mono Apr 19 07:53:06 volumio volumio[889]: Mono: Playback [off] Apr 19 07:53:06 volumio volumio[889]: info: VolumeController:: Volume=undefined Mute =false Apr 19 07:53:06 volumio volumio[889]: info: CoreStateMachine::pushState Apr 19 07:53:06 volumio volumio[889]: info: CorePlayQueue::getTrack 0 Apr 19 07:53:06 volumio volumio[889]: info: CoreCommandRouter::volumioPushState Apr 19 07:53:06 volumio volumio[889]: info: CorePlayQueue::getTrack 0 Apr 19 07:53:06 volumio volumio[889]: info: Completed starting Core Plugins Apr 19 07:53:06 volumio volumio[889]: info: ------------------------------------------- Apr 19 07:53:06 volumio volumio[889]: info: ----- MyVolumio plugins startup ---- Apr 19 07:53:06 volumio volumio[889]: info: ------------------------------------------- Apr 19 07:53:06 volumio volumio[889]: info: [MyVolumio PluginManager] Fetching plans data.... Apr 19 07:53:06 volumio sudo[1635]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Apr 19 07:53:06 volumio sudo[1635]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 07:53:06 volumio systemd[1]: Starting Wireless Services... Apr 19 07:53:06 volumio volumio[889]: info: CoreCommandRouter::volumioGetState Apr 19 07:53:06 volumio volumio[889]: info: CorePlayQueue::getTrack 0 Apr 19 07:53:07 volumio wireless.js[1640]: WIRELESS: No wireless interface, exiting Apr 19 07:53:07 volumio volumio[889]: info: MPD running with PID1597 Apr 19 07:53:07 volumio volumio[889]: ,establishing connection Apr 19 07:53:07 volumio volumio[889]: info: Starting Shairport Sync Apr 19 07:53:07 volumio volumio[889]: info: Starting Shairport Sync Apr 19 07:53:07 volumio volumio[889]: info: Starting Shairport Sync Apr 19 07:53:07 volumio iw[1652]: command failed: No such device (-19) Apr 19 07:53:07 volumio sudo[1659]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 19 07:53:07 volumio sudo[1655]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 19 07:53:07 volumio sudo[1655]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 07:53:07 volumio sudo[1659]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 07:53:07 volumio sudo[1657]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 19 07:53:07 volumio sudo[1657]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 07:53:07 volumio iwconfig[1661]: Error for wireless request "Set Power Management" (8B2C) : Apr 19 07:53:07 volumio iwconfig[1661]: SET failed on device wlan0 ; No such device. Apr 19 07:53:07 volumio systemd[1]: wireless.service: Succeeded. Apr 19 07:53:07 volumio systemd[1]: Started Wireless Services. Apr 19 07:53:07 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 19 07:53:07 volumio sudo[1635]: pam_unix(sudo:session): session closed for user root Apr 19 07:53:07 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 19 07:53:07 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 19 07:53:07 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 19 07:53:07 volumio sudo[1655]: pam_unix(sudo:session): session closed for user root Apr 19 07:53:07 volumio sudo[1659]: pam_unix(sudo:session): session closed for user root Apr 19 07:53:07 volumio sudo[1657]: pam_unix(sudo:session): session closed for user root Apr 19 07:53:07 volumio volumio[889]: error: MPD error: The expression evaluated to a falsy value: Apr 19 07:53:07 volumio volumio[889]: assert.ok(self.idling) Apr 19 07:53:07 volumio volumio[889]: error: The expression evaluated to a falsy value: Apr 19 07:53:07 volumio volumio[889]: assert.ok(self.idling) Apr 19 07:53:07 volumio volumio[889]: error: MPD error: The expression evaluated to a falsy value: Apr 19 07:53:07 volumio volumio[889]: assert.ok(self.idling) Apr 19 07:53:07 volumio volumio[889]: error: The expression evaluated to a falsy value: Apr 19 07:53:07 volumio volumio[889]: assert.ok(self.idling) Apr 19 07:53:08 volumio volumio[889]: info: Shairport-Sync Started Apr 19 07:53:08 volumio volumio[889]: Error adding Membership: Error: addMembership EINVAL Apr 19 07:53:08 volumio volumio[889]: info: Shairport-Sync Started Apr 19 07:53:08 volumio volumio[889]: info: Shairport-Sync Started Apr 19 07:53:08 volumio volumio[889]: error: updateQueue error: null Apr 19 07:53:08 volumio volumio[889]: info: Discovery: A device disappeared from network Apr 19 07:53:08 volumio volumio[889]: info: Discovery: Device volumio disappeared from network Apr 19 07:53:08 volumio volumio-remote-updater[655]: [2025-04-19 14:53:08] [connect] Successful connection Apr 19 07:53:08 volumio volumio-remote-updater[655]: [2025-04-19 14:53:08] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1745074388 101 Apr 19 07:53:08 volumio volumio[889]: 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 Apr 19 07:53:09 volumio volumio[1619]: Generating RSA private key, 4096 bit long modulus (2 primes) Apr 19 07:53:09 volumio volumio[1619]: ..........................++++ Apr 19 07:53:11 volumio volumio[1619]: ..................................................++++ Apr 19 07:53:11 volumio volumio[1619]: e is 65537 (0x010001) Apr 19 07:53:11 volumio volumio[1619]: writing RSA key Apr 19 07:53:11 volumio volumio[889]: info: Discovery: Started advertising with name: Volumio Apr 19 07:53:12 volumio volumio[889]: info: Discovery: adding 441af139-b8c2-4279-b920-b22811dd2222 Apr 19 07:53:12 volumio volumio[889]: info: Discovery: Found device Volumio Apr 19 07:53:12 volumio volumio[889]: info: CoreCommandRouter::volumioGetState Apr 19 07:53:12 volumio volumio[889]: info: CorePlayQueue::getTrack 0 Apr 19 07:53:12 volumio volumio[889]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Apr 19 07:53:13 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: wizard , reportWirelessConnection Apr 19 07:53:13 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessInfo Apr 19 07:53:13 volumio sudo[1727]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 19 07:53:13 volumio sudo[1727]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 07:53:13 volumio sudo[1727]: pam_unix(sudo:session): session closed for user root Apr 19 07:53:15 volumio volumio[889]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Apr 19 07:53:15 volumio volumio[889]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Apr 19 07:53:15 volumio volumio[889]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Apr 19 07:53:15 volumio volumio[889]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Apr 19 07:53:15 volumio volumio[889]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Apr 19 07:53:15 volumio volumio[889]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Apr 19 07:53:15 volumio volumio[889]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Apr 19 07:53:15 volumio volumio[889]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Apr 19 07:53:15 volumio volumio[889]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Apr 19 07:53:15 volumio volumio[889]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Apr 19 07:53:15 volumio volumio[889]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Apr 19 07:53:15 volumio volumio[889]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Apr 19 07:53:15 volumio volumio[889]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Apr 19 07:53:15 volumio volumio[889]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Apr 19 07:53:15 volumio volumio[889]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Apr 19 07:53:15 volumio volumio[889]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Apr 19 07:53:15 volumio volumio[889]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Apr 19 07:53:15 volumio volumio[889]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Apr 19 07:53:15 volumio volumio[889]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Apr 19 07:53:15 volumio volumio[889]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Apr 19 07:53:15 volumio volumio[889]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Apr 19 07:53:15 volumio volumio[889]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Apr 19 07:53:15 volumio volumio[889]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Apr 19 07:53:15 volumio volumio[889]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Apr 19 07:53:15 volumio volumio[889]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Apr 19 07:53:15 volumio volumio[889]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Apr 19 07:53:16 volumio volumio[889]: info: Adding plugin bluetooth to MyMusic Plugins Apr 19 07:53:16 volumio volumio[889]: info: Adding plugin multiroom to MyMusic Plugins Apr 19 07:53:16 volumio volumio[889]: info: Adding plugin metavolumio to MyMusic Plugins Apr 19 07:53:17 volumio kernel: usb 3-1: USB disconnect, device number 2 Apr 19 07:53:17 volumio kernel: usb 3-1.4: USB disconnect, device number 7 Apr 19 07:53:17 volumio kernel: usb 3-1.4.2: USB disconnect, device number 8 Apr 19 07:53:17 volumio thd[642]: Error reading device '/dev/input/event4' Apr 19 07:53:17 volumio volumio[889]: info: Adding plugin cd_controller to MyMusic Plugins Apr 19 07:53:17 volumio volumio[889]: info: Adding plugin smart_inputs to MyMusic Plugins Apr 19 07:53:17 volumio thd[642]: Error reading device '/dev/input/event5' Apr 19 07:53:17 volumio volumio[889]: info: Adding plugin tidalconnect to MyMusic Plugins Apr 19 07:53:17 volumio volumio[889]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Apr 19 07:53:17 volumio thd[642]: Error reading device '/dev/input/event6' Apr 19 07:53:17 volumio kernel: usb 3-1.4.3: USB disconnect, device number 9 Apr 19 07:53:17 volumio thd[642]: Error reading device '/dev/input/event7' Apr 19 07:53:17 volumio thd[642]: Error reading device '/dev/input/event8' Apr 19 07:53:21 volumio volumio[889]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Apr 19 07:53:21 volumio volumio[889]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Apr 19 07:53:21 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 07:53:21 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 07:53:21 volumio volumio[889]: info: Starting MyVolumio Remote Streaming Endpoints Apr 19 07:53:21 volumio volumio[889]: info: MyVolumio not started Apr 19 07:53:21 volumio volumio[889]: info: Initializing device activation check Apr 19 07:53:21 volumio volumio[889]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Apr 19 07:53:21 volumio volumio[889]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Apr 19 07:53:25 volumio volumio[889]: info: Starting Streaming Service Transparent Proxy Apr 19 07:53:25 volumio volumio[889]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Apr 19 07:53:25 volumio volumio[889]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Apr 19 07:53:25 volumio volumio[889]: info: Streaming services startup Apr 19 07:53:25 volumio volumio[889]: info: Starting Streaming Daemon Apr 19 07:53:25 volumio sudo[1783]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 19 07:53:25 volumio sudo[1783]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 07:53:25 volumio volumio[889]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Apr 19 07:53:25 volumio sudo[1783]: pam_unix(sudo:session): session closed for user root Apr 19 07:53:25 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart Apr 19 07:53:25 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart Apr 19 07:53:25 volumio volumio[889]: info: Refreshing Cached IP Addresses Apr 19 07:53:25 volumio sudo[1790]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Apr 19 07:53:25 volumio sudo[1790]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 07:53:25 volumio sudo[1792]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 19 07:53:25 volumio sudo[1792]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 07:53:25 volumio sudo[1792]: pam_unix(sudo:session): session closed for user root Apr 19 07:53:25 volumio sudo[1790]: pam_unix(sudo:session): session closed for user root Apr 19 07:53:25 volumio sudo[1795]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 19 07:53:25 volumio sudo[1795]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 07:53:25 volumio sudo[1795]: pam_unix(sudo:session): session closed for user root Apr 19 07:53:26 volumio volumio[889]: error: Cannot start Volumio Streaming Daemon Apr 19 07:53:26 volumio volumio[889]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Apr 19 07:53:26 volumio volumio[889]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Apr 19 07:53:26 volumio systemd[1]: upmpdcli.service: Succeeded. Apr 19 07:53:26 volumio volumio[889]: STREAMING PROXY: Starting server on port 3245 Apr 19 07:53:26 volumio volumio[889]: Node JS runtime: 14 Apr 19 07:53:34 volumio dbus-daemon[657]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by ':1.15' (uid=0 pid=1437 comm="/usr/lib/chromium/chromium --show-component-extens") Apr 19 07:53:34 volumio systemd[1]: Starting Bluetooth service... Apr 19 07:53:34 volumio kernel: cryptd: max_cpu_qlen set to 1000 Apr 19 07:53:34 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 19 07:53:34 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 07:53:34 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 19 07:53:34 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Apr 19 07:53:35 volumio volumio-remote-updater[655]: No test mode Apr 19 07:53:35 volumio volumio-remote-updater[655]: No alpha test mode Apr 19 07:53:35 volumio volumio[889]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Apr 19 07:53:35 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Apr 19 07:53:36 volumio sudo[1888]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Apr 19 07:53:36 volumio sudo[1888]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 07:53:36 volumio sudo[1884]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 19 07:53:36 volumio sudo[1886]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 19 07:53:36 volumio sudo[1886]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 07:53:36 volumio sudo[1884]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 07:53:36 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Apr 19 07:53:36 volumio sudo[1888]: pam_unix(sudo:session): session closed for user root Apr 19 07:53:36 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 19 07:53:36 volumio sudo[1884]: pam_unix(sudo:session): session closed for user root Apr 19 07:53:36 volumio volumio[889]: info: Upmpdcli Daemon Started Apr 19 07:53:36 volumio volumio[889]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Apr 19 07:53:36 volumio volumio[889]: info: CoreCommandRouter::volumioGetState Apr 19 07:53:36 volumio volumio[889]: info: CorePlayQueue::getTrack 0 Apr 19 07:53:37 volumio sudo[1886]: pam_unix(sudo:session): session closed for user root Apr 19 07:53:37 volumio volumio[1896]: writing RSA key Apr 19 07:53:38 volumio kernel: Bluetooth: Core ver 2.22 Apr 19 07:53:38 volumio kernel: NET: Registered PF_BLUETOOTH protocol family Apr 19 07:53:38 volumio kernel: Bluetooth: HCI device and connection manager initialized Apr 19 07:53:38 volumio kernel: Bluetooth: HCI socket layer initialized Apr 19 07:53:38 volumio kernel: Bluetooth: L2CAP socket layer initialized Apr 19 07:53:38 volumio kernel: Bluetooth: SCO socket layer initialized Apr 19 07:53:38 volumio bluetoothd[1827]: Bluetooth daemon 5.50 Apr 19 07:53:39 volumio bluetoothd[1827]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Apr 19 07:53:39 volumio dbus-daemon[657]: [system] Successfully activated service 'org.bluez' Apr 19 07:53:39 volumio systemd[1]: Started Bluetooth service. Apr 19 07:53:39 volumio bluetoothd[1827]: Starting SDP server Apr 19 07:53:39 volumio bluetoothd[1827]: Excluding (cli) sap Apr 19 07:53:40 volumio kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Apr 19 07:53:40 volumio kernel: Bluetooth: BNEP filters: protocol multicast Apr 19 07:53:40 volumio kernel: Bluetooth: BNEP socket layer initialized Apr 19 07:53:40 volumio bluetoothd[1827]: Bluetooth management interface 1.22 initialized Apr 19 07:53:40 volumio dbus-daemon[657]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.16' (uid=0 pid=1827 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Apr 19 07:53:40 volumio systemd[1]: Starting Hostname Service... Apr 19 07:53:41 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 07:53:41 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 07:53:41 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Apr 19 07:53:41 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 19 07:53:41 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Apr 19 07:53:41 volumio volumio[889]: info: CoreCommandRouter::volumioGetBrowseSources Apr 19 07:53:41 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 19 07:53:44 volumio dbus-daemon[657]: [system] Successfully activated service 'org.freedesktop.hostname1' Apr 19 07:53:44 volumio systemd[1]: Started Hostname Service. Apr 19 07:53:45 volumio volumio[889]: error: MyVolumio Plugin failed to authenticate in a timely fashion Apr 19 07:53:45 volumio volumio[889]: info: Completed starting MyVolumio Plugin Apr 19 07:53:45 volumio volumio[889]: [Metrics] CommandRouter: 74s 232.35ms Apr 19 07:53:45 volumio volumio[889]: info: CoreCommandRouter::volumiosetStartupVolume Apr 19 07:53:45 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 07:53:45 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 07:53:45 volumio volumio[889]: info: CoreCommandRouter::Close All Modals sent Apr 19 07:53:45 volumio volumio[889]: info: CoreCommandRouter::Close All Modals sent Apr 19 07:53:46 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Apr 19 07:53:46 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 19 07:53:48 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Apr 19 07:53:48 volumio volumio[889]: info: Version has changed, forcing UI Reload Apr 19 07:53:48 volumio volumio[889]: info: CoreCommandRouter::Reload Ui Apr 19 07:53:51 volumio kernel: snd_hda_intel 0000:00:01.1: IRQ timing workaround is activated for card #0. Suggest a bigger bdl_pos_adj. Apr 19 07:53:52 volumio volumio[889]: info: BOOT COMPLETED Apr 19 07:54:14 volumio systemd[1]: systemd-hostnamed.service: Succeeded. Apr 19 07:54:29 volumio sudo[2142]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 19 07:54:29 volumio sudo[2142]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 07:54:29 volumio sudo[2142]: pam_unix(sudo:session): session closed for user root Apr 19 07:54:29 volumio sudo[2144]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 19 07:54:29 volumio sudo[2144]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 07:54:29 volumio sudo[2144]: pam_unix(sudo:session): session closed for user root Apr 19 07:54:29 volumio volumio[889]: verbose: New Socket.io Connection to 192.168.1.99 from 192.168.1.64 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Apr 19 07:54:30 volumio volumio[889]: info: CoreCommandRouter::volumioGetVisibleSources Apr 19 07:54:30 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 19 07:54:30 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Apr 19 07:54:30 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Apr 19 07:54:30 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Apr 19 07:54:30 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Apr 19 07:54:30 volumio volumio[889]: info: Received Get System Info Apr 19 07:54:30 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 19 07:54:30 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 19 07:54:30 volumio volumio[889]: info: Discovery: Getting this device information Apr 19 07:54:30 volumio volumio[889]: info: CoreCommandRouter::volumioGetState Apr 19 07:54:30 volumio volumio[889]: info: CorePlayQueue::getTrack 0 Apr 19 07:54:30 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 19 07:54:30 volumio volumio-remote-updater[655]: No test mode Apr 19 07:54:30 volumio volumio-remote-updater[655]: No alpha test mode Apr 19 07:54:30 volumio volumio[889]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 19 07:54:30 volumio volumio[889]: info: Retrieving Cloud Streaming UI Apr 19 07:54:30 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 19 07:54:30 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 19 07:54:31 volumio volumio[889]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Apr 19 07:54:31 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Apr 19 07:54:31 volumio volumio[889]: info: Getting Tidal Cloud Configuration Apr 19 07:54:31 volumio volumio[889]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 19 07:54:31 volumio volumio[889]: info: Getting Qobuz Cloud Configuration Apr 19 07:54:31 volumio volumio[889]: info: Asking plugin for UI Config Apr 19 07:54:31 volumio volumio[889]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 19 07:54:31 volumio volumio[889]: info: Getting Spotify Cloud Configuration Apr 19 07:54:31 volumio volumio[889]: info: Asking plugin for UI Config Apr 19 07:54:31 volumio volumio[889]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 19 07:54:31 volumio volumio[889]: info: Saving Spotify Acccount Apr 19 07:54:31 volumio volumio[889]: info: Got Tidal Cloud Configuration Apr 19 07:54:31 volumio volumio[889]: info: Got it Apr 19 07:54:31 volumio volumio[889]: info: Got it Apr 19 07:54:31 volumio volumio[889]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Apr 19 07:54:31 volumio volumio[889]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 19 07:54:31 volumio volumio[889]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Apr 19 07:54:31 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 19 07:54:31 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Apr 19 07:54:31 volumio volumio[889]: info: Loading i18n strings for locale en Apr 19 07:54:31 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Apr 19 07:54:31 volumio volumio[889]: info: Setting timezone to America/Los_Angeles Apr 19 07:54:32 volumio sudo[2157]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Apr 19 07:54:32 volumio sudo[2157]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 07:54:32 volumio sudo[2157]: pam_unix(sudo:session): session closed for user root Apr 19 07:54:32 volumio sudo[2160]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/America/Los_Angeles /etc/localtime Apr 19 07:54:32 volumio sudo[2160]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 07:54:32 volumio sudo[2160]: pam_unix(sudo:session): session closed for user root Apr 19 07:54:32 volumio sudo[2163]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Apr 19 07:54:32 volumio sudo[2163]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 07:54:34 volumio sudo[2163]: pam_unix(sudo:session): session closed for user root Apr 19 07:54:35 volumio sudo[2167]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone America/Los_Angeles Apr 19 07:54:35 volumio sudo[2167]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 07:54:35 volumio dbus-daemon[657]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.19' (uid=0 pid=2168 comm="/usr/bin/timedatectl set-timezone America/Los_Ange") Apr 19 07:54:35 volumio systemd[1]: Starting Time & Date Service... Apr 19 07:54:35 volumio dbus-daemon[657]: [system] Successfully activated service 'org.freedesktop.timedate1' Apr 19 07:54:35 volumio systemd[1]: Started Time & Date Service. Apr 19 07:54:35 volumio sudo[2167]: pam_unix(sudo:session): session closed for user root Apr 19 07:54:35 volumio volumio[889]: info: Fetching Streaming Services browse cache Apr 19 07:54:37 volumio volumio[889]: Updating browse sources language Apr 19 07:54:37 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 19 07:54:42 volumio volumio[889]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Apr 19 07:54:43 volumio volumio[889]: info: CoreCommandRouter::volumioGetVisibleSources Apr 19 07:54:43 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 19 07:54:43 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Apr 19 07:54:43 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Apr 19 07:54:43 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Apr 19 07:54:43 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Apr 19 07:54:43 volumio volumio[889]: info: Received Get System Info Apr 19 07:54:43 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 19 07:54:43 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 19 07:54:43 volumio volumio[889]: info: Discovery: Getting this device information Apr 19 07:54:43 volumio volumio[889]: info: CoreCommandRouter::volumioGetState Apr 19 07:54:43 volumio volumio[889]: info: CorePlayQueue::getTrack 0 Apr 19 07:54:43 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 19 07:54:43 volumio volumio-remote-updater[655]: No test mode Apr 19 07:54:43 volumio volumio-remote-updater[655]: No alpha test mode Apr 19 07:54:44 volumio volumio[889]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Apr 19 07:54:44 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Apr 19 07:54:44 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 19 07:54:44 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 19 07:54:44 volumio volumio[889]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 19 07:54:44 volumio volumio[889]: info: Retrieving Cloud Streaming UI Apr 19 07:54:44 volumio volumio[889]: info: Getting Tidal Cloud Configuration Apr 19 07:54:44 volumio volumio[889]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 19 07:54:44 volumio volumio[889]: info: Getting Qobuz Cloud Configuration Apr 19 07:54:44 volumio volumio[889]: info: Asking plugin for UI Config Apr 19 07:54:44 volumio volumio[889]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 19 07:54:44 volumio volumio[889]: info: Getting Spotify Cloud Configuration Apr 19 07:54:44 volumio volumio[889]: info: Asking plugin for UI Config Apr 19 07:54:44 volumio volumio[889]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 19 07:54:44 volumio volumio[889]: info: Saving Spotify Acccount Apr 19 07:54:44 volumio volumio[889]: info: Got Tidal Cloud Configuration Apr 19 07:54:44 volumio volumio[889]: info: Got it Apr 19 07:54:44 volumio volumio[889]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Apr 19 07:54:44 volumio volumio[889]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 19 07:54:44 volumio volumio[889]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Apr 19 07:54:44 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 19 07:54:45 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Apr 19 07:54:45 volumio volumio[889]: info: Loading i18n strings for locale en Apr 19 07:54:45 volumio volumio[889]: Updating browse sources language Apr 19 07:54:45 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 19 07:54:46 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Apr 19 07:54:46 volumio volumio[889]: info: Setting timezone to UTC Apr 19 07:54:46 volumio sudo[2189]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Apr 19 07:54:46 volumio sudo[2189]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 07:54:46 volumio sudo[2189]: pam_unix(sudo:session): session closed for user root Apr 19 07:54:46 volumio sudo[2192]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/UTC /etc/localtime Apr 19 07:54:46 volumio sudo[2192]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 07:54:46 volumio sudo[2192]: pam_unix(sudo:session): session closed for user root Apr 19 07:54:46 volumio sudo[2195]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Apr 19 07:54:46 volumio sudo[2195]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 07:54:47 volumio sudo[2195]: pam_unix(sudo:session): session closed for user root Apr 19 07:54:47 volumio sudo[2198]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone UTC Apr 19 07:54:47 volumio sudo[2198]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 07:54:47 volumio systemd-timedated[2169]: Failed to set time zone: Permission denied Apr 19 07:54:47 volumio sudo[2198]: pam_unix(sudo:session): session closed for user root Apr 19 07:54:47 volumio volumio[889]: Failed to set time zone: Access denied Apr 19 07:54:47 volumio volumio[889]: info: Could not set timezone, retrying Apr 19 07:54:47 volumio volumio[889]: info: Fetching Streaming Services browse cache Apr 19 07:54:48 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Apr 19 07:54:48 volumio volumio[889]: info: Loading i18n strings for locale en Apr 19 07:54:48 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Apr 19 07:54:48 volumio volumio[889]: info: Setting timezone to America/Los_Angeles Apr 19 07:54:49 volumio sudo[2201]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Apr 19 07:54:49 volumio sudo[2201]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 07:54:49 volumio sudo[2201]: pam_unix(sudo:session): session closed for user root Apr 19 07:54:49 volumio sudo[2221]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/America/Los_Angeles /etc/localtime Apr 19 07:54:49 volumio sudo[2221]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 07:54:49 volumio sudo[2221]: pam_unix(sudo:session): session closed for user root Apr 19 07:54:49 volumio sudo[2224]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Apr 19 07:54:49 volumio sudo[2224]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 07:54:49 volumio sudo[2224]: pam_unix(sudo:session): session closed for user root Apr 19 07:54:50 volumio sudo[2227]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone America/Los_Angeles Apr 19 07:54:50 volumio sudo[2227]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 07:54:50 volumio systemd-timedated[2169]: Failed to set time zone: Permission denied Apr 19 07:54:50 volumio sudo[2227]: pam_unix(sudo:session): session closed for user root Apr 19 07:54:50 volumio volumio[889]: Failed to set time zone: Access denied Apr 19 07:54:50 volumio volumio[889]: info: Could not set timezone, retrying Apr 19 07:54:50 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Apr 19 07:54:50 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Apr 19 07:54:50 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 19 07:54:50 volumio volumio[889]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Apr 19 07:54:50 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Apr 19 07:54:50 volumio volumio[889]: info: Received Get System Version Apr 19 07:54:50 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 19 07:54:50 volumio volumio[889]: info: Received Get System Info Apr 19 07:54:50 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 19 07:54:50 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 19 07:54:50 volumio volumio[889]: info: Discovery: Getting this device information Apr 19 07:54:50 volumio volumio[889]: info: CoreCommandRouter::volumioGetState Apr 19 07:54:50 volumio volumio[889]: info: CorePlayQueue::getTrack 0 Apr 19 07:54:50 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 19 07:54:50 volumio sudo[2230]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 19 07:54:50 volumio sudo[2230]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 07:54:50 volumio sudo[2230]: pam_unix(sudo:session): session closed for user root Apr 19 07:54:50 volumio sudo[2235]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone UTC Apr 19 07:54:50 volumio sudo[2235]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 07:54:50 volumio systemd-timedated[2169]: Failed to set time zone: Permission denied Apr 19 07:54:50 volumio sudo[2235]: pam_unix(sudo:session): session closed for user root Apr 19 07:54:50 volumio volumio[889]: Failed to set time zone: Access denied Apr 19 07:54:50 volumio volumio[889]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 19 07:54:50 volumio volumio[889]: Error: Command failed: /usr/bin/sudo /usr/bin/timedatectl set-timezone 'UTC' Apr 19 07:54:50 volumio volumio[889]: Failed to set time zone: Access denied Apr 19 07:54:50 volumio volumio[889]: at checkExecSyncError (child_process.js:790:11) Apr 19 07:54:50 volumio volumio[889]: at execSync (child_process.js:863:15) Apr 19 07:54:50 volumio volumio[889]: at Timeout._onTimeout (/volumio/app/plugins/system_controller/system/index.js:1575:9) Apr 19 07:54:50 volumio volumio[889]: at listOnTimeout (internal/timers.js:557:17) Apr 19 07:54:50 volumio volumio[889]: at processTimers (internal/timers.js:500:7) { Apr 19 07:54:50 volumio volumio[889]: status: 1, Apr 19 07:54:50 volumio volumio[889]: signal: null, Apr 19 07:54:50 volumio volumio[889]: output: [ null, '', 'Failed to set time zone: Access denied\n' ], Apr 19 07:54:50 volumio volumio[889]: pid: 2234, Apr 19 07:54:50 volumio volumio[889]: stdout: '', Apr 19 07:54:50 volumio volumio[889]: stderr: 'Failed to set time zone: Access denied\n' Apr 19 07:54:50 volumio volumio[889]: } Apr 19 07:54:50 volumio volumio[889]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 19 07:54:50 volumio sudo[2246]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-19 07:53 Apr 19 07:54:50 volumio sudo[2246]: 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="e32c2c71ae78852844c70357816bdd871c155ab8" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 31 Mar 2025 07:27:48 PM CEST" VOLUMIO_VERSION="3.799" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="4bc1ecb08f34ad14b420d6678a2aef1b"