-- Logs begin at Mon 2025-05-05 20:25:42 CEST, end at Mon 2025-05-05 20:52:11 CEST. -- May 05 20:51:03 volumio ntpd[1225]: Soliciting pool server 37.247.53.178 May 05 20:51:08 volumio volumio[1236]: info: CoreCommandRouter::volumioGetQueue May 05 20:51:08 volumio volumio[1236]: info: CoreStateMachine::getQueue May 05 20:51:08 volumio volumio[1236]: info: CorePlayQueue::getQueue May 05 20:51:16 volumio volumio[1236]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 05 20:51:16 volumio volumio[1236]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 05 20:51:18 volumio volumio[1236]: info: Preload queue cleared May 05 20:51:20 volumio volumio[1236]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 05 20:51:20 volumio volumio[1236]: info: Preload queue cleared May 05 20:51:31 volumio ntpd[1225]: Soliciting pool server 162.159.200.1 May 05 20:51:33 volumio ntpd[1225]: Soliciting pool server 93.94.88.50 May 05 20:51:33 volumio volumio[1236]: info: Preload queue cleared May 05 20:51:33 volumio volumio[1236]: info: CoreCommandRouter::volumioReplaceandPlayItems May 05 20:51:33 volumio volumio[1236]: info: CoreStateMachine::ClearQueue May 05 20:51:33 volumio volumio[1236]: info: CoreStateMachine::stop May 05 20:51:33 volumio volumio[1236]: info: CoreStateMachine::setConsumeUpdateService undefined May 05 20:51:33 volumio volumio[1236]: info: CoreStateMachine::stPlaybackTimer May 05 20:51:33 volumio volumio[1236]: info: CoreStateMachine::updateTrackBlock May 05 20:51:33 volumio volumio[1236]: info: CorePlayQueue::getTrackBlock May 05 20:51:33 volumio volumio[1236]: info: CoreStateMachine::pushState May 05 20:51:33 volumio volumio[1236]: info: CorePlayQueue::getTrack 5 May 05 20:51:33 volumio volumio[1236]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 05 20:51:33 volumio volumio[1236]: info: CoreCommandRouter::volumioPushState May 05 20:51:33 volumio volumio[1236]: info: CoreStateMachine::serviceStop May 05 20:51:33 volumio volumio[1236]: info: CorePlayQueue::getTrack 5 May 05 20:51:33 volumio volumio[1236]: info: CoreCommandRouter::serviceStop May 05 20:51:33 volumio volumio[1236]: info: ControllerMpd::stop May 05 20:51:33 volumio volumio[1236]: verbose: ControllerMpd::sendMpdCommand stop May 05 20:51:33 volumio volumio[1236]: info: CorePlayQueue::clearPlayQueue May 05 20:51:33 volumio volumio[1236]: info: CorePlayQueue::saveQueue May 05 20:51:33 volumio volumio[1236]: info: CoreCommandRouter::volumioPushQueue May 05 20:51:33 volumio volumio[1236]: info: CoreStateMachine::addQueueItems May 05 20:51:33 volumio volumio[1236]: info: CorePlayQueue::addQueueItems May 05 20:51:33 volumio volumio[1236]: info: Preload queue cleared May 05 20:51:33 volumio volumio[1236]: info: Adding Item to queue: https://hirschmilch.de:7000/chillout.aac May 05 20:51:33 volumio volumio[1236]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 05 20:51:33 volumio volumio[1236]: info: CoreCommandRouter::volumioPushQueue May 05 20:51:33 volumio volumio[1236]: info: CorePlayQueue::saveQueue May 05 20:51:33 volumio volumio[1236]: info: CoreStateMachine::updateTrackBlock May 05 20:51:33 volumio volumio[1236]: info: CorePlayQueue::getTrackBlock May 05 20:51:33 volumio volumio[1236]: info: CoreCommandRouter::volumioPlay May 05 20:51:33 volumio volumio[1236]: info: CoreStateMachine::play index 0 May 05 20:51:33 volumio volumio[1236]: info: CoreStateMachine::setConsumeUpdateService undefined May 05 20:51:33 volumio volumio[1236]: info: CoreStateMachine::stop May 05 20:51:33 volumio volumio[1236]: info: CoreStateMachine::setConsumeUpdateService undefined May 05 20:51:33 volumio volumio[1236]: info: CoreStateMachine::play index undefined May 05 20:51:33 volumio volumio[1236]: info: CoreStateMachine::setConsumeUpdateService undefined May 05 20:51:33 volumio volumio[1236]: info: CorePlayQueue::getTrack 0 May 05 20:51:33 volumio volumio[1236]: info: CoreStateMachine::startPlaybackTimer May 05 20:51:33 volumio volumio[1236]: info: CorePlayQueue::getTrack 0 May 05 20:51:33 volumio volumio[1236]: info: [1746471093632] ControllerWebradio::clearAddPlayTrack May 05 20:51:33 volumio volumio[1236]: verbose: ControllerMpd::sendMpdCommand stop May 05 20:51:33 volumio volumio[1236]: info: May 05 20:51:33 volumio volumio[1236]: ---------------------------- MPD announces state update: player May 05 20:51:33 volumio volumio[1236]: info: sendMpdCommand stop took 127 milliseconds May 05 20:51:33 volumio volumio[1236]: info: ControllerMpd::getState May 05 20:51:33 volumio volumio[1236]: verbose: ControllerMpd::sendMpdCommand status May 05 20:51:33 volumio volumio[1236]: info: sendMpdCommand stop took 109 milliseconds May 05 20:51:33 volumio volumio[1236]: verbose: ControllerMpd::sendMpdCommand clear May 05 20:51:33 volumio volumio[1236]: info: May 05 20:51:33 volumio volumio[1236]: ---------------------------- MPD announces state update: player May 05 20:51:33 volumio volumio[1236]: info: ControllerMpd::getState May 05 20:51:33 volumio volumio[1236]: verbose: ControllerMpd::sendMpdCommand status May 05 20:51:33 volumio volumio[1236]: info: May 05 20:51:33 volumio volumio[1236]: ---------------------------- MPD announces state update: player May 05 20:51:33 volumio volumio[1236]: info: ControllerMpd::getState May 05 20:51:33 volumio volumio[1236]: verbose: ControllerMpd::sendMpdCommand status May 05 20:51:33 volumio volumio[1236]: info: May 05 20:51:33 volumio volumio[1236]: ---------------------------- MPD announces system playlist update May 05 20:51:33 volumio volumio[1236]: info: Ignoring MPD Status Update May 05 20:51:33 volumio volumio[1236]: info: sendMpdCommand status took 7 milliseconds May 05 20:51:33 volumio volumio[1236]: info: sendMpdCommand clear took 6 milliseconds May 05 20:51:33 volumio volumio[1236]: info: sendMpdCommand status took 6 milliseconds May 05 20:51:33 volumio volumio[1236]: verbose: ControllerMpd::parseState May 05 20:51:33 volumio volumio[1236]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 05 20:51:33 volumio volumio[1236]: verbose: ControllerMpd::sendMpdCommand load "https://hirschmilch.de:7000/chillout.aac" May 05 20:51:33 volumio volumio[1236]: verbose: ControllerMpd::parseState May 05 20:51:33 volumio volumio[1236]: info: ControllerMpd::pushState May 05 20:51:33 volumio volumio[1236]: info: CoreCommandRouter::servicePushState May 05 20:51:33 volumio volumio[1236]: info: CoreStateMachine::pushState May 05 20:51:33 volumio volumio[1236]: info: CorePlayQueue::getTrack 0 May 05 20:51:33 volumio volumio[1236]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 05 20:51:33 volumio volumio[1236]: info: CoreCommandRouter::volumioPushState May 05 20:51:33 volumio volumio[1236]: info: CorePlayQueue::getTrack 0 May 05 20:51:33 volumio volumio[1236]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 05 20:51:33 volumio volumio[1236]: info: ------------------------------ 18ms May 05 20:51:33 volumio volumio[1236]: info: May 05 20:51:33 volumio volumio[1236]: ---------------------------- MPD announces system playlist update May 05 20:51:33 volumio volumio[1236]: info: Ignoring MPD Status Update May 05 20:51:33 volumio volumio[1236]: info: May 05 20:51:33 volumio volumio[1236]: ---------------------------- MPD announces system playlist update May 05 20:51:33 volumio volumio[1236]: info: Ignoring MPD Status Update May 05 20:51:33 volumio volumio[1236]: error: updateQueue error: null May 05 20:51:33 volumio volumio[1236]: info: sendMpdCommand status took 21 milliseconds May 05 20:51:33 volumio volumio[1236]: info: ------------------------------ 20ms May 05 20:51:33 volumio volumio[1236]: info: sendMpdCommand playlistinfo took 17 milliseconds May 05 20:51:33 volumio volumio[1236]: verbose: ControllerMpd::parseState May 05 20:51:33 volumio volumio[1236]: verbose: ControllerMpd::parseTrackInfo May 05 20:51:33 volumio volumio[1236]: info: ControllerMpd::pushState May 05 20:51:33 volumio volumio[1236]: info: CoreCommandRouter::servicePushState May 05 20:51:33 volumio volumio[1236]: info: CoreStateMachine::pushState May 05 20:51:33 volumio volumio[1236]: info: CorePlayQueue::getTrack 0 May 05 20:51:33 volumio volumio[1236]: info: CoreCommandRouter::volumioPushState May 05 20:51:33 volumio volumio[1236]: info: CorePlayQueue::getTrack 0 May 05 20:51:33 volumio volumio[1236]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 05 20:51:33 volumio volumio[1236]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 05 20:51:33 volumio volumio[1236]: info: ------------------------------ 36ms May 05 20:51:33 volumio volumio[1236]: info: ------------------------------ 42ms May 05 20:51:37 volumio volumio[1236]: info: CoreCommandRouter::volumioGetState May 05 20:51:37 volumio volumio[1236]: info: CorePlayQueue::getTrack 0 May 05 20:51:37 volumio volumio[1236]: verbose: ControllerMpd::sendMpdCommand add "https://hirschmilch.de:7000/chillout.aac" May 05 20:51:37 volumio volumio[1236]: error: updateQueue error: null May 05 20:51:37 volumio volumio[1236]: error: updateQueue error: null May 05 20:51:37 volumio volumio[1236]: info: May 05 20:51:37 volumio volumio[1236]: ---------------------------- MPD announces system playlist update May 05 20:51:37 volumio volumio[1236]: info: Ignoring MPD Status Update May 05 20:51:37 volumio volumio[1236]: info: ------------------------------ 4143ms May 05 20:51:37 volumio volumio[1236]: info: ------------------------------ 4143ms May 05 20:51:37 volumio volumio[1236]: info: sendMpdCommand add "https://hirschmilch.de:7000/chillout.aac" took 3 milliseconds May 05 20:51:37 volumio volumio[1236]: info: CoreStateMachine::setConsumeUpdateService mpd May 05 20:51:37 volumio volumio[1236]: verbose: ControllerMpd::sendMpdCommand play May 05 20:51:37 volumio volumio[1236]: Upnp client error: Error: This socket has been ended by the other party May 05 20:51:37 volumio volumio[1236]: Upnp client error: Error: This socket has been ended by the other party May 05 20:51:37 volumio volumio[1236]: info: May 05 20:51:37 volumio volumio[1236]: ---------------------------- MPD announces system playlist update May 05 20:51:37 volumio volumio[1236]: info: Ignoring MPD Status Update May 05 20:51:37 volumio volumio[1236]: info: May 05 20:51:37 volumio volumio[1236]: ---------------------------- MPD announces system playlist update May 05 20:51:37 volumio volumio[1236]: info: Ignoring MPD Status Update May 05 20:51:37 volumio volumio[1236]: info: ------------------------------ 6ms May 05 20:51:37 volumio volumio[1236]: info: sendMpdCommand play took 5 milliseconds May 05 20:51:37 volumio volumio[1236]: info: ------------------------------ 3ms May 05 20:51:37 volumio volumio[1236]: info: ------------------------------ 2ms May 05 20:51:40 volumio volumio[1236]: info: May 05 20:51:40 volumio volumio[1236]: ---------------------------- MPD announces state update: player May 05 20:51:40 volumio volumio[1236]: info: ControllerMpd::getState May 05 20:51:40 volumio volumio[1236]: verbose: ControllerMpd::sendMpdCommand status May 05 20:51:40 volumio volumio[1236]: info: May 05 20:51:40 volumio volumio[1236]: ---------------------------- MPD announces state update: player May 05 20:51:40 volumio volumio[1236]: info: ControllerMpd::getState May 05 20:51:40 volumio volumio[1236]: verbose: ControllerMpd::sendMpdCommand status May 05 20:51:40 volumio volumio[1236]: info: May 05 20:51:40 volumio volumio[1236]: ---------------------------- MPD announces state update: player May 05 20:51:40 volumio volumio[1236]: info: ControllerMpd::getState May 05 20:51:40 volumio volumio[1236]: verbose: ControllerMpd::sendMpdCommand status May 05 20:51:40 volumio volumio[1236]: verbose: ControllerMpd::sendMpdCommand clearerror May 05 20:51:40 volumio volumio[1236]: info: sendMpdCommand status took 8 milliseconds May 05 20:51:40 volumio volumio[1236]: verbose: ControllerMpd::sendMpdCommand clearerror May 05 20:51:40 volumio volumio[1236]: info: sendMpdCommand status took 7 milliseconds May 05 20:51:40 volumio volumio[1236]: verbose: ControllerMpd::parseState May 05 20:51:40 volumio volumio[1236]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 05 20:51:40 volumio volumio[1236]: verbose: ControllerMpd::parseState May 05 20:51:40 volumio volumio[1236]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 05 20:51:40 volumio volumio[1236]: verbose: ControllerMpd::sendMpdCommand clearerror May 05 20:51:40 volumio volumio[1236]: info: sendMpdCommand status took 16 milliseconds May 05 20:51:40 volumio volumio[1236]: info: sendMpdCommand clearerror took 12 milliseconds May 05 20:51:40 volumio volumio[1236]: info: sendMpdCommand clearerror took 11 milliseconds May 05 20:51:40 volumio volumio[1236]: info: sendMpdCommand playlistinfo took 8 milliseconds May 05 20:51:40 volumio volumio[1236]: info: sendMpdCommand playlistinfo took 7 milliseconds May 05 20:51:40 volumio volumio[1236]: verbose: ControllerMpd::parseState May 05 20:51:40 volumio volumio[1236]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 05 20:51:40 volumio volumio[1236]: verbose: ControllerMpd::parseTrackInfo May 05 20:51:40 volumio volumio[1236]: verbose: ControllerMpd::parseTrackInfo May 05 20:51:40 volumio volumio[1236]: info: ControllerMpd::pushState May 05 20:51:40 volumio volumio[1236]: info: CoreCommandRouter::servicePushState May 05 20:51:40 volumio volumio[1236]: info: CoreStateMachine::pushState May 05 20:51:41 volumio volumio[1236]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 05 20:51:41 volumio volumio[1236]: info: CoreCommandRouter::volumioPushState May 05 20:51:41 volumio volumio[1236]: info: CorePlayQueue::getTrack 0 May 05 20:51:41 volumio volumio[1236]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"chillout.aac","artist":null,"album":null,"uri":"https://hirschmilch.de:7000/chillout.aac","trackType":"aac"} May 05 20:51:41 volumio volumio[1236]: verbose: CURRENT POSITION 0 May 05 20:51:41 volumio volumio[1236]: info: CoreStateMachine::syncState stateService stop May 05 20:51:41 volumio volumio[1236]: info: CoreStateMachine::syncState currentStatus stop May 05 20:51:41 volumio volumio[1236]: info: CoreStateMachine::pushState May 05 20:51:41 volumio volumio[1236]: info: CoreCommandRouter::volumioPushState May 05 20:51:41 volumio volumio[1236]: info: No code May 05 20:51:41 volumio volumio[1236]: info: CoreStateMachine::pushState May 05 20:51:41 volumio volumio[1236]: info: CoreCommandRouter::volumioPushState May 05 20:51:41 volumio volumio[1236]: info: ControllerMpd::pushState May 05 20:51:41 volumio volumio[1236]: info: CoreCommandRouter::servicePushState May 05 20:51:41 volumio volumio[1236]: info: CoreStateMachine::pushState May 05 20:51:41 volumio volumio[1236]: info: CoreCommandRouter::volumioPushState May 05 20:51:41 volumio volumio[1236]: info: CorePlayQueue::getTrack 0 May 05 20:51:41 volumio volumio[1236]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"chillout.aac","artist":null,"album":null,"uri":"https://hirschmilch.de:7000/chillout.aac","trackType":"aac"} May 05 20:51:41 volumio volumio[1236]: verbose: CURRENT POSITION 0 May 05 20:51:41 volumio volumio[1236]: info: CoreStateMachine::syncState stateService stop May 05 20:51:41 volumio volumio[1236]: info: CoreStateMachine::syncState currentStatus stop May 05 20:51:41 volumio volumio[1236]: info: CoreStateMachine::pushState May 05 20:51:41 volumio volumio[1236]: info: CoreCommandRouter::volumioPushState May 05 20:51:41 volumio volumio[1236]: info: No code May 05 20:51:41 volumio volumio[1236]: info: CoreStateMachine::pushState May 05 20:51:41 volumio volumio[1236]: info: CoreCommandRouter::volumioPushState May 05 20:51:41 volumio volumio[1236]: info: ------------------------------ 59ms May 05 20:51:41 volumio volumio[1236]: info: ------------------------------ 58ms May 05 20:51:41 volumio volumio[1236]: info: sendMpdCommand clearerror took 44 milliseconds May 05 20:51:41 volumio volumio[1236]: info: sendMpdCommand playlistinfo took 43 milliseconds May 05 20:51:41 volumio volumio[1236]: verbose: ControllerMpd::parseTrackInfo May 05 20:51:41 volumio volumio[1236]: info: ControllerMpd::pushState May 05 20:51:41 volumio volumio[1236]: info: CoreCommandRouter::servicePushState May 05 20:51:41 volumio volumio[1236]: info: CoreStateMachine::pushState May 05 20:51:41 volumio volumio[1236]: info: CoreCommandRouter::volumioPushState May 05 20:51:41 volumio volumio[1236]: info: CorePlayQueue::getTrack 0 May 05 20:51:41 volumio volumio[1236]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"chillout.aac","artist":null,"album":null,"uri":"https://hirschmilch.de:7000/chillout.aac","trackType":"aac"} May 05 20:51:41 volumio volumio[1236]: verbose: CURRENT POSITION 0 May 05 20:51:41 volumio volumio[1236]: info: CoreStateMachine::syncState stateService stop May 05 20:51:41 volumio volumio[1236]: info: CoreStateMachine::syncState currentStatus stop May 05 20:51:41 volumio volumio[1236]: info: CoreStateMachine::pushState May 05 20:51:41 volumio volumio[1236]: info: CoreCommandRouter::volumioPushState May 05 20:51:41 volumio volumio[1236]: info: No code May 05 20:51:41 volumio volumio[1236]: info: CoreStateMachine::pushState May 05 20:51:41 volumio volumio[1236]: info: CoreCommandRouter::volumioPushState May 05 20:51:41 volumio volumio[1236]: info: ------------------------------ 77ms May 05 20:51:46 volumio volumio[1236]: info: CoreCommandRouter::getUIConfigOnPlugin May 05 20:51:46 volumio volumio[1236]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 05 20:51:46 volumio volumio[1236]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork May 05 20:51:46 volumio sudo[5836]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 May 05 20:51:46 volumio sudo[5836]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 20:51:46 volumio sudo[5836]: pam_unix(sudo:session): session closed for user root May 05 20:51:46 volumio sudo[5841]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 May 05 20:51:46 volumio sudo[5841]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 20:51:46 volumio sudo[5841]: pam_unix(sudo:session): session closed for user root May 05 20:51:46 volumio sudo[5848]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 May 05 20:51:46 volumio sudo[5848]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 20:51:46 volumio sudo[5848]: pam_unix(sudo:session): session closed for user root May 05 20:51:46 volumio sudo[5856]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 May 05 20:51:46 volumio sudo[5856]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 20:51:46 volumio sudo[5856]: pam_unix(sudo:session): session closed for user root May 05 20:51:46 volumio sudo[5862]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 05 20:51:46 volumio sudo[5862]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 20:51:46 volumio sudo[5862]: pam_unix(sudo:session): session closed for user root May 05 20:51:46 volumio sudo[5865]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 05 20:51:46 volumio sudo[5865]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 20:51:46 volumio sudo[5865]: pam_unix(sudo:session): session closed for user root May 05 20:51:47 volumio volumio[1236]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache May 05 20:51:47 volumio volumio[1236]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks May 05 20:51:47 volumio sudo[5868]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 05 20:51:47 volumio sudo[5868]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 20:51:50 volumio ntpd[1225]: Soliciting pool server 85.199.214.99 May 05 20:51:51 volumio sudo[5868]: pam_unix(sudo:session): session closed for user root May 05 20:51:53 volumio volumio[1236]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNetworkSettings May 05 20:51:53 volumio volumio[1236]: info: Saving new wireless network May 05 20:51:53 volumio sudo[5915]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/wpa_supplicant/wpa_supplicant.conf May 05 20:51:53 volumio sudo[5915]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 20:51:53 volumio sudo[5915]: pam_unix(sudo:session): session closed for user root May 05 20:51:53 volumio volumio[1236]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart May 05 20:51:53 volumio volumio[1236]: info: Discovery: Restarting Advertising due to device name change May 05 20:51:53 volumio sudo[5918]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service May 05 20:51:53 volumio sudo[5918]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 20:51:53 volumio kernel: wlan0: deauthenticating from 10:3c:59:85:0c:b3 by local choice (Reason: 3=DEAUTH_LEAVING) May 05 20:51:53 volumio systemd[1]: Stopping Wireless Services... May 05 20:51:53 volumio dhcpcd[773]: wlan0: carrier lost May 05 20:51:53 volumio avahi-daemon[726]: Withdrawing address record for 192.168.0.123 on wlan0. May 05 20:51:53 volumio avahi-daemon[726]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.123. May 05 20:51:53 volumio wpa_supplicant[1141]: wlan0: CTRL-EVENT-DISCONNECTED bssid=10:3c:59:85:0c:b3 reason=3 locally_generated=1 May 05 20:51:53 volumio avahi-daemon[726]: Interface wlan0.IPv4 no longer relevant for mDNS. May 05 20:51:53 volumio volumio[1236]: info: Discovery: A device disappeared from network May 05 20:51:53 volumio volumio[1236]: info: Discovery: Device volumio disappeared from network May 05 20:51:53 volumio dhcpcd[773]: eth0: adding default route May 05 20:51:53 volumio dhcpcd[773]: wlan0: deleting route to 192.168.0.0/24 May 05 20:51:53 volumio dhcpcd[773]: wlan0: deleting default route via 192.168.0.1 May 05 20:51:53 volumio wpa_supplicant[1141]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 May 05 20:51:53 volumio wpa_supplicant[1141]: wlan0: CTRL-EVENT-TERMINATING May 05 20:51:53 volumio systemd[1]: wireless.service: Succeeded. May 05 20:51:53 volumio systemd[1]: Stopped Wireless Services. May 05 20:51:53 volumio systemd[1]: Starting Wireless Services... May 05 20:51:53 volumio wireless.js[5944]: Cleaning previous... May 05 20:51:53 volumio sudo[5959]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 May 05 20:51:53 volumio sudo[5959]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 20:51:53 volumio sudo[5959]: pam_unix(sudo:session): session closed for user root May 05 20:51:53 volumio sudo[5961]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down May 05 20:51:53 volumio sudo[5961]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 20:51:53 volumio sudo[5961]: pam_unix(sudo:session): session closed for user root May 05 20:51:53 volumio wireless.js[5944]: Stopped aP May 05 20:51:53 volumio sudo[5968]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up May 05 20:51:53 volumio sudo[5968]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 20:51:53 volumio sudo[5968]: pam_unix(sudo:session): session closed for user root May 05 20:51:53 volumio sudo[5970]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get May 05 20:51:53 volumio sudo[5970]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 20:51:53 volumio sudo[5970]: pam_unix(sudo:session): session closed for user root May 05 20:51:54 volumio sudo[5977]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up May 05 20:51:54 volumio sudo[5977]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 20:51:54 volumio sudo[5977]: pam_unix(sudo:session): session closed for user root May 05 20:51:54 volumio sudo[5979]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan May 05 20:51:54 volumio sudo[5979]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 20:51:54 volumio volumio[1236]: info: Discovery: A device disappeared from network May 05 20:51:54 volumio sudo[5979]: pam_unix(sudo:session): session closed for user root May 05 20:51:55 volumio wireless.js[5944]: Start wireless flow May 05 20:51:55 volumio wireless.js[5944]: Stopped hotspot (if there).. May 05 20:51:55 volumio sudo[5984]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 May 05 20:51:55 volumio sudo[5984]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 20:51:55 volumio sudo[5984]: pam_unix(sudo:session): session closed for user root May 05 20:51:55 volumio sudo[5986]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down May 05 20:51:55 volumio sudo[5986]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 20:51:55 volumio sudo[5986]: pam_unix(sudo:session): session closed for user root May 05 20:51:55 volumio wireless.js[5944]: DHCP IP May 05 20:51:55 volumio wpa_supplicant[5988]: Successfully initialized wpa_supplicant May 05 20:51:55 volumio wireless.js[5944]: Start ap May 05 20:51:55 volumio sudo[5989]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd May 05 20:51:55 volumio sudo[5989]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 20:51:55 volumio dhcpcd[5990]: sending commands to master dhcpcd process May 05 20:51:55 volumio dhcpcd[773]: control command: /sbin/dhcpcd May 05 20:51:55 volumio sudo[5989]: pam_unix(sudo:session): session closed for user root May 05 20:51:56 volumio wireless.js[5944]: trying... May 05 20:51:56 volumio sudo[5993]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 05 20:51:56 volumio sudo[5993]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 20:51:56 volumio sudo[5993]: pam_unix(sudo:session): session closed for user root May 05 20:51:56 volumio wpa_supplicant[5991]: wlan0: SME: Trying to authenticate with 10:3c:59:85:0c:b3 (SSID='5GPowerWiFi-850CB3' freq=2462 MHz) May 05 20:51:56 volumio kernel: wlan0: authenticate with 10:3c:59:85:0c:b3 May 05 20:51:56 volumio kernel: wlan0: 80 MHz not supported, disabling VHT May 05 20:51:56 volumio kernel: wlan0: send auth to 10:3c:59:85:0c:b3 (try 1/3) May 05 20:51:56 volumio kernel: wlan0: send auth to 10:3c:59:85:0c:b3 (try 2/3) May 05 20:51:56 volumio wpa_supplicant[5991]: wlan0: Trying to associate with 10:3c:59:85:0c:b3 (SSID='5GPowerWiFi-850CB3' freq=2462 MHz) May 05 20:51:56 volumio kernel: wlan0: authenticated May 05 20:51:56 volumio kernel: wlan0: associate with 10:3c:59:85:0c:b3 (try 1/3) May 05 20:51:56 volumio kernel: wlan0: RX AssocResp from 10:3c:59:85:0c:b3 (capab=0x1431 status=0 aid=3) May 05 20:51:56 volumio kernel: usb 2-1.1: rtl8xxxu_bss_info_changed: HT supported May 05 20:51:56 volumio kernel: wlan0: associated May 05 20:51:56 volumio wpa_supplicant[5991]: wlan0: Associated with 10:3c:59:85:0c:b3 May 05 20:51:56 volumio wpa_supplicant[5991]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 May 05 20:51:56 volumio wpa_supplicant[5991]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=IT May 05 20:51:56 volumio wpa_supplicant[5991]: wlan0: WPA: Key negotiation completed with 10:3c:59:85:0c:b3 [PTK=CCMP GTK=CCMP] May 05 20:51:56 volumio wpa_supplicant[5991]: wlan0: CTRL-EVENT-CONNECTED - Connection to 10:3c:59:85:0c:b3 completed [id=0 id_str=] May 05 20:51:56 volumio dhcpcd[773]: wlan0: carrier acquired May 05 20:51:56 volumio kernel: wlan0: Limiting TX power to 20 (20 - 0) dBm as advertised by 10:3c:59:85:0c:b3 May 05 20:51:56 volumio dhcpcd[773]: wlan0: IAID 43:b1:1a:48 May 05 20:51:56 volumio dhcpcd[773]: wlan0: rebinding lease of 192.168.0.123 May 05 20:51:56 volumio dhcpcd[773]: wlan0: probing address 192.168.0.123/24 May 05 20:51:57 volumio wireless.js[5944]: trying... May 05 20:51:57 volumio sudo[5997]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 05 20:51:57 volumio sudo[5997]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 20:51:57 volumio sudo[5997]: pam_unix(sudo:session): session closed for user root May 05 20:51:57 volumio dhcpcd[773]: wlan0: soliciting an IPv6 router May 05 20:51:58 volumio wireless.js[5944]: trying... May 05 20:51:58 volumio sudo[6000]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 05 20:51:58 volumio sudo[6000]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 20:51:58 volumio sudo[6000]: pam_unix(sudo:session): session closed for user root May 05 20:51:58 volumio volumio[1236]: info: Discovery: Started advertising with name: Volumio May 05 20:51:58 volumio ntpd[1225]: Deleting interface #3 wlan0, 192.168.0.123#123, interface stats: received=8, sent=33, dropped=0, active_time=1563 secs May 05 20:51:59 volumio wireless.js[5944]: trying... May 05 20:51:59 volumio sudo[6004]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 05 20:51:59 volumio sudo[6004]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 20:51:59 volumio sudo[6004]: pam_unix(sudo:session): session closed for user root May 05 20:52:00 volumio volumio[1236]: info: CoreCommandRouter::volumioGetState May 05 20:52:00 volumio wireless.js[5944]: trying... May 05 20:52:00 volumio sudo[6008]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 05 20:52:00 volumio sudo[6008]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 20:52:00 volumio sudo[6008]: pam_unix(sudo:session): session closed for user root May 05 20:52:01 volumio wireless.js[5944]: trying... May 05 20:52:01 volumio sudo[6011]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 05 20:52:01 volumio sudo[6011]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 20:52:01 volumio sudo[6011]: pam_unix(sudo:session): session closed for user root May 05 20:52:01 volumio dhcpcd[773]: wlan0: leased 192.168.0.123 for 86400 seconds May 05 20:52:01 volumio dhcpcd[773]: wlan0: adding route to 192.168.0.0/24 May 05 20:52:01 volumio dhcpcd[773]: wlan0: adding default route via 192.168.0.1 May 05 20:52:01 volumio avahi-daemon[726]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.123. May 05 20:52:01 volumio dhcpcd[773]: eth0: deleting default route May 05 20:52:01 volumio avahi-daemon[726]: New relevant interface wlan0.IPv4 for mDNS. May 05 20:52:01 volumio dhcpcd[1503]: eth0: deleted default route May 05 20:52:01 volumio avahi-daemon[726]: Registering new address record for 192.168.0.123 on wlan0.IPv4. May 05 20:52:01 volumio ntpd[1225]: ntpd exiting on signal 15 (Terminated) May 05 20:52:01 volumio ntpd[1225]: 45.135.69.197 local addr 169.254.93.118 -> May 05 20:52:01 volumio systemd[1]: Stopping Network Time Service... May 05 20:52:01 volumio ntpd[1225]: 212.45.144.206 local addr 169.254.93.118 -> May 05 20:52:01 volumio ntpd[1225]: 89.46.74.148 local addr 169.254.93.118 -> May 05 20:52:01 volumio ntpd[1225]: 95.110.254.234 local addr 169.254.93.118 -> May 05 20:52:01 volumio ntpd[1225]: 193.204.114.105 local addr 169.254.93.118 -> May 05 20:52:01 volumio ntpd[1225]: 185.19.184.35 local addr 169.254.93.118 -> May 05 20:52:01 volumio systemd[1]: ntp.service: Succeeded. May 05 20:52:01 volumio systemd[1]: Stopped Network Time Service. May 05 20:52:01 volumio systemd[1]: Starting Network Time Service... May 05 20:52:01 volumio ntpd[6048]: ntpd 4.2.8p12@1.3728-o (1): Starting May 05 20:52:01 volumio ntpd[6048]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 May 05 20:52:01 volumio systemd[1]: Started Network Time Service. May 05 20:52:01 volumio ntpd[6054]: proto: precision = 0.147 usec (-23) May 05 20:52:01 volumio ntpd[6054]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature May 05 20:52:01 volumio ntpd[6054]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 May 05 20:52:01 volumio ntpd[6054]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 860 days ago May 05 20:52:01 volumio ntpd[6054]: Listen and drop on 0 v6wildcard [::]:123 May 05 20:52:01 volumio ntpd[6054]: Listen and drop on 1 v4wildcard 0.0.0.0:123 May 05 20:52:01 volumio ntpd[6054]: Listen normally on 2 lo 127.0.0.1:123 May 05 20:52:01 volumio ntpd[6054]: Listen normally on 3 eth0 169.254.93.118:123 May 05 20:52:01 volumio ntpd[6054]: Listen normally on 4 wlan0 192.168.0.123:123 May 05 20:52:01 volumio ntpd[6054]: Listening on routing socket on fd #21 for interface updates May 05 20:52:01 volumio ntpd[6054]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized May 05 20:52:01 volumio ntpd[6054]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized May 05 20:52:02 volumio wireless.js[5944]: trying... May 05 20:52:02 volumio sudo[6059]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 05 20:52:02 volumio sudo[6059]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 20:52:02 volumio sudo[6059]: pam_unix(sudo:session): session closed for user root May 05 20:52:02 volumio wireless.js[5944]: Connected to: ----5GPowerWiFi-850CB3 May 05 20:52:02 volumio wireless.js[5944]: ---- May 05 20:52:02 volumio sudo[6062]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 05 20:52:02 volumio sudo[6062]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 20:52:02 volumio sudo[6062]: pam_unix(sudo:session): session closed for user root May 05 20:52:02 volumio wireless.js[5944]: ... joined AP, wlan0 IPv4 is 192.168.0.123, ipV6 is undefined May 05 20:52:02 volumio wireless.js[5944]: It's done! AP May 05 20:52:02 volumio iwconfig[6066]: Error for wireless request "Set Power Management" (8B2C) : May 05 20:52:02 volumio iwconfig[6066]: SET failed on device wlan0 ; Operation not supported. May 05 20:52:02 volumio systemd[1]: Started Wireless Services. May 05 20:52:02 volumio sudo[5918]: pam_unix(sudo:session): session closed for user root May 05 20:52:02 volumio ntpd[6054]: Soliciting pool server 185.19.184.35 May 05 20:52:03 volumio ntpd[6054]: Soliciting pool server 93.94.88.50 May 05 20:52:04 volumio volumio[1236]: info: Discovery: adding 793c398d-970c-4969-89f7-2a99c140c182 May 05 20:52:04 volumio volumio[1236]: info: Discovery: Found device Volumio May 05 20:52:04 volumio volumio[1236]: info: CoreCommandRouter::volumioGetState May 05 20:52:04 volumio ntpd[6054]: Soliciting pool server 151.22.209.90 May 05 20:52:04 volumio volumio[1236]: info: Discovery: this is already registered, 793c398d-970c-4969-89f7-2a99c140c182 May 05 20:52:04 volumio volumio[1236]: info: Discovery: Found device Volumio May 05 20:52:04 volumio volumio[1236]: info: CoreCommandRouter::volumioGetState May 05 20:52:04 volumio ntpd[6054]: Soliciting pool server 93.94.88.51 May 05 20:52:04 volumio ntpd[6054]: Soliciting pool server 212.6.50.243 May 05 20:52:04 volumio ntpd[6054]: Soliciting pool server 162.159.200.1 May 05 20:52:05 volumio volumio[1236]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium May 05 20:52:05 volumio volumio[1236]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium May 05 20:52:05 volumio volumio[1236]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium May 05 20:52:05 volumio volumio[1236]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium May 05 20:52:05 volumio volumio[1236]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium May 05 20:52:05 volumio volumio[1236]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium May 05 20:52:05 volumio volumio[1236]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium May 05 20:52:05 volumio volumio[1236]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium May 05 20:52:05 volumio volumio[1236]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium May 05 20:52:05 volumio volumio[1236]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium May 05 20:52:05 volumio volumio[1236]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium May 05 20:52:05 volumio volumio[1236]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar May 05 20:52:05 volumio volumio[1236]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar May 05 20:52:05 volumio volumio[1236]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar May 05 20:52:05 volumio volumio[1236]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar May 05 20:52:05 volumio volumio[1236]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar May 05 20:52:05 volumio volumio[1236]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar May 05 20:52:05 volumio volumio[1236]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar May 05 20:52:05 volumio volumio[1236]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar May 05 20:52:05 volumio volumio[1236]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar May 05 20:52:05 volumio volumio[1236]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar May 05 20:52:05 volumio volumio[1236]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso May 05 20:52:05 volumio volumio[1236]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso May 05 20:52:05 volumio volumio[1236]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso May 05 20:52:05 volumio volumio[1236]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso May 05 20:52:05 volumio volumio[1236]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso May 05 20:52:05 volumio volumio[1236]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso May 05 20:52:05 volumio volumio[1236]: info: Adding plugin bluetooth to MyMusic Plugins May 05 20:52:05 volumio volumio[1236]: info: Adding plugin multiroom to MyMusic Plugins May 05 20:52:05 volumio volumio[1236]: info: Adding plugin metavolumio to MyMusic Plugins May 05 20:52:05 volumio volumio[1236]: info: Adding plugin cd_controller to MyMusic Plugins May 05 20:52:05 volumio volumio[1236]: info: Adding plugin smart_inputs to MyMusic Plugins May 05 20:52:05 volumio volumio[1236]: info: Adding plugin tidalconnect to MyMusic Plugins May 05 20:52:05 volumio volumio[1236]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... May 05 20:52:05 volumio volumio[1236]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded May 05 20:52:05 volumio volumio[1236]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio May 05 20:52:05 volumio volumio[1236]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 05 20:52:05 volumio volumio[1236]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 05 20:52:05 volumio volumio[1236]: info: Starting MyVolumio Remote Streaming Endpoints May 05 20:52:05 volumio volumio[1236]: info: MyVolumio login type: Token May 05 20:52:05 volumio volumio[1236]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started May 05 20:52:05 volumio volumio[1236]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... May 05 20:52:05 volumio ntpd[6054]: Soliciting pool server 162.159.200.123 May 05 20:52:05 volumio ntpd[6054]: Soliciting pool server 37.247.53.178 May 05 20:52:05 volumio ntpd[6054]: Soliciting pool server 81.56.230.156 May 05 20:52:06 volumio ntpd[6054]: Soliciting pool server 185.157.229.254 May 05 20:52:06 volumio volumio[1236]: info: Starting Streaming Service Transparent Proxy May 05 20:52:06 volumio volumio[1236]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded May 05 20:52:06 volumio volumio[1236]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services May 05 20:52:06 volumio volumio[1236]: info: Streaming services startup May 05 20:52:06 volumio volumio[1236]: info: Starting Streaming Daemon May 05 20:52:06 volumio sudo[6081]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 05 20:52:06 volumio sudo[6081]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 20:52:06 volumio sudo[6081]: pam_unix(sudo:session): session closed for user root May 05 20:52:06 volumio volumio[1236]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started May 05 20:52:06 volumio volumio[1236]: error: Cannot start Volumio Streaming Daemon May 05 20:52:06 volumio volumio[1236]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 05 20:52:06 volumio volumio[1236]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 05 20:52:06 volumio volumio[1236]: STREAMING PROXY: Starting server on port 3245 May 05 20:52:06 volumio volumio[1236]: Node JS runtime: 14 May 05 20:52:06 volumio ntpd[6054]: Soliciting pool server 31.14.133.122 May 05 20:52:06 volumio ntpd[6054]: Soliciting pool server 2a00:6d41:10:1194::4 May 05 20:52:06 volumio ntpd[6054]: Soliciting pool server 85.199.214.99 May 05 20:52:07 volumio volumio[1236]: error: MyVolumio Custom Token format not valid, refreshing it May 05 20:52:07 volumio volumio[1236]: info: CoreCommandRouter::executeOnPlugin: wizard , reportWirelessConnection May 05 20:52:07 volumio volumio[1236]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessInfo May 05 20:52:07 volumio sudo[6088]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 05 20:52:07 volumio sudo[6088]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 20:52:07 volumio sudo[6088]: pam_unix(sudo:session): session closed for user root May 05 20:52:07 volumio sudo[6091]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 May 05 20:52:07 volumio sudo[6091]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 20:52:07 volumio sudo[6091]: pam_unix(sudo:session): session closed for user root May 05 20:52:07 volumio ntpd[6054]: Soliciting pool server 95.110.254.234 May 05 20:52:08 volumio volumio[1236]: info: MyVolumio login type: Token May 05 20:52:09 volumio volumio[1236]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN May 05 20:52:09 volumio volumio[1236]: info: MyVolumio token set successfully May 05 20:52:09 volumio volumio[1236]: info: MYVOLUMIO: Adding device May 05 20:52:09 volumio volumio[1236]: info: MYVOLUMIO: Evaluating Server May 05 20:52:10 volumio volumio[1236]: info: MyVolumio Plan changed: premium May 05 20:52:10 volumio volumio[1236]: info: [MyVolumio PluginManager] Subscribed plan changed to premium May 05 20:52:10 volumio volumio[1236]: info: Removing browser output: myVolumio user plan is not superstar May 05 20:52:10 volumio volumio[1236]: info: Removing audio output: May 05 20:52:10 volumio volumio[1236]: info: MYVOLUMIO: Adding device May 05 20:52:10 volumio volumio[1236]: info: MYVOLUMIO: Evaluating Server May 05 20:52:10 volumio volumio[1236]: info: Remote config written successfully May 05 20:52:10 volumio volumio[1236]: info: Starting Tunnel 1 May 05 20:52:10 volumio volumio[1236]: info: Starting Tunnel Connection Checker May 05 20:52:11 volumio volumio[1236]: info: MYVolumio Device enabled May 05 20:52:11 volumio volumio[1236]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... May 05 20:52:11 volumio volumio[1236]: info: MyVolumio status changed May 05 20:52:11 volumio volumio[1236]: info: Streaming services startup May 05 20:52:11 volumio volumio[1236]: info: Starting Streaming Daemon May 05 20:52:10 volumio ntpd[6054]: receive: Unexpected origin timestamp 0xebc3875a.f7b7f0b2 does not match aorg 0000000000.00000000 from server@81.56.230.156 xmt 0xebc3875a.0456f75d May 05 20:52:10 volumio ntpd[6054]: receive: Unexpected origin timestamp 0xebc3875a.f7be5008 does not match aorg 0000000000.00000000 from server@93.94.88.50 xmt 0xebc3875a.062963fb May 05 20:52:10 volumio sudo[6129]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 05 20:52:10 volumio sudo[6129]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 20:52:10 volumio volumio[1236]: info: Setting Geolocation for MyVolumio to eu10 May 05 20:52:10 volumio volumio[1236]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 05 20:52:10 volumio volumio[1236]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 05 20:52:10 volumio volumio[1236]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 05 20:52:10 volumio sudo[6129]: pam_unix(sudo:session): session closed for user root May 05 20:52:10 volumio volumio[1236]: error: Failed to ping endpoint eu6.myvolumio.org : unknown error May 05 20:52:10 volumio volumio[1236]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 05 20:52:10 volumio volumio[1236]: Error: Unable to resolve or reject the same promise twice May 05 20:52:10 volumio volumio[1236]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) May 05 20:52:10 volumio volumio[1236]: at Socket. (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:32086) May 05 20:52:10 volumio volumio[1236]: at Socket.emit (events.js:412:35) May 05 20:52:10 volumio volumio[1236]: at endReadableNT (internal/streams/readable.js:1333:12) May 05 20:52:10 volumio volumio[1236]: at processTicksAndRejections (internal/process/task_queues.js:82:21) May 05 20:52:10 volumio volumio[1236]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 05 20:52:10 volumio sudo[6140]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-05 20:51 May 05 20:52:10 volumio sudo[6140]: 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="4b122b2365bb8c09786cd63b50d1ea6db780eb4e" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 09:13:11 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="ac782621640fcc70c07e91a81b6d958b"