-- Logs begin at Fri 2025-10-24 21:19:13 UTC, end at Fri 2025-10-24 21:24:18 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) Oct 24 21:24:05 volumio.local.local sudo[3356]: pam_unix(sudo:session): session closed for user root Oct 24 21:24:06 volumio.local.local volumio-remote-updater[551]: [2025-10-24 21:24:06] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Oct 24 21:24:06 volumio.local.local volumio-remote-updater[551]: [2025-10-24 21:24:06] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Oct 24 21:24:06 volumio.local.local systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Oct 24 21:24:06 volumio.local.local qobuz-connect[1958]: 20251024 21:24:06.029 [1958.1958] INFO SampleApp: Socket closed Oct 24 21:24:06 volumio.local.local systemd[1]: media-Volume.mount: Succeeded. Oct 24 21:24:06 volumio.local.local systemd[1]: upmpdcli.service: Succeeded. Oct 24 21:24:06 volumio.local.local ntfs-3g[1371]: Unmounting /dev/sda1 (Volume) Oct 24 21:24:06 volumio.local.local systemd[1]: volumio.service: Failed with result 'exit-code'. Oct 24 21:24:06 volumio.local.local systemd[1]: Started dynamicswap service. Oct 24 21:24:06 volumio.local.local systemd[1]: dynamicswap.service: Succeeded. Oct 24 21:24:06 volumio.local.local systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Oct 24 21:24:06 volumio.local.local systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Oct 24 21:24:06 volumio.local.local systemd[1]: Stopped Volumio Backend Module. Oct 24 21:24:06 volumio.local.local systemd[1]: Started Volumio Backend Module. Oct 24 21:24:06 volumio.local.local systemd[1]: Started dynamicswap service. Oct 24 21:24:06 volumio.local.local systemd[1]: dynamicswap.service: Succeeded. Oct 24 21:24:07 volumio.local.local volumio[3378]: info: ------------------------------------------- Oct 24 21:24:07 volumio.local.local volumio[3378]: info: ----- Volumio3 ---- Oct 24 21:24:07 volumio.local.local volumio[3378]: info: ------------------------------------------- Oct 24 21:24:07 volumio.local.local volumio[3378]: info: ----- System startup ---- Oct 24 21:24:07 volumio.local.local volumio[3378]: info: ------------------------------------------- Oct 24 21:24:07 volumio.local.local kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x00 driverbyte=0x08 Oct 24 21:24:07 volumio.local.local kernel: sd 0:0:0:0: [sda] tag#0 Sense Key : 0x3 [current] Oct 24 21:24:07 volumio.local.local kernel: sd 0:0:0:0: [sda] tag#0 ASC=0x11 ASCQ=0x0 Oct 24 21:24:07 volumio.local.local kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 00 00 0a 08 00 00 f0 00 Oct 24 21:24:07 volumio.local.local kernel: blk_update_request: critical medium error, dev sda, sector 2568 Oct 24 21:24:07 volumio.local.local volumio[3378]: info: MYVOLUMIO Environment detected Oct 24 21:24:07 volumio.local.local volumio[3378]: info: Plugin folders cleanup Oct 24 21:24:07 volumio.local.local volumio[3378]: info: Scanning into folder /volumio/app/plugins/ Oct 24 21:24:07 volumio.local.local volumio[3378]: info: Scanning category audio_interface Oct 24 21:24:07 volumio.local.local volumio[3378]: info: Scanning category miscellanea Oct 24 21:24:07 volumio.local.local volumio[3378]: info: Scanning category music_service Oct 24 21:24:07 volumio.local.local volumio[3378]: info: Scanning category plugins.json Oct 24 21:24:07 volumio.local.local volumio[3378]: info: Scanning category system_controller Oct 24 21:24:07 volumio.local.local volumio[3378]: info: Scanning category user_interface Oct 24 21:24:07 volumio.local.local volumio[3378]: info: Scanning into folder /data/plugins/ Oct 24 21:24:07 volumio.local.local volumio[3378]: info: Scanning category music_service Oct 24 21:24:07 volumio.local.local volumio[3378]: info: Scanning category system_controller Oct 24 21:24:07 volumio.local.local volumio[3378]: info: Scanning category user_interface Oct 24 21:24:07 volumio.local.local volumio[3378]: info: Plugin folders cleanup completed Oct 24 21:24:07 volumio.local.local volumio[3378]: info: ------------------------------------------- Oct 24 21:24:07 volumio.local.local volumio[3378]: info: ----- Core plugins startup ---- Oct 24 21:24:07 volumio.local.local volumio[3378]: info: ------------------------------------------- Oct 24 21:24:07 volumio.local.local volumio[3378]: info: Loading plugins from folder /volumio/app/plugins/ Oct 24 21:24:07 volumio.local.local volumio[3378]: info: Adding plugin upnp to MyMusic Plugins Oct 24 21:24:07 volumio.local.local volumio[3378]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 24 21:24:07 volumio.local.local volumio[3378]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 24 21:24:07 volumio.local.local volumio[3378]: info: Loading plugins from folder /data/plugins/ Oct 24 21:24:07 volumio.local.local volumio[3378]: info: Loading plugin "system"... Oct 24 21:24:07 volumio.local.local volumio[3378]: info: Loading plugin "ampswitch"... Oct 24 21:24:07 volumio.local.local volumio[3378]: info: Applying required configuration parameters for plugin ampswitch Oct 24 21:24:07 volumio.local.local volumio[3378]: info: Loading plugin "appearance"... Oct 24 21:24:08 volumio.local.local volumio[3378]: info: Loading plugin "network"... Oct 24 21:24:08 volumio.local.local volumio[3378]: info: Refreshing Cached IP Addresses Oct 24 21:24:08 volumio.local.local sudo[3406]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 24 21:24:08 volumio.local.local sudo[3408]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 24 21:24:08 volumio.local.local volumio[3378]: info: Loading plugin "services"... Oct 24 21:24:08 volumio.local.local volumio[3378]: info: Loading plugin "alsa_controller"... Oct 24 21:24:08 volumio.local.local sudo[3406]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:24:08 volumio.local.local sudo[3408]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:24:08 volumio.local.local sudo[3408]: pam_unix(sudo:session): session closed for user root Oct 24 21:24:08 volumio.local.local sudo[3421]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 24 21:24:08 volumio.local.local sudo[3406]: pam_unix(sudo:session): session closed for user root Oct 24 21:24:08 volumio.local.local sudo[3421]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:24:08 volumio.local.local volumio[3378]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 24 21:24:08 volumio.local.local volumio[3378]: info: Loading plugin "wizard"... Oct 24 21:24:08 volumio.local.local volumio[3378]: info: Loading plugin "networkfs"... Oct 24 21:24:08 volumio.local.local volumio[3378]: info: Starting Udev Watcher for removable devices Oct 24 21:24:08 volumio.local.local volumio[3378]: info: Ignoring mount for partition: BOOT Oct 24 21:24:08 volumio.local.local volumio[3378]: info: Ignoring mount for partition: volumio Oct 24 21:24:08 volumio.local.local volumio[3378]: info: Ignoring mount for partition: volumio_data Oct 24 21:24:08 volumio.local.local volumio[3378]: info: Mounting Device Volume Oct 24 21:24:08 volumio.local.local sudo[3460]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/Volume -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Oct 24 21:24:08 volumio.local.local sudo[3460]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:24:08 volumio.local.local sudo[3421]: pam_unix(sudo:session): session closed for user root Oct 24 21:24:08 volumio.local.local kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x00 driverbyte=0x08 Oct 24 21:24:08 volumio.local.local kernel: sd 0:0:0:0: [sda] tag#0 Sense Key : 0x3 [current] Oct 24 21:24:08 volumio.local.local kernel: sd 0:0:0:0: [sda] tag#0 ASC=0x11 ASCQ=0x0 Oct 24 21:24:08 volumio.local.local kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 00 00 0a e0 00 00 08 00 Oct 24 21:24:08 volumio.local.local kernel: blk_update_request: critical medium error, dev sda, sector 2784 Oct 24 21:24:08 volumio.local.local kernel: Buffer I/O error on dev sda1, logical block 92, async page read Oct 24 21:24:10 volumio.local.local kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x00 driverbyte=0x08 Oct 24 21:24:10 volumio.local.local kernel: sd 0:0:0:0: [sda] tag#0 Sense Key : 0x3 [current] Oct 24 21:24:10 volumio.local.local kernel: sd 0:0:0:0: [sda] tag#0 ASC=0x11 ASCQ=0x0 Oct 24 21:24:10 volumio.local.local kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 00 00 0a e0 00 00 08 00 Oct 24 21:24:10 volumio.local.local kernel: blk_update_request: critical medium error, dev sda, sector 2784 Oct 24 21:24:10 volumio.local.local kernel: Buffer I/O error on dev sda1, logical block 92, async page read Oct 24 21:24:10 volumio.local.local ntfs-3g[3470]: Version 2017.3.23AR.3 integrated FUSE 28 Oct 24 21:24:10 volumio.local.local ntfs-3g[3470]: Mounted /dev/sda1 (Read-Write, label "Volume", NTFS 3.1) Oct 24 21:24:10 volumio.local.local ntfs-3g[3470]: Cmdline options: rw,noatime,dmask=0000,fmask=0000,iocharset=utf8 Oct 24 21:24:10 volumio.local.local ntfs-3g[3470]: Mount options: iocharset=utf8,allow_other,nonempty,noatime,rw,default_permissions,fsname=/dev/sda1,blkdev,blksize=4096 Oct 24 21:24:10 volumio.local.local ntfs-3g[3470]: Global ownership and permissions enforced, configuration type 7 Oct 24 21:24:10 volumio.local.local sudo[3460]: pam_unix(sudo:session): session closed for user root Oct 24 21:24:10 volumio.local.local volumio[3378]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 24 21:24:10 volumio.local.local volumio[3378]: info: Loading plugin "volumio_command_line_client"... Oct 24 21:24:10 volumio.local.local volumio[3378]: info: Loading plugin "upnp"... Oct 24 21:24:10 volumio.local.local volumio[3378]: info: [1761341050751] Starting Upmpd Daemon Oct 24 21:24:10 volumio.local.local volumio[3378]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 24 21:24:10 volumio.local.local volumio[3378]: info: Loading plugin "my_music"... Oct 24 21:24:10 volumio.local.local volumio[3378]: info: Loading plugin "mpd"... Oct 24 21:24:10 volumio.local.local volumio[3378]: info: Loading plugin "upnp_browser"... Oct 24 21:24:11 volumio.local.local volumio-remote-updater[551]: [2025-10-24 21:24:11] [connect] Successful connection Oct 24 21:24:11 volumio.local.local volumio[3378]: info: Loading plugin "alarm-clock"... Oct 24 21:24:11 volumio.local.local volumio[3378]: info: Loading plugin "airplay_emulation"... Oct 24 21:24:11 volumio.local.local volumio[3378]: info: Starting Shairport Sync Oct 24 21:24:11 volumio.local.local volumio[3378]: info: Loading plugin "last_100"... Oct 24 21:24:11 volumio.local.local volumio[3378]: info: Loading plugin "webradio"... Oct 24 21:24:11 volumio.local.local volumio[3378]: info: Loading plugin "i2s_dacs"... Oct 24 21:24:11 volumio.local.local volumio[3378]: info: I2S DAC not set, start Auto-detection Oct 24 21:24:11 volumio.local.local volumio[3378]: info: Loading plugin "volumiodiscovery"... Oct 24 21:24:11 volumio.local.local volumio[3378]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 24 21:24:11 volumio.local.local volumio[3378]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 24 21:24:11 volumio.local.local node[3378]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 24 21:24:11 volumio.local.local volumio[3378]: *** WARNING *** For more information see Oct 24 21:24:11 volumio.local.local volumio[3378]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 24 21:24:11 volumio.local.local volumio[3378]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 24 21:24:11 volumio.local.local volumio[3378]: *** WARNING *** For more information see Oct 24 21:24:11 volumio.local.local node[3378]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 24 21:24:11 volumio.local.local node[3378]: *** WARNING *** For more information see Oct 24 21:24:11 volumio.local.local node[3378]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 24 21:24:11 volumio.local.local node[3378]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 24 21:24:11 volumio.local.local node[3378]: *** WARNING *** For more information see Oct 24 21:24:11 volumio.local.local volumio[3378]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 24 21:24:11 volumio.local.local volumio[3378]: info: Discovery: Started advertising with name: Volumio.local.local Oct 24 21:24:11 volumio.local.local volumio[3378]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 24 21:24:11 volumio.local.local volumio[3378]: info: Loading plugin "youtube2"... Oct 24 21:24:12 volumio.local.local kernel: EXT4-fs (mmcblk1p3): error count since last fsck: 7165 Oct 24 21:24:12 volumio.local.local kernel: EXT4-fs (mmcblk1p3): initial error at time 1690737402: ext4_free_inode:371 Oct 24 21:24:12 volumio.local.local kernel: EXT4-fs (mmcblk1p3): last error at time 1742157839: ext4_free_inode:383 Oct 24 21:24:12 volumio.local.local volumio[3378]: info: Loading plugin "ytcr"... Oct 24 21:24:14 volumio.local.local volumio[3378]: info: Loading plugin "ytmusic"... Oct 24 21:24:15 volumio.local.local volumio[3378]: info: Loading plugin "outputs"... Oct 24 21:24:15 volumio.local.local volumio[3378]: info: Loading plugin "albumart"... Oct 24 21:24:15 volumio.local.local volumio[3378]: info: Plugin example_plugin is not enabled Oct 24 21:24:15 volumio.local.local volumio[3378]: info: Loading plugin "inputs"... Oct 24 21:24:15 volumio.local.local volumio[3378]: info: Loading plugin "updater_comm"... Oct 24 21:24:15 volumio.local.local volumio[3378]: info: Plugin mpdemulation is not enabled Oct 24 21:24:15 volumio.local.local volumio[3378]: info: Loading plugin "rest_api"... Oct 24 21:24:15 volumio.local.local volumio[3378]: info: Loading plugin "websocket"... Oct 24 21:24:15 volumio.local.local volumio[3378]: info: Starting Socket.io Server version 2.3.0 Oct 24 21:24:15 volumio.local.local volumio[3378]: info: Loading plugin "80s80s"... Oct 24 21:24:15 volumio.local.local volumio[3378]: Forking 3 albumart workers Oct 24 21:24:15 volumio.local.local volumio[3378]: info: Applying required configuration parameters for plugin 80s80s Oct 24 21:24:15 volumio.local.local volumio[3378]: info: [1761341055888] [80s80s] API delay: 30 Oct 24 21:24:15 volumio.local.local volumio[3378]: info: Loading plugin "RoonBridge"... Oct 24 21:24:16 volumio.local.local volumio[3378]: info: Applying required configuration parameters for plugin RoonBridge Oct 24 21:24:16 volumio.local.local volumio[3378]: info: Loading plugin "radio_paradise"... Oct 24 21:24:16 volumio.local.local volumio[3378]: info: Applying required configuration parameters for plugin radio_paradise Oct 24 21:24:16 volumio.local.local volumio[3378]: info: [1761341056208] [RadioParadise] API delay: 5 Oct 24 21:24:16 volumio.local.local volumio[3378]: info: Plugin randomizer is not enabled Oct 24 21:24:16 volumio.local.local volumio[3378]: info: Loading i18n strings for locale de Oct 24 21:24:16 volumio.local.local volumio[3378]: Updating browse sources language Oct 24 21:24:16 volumio.local.local volumio[3378]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 24 21:24:16 volumio.local.local volumio[3378]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 24 21:24:16 volumio.local.local volumio[3378]: info: CoreCommandRouter::initPlayerControls Oct 24 21:24:16 volumio.local.local volumio[3378]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 21:24:16 volumio.local.local volumio[3378]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 21:24:16 volumio.local.local volumio[3378]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 21:24:16 volumio.local.local volumio[3378]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 21:24:16 volumio.local.local volumio[3378]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 21:24:16 volumio.local.local volumio[3378]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 24 21:24:16 volumio.local.local volumio[3378]: Express server listening on port 3000 Oct 24 21:24:16 volumio.local.local volumio[3378]: [Metrics] WebUI: 9s 475.83ms Oct 24 21:24:16 volumio.local.local volumio[3378]: info: CoreStateMachine::resetVolumioState Oct 24 21:24:16 volumio.local.local volumio[3378]: info: CoreStateMachine::getcurrentVolume Oct 24 21:24:16 volumio.local.local volumio[3378]: info: CoreCommandRouter::volumioRetrievevolume Oct 24 21:24:16 volumio.local.local volumio[3378]: Starting albumart workers Oct 24 21:24:16 volumio.local.local volumio[3378]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 24 21:24:16 volumio.local.local volumio[3378]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Oct 24 21:24:16 volumio.local.local volumio[3378]: info: Completed loading Core Plugins Oct 24 21:24:16 volumio.local.local volumio[3378]: info: Preparing to generate the ALSA configuration file Oct 24 21:24:16 volumio.local.local volumio[3378]: Starting albumart workers Oct 24 21:24:16 volumio.local.local volumio[3378]: info: Volumio Network Manager: Network status updated: 3 Oct 24 21:24:16 volumio.local.local volumio[3378]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 24 21:24:16 volumio.local.local volumio[3378]: info: Reading ALSA contributions from plugins. Oct 24 21:24:16 volumio.local.local volumio[3378]: 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: 1 Oct 24 21:24:16 volumio.local.local volumio[3378]: 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: 2 Oct 24 21:24:16 volumio.local.local volumio[3378]: Starting albumart workers Oct 24 21:24:16 volumio.local.local volumio[3378]: 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: 3 Oct 24 21:24:16 volumio.local.local volumio[3378]: verbose: New Socket.io Connection to 192.168.178.57 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: 4 Oct 24 21:24:16 volumio.local.local volumio[3378]: 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: 5 Oct 24 21:24:16 volumio.local.local volumio[3378]: 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:16 volumio.local.local volumio[3378]: 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:16 volumio.local.local volumio[3378]: verbose: New Socket.io Connection to 192.168.178.57 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: 8 Oct 24 21:24:16 volumio.local.local volumio[3378]: 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: 9 Oct 24 21:24:16 volumio.local.local volumio[3378]: 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: 10 Oct 24 21:24:16 volumio.local.local volumio[3378]: 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: 11 Oct 24 21:24:16 volumio.local.local volumio[3378]: verbose: New Socket.io Connection to 192.168.178.57 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: 12 Oct 24 21:24:16 volumio.local.local volumio[3378]: 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: 13 Oct 24 21:24:16 volumio.local.local volumio[3378]: 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: 14 Oct 24 21:24:16 volumio.local.local volumio[3378]: 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: 15 Oct 24 21:24:16 volumio.local.local volumio[3378]: verbose: New Socket.io Connection to 192.168.178.57 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: 16 Oct 24 21:24:16 volumio.local.local volumio[3378]: 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: 17 Oct 24 21:24:16 volumio.local.local volumio[3378]: 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: 18 Oct 24 21:24:16 volumio.local.local volumio[3378]: 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: 19 Oct 24 21:24:16 volumio.local.local volumio[3378]: verbose: New Socket.io Connection to 192.168.178.57 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: 20 Oct 24 21:24:16 volumio.local.local volumio[3378]: 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: 21 Oct 24 21:24:16 volumio.local.local volumio[3378]: 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: 22 Oct 24 21:24:16 volumio.local.local volumio[3378]: 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: 23 Oct 24 21:24:16 volumio.local.local volumio[3378]: verbose: New Socket.io Connection to 192.168.178.57 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: 24 Oct 24 21:24:16 volumio.local.local volumio[3378]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Mixer hw:0 load error: No such device or address Oct 24 21:24:16 volumio.local.local volumio[3378]: 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: 25 Oct 24 21:24:16 volumio.local.local volumio[3378]: 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: 26 Oct 24 21:24:16 volumio.local.local volumio[3378]: 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: 27 Oct 24 21:24:16 volumio.local.local volumio[3378]: verbose: New Socket.io Connection to 192.168.178.57 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: 28 Oct 24 21:24:16 volumio.local.local volumio[3378]: 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: 29 Oct 24 21:24:16 volumio.local.local volumio[3378]: 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: 30 Oct 24 21:24:16 volumio.local.local volumio[3378]: 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: 31 Oct 24 21:24:16 volumio.local.local volumio[3378]: verbose: New Socket.io Connection to 192.168.178.57 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: 32 Oct 24 21:24:16 volumio.local.local volumio[3378]: 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: 33 Oct 24 21:24:16 volumio.local.local volumio[3378]: 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: 34 Oct 24 21:24:16 volumio.local.local volumio[3378]: verbose: New Socket.io Connection to 192.168.178.57 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: 35 Oct 24 21:24:16 volumio.local.local volumio[3378]: 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: 36 Oct 24 21:24:16 volumio.local.local volumio[3378]: 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: 37 Oct 24 21:24:16 volumio.local.local volumio[3378]: 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: 38 Oct 24 21:24:16 volumio.local.local volumio[3378]: 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: 39 Oct 24 21:24:16 volumio.local.local volumio[3378]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 40 Oct 24 21:24:16 volumio.local.local volumio[3378]: info: Reloading queue from file Oct 24 21:24:16 volumio.local.local volumio[3378]: info: VolumeController:: Volume=undefined Mute =false Oct 24 21:24:16 volumio.local.local volumio[3378]: info: CoreStateMachine::pushState Oct 24 21:24:16 volumio.local.local volumio[3378]: info: CorePlayQueue::getTrack 0 Oct 24 21:24:16 volumio.local.local volumio[3378]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 21:24:16 volumio.local.local volumio[3378]: info: CoreCommandRouter::volumioPushState Oct 24 21:24:16 volumio.local.local volumio[3378]: info: CoreStateMachine::updateTrackBlock Oct 24 21:24:16 volumio.local.local volumio[3378]: info: CorePlayQueue::getTrackBlock Oct 24 21:24:16 volumio.local.local volumio[3378]: info: CoreCommandRouter::volumioRetrievevolume Oct 24 21:24:16 volumio.local.local volumio[3378]: info: CoreStateMachine::setRepeat true single undefined Oct 24 21:24:16 volumio.local.local volumio[3378]: info: CoreStateMachine::pushState Oct 24 21:24:16 volumio.local.local volumio[3378]: info: CorePlayQueue::getTrack 0 Oct 24 21:24:16 volumio.local.local volumio[3378]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 21:24:16 volumio.local.local volumio[3378]: info: CoreCommandRouter::volumioPushState Oct 24 21:24:16 volumio.local.local volumio[3378]: info: CoreStateMachine::setRandom true Oct 24 21:24:16 volumio.local.local volumio[3378]: info: CoreStateMachine::pushState Oct 24 21:24:16 volumio.local.local volumio[3378]: info: CorePlayQueue::getTrack 0 Oct 24 21:24:16 volumio.local.local volumio[3378]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 21:24:16 volumio.local.local volumio[3378]: info: CoreCommandRouter::volumioPushState Oct 24 21:24:16 volumio.local.local volumio[3378]: info: Setting Device type: Tinkerboard Oct 24 21:24:16 volumio.local.local volumio[3378]: info: CoreCommandRouter::volumioGetVisibleSources Oct 24 21:24:16 volumio.local.local volumio[3378]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 24 21:24:16 volumio.local.local volumio[3378]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Mixer hw:0 load error: No such device or address Oct 24 21:24:17 volumio.local.local volumio[3378]: info: Discovery: adding 99c6faf8-7221-47c3-a127-4993511b63e0 Oct 24 21:24:17 volumio.local.local volumio[3378]: info: Discovery: Found device Volumio.local.local Oct 24 21:24:17 volumio.local.local volumio[3378]: info: CoreCommandRouter::volumioGetState Oct 24 21:24:17 volumio.local.local volumio[3378]: info: CorePlayQueue::getTrack 0 Oct 24 21:24:17 volumio.local.local volumio[3378]: info: CoreCommandRouter::volumioGetVisibleSources Oct 24 21:24:17 volumio.local.local volumio[3378]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 24 21:24:17 volumio.local.local volumio[3378]: info: VolumeController:: Volume=undefined Mute =false Oct 24 21:24:17 volumio.local.local volumio[3378]: info: CoreStateMachine::pushState Oct 24 21:24:17 volumio.local.local volumio[3378]: info: CorePlayQueue::getTrack 0 Oct 24 21:24:17 volumio.local.local volumio[3378]: info: CoreCommandRouter::volumioPushState Oct 24 21:24:17 volumio.local.local volumio[3378]: info: Discovery: this is already registered, 99c6faf8-7221-47c3-a127-4993511b63e0 Oct 24 21:24:17 volumio.local.local volumio[3378]: info: Discovery: Found device Volumio.local.local Oct 24 21:24:17 volumio.local.local volumio[3378]: info: CoreCommandRouter::volumioGetState Oct 24 21:24:17 volumio.local.local volumio[3378]: info: CorePlayQueue::getTrack 0 Oct 24 21:24:17 volumio.local.local volumio[3378]: info: Asound.conf file unchanged, so no further update is needed Oct 24 21:24:17 volumio.local.local volumio[3378]: info: Output device has changed, restarting MPD Oct 24 21:24:17 volumio.local.local volumio[3378]: info: Output device has changed, restarting Shairport Sync Oct 24 21:24:17 volumio.local.local sudo[3580]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 24 21:24:17 volumio.local.local volumio[3378]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 21:24:17 volumio.local.local volumio[3378]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 24 21:24:17 volumio.local.local sudo[3580]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:24:17 volumio.local.local sudo[3583]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 24 21:24:17 volumio.local.local sudo[3580]: pam_unix(sudo:session): session closed for user root Oct 24 21:24:17 volumio.local.local sudo[3583]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:24:17 volumio.local.local volumio[3378]: info: ___________ START PLUGINS ___________ Oct 24 21:24:17 volumio.local.local systemd[1]: Stopping Music Player Daemon... Oct 24 21:24:17 volumio.local.local volumio[3378]: info: ControllerMpd::onStart: Initializing MPD Oct 24 21:24:17 volumio.local.local volumio[3378]: info: Creating MPD Configuration file Oct 24 21:24:17 volumio.local.local kernel: rockchip-pinctrl pinctrl: pin 0 is unrouted Oct 24 21:24:17 volumio.local.local volumio[3378]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 24 21:24:17 volumio.local.local volumio[3378]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 24 21:24:17 volumio.local.local volumio[3378]: info: [1761341057202] CoreMusicLibrary::Adding element Medienserver Oct 24 21:24:17 volumio.local.local volumio[3378]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 24 21:24:17 volumio.local.local sudo[3605]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 24 21:24:17 volumio.local.local sudo[3608]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 24 21:24:17 volumio.local.local sudo[3605]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:24:17 volumio.local.local volumio[3378]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 21:24:17 volumio.local.local volumio[3378]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 24 21:24:17 volumio.local.local sudo[3605]: pam_unix(sudo:session): session closed for user root Oct 24 21:24:17 volumio.local.local sudo[3608]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:24:17 volumio.local.local systemd[1]: mpd.service: Succeeded. Oct 24 21:24:17 volumio.local.local systemd[1]: Stopped Music Player Daemon. Oct 24 21:24:17 volumio.local.local systemd[1]: Starting Music Player Daemon... Oct 24 21:24:17 volumio.local.local volumio[3378]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 21:24:17 volumio.local.local volumio[3378]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 24 21:24:17 volumio.local.local volumio[3378]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 24 21:24:17 volumio.local.local volumio[3378]: info: [1761341057293] CoreMusicLibrary::Adding element Last_100 Oct 24 21:24:17 volumio.local.local volumio[3378]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 24 21:24:17 volumio.local.local volumio[3378]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 24 21:24:17 volumio.local.local volumio[3378]: info: [1761341057296] CoreMusicLibrary::Adding element Webradio Oct 24 21:24:17 volumio.local.local volumio[3378]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 24 21:24:17 volumio.local.local volumio[3378]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 24 21:24:17 volumio.local.local volumio[3378]: info: Initializing BBC Radios Oct 24 21:24:17 volumio.local.local sudo[3624]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 24 21:24:17 volumio.local.local sudo[3624]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:24:17 volumio.local.local sudo[3624]: pam_unix(sudo:session): session closed for user root Oct 24 21:24:17 volumio.local.local volumio[3378]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 24 21:24:17 volumio.local.local volumio[3378]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 24 21:24:17 volumio.local.local volumio[3378]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 24 21:24:17 volumio.local.local volumio[3378]: info: [1761341057360] CoreMusicLibrary::Adding element YouTube2 Oct 24 21:24:17 volumio.local.local volumio[3378]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 24 21:24:17 volumio.local.local volumio[3378]: Cannot find translation for source YouTube2 Oct 24 21:24:17 volumio.local.local volumio[3378]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 24 21:24:17 volumio.local.local volumio[3378]: info: [1761341057389] CoreMusicLibrary::Adding element YouTube Music Oct 24 21:24:17 volumio.local.local volumio[3378]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 24 21:24:17 volumio.local.local volumio[3378]: Cannot find translation for source YouTube2 Oct 24 21:24:17 volumio.local.local volumio[3378]: Cannot find translation for source YouTube Music Oct 24 21:24:17 volumio.local.local volumio[3378]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 24 21:24:17 volumio.local.local volumio[3378]: info: [1761341057394] CoreMusicLibrary::Adding element 80s80s Radio Oct 24 21:24:17 volumio.local.local volumio[3378]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 24 21:24:17 volumio.local.local volumio[3378]: Cannot find translation for source YouTube2 Oct 24 21:24:17 volumio.local.local volumio[3378]: Cannot find translation for source YouTube Music Oct 24 21:24:17 volumio.local.local volumio[3378]: Cannot find translation for source 80s80s Radio Oct 24 21:24:17 volumio.local.local volumio[3378]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 24 21:24:17 volumio.local.local volumio[3378]: info: [1761341057414] CoreMusicLibrary::Adding element Radio Paradise Oct 24 21:24:17 volumio.local.local volumio[3378]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 24 21:24:17 volumio.local.local volumio[3378]: Cannot find translation for source YouTube2 Oct 24 21:24:17 volumio.local.local volumio[3378]: Cannot find translation for source YouTube Music Oct 24 21:24:17 volumio.local.local volumio[3378]: Cannot find translation for source 80s80s Radio Oct 24 21:24:17 volumio.local.local volumio[3378]: Cannot find translation for source Radio Paradise Oct 24 21:24:17 volumio.local.local volumio[3378]: info: Volumio Calling Home Oct 24 21:24:17 volumio.local.local sudo[3649]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Oct 24 21:24:17 volumio.local.local sudo[3649]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:24:17 volumio.local.local sudo[3649]: pam_unix(sudo:session): session closed for user root Oct 24 21:24:17 volumio.local.local volumio[3378]: info: Volumio called home Oct 24 21:24:17 volumio.local.local volumio[3378]: info: MPD Permissions set Oct 24 21:24:17 volumio.local.local volumio[3378]: info: MPD Permissions set Oct 24 21:24:17 volumio.local.local volumio[3378]: info: [ASDebug] CurState: stop PrevState: na Oct 24 21:24:17 volumio.local.local volumio[3378]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Oct 24 21:24:17 volumio.local.local volumio[3378]: info: [ASDebug] CurState: stop PrevState: na Oct 24 21:24:17 volumio.local.local volumio[3378]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Oct 24 21:24:17 volumio.local.local volumio[3378]: info: CoreCommandRouter::volumioGetState Oct 24 21:24:17 volumio.local.local volumio[3378]: info: CorePlayQueue::getTrack 0 Oct 24 21:24:17 volumio.local.local volumio[3378]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 41 Oct 24 21:24:17 volumio.local.local volumio[3378]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 21:24:17 volumio.local.local volumio[3378]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 21:24:17 volumio.local.local volumio[3378]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 21:24:17 volumio.local.local volumio[3378]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 21:24:17 volumio.local.local volumio[3378]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 21:24:17 volumio.local.local volumio[3378]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 21:24:17 volumio.local.local volumio[3378]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 21:24:17 volumio.local.local volumio[3378]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 21:24:17 volumio.local.local volumio[3378]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 24 21:24:17 volumio.local.local volumio[3378]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 24 21:24:17 volumio.local.local volumio[3378]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 21:24:17 volumio.local.local volumio[3378]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 21:24:17 volumio.local.local volumio[3378]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 21:24:17 volumio.local.local volumio[3378]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 21:24:17 volumio.local.local volumio[3378]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 21:24:17 volumio.local.local volumio[3378]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 21:24:17 volumio.local.local volumio[3378]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 21:24:17 volumio.local.local volumio[3378]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 21:24:17 volumio.local.local volumio[3378]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 21:24:17 volumio.local.local volumio[3378]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 24 21:24:17 volumio.local.local volumio[3378]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 24 21:24:17 volumio.local.local volumio[3378]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 21:24:17 volumio.local.local volumio[3378]: info: [ASDebug] CurState: stop PrevState: na Oct 24 21:24:17 volumio.local.local volumio[3378]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Oct 24 21:24:17 volumio.local.local volumio[3378]: info: Starting Shairport Sync Oct 24 21:24:17 volumio.local.local volumio[3378]: info: Starting Shairport Sync Oct 24 21:24:17 volumio.local.local sudo[3674]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 24 21:24:17 volumio.local.local volumio[3378]: info: Starting Shairport Sync Oct 24 21:24:17 volumio.local.local sudo[3674]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:24:17 volumio.local.local sudo[3679]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 24 21:24:17 volumio.local.local systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 24 21:24:17 volumio.local.local systemd[1]: shairport-sync.service: Succeeded. Oct 24 21:24:17 volumio.local.local systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 24 21:24:17 volumio.local.local volumio[3378]: info: CoreCommandRouter::volumioGetState Oct 24 21:24:17 volumio.local.local volumio[3378]: info: CorePlayQueue::getTrack 0 Oct 24 21:24:17 volumio.local.local systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 24 21:24:17 volumio.local.local sudo[3684]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 24 21:24:17 volumio.local.local sudo[3674]: pam_unix(sudo:session): session closed for user root Oct 24 21:24:17 volumio.local.local sudo[3679]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:24:17 volumio.local.local sudo[3684]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:24:17 volumio.local.local systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 24 21:24:17 volumio.local.local systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Oct 24 21:24:17 volumio.local.local systemd[1]: shairport-sync.service: Succeeded. Oct 24 21:24:17 volumio.local.local systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 24 21:24:18 volumio.local.local systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 24 21:24:18 volumio.local.local sudo[3679]: pam_unix(sudo:session): session closed for user root Oct 24 21:24:18 volumio.local.local volumio[3378]: info: Shairport-Sync Started Oct 24 21:24:18 volumio.local.local volumio[3378]: Error adding Membership: Error: addMembership EINVAL Oct 24 21:24:18 volumio.local.local systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 24 21:24:18 volumio.local.local systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Oct 24 21:24:18 volumio.local.local systemd[1]: shairport-sync.service: Succeeded. Oct 24 21:24:18 volumio.local.local systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 24 21:24:18 volumio.local.local volumio[3378]: info: Shairport-Sync Started Oct 24 21:24:18 volumio.local.local systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 24 21:24:18 volumio.local.local sudo[3684]: pam_unix(sudo:session): session closed for user root Oct 24 21:24:18 volumio.local.local volumio[3378]: info: Shairport-Sync Started Oct 24 21:24:18 volumio.local.local mpd[3647]: Oct 24 21:24 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 24 21:24:18 volumio.local.local systemd[1]: Started Music Player Daemon. Oct 24 21:24:18 volumio.local.local sudo[3583]: pam_unix(sudo:session): session closed for user root Oct 24 21:24:18 volumio.local.local sudo[3608]: pam_unix(sudo:session): session closed for user root Oct 24 21:24:18 volumio.local.local volumio[3378]: error: MPD error: The expression evaluated to a falsy value: Oct 24 21:24:18 volumio.local.local volumio[3378]: assert.ok(self.idling) Oct 24 21:24:18 volumio.local.local volumio[3378]: error: The expression evaluated to a falsy value: Oct 24 21:24:18 volumio.local.local volumio[3378]: assert.ok(self.idling) Oct 24 21:24:18 volumio.local.local volumio[3378]: error: updateQueue error: null Oct 24 21:24:18 volumio.local.local volumio[3378]: info: [yt-cast-receiver] DIAL server listening on port 8098 Oct 24 21:24:18 volumio.local.local volumio[3378]: info: CoreCommandRouter::volumioRetrievevolume Oct 24 21:24:18 volumio.local.local volumio[3378]: info: MPD running with PID3647 Oct 24 21:24:18 volumio.local.local volumio[3378]: ,establishing connection Oct 24 21:24:18 volumio.local.local volumio[3378]: error: updateQueue error: null Oct 24 21:24:18 volumio.local.local volumio[3378]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Mixer hw:0 load error: No such device or address Oct 24 21:24:18 volumio.local.local volumio[3378]: info: [ASDebug] Togle GPIO: OFF Oct 24 21:24:18 volumio.local.local volumio[3378]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 24 21:24:18 volumio.local.local volumio[3378]: Error: EPERM: operation not permitted, write Oct 24 21:24:18 volumio.local.local volumio[3378]: at Object.writeSync (fs.js:737:3) Oct 24 21:24:18 volumio.local.local volumio[3378]: at Gpio.writeSync (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:243:8) Oct 24 21:24:18 volumio.local.local volumio[3378]: at AmpSwitchController.off (/data/plugins/system_controller/ampswitch/index.js:221:23) Oct 24 21:24:18 volumio.local.local volumio[3378]: at Timeout._onTimeout (/data/plugins/system_controller/ampswitch/index.js:195:88) Oct 24 21:24:18 volumio.local.local volumio[3378]: at listOnTimeout (internal/timers.js:557:17) Oct 24 21:24:18 volumio.local.local volumio[3378]: at processTimers (internal/timers.js:500:7) { Oct 24 21:24:18 volumio.local.local volumio[3378]: errno: -1, Oct 24 21:24:18 volumio.local.local volumio[3378]: syscall: 'write', Oct 24 21:24:18 volumio.local.local volumio[3378]: code: 'EPERM' Oct 24 21:24:18 volumio.local.local volumio[3378]: } Oct 24 21:24:18 volumio.local.local volumio[3378]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 24 21:24:18 volumio.local.local sudo[3744]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-10-24 21:23 Oct 24 21:24:18 volumio.local.local sudo[3744]: 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"