-- Logs begin at Fri 2025-10-24 21:19:13 UTC, end at Fri 2025-10-24 21:24:05 UTC. -- Oct 24 21:23:00 volumio.local.local wireless.js[2705]: trying... Oct 24 21:23:00 volumio.local.local sudo[2844]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 24 21:23:00 volumio.local.local sudo[2844]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:23:00 volumio.local.local sudo[2844]: pam_unix(sudo:session): session closed for user root Oct 24 21:23:00 volumio.local.local volumio[1243]: info: Volumio Network Manager: Network status updated: 1 Oct 24 21:23:00 volumio.local.local wpa_supplicant[2826]: wlan0: Trying to associate with cc:ce:1e:80:25:64 (SSID='FRITZ!Box 7490' freq=2437 MHz) Oct 24 21:23:00 volumio.local.local wpa_supplicant[2826]: wlan0: Associated with cc:ce:1e:80:25:64 Oct 24 21:23:00 volumio.local.local wpa_supplicant[2826]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Oct 24 21:23:00 volumio.local.local wpa_supplicant[2826]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=DE Oct 24 21:23:00 volumio.local.local kernel: cfg80211: Regulatory domain changed to country: DE Oct 24 21:23:00 volumio.local.local kernel: cfg80211: DFS Master region: ETSI Oct 24 21:23:00 volumio.local.local kernel: cfg80211: (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp), (dfs_cac_time) Oct 24 21:23:00 volumio.local.local kernel: cfg80211: (2400000 KHz - 2483500 KHz @ 40000 KHz), (N/A, 2000 mBm), (N/A) Oct 24 21:23:00 volumio.local.local kernel: cfg80211: (5150000 KHz - 5250000 KHz @ 80000 KHz, 200000 KHz AUTO), (N/A, 2000 mBm), (N/A) Oct 24 21:23:00 volumio.local.local kernel: cfg80211: (5250000 KHz - 5350000 KHz @ 80000 KHz, 200000 KHz AUTO), (N/A, 2000 mBm), (0 s) Oct 24 21:23:00 volumio.local.local kernel: cfg80211: (5470000 KHz - 5725000 KHz @ 160000 KHz), (N/A, 2698 mBm), (0 s) Oct 24 21:23:00 volumio.local.local kernel: cfg80211: (5725000 KHz - 5875000 KHz @ 80000 KHz), (N/A, 1397 mBm), (N/A) Oct 24 21:23:00 volumio.local.local kernel: cfg80211: (57000000 KHz - 66000000 KHz @ 2160000 KHz), (N/A, 4000 mBm), (N/A) Oct 24 21:23:00 volumio.local.local wpa_supplicant[2826]: wlan0: WPA: Key negotiation completed with cc:ce:1e:80:25:64 [PTK=CCMP GTK=CCMP] Oct 24 21:23:00 volumio.local.local wpa_supplicant[2826]: wlan0: CTRL-EVENT-CONNECTED - Connection to cc:ce:1e:80:25:64 completed [id=0 id_str=] Oct 24 21:23:00 volumio.local.local dhcpcd[677]: wlan0: carrier acquired Oct 24 21:23:00 volumio.local.local dhcpcd[677]: wlan0: IAID 55:76:5f:95 Oct 24 21:23:00 volumio.local.local dhcpcd[677]: wlan0: rebinding lease of 192.168.178.57 Oct 24 21:23:00 volumio.local.local dhcpcd[677]: wlan0: probing address 192.168.178.57/24 Oct 24 21:23:01 volumio.local.local wireless.js[2705]: trying... Oct 24 21:23:01 volumio.local.local sudo[2871]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 24 21:23:01 volumio.local.local sudo[2871]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:23:01 volumio.local.local sudo[2871]: pam_unix(sudo:session): session closed for user root Oct 24 21:23:01 volumio.local.local dhcpcd[677]: wlan0: soliciting an IPv6 router Oct 24 21:23:01 volumio.local.local volumio[1243]: info: Discovery: Started advertising with name: Volumio.local.local Oct 24 21:23:02 volumio.local.local wireless.js[2705]: trying... Oct 24 21:23:02 volumio.local.local sudo[2883]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 24 21:23:02 volumio.local.local sudo[2883]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:23:02 volumio.local.local sudo[2883]: pam_unix(sudo:session): session closed for user root Oct 24 21:23:02 volumio.local.local volumio[1243]: info: Discovery: adding 99c6faf8-7221-47c3-a127-4993511b63e0 Oct 24 21:23:02 volumio.local.local volumio[1243]: info: Discovery: Found device Volumio.local.local Oct 24 21:23:02 volumio.local.local volumio[1243]: info: CoreCommandRouter::volumioGetState Oct 24 21:23:02 volumio.local.local volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 24 21:23:03 volumio.local.local wireless.js[2705]: trying... Oct 24 21:23:03 volumio.local.local sudo[2894]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 24 21:23:03 volumio.local.local sudo[2894]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:23:03 volumio.local.local sudo[2894]: pam_unix(sudo:session): session closed for user root Oct 24 21:23:04 volumio.local.local wireless.js[2705]: trying... Oct 24 21:23:04 volumio.local.local sudo[2904]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 24 21:23:04 volumio.local.local sudo[2904]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:23:04 volumio.local.local sudo[2904]: pam_unix(sudo:session): session closed for user root Oct 24 21:23:05 volumio.local.local wireless.js[2705]: trying... Oct 24 21:23:05 volumio.local.local sudo[2918]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 24 21:23:05 volumio.local.local sudo[2918]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:23:05 volumio.local.local sudo[2918]: pam_unix(sudo:session): session closed for user root Oct 24 21:23:05 volumio.local.local dhcpcd[677]: wlan0: leased 192.168.178.57 for 864000 seconds Oct 24 21:23:05 volumio.local.local dhcpcd[677]: wlan0: adding route to 192.168.178.0/24 Oct 24 21:23:05 volumio.local.local dhcpcd[677]: wlan0: adding default route via 192.168.178.1 Oct 24 21:23:05 volumio.local.local avahi-daemon[563]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.178.57. Oct 24 21:23:05 volumio.local.local avahi-daemon[563]: New relevant interface wlan0.IPv4 for mDNS. Oct 24 21:23:05 volumio.local.local avahi-daemon[563]: Registering new address record for 192.168.178.57 on wlan0.IPv4. Oct 24 21:23:05 volumio.local.local ntpd[2722]: ntpd exiting on signal 15 (Terminated) Oct 24 21:23:05 volumio.local.local ntpd[2722]: 192.168.178.1 local addr 192.168.178.137 -> Oct 24 21:23:05 volumio.local.local systemd[1]: Stopping Network Time Service... Oct 24 21:23:05 volumio.local.local volumio[1243]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 24 21:23:05 volumio.local.local systemd[1]: ntp.service: Succeeded. Oct 24 21:23:05 volumio.local.local systemd[1]: Stopped Network Time Service. Oct 24 21:23:05 volumio.local.local volumio[1243]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 24 21:23:05 volumio.local.local systemd[1]: Starting Network Time Service... Oct 24 21:23:05 volumio.local.local volumio[1243]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 24 21:23:05 volumio.local.local ntpd[2967]: ntpd 4.2.8p12@1.3728-o (1): Starting Oct 24 21:23:05 volumio.local.local ntpd[2967]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -c /run/ntp.conf.dhcp -u 104:103 Oct 24 21:23:05 volumio.local.local ntpd[2973]: proto: precision = 0.583 usec (-21) Oct 24 21:23:05 volumio.local.local systemd[1]: Started Network Time Service. Oct 24 21:23:05 volumio.local.local ntpd[2973]: Listen and drop on 0 v6wildcard [::]:123 Oct 24 21:23:05 volumio.local.local ntpd[2973]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Oct 24 21:23:05 volumio.local.local ntpd[2973]: Listen normally on 2 lo 127.0.0.1:123 Oct 24 21:23:05 volumio.local.local ntpd[2973]: Listen normally on 3 eth0 192.168.178.137:123 Oct 24 21:23:05 volumio.local.local ntpd[2973]: Listen normally on 4 wlan0 192.168.178.57:123 Oct 24 21:23:05 volumio.local.local ntpd[2973]: Listening on routing socket on fd #21 for interface updates Oct 24 21:23:05 volumio.local.local ntpd[2973]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 24 21:23:05 volumio.local.local ntpd[2973]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 24 21:23:06 volumio.local.local wireless.js[2705]: trying... Oct 24 21:23:06 volumio.local.local sudo[2976]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 24 21:23:06 volumio.local.local sudo[2976]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:23:06 volumio.local.local sudo[2976]: pam_unix(sudo:session): session closed for user root Oct 24 21:23:06 volumio.local.local wireless.js[2705]: Connected to: ----FRITZ!Box 7490 Oct 24 21:23:06 volumio.local.local wireless.js[2705]: ---- Oct 24 21:23:06 volumio.local.local sudo[2986]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 24 21:23:06 volumio.local.local sudo[2986]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:23:06 volumio.local.local sudo[2986]: pam_unix(sudo:session): session closed for user root Oct 24 21:23:06 volumio.local.local wireless.js[2705]: ... joined AP, wlan0 IPv4 is 192.168.178.57, ipV6 is undefined Oct 24 21:23:06 volumio.local.local wireless.js[2705]: It's done! AP Oct 24 21:23:06 volumio.local.local iwconfig[2997]: Error for wireless request "Set Power Management" (8B2C) : Oct 24 21:23:06 volumio.local.local iwconfig[2997]: SET failed on device wlan0 ; Operation not permitted. Oct 24 21:23:06 volumio.local.local systemd[1]: Started Wireless Services. Oct 24 21:23:06 volumio.local.local sudo[2652]: pam_unix(sudo:session): session closed for user root Oct 24 21:23:06 volumio.local.local volumio[1243]: info: Discovery: this is already registered, 99c6faf8-7221-47c3-a127-4993511b63e0 Oct 24 21:23:06 volumio.local.local volumio[1243]: info: Discovery: Found device Volumio.local.local Oct 24 21:23:06 volumio.local.local volumio[1243]: info: CoreCommandRouter::volumioGetState Oct 24 21:23:06 volumio.local.local volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 24 21:23:07 volumio.local.local volumio[1243]: verbose: New Socket.io Connection to 192.168.178.57 from 192.168.178.67 UA: Mozilla/5.0 (iPad; CPU OS 9_3_5 like Mac OS X) AppleWebKit/601.1.46 (KHTML, like Gecko) Mobile/13G36 Engine version: 3 Transport: polling Total Clients: 7 Oct 24 21:23:07 volumio.local.local volumio[1243]: info: CoreCommandRouter::volumioGetState Oct 24 21:23:07 volumio.local.local volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 24 21:23:07 volumio.local.local volumio[1243]: info: CoreCommandRouter::volumioGetQueue Oct 24 21:23:07 volumio.local.local volumio[1243]: info: CoreStateMachine::getQueue Oct 24 21:23:07 volumio.local.local volumio[1243]: info: CorePlayQueue::getQueue Oct 24 21:23:07 volumio.local.local volumio[1243]: info: CoreCommandRouter::volumioGetVisibleSources Oct 24 21:23:07 volumio.local.local volumio[1243]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 24 21:23:07 volumio.local.local volumio[1243]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 24 21:23:07 volumio.local.local volumio[1243]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 24 21:23:07 volumio.local.local volumio[1243]: info: Listing playlists Oct 24 21:23:08 volumio.local.local volumio[1243]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 24 21:23:09 volumio.local.local volumio[1243]: xcb_connection_has_error() returned true Oct 24 21:23:09 volumio.local.local volumio[1243]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 24 21:23:09 volumio.local.local volumio[1243]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 24 21:23:09 volumio.local.local volumio[1243]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 24 21:23:09 volumio.local.local volumio[1243]: xcb_connection_has_error() returned true Oct 24 21:23:09 volumio.local.local volumio[1243]: amixer: Mixer hw:0 load error: No such device or address Oct 24 21:23:09 volumio.local.local volumio[1243]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 24 21:23:09 volumio.local.local volumio[1243]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 24 21:23:09 volumio.local.local volumio[1243]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 24 21:23:09 volumio.local.local volumio[1243]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 24 21:23:09 volumio.local.local volumio[1243]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 24 21:23:09 volumio.local.local volumio[1243]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 24 21:23:09 volumio.local.local volumio[1243]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 24 21:23:09 volumio.local.local volumio[1243]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 24 21:23:09 volumio.local.local volumio[1243]: xcb_connection_has_error() returned true Oct 24 21:23:09 volumio.local.local volumio[1243]: amixer: Mixer hw:0 load error: No such device or address Oct 24 21:23:09 volumio.local.local volumio[1243]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 24 21:23:10 volumio.local.local volumio[1243]: info: Volumio Network Manager: Network status updated: 3 Oct 24 21:23:11 volumio.local.local volumio[1243]: info: CoreCommandRouter::executeOnPlugin: wizard , reportWirelessConnection Oct 24 21:23:11 volumio.local.local volumio[1243]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessInfo Oct 24 21:23:11 volumio.local.local sudo[3027]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 24 21:23:11 volumio.local.local sudo[3027]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:23:11 volumio.local.local sudo[3027]: pam_unix(sudo:session): session closed for user root Oct 24 21:23:11 volumio.local.local sudo[3037]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 24 21:23:11 volumio.local.local sudo[3037]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:23:11 volumio.local.local sudo[3037]: pam_unix(sudo:session): session closed for user root Oct 24 21:23:16 volumio.local.local volumio[1243]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart Oct 24 21:23:16 volumio.local.local volumio[1243]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart Oct 24 21:23:16 volumio.local.local volumio[1243]: info: Refreshing Cached IP Addresses Oct 24 21:23:16 volumio.local.local sudo[3087]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Oct 24 21:23:16 volumio.local.local sudo[3087]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:23:16 volumio.local.local sudo[3091]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 24 21:23:16 volumio.local.local sudo[3087]: pam_unix(sudo:session): session closed for user root Oct 24 21:23:16 volumio.local.local volumio[1243]: error: Upnp client error: Error: This socket has been ended by the other party Oct 24 21:23:16 volumio.local.local sudo[3091]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:23:16 volumio.local.local sudo[3098]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 24 21:23:16 volumio.local.local sudo[3091]: pam_unix(sudo:session): session closed for user root Oct 24 21:23:16 volumio.local.local sudo[3098]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:23:16 volumio.local.local sudo[3098]: pam_unix(sudo:session): session closed for user root Oct 24 21:23:24 volumio.local.local volumio[1243]: info: CoreCommandRouter::executeOnPlugin: radio_paradise , handleBrowseUri Oct 24 21:23:24 volumio.local.local volumio[1243]: info: Preload queue cleared Oct 24 21:23:25 volumio.local.local volumio[1243]: info: Preload queue cleared Oct 24 21:23:25 volumio.local.local volumio[1243]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 24 21:23:25 volumio.local.local volumio[1243]: info: CoreStateMachine::ClearQueue Oct 24 21:23:25 volumio.local.local volumio[1243]: info: CoreStateMachine::stop Oct 24 21:23:25 volumio.local.local volumio[1243]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 24 21:23:25 volumio.local.local volumio[1243]: info: CorePlayQueue::clearPlayQueue Oct 24 21:23:25 volumio.local.local volumio[1243]: info: CorePlayQueue::saveQueue Oct 24 21:23:25 volumio.local.local volumio[1243]: info: CoreCommandRouter::volumioPushQueue Oct 24 21:23:25 volumio.local.local volumio[1243]: info: CoreStateMachine::addQueueItems Oct 24 21:23:25 volumio.local.local volumio[1243]: info: CorePlayQueue::addQueueItems Oct 24 21:23:25 volumio.local.local volumio[1243]: info: Preload queue cleared Oct 24 21:23:25 volumio.local.local volumio[1243]: info: Adding Item to queue: webrp/1 Oct 24 21:23:25 volumio.local.local volumio[1243]: info: Exploding uri webrp/1 in service radio_paradise Oct 24 21:23:25 volumio.local.local volumio[1243]: info: CoreCommandRouter::volumioPushQueue Oct 24 21:23:25 volumio.local.local volumio[1243]: info: CorePlayQueue::saveQueue Oct 24 21:23:25 volumio.local.local volumio[1243]: info: CoreStateMachine::updateTrackBlock Oct 24 21:23:25 volumio.local.local volumio[1243]: info: CorePlayQueue::getTrackBlock Oct 24 21:23:25 volumio.local.local volumio[1243]: info: CoreCommandRouter::volumioPlay Oct 24 21:23:25 volumio.local.local volumio[1243]: info: CoreStateMachine::play index 0 Oct 24 21:23:25 volumio.local.local volumio[1243]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 24 21:23:25 volumio.local.local volumio[1243]: info: CoreStateMachine::stop Oct 24 21:23:25 volumio.local.local volumio[1243]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 24 21:23:25 volumio.local.local volumio[1243]: info: CoreStateMachine::play index undefined Oct 24 21:23:25 volumio.local.local volumio[1243]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 24 21:23:25 volumio.local.local volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 24 21:23:25 volumio.local.local volumio[1243]: info: CoreStateMachine::startPlaybackTimer Oct 24 21:23:25 volumio.local.local volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 24 21:23:25 volumio.local.local volumio[1243]: info: CoreCommandRouter::volumioGetVisibleSources Oct 24 21:23:25 volumio.local.local volumio[1243]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 24 21:23:25 volumio.local.local volumio[1243]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 24 21:23:25 volumio.local.local volumio[1243]: verbose: ControllerMpd::sendMpdCommand stop Oct 24 21:23:25 volumio.local.local volumio[1243]: info: sendMpdCommand stop took 5 milliseconds Oct 24 21:23:25 volumio.local.local volumio[1243]: verbose: ControllerMpd::sendMpdCommand clear Oct 24 21:23:25 volumio.local.local volumio[1243]: info: Oct 24 21:23:25 volumio.local.local volumio[1243]: ---------------------------- MPD announces system playlist update Oct 24 21:23:25 volumio.local.local volumio[1243]: info: Ignoring MPD Status Update Oct 24 21:23:25 volumio.local.local volumio[1243]: info: sendMpdCommand clear took 2 milliseconds Oct 24 21:23:25 volumio.local.local volumio[1243]: verbose: ControllerMpd::sendMpdCommand consume 1 Oct 24 21:23:25 volumio.local.local volumio[1243]: error: updateQueue error: null Oct 24 21:23:25 volumio.local.local volumio[1243]: info: Oct 24 21:23:25 volumio.local.local volumio[1243]: ---------------------------- MPD announces state update: options Oct 24 21:23:25 volumio.local.local volumio[1243]: info: ------------------------------ 8ms Oct 24 21:23:25 volumio.local.local volumio[1243]: info: sendMpdCommand consume 1 took 4 milliseconds Oct 24 21:23:25 volumio.local.local volumio[1243]: info: ControllerMpd::getState Oct 24 21:23:25 volumio.local.local volumio[1243]: verbose: ControllerMpd::sendMpdCommand status Oct 24 21:23:25 volumio.local.local volumio[1243]: info: [1761341005231] [RadioParadise] set to consume mode, adding url: https://stream.radioparadise.com/mellow-flac Oct 24 21:23:25 volumio.local.local volumio[1243]: verbose: ControllerMpd::sendMpdCommand add "https://stream.radioparadise.com/mellow-flac" Oct 24 21:23:25 volumio.local.local volumio[1243]: info: Oct 24 21:23:25 volumio.local.local volumio[1243]: ---------------------------- MPD announces system playlist update Oct 24 21:23:25 volumio.local.local volumio[1243]: info: Ignoring MPD Status Update Oct 24 21:23:25 volumio.local.local volumio[1243]: info: sendMpdCommand status took 3 milliseconds Oct 24 21:23:25 volumio.local.local volumio[1243]: info: sendMpdCommand add "https://stream.radioparadise.com/mellow-flac" took 2 milliseconds Oct 24 21:23:25 volumio.local.local volumio[1243]: verbose: ControllerMpd::parseState Oct 24 21:23:25 volumio.local.local volumio[1243]: verbose: ControllerMpd::sendMpdCommand play Oct 24 21:23:25 volumio.local.local volumio[1243]: info: ControllerMpd::pushState Oct 24 21:23:25 volumio.local.local volumio[1243]: info: CoreCommandRouter::servicePushState Oct 24 21:23:25 volumio.local.local volumio[1243]: info: CoreStateMachine::pushState Oct 24 21:23:25 volumio.local.local volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 24 21:23:25 volumio.local.local volumio[1243]: info: CoreCommandRouter::volumioPushState Oct 24 21:23:25 volumio.local.local volumio[1243]: info: MRS: Pushing multiroomSync output update for this device Oct 24 21:23:25 volumio.local.local volumio[1243]: info: MRS: Pushing multiroomSync output Oct 24 21:23:25 volumio.local.local volumio[1243]: info: CoreCommandRouter::volumioGetState Oct 24 21:23:25 volumio.local.local volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 24 21:23:25 volumio.local.local volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 24 21:23:25 volumio.local.local volumio[1243]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Oct 24 21:23:25 volumio.local.local volumio[1243]: info: ------------------------------ 26ms Oct 24 21:23:25 volumio.local.local volumio[1243]: info: ------------------------------ 25ms Oct 24 21:23:25 volumio.local.local volumio[1243]: info: sendMpdCommand play took 20 milliseconds Oct 24 21:23:25 volumio.local.local volumio[1243]: info: [1761341005259] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=1 Oct 24 21:23:25 volumio.local.local volumio[1243]: info: [1761341005877] [RadioParadise] received new metadata: {"time":50,"artist":"Xavier Rudd","title":"Spirit Bird","album":"Spirit Bird","year":"2012","cover":"https://img.radioparadise.com/covers/l/B007RU6JK6.jpg","cover_med":"https://img.radioparadise.com/covers/m/B007RU6JK6.jpg","cover_small":"https://img.radioparadise.com/covers/s/B007RU6JK6.jpg"} Oct 24 21:23:25 volumio.local.local volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 24 21:23:25 volumio.local.local volumio[1243]: info: CoreCommandRouter::servicePushState Oct 24 21:23:25 volumio.local.local volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 24 21:23:25 volumio.local.local volumio[1243]: verbose: STATE SERVICE {"status":"play","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/B007RU6JK6.jpg","uri":"https://stream.radioparadise.com/mellow-flac","name":"Spirit Bird","title":"Spirit Bird","artist":"Xavier Rudd","album":"Spirit Bird","streaming":true,"disableUiControls":true,"duration":55,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Oct 24 21:23:25 volumio.local.local volumio[1243]: verbose: CURRENT POSITION 0 Oct 24 21:23:25 volumio.local.local volumio[1243]: info: CoreStateMachine::syncState stateService play Oct 24 21:23:25 volumio.local.local volumio[1243]: info: CoreStateMachine::syncState currentStatus stop Oct 24 21:23:25 volumio.local.local volumio[1243]: info: [1761341005880] [RadioParadise] setting new timer with duration of 55000 seconds. Oct 24 21:23:26 volumio.local.local sudo[3138]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 24 21:23:26 volumio.local.local sudo[3138]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:23:26 volumio.local.local sudo[3138]: pam_unix(sudo:session): session closed for user root Oct 24 21:23:26 volumio.local.local sudo[3144]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 24 21:23:26 volumio.local.local sudo[3147]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Oct 24 21:23:26 volumio.local.local sudo[3144]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:23:26 volumio.local.local sudo[3147]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:23:26 volumio.local.local sudo[3144]: pam_unix(sudo:session): session closed for user root Oct 24 21:23:26 volumio.local.local sudo[3147]: pam_unix(sudo:session): session closed for user root Oct 24 21:23:26 volumio.local.local volumio[1243]: info: Upmpdcli Daemon Started Oct 24 21:23:26 volumio.local.local volumio[1243]: info: Oct 24 21:23:26 volumio.local.local volumio[1243]: ---------------------------- MPD announces state update: player Oct 24 21:23:26 volumio.local.local volumio[1243]: info: ControllerMpd::getState Oct 24 21:23:26 volumio.local.local volumio[1243]: verbose: ControllerMpd::sendMpdCommand status Oct 24 21:23:26 volumio.local.local volumio[1243]: info: Oct 24 21:23:26 volumio.local.local volumio[1243]: ---------------------------- MPD announces state update: player Oct 24 21:23:26 volumio.local.local volumio[1243]: info: sendMpdCommand status took 2 milliseconds Oct 24 21:23:26 volumio.local.local volumio[1243]: info: ControllerMpd::getState Oct 24 21:23:26 volumio.local.local volumio[1243]: verbose: ControllerMpd::sendMpdCommand status Oct 24 21:23:26 volumio.local.local volumio[1243]: verbose: ControllerMpd::parseState Oct 24 21:23:26 volumio.local.local volumio[1243]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 21:23:26 volumio.local.local volumio[1243]: info: sendMpdCommand status took 2 milliseconds Oct 24 21:23:26 volumio.local.local volumio[1243]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 24 21:23:26 volumio.local.local volumio[1243]: verbose: ControllerMpd::parseState Oct 24 21:23:26 volumio.local.local volumio[1243]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 21:23:26 volumio.local.local volumio[1243]: verbose: ControllerMpd::parseTrackInfo Oct 24 21:23:26 volumio.local.local volumio[1243]: info: ControllerMpd::pushState Oct 24 21:23:26 volumio.local.local volumio[1243]: info: CoreCommandRouter::servicePushState Oct 24 21:23:26 volumio.local.local volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 24 21:23:26 volumio.local.local volumio[1243]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Oct 24 21:23:26 volumio.local.local volumio[1243]: info: ------------------------------ 14ms Oct 24 21:23:26 volumio.local.local volumio[1243]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 24 21:23:26 volumio.local.local volumio[1243]: verbose: ControllerMpd::parseTrackInfo Oct 24 21:23:26 volumio.local.local volumio[1243]: info: ControllerMpd::pushState Oct 24 21:23:26 volumio.local.local volumio[1243]: info: CoreCommandRouter::servicePushState Oct 24 21:23:26 volumio.local.local volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 24 21:23:26 volumio.local.local volumio[1243]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Oct 24 21:23:26 volumio.local.local volumio[1243]: info: ------------------------------ 12ms Oct 24 21:23:26 volumio.local.local volumio[1243]: info: Oct 24 21:23:26 volumio.local.local volumio[1243]: ---------------------------- MPD announces system playlist update Oct 24 21:23:26 volumio.local.local volumio[1243]: info: Ignoring MPD Status Update Oct 24 21:23:26 volumio.local.local volumio[1243]: info: Oct 24 21:23:26 volumio.local.local volumio[1243]: ---------------------------- MPD announces state update: player Oct 24 21:23:26 volumio.local.local volumio[1243]: info: ControllerMpd::getState Oct 24 21:23:26 volumio.local.local volumio[1243]: verbose: ControllerMpd::sendMpdCommand status Oct 24 21:23:26 volumio.local.local volumio[1243]: info: ------------------------------ 5ms Oct 24 21:23:26 volumio.local.local volumio[1243]: info: sendMpdCommand status took 1 milliseconds Oct 24 21:23:26 volumio.local.local volumio[1243]: verbose: ControllerMpd::parseState Oct 24 21:23:26 volumio.local.local volumio[1243]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 21:23:26 volumio.local.local volumio[1243]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 24 21:23:26 volumio.local.local volumio[1243]: verbose: ControllerMpd::parseTrackInfo Oct 24 21:23:26 volumio.local.local volumio[1243]: info: ControllerMpd::pushState Oct 24 21:23:26 volumio.local.local volumio[1243]: info: CoreCommandRouter::servicePushState Oct 24 21:23:26 volumio.local.local volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 24 21:23:26 volumio.local.local volumio[1243]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Oct 24 21:23:26 volumio.local.local volumio[1243]: info: ------------------------------ 8ms Oct 24 21:23:54 volumio.local.local volumio[1243]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 24 21:23:54 volumio.local.local volumio[1243]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 24 21:23:54 volumio.local.local volumio[1243]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 24 21:23:54 volumio.local.local volumio[1243]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 24 21:23:54 volumio.local.local volumio[1243]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Oct 24 21:23:54 volumio.local.local volumio[1243]: info: Received Get System Version Oct 24 21:23:54 volumio.local.local volumio[1243]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 24 21:23:54 volumio.local.local volumio[1243]: info: CoreCommandRouter::executeOnPlugin: appearance , getBackgrounds Oct 24 21:24:00 volumio.local.local volumio[1243]: info: CoreCommandRouter::executeOnPlugin: wizard , openWizard Oct 24 21:24:00 volumio.local.local volumio[1243]: info: CoreCommandRouter::Reload Ui Oct 24 21:24:01 volumio.local.local volumio[1243]: verbose: New Socket.io Connection to 192.168.178.137 from 192.168.178.114 UA: Mozilla/5.0 (Android 15; Mobile; rv:144.0) Gecko/144.0 Firefox/144.0 Engine version: 3 Transport: polling Total Clients: 6 Oct 24 21:24:01 volumio.local.local volumio[1243]: info: CoreCommandRouter::volumioGetState Oct 24 21:24:01 volumio.local.local volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 24 21:24:01 volumio.local.local volumio[1243]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 24 21:24:01 volumio.local.local volumio[1243]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 24 21:24:01 volumio.local.local volumio[1243]: info: Listing playlists Oct 24 21:24:01 volumio.local.local volumio[1243]: info: CoreCommandRouter::volumioGetQueue Oct 24 21:24:01 volumio.local.local volumio[1243]: info: CoreStateMachine::getQueue Oct 24 21:24:01 volumio.local.local volumio[1243]: info: CorePlayQueue::getQueue Oct 24 21:24:01 volumio.local.local volumio[1243]: info: CoreCommandRouter::volumioGetVisibleSources Oct 24 21:24:01 volumio.local.local volumio[1243]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 24 21:24:02 volumio.local.local sudo[3247]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 24 21:24:02 volumio.local.local sudo[3247]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:24:02 volumio.local.local sudo[3251]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 24 21:24:02 volumio.local.local sudo[3247]: pam_unix(sudo:session): session closed for user root Oct 24 21:24:02 volumio.local.local sudo[3251]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:24:02 volumio.local.local sudo[3251]: pam_unix(sudo:session): session closed for user root Oct 24 21:24:02 volumio.local.local volumio[1243]: verbose: New Socket.io Connection to 192.168.178.137 from 192.168.178.67 UA: Mozilla/5.0 (iPad; CPU OS 9_3_5 like Mac OS X) AppleWebKit/601.1.46 (KHTML, like Gecko) Mobile/13G36 Engine version: 3 Transport: polling Total Clients: 7 Oct 24 21:24:02 volumio.local.local volumio[1243]: info: CoreCommandRouter::volumioGetVisibleSources Oct 24 21:24:02 volumio.local.local volumio[1243]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 24 21:24:02 volumio.local.local volumio[1243]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Oct 24 21:24:02 volumio.local.local volumio[1243]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Oct 24 21:24:02 volumio.local.local volumio[1243]: xcb_connection_has_error() returned true Oct 24 21:24:02 volumio.local.local volumio[1243]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Oct 24 21:24:02 volumio.local.local volumio[1243]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Oct 24 21:24:02 volumio.local.local volumio[1243]: info: Received Get System Info Oct 24 21:24:02 volumio.local.local volumio[1243]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 24 21:24:02 volumio.local.local volumio[1243]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 24 21:24:02 volumio.local.local volumio[1243]: info: Discovery: Getting this device information Oct 24 21:24:02 volumio.local.local volumio[1243]: info: CoreCommandRouter::volumioGetState Oct 24 21:24:02 volumio.local.local volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 24 21:24:02 volumio.local.local volumio[1243]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 24 21:24:02 volumio.local.local volumio-remote-updater[551]: No test mode Oct 24 21:24:02 volumio.local.local volumio-remote-updater[551]: No alpha test mode Oct 24 21:24:02 volumio.local.local volumio[1243]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 24 21:24:02 volumio.local.local volumio[1243]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 24 21:24:02 volumio.local.local volumio[1243]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Oct 24 21:24:02 volumio.local.local volumio[1243]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Oct 24 21:24:03 volumio.local.local volumio[1243]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 24 21:24:03 volumio.local.local volumio[1243]: info: Retrieving Cloud Streaming UI Oct 24 21:24:03 volumio.local.local volumio[1243]: info: Getting Tidal Cloud Configuration Oct 24 21:24:03 volumio.local.local volumio[1243]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 24 21:24:03 volumio.local.local volumio[1243]: info: Getting Qobuz Cloud Configuration Oct 24 21:24:03 volumio.local.local volumio[1243]: info: Asking plugin for UI Config Oct 24 21:24:03 volumio.local.local volumio[1243]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 24 21:24:03 volumio.local.local volumio[1243]: info: Getting Spotify Cloud Configuration Oct 24 21:24:03 volumio.local.local volumio[1243]: info: Asking plugin for UI Config Oct 24 21:24:03 volumio.local.local volumio[1243]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 24 21:24:03 volumio.local.local volumio[1243]: info: Saving Spotify Acccount Oct 24 21:24:03 volumio.local.local volumio[1243]: info: Got it Oct 24 21:24:03 volumio.local.local volumio[1243]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Oct 24 21:24:03 volumio.local.local volumio[1243]: info: Got Tidal Cloud Configuration Oct 24 21:24:03 volumio.local.local volumio[1243]: info: Got it Oct 24 21:24:03 volumio.local.local volumio[1243]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 24 21:24:04 volumio.local.local volumio[1243]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Oct 24 21:24:04 volumio.local.local volumio[1243]: info: Loading i18n strings for locale de Oct 24 21:24:04 volumio.local.local volumio[1243]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Oct 24 21:24:04 volumio.local.local volumio[1243]: info: Setting timezone to null Oct 24 21:24:04 volumio.local.local sudo[3271]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Oct 24 21:24:04 volumio.local.local sudo[3271]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:24:04 volumio.local.local sudo[3271]: pam_unix(sudo:session): session closed for user root Oct 24 21:24:04 volumio.local.local sudo[3281]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/null /etc/localtime Oct 24 21:24:04 volumio.local.local sudo[3281]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:24:04 volumio.local.local sudo[3281]: pam_unix(sudo:session): session closed for user root Oct 24 21:24:04 volumio.local.local sudo[3291]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Oct 24 21:24:04 volumio.local.local sudo[3291]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:24:04 volumio.local.local sudo[3291]: pam_unix(sudo:session): session closed for user root Oct 24 21:24:04 volumio.local.local volumio[1243]: /bin/chmod: cannot operate on dangling symlink '/etc/localtime' Oct 24 21:24:04 volumio.local.local volumio[1243]: error: Could not set timezone: Error: Command failed: /usr/bin/sudo /bin/chmod 777 /etc/localtime Oct 24 21:24:04 volumio.local.local volumio[1243]: /bin/chmod: cannot operate on dangling symlink '/etc/localtime' Oct 24 21:24:04 volumio.local.local sudo[3301]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone null Oct 24 21:24:04 volumio.local.local sudo[3301]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:24:04 volumio.local.local dbus-daemon[552]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.47' (uid=0 pid=3306 comm="/usr/bin/timedatectl set-timezone null ") Oct 24 21:24:04 volumio.local.local systemd[1]: Starting Time & Date Service... Oct 24 21:24:04 volumio.local.local systemd-timedated[3307]: /etc/localtime should be a symbolic link to a time zone data file in /usr/share/zoneinfo/. Oct 24 21:24:04 volumio.local.local dbus-daemon[552]: [system] Successfully activated service 'org.freedesktop.timedate1' Oct 24 21:24:04 volumio.local.local systemd[1]: Started Time & Date Service. Oct 24 21:24:04 volumio.local.local sudo[3301]: pam_unix(sudo:session): session closed for user root Oct 24 21:24:04 volumio.local.local volumio[1243]: Failed to set time zone: Invalid time zone 'null' Oct 24 21:24:04 volumio.local.local volumio[1243]: info: Could not set timezone, retrying Oct 24 21:24:04 volumio.local.local volumio[1243]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 24 21:24:04 volumio.local.local volumio[1243]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 24 21:24:04 volumio.local.local volumio[1243]: Updating browse sources language Oct 24 21:24:04 volumio.local.local volumio[1243]: Cannot find translation for source YouTube2 Oct 24 21:24:04 volumio.local.local volumio[1243]: Cannot find translation for source YouTube Music Oct 24 21:24:04 volumio.local.local volumio[1243]: Cannot find translation for source 80s80s Radio Oct 24 21:24:04 volumio.local.local volumio[1243]: Cannot find translation for source Radio Paradise Oct 24 21:24:04 volumio.local.local volumio[1243]: Cannot find translation for source QOBUZ Oct 24 21:24:04 volumio.local.local volumio[1243]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 24 21:24:04 volumio.local.local volumio[1243]: Cannot find translation for source YouTube2 Oct 24 21:24:04 volumio.local.local volumio[1243]: Cannot find translation for source YouTube Music Oct 24 21:24:04 volumio.local.local volumio[1243]: Cannot find translation for source 80s80s Radio Oct 24 21:24:04 volumio.local.local volumio[1243]: Cannot find translation for source Radio Paradise Oct 24 21:24:04 volumio.local.local volumio[1243]: Cannot find translation for source QOBUZ Oct 24 21:24:04 volumio.local.local volumio[1243]: info: Fetching Streaming Services browse cache Oct 24 21:24:05 volumio.local.local sudo[3314]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 24 21:24:05 volumio.local.local sudo[3317]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 24 21:24:05 volumio.local.local sudo[3314]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:24:05 volumio.local.local sudo[3317]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:24:05 volumio.local.local sudo[3314]: pam_unix(sudo:session): session closed for user root Oct 24 21:24:05 volumio.local.local sudo[3317]: pam_unix(sudo:session): session closed for user root Oct 24 21:24:05 volumio.local.local volumio[1243]: verbose: New Socket.io Connection to 192.168.178.137 from 192.168.178.114 UA: Mozilla/5.0 (Android 15; Mobile; rv:144.0) Gecko/144.0 Firefox/144.0 Engine version: 3 Transport: polling Total Clients: 7 Oct 24 21:24:05 volumio.local.local volumio[1243]: info: CoreCommandRouter::volumioGetVisibleSources Oct 24 21:24:05 volumio.local.local volumio[1243]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 24 21:24:05 volumio.local.local volumio[1243]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Oct 24 21:24:05 volumio.local.local volumio[1243]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Oct 24 21:24:05 volumio.local.local volumio[1243]: xcb_connection_has_error() returned true Oct 24 21:24:05 volumio.local.local volumio[1243]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Oct 24 21:24:05 volumio.local.local volumio[1243]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Oct 24 21:24:05 volumio.local.local volumio[1243]: info: Received Get System Info Oct 24 21:24:05 volumio.local.local volumio[1243]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 24 21:24:05 volumio.local.local volumio[1243]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 24 21:24:05 volumio.local.local volumio[1243]: info: Discovery: Getting this device information Oct 24 21:24:05 volumio.local.local volumio[1243]: info: CoreCommandRouter::volumioGetState Oct 24 21:24:05 volumio.local.local volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 24 21:24:05 volumio.local.local volumio[1243]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 24 21:24:05 volumio.local.local volumio-remote-updater[551]: No test mode Oct 24 21:24:05 volumio.local.local volumio-remote-updater[551]: No alpha test mode Oct 24 21:24:05 volumio.local.local volumio[1243]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 24 21:24:05 volumio.local.local volumio[1243]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 24 21:24:05 volumio.local.local volumio[1243]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 24 21:24:05 volumio.local.local volumio[1243]: info: Retrieving Cloud Streaming UI Oct 24 21:24:05 volumio.local.local volumio[1243]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Oct 24 21:24:05 volumio.local.local volumio[1243]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Oct 24 21:24:05 volumio.local.local volumio[1243]: info: Getting Tidal Cloud Configuration Oct 24 21:24:05 volumio.local.local volumio[1243]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 24 21:24:05 volumio.local.local volumio[1243]: info: Getting Qobuz Cloud Configuration Oct 24 21:24:05 volumio.local.local volumio[1243]: info: Asking plugin for UI Config Oct 24 21:24:05 volumio.local.local volumio[1243]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 24 21:24:05 volumio.local.local volumio[1243]: info: Getting Spotify Cloud Configuration Oct 24 21:24:05 volumio.local.local volumio[1243]: info: Asking plugin for UI Config Oct 24 21:24:05 volumio.local.local volumio[1243]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 24 21:24:05 volumio.local.local volumio[1243]: info: Saving Spotify Acccount Oct 24 21:24:05 volumio.local.local volumio[1243]: info: Got it Oct 24 21:24:05 volumio.local.local volumio[1243]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Oct 24 21:24:05 volumio.local.local volumio[1243]: info: Got Tidal Cloud Configuration Oct 24 21:24:05 volumio.local.local volumio[1243]: info: Got it Oct 24 21:24:05 volumio.local.local volumio[1243]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 24 21:24:05 volumio.local.local sudo[3338]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone null Oct 24 21:24:05 volumio.local.local sudo[3338]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:24:05 volumio.local.local sudo[3338]: pam_unix(sudo:session): session closed for user root Oct 24 21:24:05 volumio.local.local volumio[1243]: Failed to set time zone: Invalid time zone 'null' Oct 24 21:24:05 volumio.local.local volumio[1243]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 24 21:24:05 volumio.local.local volumio[1243]: Error: Command failed: /usr/bin/sudo /usr/bin/timedatectl set-timezone 'null' Oct 24 21:24:05 volumio.local.local volumio[1243]: Failed to set time zone: Invalid time zone 'null' Oct 24 21:24:05 volumio.local.local volumio[1243]: at checkExecSyncError (child_process.js:790:11) Oct 24 21:24:05 volumio.local.local volumio[1243]: at execSync (child_process.js:863:15) Oct 24 21:24:05 volumio.local.local volumio[1243]: at Timeout._onTimeout (/volumio/app/plugins/system_controller/system/index.js:1680:9) Oct 24 21:24:05 volumio.local.local volumio[1243]: at listOnTimeout (internal/timers.js:557:17) Oct 24 21:24:05 volumio.local.local volumio[1243]: at processTimers (internal/timers.js:500:7) { Oct 24 21:24:05 volumio.local.local volumio[1243]: status: 1, Oct 24 21:24:05 volumio.local.local volumio[1243]: signal: null, Oct 24 21:24:05 volumio.local.local volumio[1243]: output: [ null, '', "Failed to set time zone: Invalid time zone 'null'\n" ], Oct 24 21:24:05 volumio.local.local volumio[1243]: pid: 3337, Oct 24 21:24:05 volumio.local.local volumio[1243]: stdout: '', Oct 24 21:24:05 volumio.local.local volumio[1243]: stderr: "Failed to set time zone: Invalid time zone 'null'\n" Oct 24 21:24:05 volumio.local.local volumio[1243]: } Oct 24 21:24:05 volumio.local.local volumio[1243]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 24 21:24:05 volumio.local.local sudo[3356]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-10-24 21:23 Oct 24 21:24:05 volumio.local.local sudo[3356]: 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="669bb6cc406bb77b11c5184fedb8a4dfd19ba4ec" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:55:15 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="tinkerboard" VOLUMIO_DEVICENAME="Asus Tinkerboard" VOLUMIO_HASH="2f3686ade78abf3b92c180a0c5b20c6b"