-- Logs begin at Thu 2024-07-25 11:30:44 MSK, end at Thu 2024-07-25 12:21:12 MSK. -- Jul 25 12:20:01 volumio ntpd[877]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Jul 25 12:20:01 volumio ntpd[877]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Jul 25 12:20:09 volumio ntpd[877]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Jul 25 12:20:12 volumio volumio[1364]: info: CoreCommandRouter::volumioPlay Jul 25 12:20:12 volumio volumio[1364]: info: CoreStateMachine::play index undefined Jul 25 12:20:12 volumio volumio[1364]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 25 12:20:12 volumio volumio[1364]: info: CorePlayQueue::getTrack 0 Jul 25 12:20:12 volumio volumio[1364]: info: CoreStateMachine::startPlaybackTimer Jul 25 12:20:12 volumio volumio[1364]: info: CorePlayQueue::getTrack 0 Jul 25 12:20:12 volumio volumio[1364]: info: [1721899212164] ControllerWebradio::clearAddPlayTrack Jul 25 12:20:12 volumio volumio[1364]: verbose: ControllerMpd::sendMpdCommand stop Jul 25 12:20:12 volumio volumio[1364]: info: sendMpdCommand stop took 7 milliseconds Jul 25 12:20:12 volumio volumio[1364]: verbose: ControllerMpd::sendMpdCommand clear Jul 25 12:20:12 volumio volumio[1364]: info: Jul 25 12:20:12 volumio volumio[1364]: ---------------------------- MPD announces system playlist update Jul 25 12:20:12 volumio volumio[1364]: info: Ignoring MPD Status Update Jul 25 12:20:12 volumio volumio[1364]: info: sendMpdCommand clear took 4 milliseconds Jul 25 12:20:12 volumio volumio[1364]: verbose: ControllerMpd::sendMpdCommand load "https://retro.hostingradio.ru:8043/retro128" Jul 25 12:20:12 volumio volumio[1364]: info: Jul 25 12:20:12 volumio volumio[1364]: ---------------------------- MPD announces system playlist update Jul 25 12:20:12 volumio volumio[1364]: info: Ignoring MPD Status Update Jul 25 12:20:12 volumio volumio[1364]: info: Jul 25 12:20:12 volumio volumio[1364]: ---------------------------- MPD announces system playlist update Jul 25 12:20:12 volumio volumio[1364]: info: Ignoring MPD Status Update Jul 25 12:20:12 volumio volumio[1364]: error: updateQueue error: null Jul 25 12:20:12 volumio volumio[1364]: info: ------------------------------ 12ms Jul 25 12:20:12 volumio volumio[1364]: error: updateQueue error: null Jul 25 12:20:12 volumio volumio[1364]: error: updateQueue error: null Jul 25 12:20:12 volumio volumio[1364]: verbose: ControllerMpd::sendMpdCommand add "https://retro.hostingradio.ru:8043/retro128" Jul 25 12:20:12 volumio volumio[1364]: info: ------------------------------ 618ms Jul 25 12:20:12 volumio volumio[1364]: info: ------------------------------ 616ms Jul 25 12:20:12 volumio volumio[1364]: info: Jul 25 12:20:12 volumio volumio[1364]: ---------------------------- MPD announces system playlist update Jul 25 12:20:12 volumio volumio[1364]: info: Ignoring MPD Status Update Jul 25 12:20:12 volumio volumio[1364]: info: sendMpdCommand add "https://retro.hostingradio.ru:8043/retro128" took 3 milliseconds Jul 25 12:20:12 volumio volumio[1364]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 25 12:20:12 volumio volumio[1364]: verbose: ControllerMpd::sendMpdCommand play Jul 25 12:20:12 volumio volumio[1364]: info: Jul 25 12:20:12 volumio volumio[1364]: ---------------------------- MPD announces system playlist update Jul 25 12:20:12 volumio volumio[1364]: info: Ignoring MPD Status Update Jul 25 12:20:12 volumio volumio[1364]: info: Jul 25 12:20:12 volumio volumio[1364]: ---------------------------- MPD announces system playlist update Jul 25 12:20:12 volumio volumio[1364]: info: Ignoring MPD Status Update Jul 25 12:20:12 volumio volumio[1364]: info: ------------------------------ 11ms Jul 25 12:20:12 volumio volumio[1364]: info: sendMpdCommand play took 9 milliseconds Jul 25 12:20:12 volumio volumio[1364]: info: ------------------------------ 7ms Jul 25 12:20:12 volumio volumio[1364]: info: ------------------------------ 5ms Jul 25 12:20:13 volumio volumio[1364]: info: Jul 25 12:20:13 volumio volumio[1364]: ---------------------------- MPD announces state update: player Jul 25 12:20:13 volumio volumio[1364]: info: ControllerMpd::getState Jul 25 12:20:13 volumio volumio[1364]: verbose: ControllerMpd::sendMpdCommand status Jul 25 12:20:13 volumio volumio[1364]: info: Jul 25 12:20:13 volumio volumio[1364]: ---------------------------- MPD announces state update: player Jul 25 12:20:13 volumio volumio[1364]: info: ControllerMpd::getState Jul 25 12:20:13 volumio volumio[1364]: verbose: ControllerMpd::sendMpdCommand status Jul 25 12:20:13 volumio volumio[1364]: info: Jul 25 12:20:13 volumio volumio[1364]: ---------------------------- MPD announces state update: player Jul 25 12:20:13 volumio volumio[1364]: info: ControllerMpd::getState Jul 25 12:20:13 volumio volumio[1364]: verbose: ControllerMpd::sendMpdCommand status Jul 25 12:20:13 volumio volumio[1364]: verbose: ControllerMpd::sendMpdCommand clearerror Jul 25 12:20:13 volumio volumio[1364]: info: sendMpdCommand status took 16 milliseconds Jul 25 12:20:13 volumio volumio[1364]: verbose: ControllerMpd::sendMpdCommand clearerror Jul 25 12:20:13 volumio volumio[1364]: info: sendMpdCommand status took 15 milliseconds Jul 25 12:20:13 volumio volumio[1364]: verbose: ControllerMpd::sendMpdCommand clearerror Jul 25 12:20:13 volumio volumio[1364]: info: sendMpdCommand status took 14 milliseconds Jul 25 12:20:13 volumio volumio[1364]: verbose: ControllerMpd::parseState Jul 25 12:20:13 volumio volumio[1364]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 25 12:20:13 volumio volumio[1364]: verbose: ControllerMpd::parseState Jul 25 12:20:13 volumio volumio[1364]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 25 12:20:13 volumio volumio[1364]: verbose: ControllerMpd::parseState Jul 25 12:20:13 volumio volumio[1364]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 25 12:20:13 volumio volumio[1364]: info: sendMpdCommand clearerror took 25 milliseconds Jul 25 12:20:13 volumio volumio[1364]: info: sendMpdCommand clearerror took 23 milliseconds Jul 25 12:20:13 volumio volumio[1364]: info: sendMpdCommand clearerror took 21 milliseconds Jul 25 12:20:13 volumio volumio[1364]: info: sendMpdCommand playlistinfo took 13 milliseconds Jul 25 12:20:13 volumio volumio[1364]: info: sendMpdCommand playlistinfo took 11 milliseconds Jul 25 12:20:13 volumio volumio[1364]: info: sendMpdCommand playlistinfo took 11 milliseconds Jul 25 12:20:13 volumio volumio[1364]: verbose: ControllerMpd::parseTrackInfo Jul 25 12:20:13 volumio volumio[1364]: verbose: ControllerMpd::parseTrackInfo Jul 25 12:20:13 volumio volumio[1364]: verbose: ControllerMpd::parseTrackInfo Jul 25 12:20:13 volumio volumio[1364]: info: ControllerMpd::pushState Jul 25 12:20:13 volumio volumio[1364]: info: CoreCommandRouter::servicePushState Jul 25 12:20:13 volumio volumio[1364]: info: CoreStateMachine::pushState Jul 25 12:20:13 volumio volumio[1364]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 25 12:20:13 volumio volumio[1364]: info: CoreCommandRouter::volumioPushState Jul 25 12:20:13 volumio volumio[1364]: info: CorePlayQueue::getTrack 0 Jul 25 12:20:13 volumio volumio[1364]: 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":"retro128","artist":null,"album":null,"uri":"https://retro.hostingradio.ru:8043/retro128","trackType":""} Jul 25 12:20:13 volumio volumio[1364]: verbose: CURRENT POSITION 0 Jul 25 12:20:13 volumio volumio[1364]: info: CoreStateMachine::syncState stateService stop Jul 25 12:20:13 volumio volumio[1364]: info: CoreStateMachine::syncState currentStatus stop Jul 25 12:20:13 volumio volumio[1364]: info: CoreStateMachine::pushState Jul 25 12:20:13 volumio volumio[1364]: info: CoreCommandRouter::volumioPushState Jul 25 12:20:13 volumio volumio[1364]: info: No code Jul 25 12:20:13 volumio volumio[1364]: info: CoreStateMachine::pushState Jul 25 12:20:13 volumio volumio[1364]: info: CoreCommandRouter::volumioPushState Jul 25 12:20:13 volumio volumio[1364]: info: ControllerMpd::pushState Jul 25 12:20:13 volumio volumio[1364]: info: CoreCommandRouter::servicePushState Jul 25 12:20:13 volumio volumio[1364]: info: CoreStateMachine::pushState Jul 25 12:20:13 volumio volumio[1364]: info: CoreCommandRouter::volumioPushState Jul 25 12:20:13 volumio volumio[1364]: info: CorePlayQueue::getTrack 0 Jul 25 12:20:13 volumio volumio[1364]: 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":"retro128","artist":null,"album":null,"uri":"https://retro.hostingradio.ru:8043/retro128","trackType":""} Jul 25 12:20:13 volumio volumio[1364]: verbose: CURRENT POSITION 0 Jul 25 12:20:13 volumio volumio[1364]: info: CoreStateMachine::syncState stateService stop Jul 25 12:20:13 volumio volumio[1364]: info: CoreStateMachine::syncState currentStatus stop Jul 25 12:20:13 volumio volumio[1364]: info: CoreStateMachine::pushState Jul 25 12:20:13 volumio volumio[1364]: info: CoreCommandRouter::volumioPushState Jul 25 12:20:13 volumio volumio[1364]: info: No code Jul 25 12:20:13 volumio volumio[1364]: info: CoreStateMachine::pushState Jul 25 12:20:13 volumio volumio[1364]: info: CoreCommandRouter::volumioPushState Jul 25 12:20:13 volumio volumio[1364]: info: ControllerMpd::pushState Jul 25 12:20:13 volumio volumio[1364]: info: CoreCommandRouter::servicePushState Jul 25 12:20:13 volumio volumio[1364]: info: CoreStateMachine::pushState Jul 25 12:20:13 volumio volumio[1364]: info: CoreCommandRouter::volumioPushState Jul 25 12:20:13 volumio volumio[1364]: info: CorePlayQueue::getTrack 0 Jul 25 12:20:13 volumio volumio[1364]: 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":"retro128","artist":null,"album":null,"uri":"https://retro.hostingradio.ru:8043/retro128","trackType":""} Jul 25 12:20:13 volumio volumio[1364]: verbose: CURRENT POSITION 0 Jul 25 12:20:13 volumio volumio[1364]: info: CoreStateMachine::syncState stateService stop Jul 25 12:20:13 volumio volumio[1364]: info: CoreStateMachine::syncState currentStatus stop Jul 25 12:20:13 volumio volumio[1364]: info: CoreStateMachine::pushState Jul 25 12:20:13 volumio volumio[1364]: info: CoreCommandRouter::volumioPushState Jul 25 12:20:13 volumio volumio[1364]: info: No code Jul 25 12:20:13 volumio volumio[1364]: info: CoreStateMachine::pushState Jul 25 12:20:13 volumio volumio[1364]: info: CoreCommandRouter::volumioPushState Jul 25 12:20:13 volumio volumio[1364]: info: ------------------------------ 174ms Jul 25 12:20:13 volumio volumio[1364]: info: ------------------------------ 174ms Jul 25 12:20:13 volumio volumio[1364]: info: ------------------------------ 172ms Jul 25 12:20:17 volumio volumio[1364]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 25 12:20:17 volumio volumio[1364]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 25 12:20:19 volumio volumio[1364]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Jul 25 12:20:19 volumio sudo[4418]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jul 25 12:20:19 volumio sudo[4418]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 25 12:20:19 volumio sudo[4413]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Jul 25 12:20:19 volumio sudo[4418]: pam_unix(sudo:session): session closed for user root Jul 25 12:20:19 volumio sudo[4413]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 25 12:20:19 volumio sudo[4427]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jul 25 12:20:19 volumio sudo[4427]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 25 12:20:19 volumio sudo[4427]: pam_unix(sudo:session): session closed for user root Jul 25 12:20:19 volumio sudo[4413]: pam_unix(sudo:session): session closed for user root Jul 25 12:20:19 volumio sudo[4437]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jul 25 12:20:19 volumio sudo[4446]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 25 12:20:19 volumio sudo[4437]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 25 12:20:19 volumio sudo[4446]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 25 12:20:19 volumio sudo[4437]: pam_unix(sudo:session): session closed for user root Jul 25 12:20:19 volumio sudo[4446]: pam_unix(sudo:session): session closed for user root Jul 25 12:20:19 volumio volumio[1364]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Jul 25 12:20:19 volumio volumio[1364]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Jul 25 12:20:19 volumio sudo[4453]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 25 12:20:19 volumio sudo[4453]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 25 12:20:19 volumio sudo[4453]: pam_unix(sudo:session): session closed for user root Jul 25 12:20:19 volumio sudo[4457]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jul 25 12:20:19 volumio sudo[4457]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 25 12:20:19 volumio ntpd[877]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Jul 25 12:20:24 volumio sudo[4457]: pam_unix(sudo:session): session closed for user root Jul 25 12:20:25 volumio volumio[1364]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNetworkSettings Jul 25 12:20:25 volumio volumio[1364]: info: Saving new wireless network Jul 25 12:20:25 volumio sudo[4461]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/wpa_supplicant/wpa_supplicant.conf Jul 25 12:20:25 volumio sudo[4461]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 25 12:20:25 volumio sudo[4461]: pam_unix(sudo:session): session closed for user root Jul 25 12:20:25 volumio volumio[1364]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Jul 25 12:20:25 volumio volumio[1364]: info: Discovery: Restarting Advertising due to device name change Jul 25 12:20:25 volumio sudo[4464]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Jul 25 12:20:25 volumio sudo[4464]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 25 12:20:25 volumio systemd[1]: Stopping Wireless Services... Jul 25 12:20:25 volumio kernel: wlan0: deauthenticating from ee:1d:7f:ad:53:d8 by local choice (Reason: 3=DEAUTH_LEAVING) Jul 25 12:20:25 volumio wpa_supplicant[4091]: wlan0: CTRL-EVENT-DISCONNECTED bssid=ee:1d:7f:ad:53:d8 reason=3 locally_generated=1 Jul 25 12:20:25 volumio dhcpcd[793]: wlan0: waiting for carrier Jul 25 12:20:25 volumio wpa_supplicant[4091]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Jul 25 12:20:25 volumio wpa_supplicant[4091]: wlan0: CTRL-EVENT-TERMINATING Jul 25 12:20:25 volumio dhcpcd[793]: wlan0: waiting for carrier Jul 25 12:20:25 volumio systemd[1]: wireless.service: Succeeded. Jul 25 12:20:25 volumio systemd[1]: Stopped Wireless Services. Jul 25 12:20:25 volumio systemd[1]: Starting Wireless Services... Jul 25 12:20:25 volumio wireless.js[4466]: Cleaning previous... Jul 25 12:20:26 volumio sudo[4480]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jul 25 12:20:26 volumio sudo[4480]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 25 12:20:26 volumio sudo[4480]: pam_unix(sudo:session): session closed for user root Jul 25 12:20:26 volumio sudo[4482]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jul 25 12:20:26 volumio sudo[4482]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 25 12:20:26 volumio sudo[4482]: pam_unix(sudo:session): session closed for user root Jul 25 12:20:26 volumio dhcpcd[793]: wlan0: waiting for carrier Jul 25 12:20:26 volumio dhcpcd[793]: wlan0: waiting for carrier Jul 25 12:20:26 volumio wireless.js[4466]: Stopped aP Jul 25 12:20:26 volumio sudo[4489]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jul 25 12:20:26 volumio sudo[4489]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 25 12:20:26 volumio sudo[4489]: pam_unix(sudo:session): session closed for user root Jul 25 12:20:26 volumio sudo[4491]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Jul 25 12:20:26 volumio sudo[4491]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 25 12:20:26 volumio sudo[4491]: pam_unix(sudo:session): session closed for user root Jul 25 12:20:26 volumio sudo[4498]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jul 25 12:20:26 volumio sudo[4498]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 25 12:20:26 volumio sudo[4498]: pam_unix(sudo:session): session closed for user root Jul 25 12:20:26 volumio sudo[4500]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Jul 25 12:20:26 volumio sudo[4500]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 25 12:20:26 volumio volumio[1364]: info: CoreCommandRouter::volumioPlay Jul 25 12:20:26 volumio volumio[1364]: info: CoreStateMachine::play index undefined Jul 25 12:20:26 volumio volumio[1364]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 25 12:20:26 volumio volumio[1364]: info: CorePlayQueue::getTrack 0 Jul 25 12:20:26 volumio volumio[1364]: info: CoreStateMachine::startPlaybackTimer Jul 25 12:20:26 volumio volumio[1364]: info: CorePlayQueue::getTrack 0 Jul 25 12:20:26 volumio volumio[1364]: info: [1721899226940] ControllerWebradio::clearAddPlayTrack Jul 25 12:20:26 volumio volumio[1364]: verbose: ControllerMpd::sendMpdCommand stop Jul 25 12:20:26 volumio volumio[1364]: info: sendMpdCommand stop took 4 milliseconds Jul 25 12:20:26 volumio volumio[1364]: verbose: ControllerMpd::sendMpdCommand clear Jul 25 12:20:26 volumio volumio[1364]: info: Jul 25 12:20:26 volumio volumio[1364]: ---------------------------- MPD announces system playlist update Jul 25 12:20:26 volumio volumio[1364]: info: Ignoring MPD Status Update Jul 25 12:20:26 volumio volumio[1364]: info: sendMpdCommand clear took 4 milliseconds Jul 25 12:20:26 volumio volumio[1364]: verbose: ControllerMpd::sendMpdCommand load "https://retro.hostingradio.ru:8043/retro128" Jul 25 12:20:26 volumio volumio[1364]: info: Jul 25 12:20:26 volumio volumio[1364]: ---------------------------- MPD announces system playlist update Jul 25 12:20:26 volumio volumio[1364]: info: Ignoring MPD Status Update Jul 25 12:20:26 volumio volumio[1364]: info: Jul 25 12:20:26 volumio volumio[1364]: ---------------------------- MPD announces system playlist update Jul 25 12:20:26 volumio volumio[1364]: info: Ignoring MPD Status Update Jul 25 12:20:26 volumio volumio[1364]: error: updateQueue error: null Jul 25 12:20:26 volumio volumio[1364]: info: ------------------------------ 16ms Jul 25 12:20:27 volumio sudo[4500]: pam_unix(sudo:session): session closed for user root Jul 25 12:20:27 volumio wireless.js[4466]: SETTING APPROPRIATE REG DOMAIN: FR Jul 25 12:20:27 volumio sudo[4506]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jul 25 12:20:27 volumio sudo[4506]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 25 12:20:27 volumio volumio[1364]: error: updateQueue error: null Jul 25 12:20:27 volumio volumio[1364]: error: updateQueue error: null Jul 25 12:20:27 volumio volumio[1364]: verbose: ControllerMpd::sendMpdCommand add "https://retro.hostingradio.ru:8043/retro128" Jul 25 12:20:27 volumio volumio[1364]: info: ------------------------------ 859ms Jul 25 12:20:27 volumio volumio[1364]: info: ------------------------------ 856ms Jul 25 12:20:27 volumio sudo[4506]: pam_unix(sudo:session): session closed for user root Jul 25 12:20:27 volumio volumio[1364]: info: Jul 25 12:20:27 volumio volumio[1364]: ---------------------------- MPD announces system playlist update Jul 25 12:20:27 volumio volumio[1364]: info: Ignoring MPD Status Update Jul 25 12:20:27 volumio volumio[1364]: info: sendMpdCommand add "https://retro.hostingradio.ru:8043/retro128" took 8 milliseconds Jul 25 12:20:27 volumio volumio[1364]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 25 12:20:27 volumio volumio[1364]: verbose: ControllerMpd::sendMpdCommand play Jul 25 12:20:27 volumio volumio[1364]: info: Jul 25 12:20:27 volumio volumio[1364]: ---------------------------- MPD announces system playlist update Jul 25 12:20:27 volumio volumio[1364]: info: Ignoring MPD Status Update Jul 25 12:20:27 volumio volumio[1364]: info: Jul 25 12:20:27 volumio volumio[1364]: ---------------------------- MPD announces system playlist update Jul 25 12:20:27 volumio volumio[1364]: info: Ignoring MPD Status Update Jul 25 12:20:27 volumio volumio[1364]: info: ------------------------------ 17ms Jul 25 12:20:27 volumio volumio[1364]: info: sendMpdCommand play took 13 milliseconds Jul 25 12:20:27 volumio volumio[1364]: info: ------------------------------ 13ms Jul 25 12:20:27 volumio volumio[1364]: info: ------------------------------ 8ms Jul 25 12:20:27 volumio sudo[4508]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set FR Jul 25 12:20:27 volumio sudo[4508]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 25 12:20:27 volumio sudo[4508]: pam_unix(sudo:session): session closed for user root Jul 25 12:20:27 volumio wireless.js[4466]: SUCCESSFULLY SET NEW REGDOMAIN: FR Jul 25 12:20:27 volumio wireless.js[4466]: Start wireless flow Jul 25 12:20:27 volumio wireless.js[4466]: Stopped hotspot (if there).. Jul 25 12:20:27 volumio sudo[4514]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jul 25 12:20:27 volumio sudo[4514]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 25 12:20:27 volumio sudo[4514]: pam_unix(sudo:session): session closed for user root Jul 25 12:20:28 volumio sudo[4516]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jul 25 12:20:28 volumio sudo[4516]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 25 12:20:28 volumio sudo[4516]: pam_unix(sudo:session): session closed for user root Jul 25 12:20:28 volumio wireless.js[4466]: DHCP IP Jul 25 12:20:28 volumio dhcpcd[793]: wlan0: waiting for carrier Jul 25 12:20:28 volumio dhcpcd[793]: wlan0: waiting for carrier Jul 25 12:20:28 volumio wireless.js[4466]: Start ap Jul 25 12:20:28 volumio wpa_supplicant[4518]: Successfully initialized wpa_supplicant Jul 25 12:20:28 volumio dhcpcd[793]: wlan0: waiting for carrier Jul 25 12:20:28 volumio sudo[4519]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Jul 25 12:20:28 volumio sudo[4519]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 25 12:20:28 volumio dhcpcd[4520]: sending commands to master dhcpcd process Jul 25 12:20:28 volumio dhcpcd[793]: control command: /sbin/dhcpcd Jul 25 12:20:28 volumio sudo[4519]: pam_unix(sudo:session): session closed for user root Jul 25 12:20:28 volumio volumio[1364]: info: Jul 25 12:20:28 volumio volumio[1364]: ---------------------------- MPD announces state update: player Jul 25 12:20:28 volumio volumio[1364]: info: ControllerMpd::getState Jul 25 12:20:28 volumio volumio[1364]: verbose: ControllerMpd::sendMpdCommand status Jul 25 12:20:28 volumio volumio[1364]: info: Jul 25 12:20:28 volumio volumio[1364]: ---------------------------- MPD announces state update: player Jul 25 12:20:28 volumio volumio[1364]: info: ControllerMpd::getState Jul 25 12:20:28 volumio volumio[1364]: verbose: ControllerMpd::sendMpdCommand status Jul 25 12:20:28 volumio volumio[1364]: info: Jul 25 12:20:28 volumio volumio[1364]: ---------------------------- MPD announces state update: player Jul 25 12:20:28 volumio volumio[1364]: info: ControllerMpd::getState Jul 25 12:20:28 volumio volumio[1364]: verbose: ControllerMpd::sendMpdCommand status Jul 25 12:20:28 volumio volumio[1364]: verbose: ControllerMpd::sendMpdCommand clearerror Jul 25 12:20:28 volumio volumio[1364]: info: sendMpdCommand status took 28 milliseconds Jul 25 12:20:28 volumio volumio[1364]: verbose: ControllerMpd::sendMpdCommand clearerror Jul 25 12:20:28 volumio volumio[1364]: info: sendMpdCommand status took 23 milliseconds Jul 25 12:20:28 volumio volumio[1364]: verbose: ControllerMpd::sendMpdCommand clearerror Jul 25 12:20:28 volumio volumio[1364]: info: sendMpdCommand status took 21 milliseconds Jul 25 12:20:28 volumio volumio[1364]: verbose: ControllerMpd::parseState Jul 25 12:20:28 volumio volumio[1364]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 25 12:20:28 volumio volumio[1364]: verbose: ControllerMpd::parseState Jul 25 12:20:28 volumio volumio[1364]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 25 12:20:28 volumio volumio[1364]: verbose: ControllerMpd::parseState Jul 25 12:20:28 volumio volumio[1364]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 25 12:20:28 volumio volumio[1364]: info: sendMpdCommand clearerror took 25 milliseconds Jul 25 12:20:28 volumio volumio[1364]: info: sendMpdCommand clearerror took 24 milliseconds Jul 25 12:20:28 volumio volumio[1364]: info: sendMpdCommand clearerror took 23 milliseconds Jul 25 12:20:28 volumio volumio[1364]: info: sendMpdCommand playlistinfo took 10 milliseconds Jul 25 12:20:28 volumio volumio[1364]: info: sendMpdCommand playlistinfo took 9 milliseconds Jul 25 12:20:28 volumio volumio[1364]: info: sendMpdCommand playlistinfo took 9 milliseconds Jul 25 12:20:28 volumio volumio[1364]: verbose: ControllerMpd::parseTrackInfo Jul 25 12:20:28 volumio volumio[1364]: verbose: ControllerMpd::parseTrackInfo Jul 25 12:20:28 volumio volumio[1364]: verbose: ControllerMpd::parseTrackInfo Jul 25 12:20:28 volumio volumio[1364]: info: ControllerMpd::pushState Jul 25 12:20:28 volumio volumio[1364]: info: CoreCommandRouter::servicePushState Jul 25 12:20:28 volumio volumio[1364]: info: CoreStateMachine::pushState Jul 25 12:20:28 volumio volumio[1364]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 25 12:20:28 volumio volumio[1364]: info: CoreCommandRouter::volumioPushState Jul 25 12:20:28 volumio volumio[1364]: info: CorePlayQueue::getTrack 0 Jul 25 12:20:28 volumio volumio[1364]: 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":"retro128","artist":null,"album":null,"uri":"https://retro.hostingradio.ru:8043/retro128","trackType":""} Jul 25 12:20:28 volumio volumio[1364]: verbose: CURRENT POSITION 0 Jul 25 12:20:28 volumio volumio[1364]: info: CoreStateMachine::syncState stateService stop Jul 25 12:20:28 volumio volumio[1364]: info: CoreStateMachine::syncState currentStatus stop Jul 25 12:20:28 volumio volumio[1364]: info: CoreStateMachine::pushState Jul 25 12:20:28 volumio volumio[1364]: info: CoreCommandRouter::volumioPushState Jul 25 12:20:28 volumio volumio[1364]: info: No code Jul 25 12:20:28 volumio volumio[1364]: info: CoreStateMachine::pushState Jul 25 12:20:28 volumio volumio[1364]: info: CoreCommandRouter::volumioPushState Jul 25 12:20:28 volumio volumio[1364]: info: ControllerMpd::pushState Jul 25 12:20:28 volumio volumio[1364]: info: CoreCommandRouter::servicePushState Jul 25 12:20:28 volumio volumio[1364]: info: CoreStateMachine::pushState Jul 25 12:20:28 volumio volumio[1364]: info: CoreCommandRouter::volumioPushState Jul 25 12:20:28 volumio volumio[1364]: info: CorePlayQueue::getTrack 0 Jul 25 12:20:28 volumio volumio[1364]: 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":"retro128","artist":null,"album":null,"uri":"https://retro.hostingradio.ru:8043/retro128","trackType":""} Jul 25 12:20:28 volumio volumio[1364]: verbose: CURRENT POSITION 0 Jul 25 12:20:28 volumio volumio[1364]: info: CoreStateMachine::syncState stateService stop Jul 25 12:20:28 volumio volumio[1364]: info: CoreStateMachine::syncState currentStatus stop Jul 25 12:20:28 volumio volumio[1364]: info: CoreStateMachine::pushState Jul 25 12:20:28 volumio volumio[1364]: info: CoreCommandRouter::volumioPushState Jul 25 12:20:28 volumio volumio[1364]: info: No code Jul 25 12:20:28 volumio volumio[1364]: info: CoreStateMachine::pushState Jul 25 12:20:28 volumio volumio[1364]: info: CoreCommandRouter::volumioPushState Jul 25 12:20:28 volumio volumio[1364]: info: ControllerMpd::pushState Jul 25 12:20:28 volumio volumio[1364]: info: CoreCommandRouter::servicePushState Jul 25 12:20:28 volumio volumio[1364]: info: CoreStateMachine::pushState Jul 25 12:20:28 volumio volumio[1364]: info: CoreCommandRouter::volumioPushState Jul 25 12:20:28 volumio volumio[1364]: info: CorePlayQueue::getTrack 0 Jul 25 12:20:28 volumio volumio[1364]: 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":"retro128","artist":null,"album":null,"uri":"https://retro.hostingradio.ru:8043/retro128","trackType":""} Jul 25 12:20:28 volumio volumio[1364]: verbose: CURRENT POSITION 0 Jul 25 12:20:28 volumio volumio[1364]: info: CoreStateMachine::syncState stateService stop Jul 25 12:20:28 volumio volumio[1364]: info: CoreStateMachine::syncState currentStatus stop Jul 25 12:20:28 volumio volumio[1364]: info: CoreStateMachine::pushState Jul 25 12:20:28 volumio volumio[1364]: info: CoreCommandRouter::volumioPushState Jul 25 12:20:28 volumio volumio[1364]: info: No code Jul 25 12:20:28 volumio volumio[1364]: info: CoreStateMachine::pushState Jul 25 12:20:28 volumio volumio[1364]: info: CoreCommandRouter::volumioPushState Jul 25 12:20:28 volumio volumio[1364]: info: ------------------------------ 187ms Jul 25 12:20:28 volumio volumio[1364]: info: ------------------------------ 188ms Jul 25 12:20:28 volumio volumio[1364]: info: ------------------------------ 182ms Jul 25 12:20:29 volumio wireless.js[4466]: trying... Jul 25 12:20:29 volumio sudo[4523]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 25 12:20:29 volumio sudo[4523]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 25 12:20:29 volumio sudo[4523]: pam_unix(sudo:session): session closed for user root Jul 25 12:20:29 volumio wpa_supplicant[4521]: wlan0: SME: Trying to authenticate with ee:1d:7f:ad:53:d8 (SSID='hometta' freq=2462 MHz) Jul 25 12:20:29 volumio kernel: wlan0: authenticate with ee:1d:7f:ad:53:d8 Jul 25 12:20:29 volumio kernel: wlan0: 80 MHz not supported, disabling VHT Jul 25 12:20:29 volumio kernel: wlan0: send auth to ee:1d:7f:ad:53:d8 (try 1/3) Jul 25 12:20:29 volumio wpa_supplicant[4521]: wlan0: Trying to associate with ee:1d:7f:ad:53:d8 (SSID='hometta' freq=2462 MHz) Jul 25 12:20:29 volumio kernel: wlan0: send auth to ee:1d:7f:ad:53:d8 (try 2/3) Jul 25 12:20:29 volumio kernel: wlan0: authenticated Jul 25 12:20:29 volumio kernel: wlan0: associate with ee:1d:7f:ad:53:d8 (try 1/3) Jul 25 12:20:29 volumio kernel: wlan0: RX AssocResp from ee:1d:7f:ad:53:d8 (capab=0x431 status=0 aid=1) Jul 25 12:20:29 volumio kernel: usb 3-1: rtl8xxxu_bss_info_changed: HT supported Jul 25 12:20:29 volumio wpa_supplicant[4521]: wlan0: Associated with ee:1d:7f:ad:53:d8 Jul 25 12:20:29 volumio wpa_supplicant[4521]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jul 25 12:20:29 volumio dhcpcd[793]: wlan0: waiting for carrier Jul 25 12:20:29 volumio wpa_supplicant[4521]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=FR Jul 25 12:20:29 volumio kernel: wlan0: associated Jul 25 12:20:29 volumio kernel: ath: EEPROM regdomain: 0x80fa Jul 25 12:20:29 volumio kernel: ath: EEPROM indicates we should expect a country code Jul 25 12:20:29 volumio kernel: ath: doing EEPROM country->regdmn map search Jul 25 12:20:29 volumio kernel: ath: country maps to regdmn code: 0x37 Jul 25 12:20:29 volumio kernel: ath: Country alpha2 being used: FR Jul 25 12:20:29 volumio kernel: ath: Regpair used: 0x37 Jul 25 12:20:29 volumio kernel: ath: regdomain 0x80fa dynamically updated by country element Jul 25 12:20:29 volumio wpa_supplicant[4521]: wlan0: WPA: Key negotiation completed with ee:1d:7f:ad:53:d8 [PTK=CCMP GTK=TKIP] Jul 25 12:20:29 volumio wpa_supplicant[4521]: wlan0: CTRL-EVENT-CONNECTED - Connection to ee:1d:7f:ad:53:d8 completed [id=0 id_str=] Jul 25 12:20:29 volumio dhcpcd[793]: wlan0: waiting for carrier Jul 25 12:20:30 volumio volumio[1364]: info: Preload queue cleared Jul 25 12:20:30 volumio volumio[1364]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 25 12:20:30 volumio volumio[1364]: info: CoreStateMachine::ClearQueue Jul 25 12:20:30 volumio volumio[1364]: info: CoreStateMachine::stop Jul 25 12:20:30 volumio volumio[1364]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 25 12:20:30 volumio volumio[1364]: info: CorePlayQueue::clearPlayQueue Jul 25 12:20:30 volumio volumio[1364]: info: CorePlayQueue::saveQueue Jul 25 12:20:30 volumio volumio[1364]: info: CoreCommandRouter::volumioPushQueue Jul 25 12:20:30 volumio volumio[1364]: info: CoreStateMachine::addQueueItems Jul 25 12:20:30 volumio volumio[1364]: info: CorePlayQueue::addQueueItems Jul 25 12:20:30 volumio volumio[1364]: info: Preload queue cleared Jul 25 12:20:30 volumio volumio[1364]: info: Adding Item to queue: https://retro.hostingradio.ru:8043/retro128 Jul 25 12:20:30 volumio volumio[1364]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jul 25 12:20:30 volumio volumio[1364]: info: CoreCommandRouter::volumioPushQueue Jul 25 12:20:30 volumio volumio[1364]: info: CorePlayQueue::saveQueue Jul 25 12:20:30 volumio volumio[1364]: info: CoreStateMachine::updateTrackBlock Jul 25 12:20:30 volumio volumio[1364]: info: CorePlayQueue::getTrackBlock Jul 25 12:20:30 volumio volumio[1364]: info: CoreCommandRouter::volumioPlay Jul 25 12:20:30 volumio volumio[1364]: info: CoreStateMachine::play index 0 Jul 25 12:20:30 volumio volumio[1364]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 25 12:20:30 volumio volumio[1364]: info: CoreStateMachine::stop Jul 25 12:20:30 volumio volumio[1364]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 25 12:20:30 volumio volumio[1364]: info: CoreStateMachine::play index undefined Jul 25 12:20:30 volumio volumio[1364]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 25 12:20:30 volumio volumio[1364]: info: CorePlayQueue::getTrack 0 Jul 25 12:20:30 volumio volumio[1364]: info: CoreStateMachine::startPlaybackTimer Jul 25 12:20:30 volumio volumio[1364]: info: CorePlayQueue::getTrack 0 Jul 25 12:20:30 volumio volumio[1364]: info: [1721899230162] ControllerWebradio::clearAddPlayTrack Jul 25 12:20:30 volumio volumio[1364]: verbose: ControllerMpd::sendMpdCommand stop Jul 25 12:20:30 volumio volumio[1364]: info: sendMpdCommand stop took 3 milliseconds Jul 25 12:20:30 volumio volumio[1364]: verbose: ControllerMpd::sendMpdCommand clear Jul 25 12:20:30 volumio volumio[1364]: info: Jul 25 12:20:30 volumio volumio[1364]: ---------------------------- MPD announces system playlist update Jul 25 12:20:30 volumio volumio[1364]: info: Ignoring MPD Status Update Jul 25 12:20:30 volumio volumio[1364]: info: sendMpdCommand clear took 6 milliseconds Jul 25 12:20:30 volumio volumio[1364]: verbose: ControllerMpd::sendMpdCommand load "https://retro.hostingradio.ru:8043/retro128" Jul 25 12:20:30 volumio volumio[1364]: info: Jul 25 12:20:30 volumio volumio[1364]: ---------------------------- MPD announces system playlist update Jul 25 12:20:30 volumio volumio[1364]: info: Ignoring MPD Status Update Jul 25 12:20:30 volumio volumio[1364]: info: Jul 25 12:20:30 volumio volumio[1364]: ---------------------------- MPD announces system playlist update Jul 25 12:20:30 volumio volumio[1364]: info: Ignoring MPD Status Update Jul 25 12:20:30 volumio wireless.js[4466]: trying... Jul 25 12:20:30 volumio volumio[1364]: info: Discovery: Started advertising with name: Volumio Jul 25 12:20:30 volumio sudo[4528]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 25 12:20:30 volumio sudo[4528]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 25 12:20:30 volumio sudo[4528]: pam_unix(sudo:session): session closed for user root Jul 25 12:20:30 volumio volumio[1364]: error: updateQueue error: null Jul 25 12:20:30 volumio volumio[1364]: error: updateQueue error: null Jul 25 12:20:30 volumio volumio[1364]: error: updateQueue error: null Jul 25 12:20:30 volumio volumio[1364]: info: ------------------------------ 635ms Jul 25 12:20:30 volumio volumio[1364]: verbose: ControllerMpd::sendMpdCommand add "https://retro.hostingradio.ru:8043/retro128" Jul 25 12:20:30 volumio volumio[1364]: info: ------------------------------ 632ms Jul 25 12:20:30 volumio volumio[1364]: info: ------------------------------ 627ms Jul 25 12:20:30 volumio volumio[1364]: info: Jul 25 12:20:30 volumio volumio[1364]: ---------------------------- MPD announces system playlist update Jul 25 12:20:30 volumio volumio[1364]: info: Ignoring MPD Status Update Jul 25 12:20:30 volumio volumio[1364]: info: sendMpdCommand add "https://retro.hostingradio.ru:8043/retro128" took 3 milliseconds Jul 25 12:20:30 volumio volumio[1364]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 25 12:20:30 volumio volumio[1364]: verbose: ControllerMpd::sendMpdCommand play Jul 25 12:20:30 volumio volumio[1364]: info: Jul 25 12:20:30 volumio volumio[1364]: ---------------------------- MPD announces system playlist update Jul 25 12:20:30 volumio volumio[1364]: info: Ignoring MPD Status Update Jul 25 12:20:30 volumio volumio[1364]: info: Jul 25 12:20:30 volumio volumio[1364]: ---------------------------- MPD announces system playlist update Jul 25 12:20:30 volumio volumio[1364]: info: Ignoring MPD Status Update Jul 25 12:20:30 volumio volumio[1364]: info: ------------------------------ 11ms Jul 25 12:20:30 volumio volumio[1364]: info: sendMpdCommand play took 9 milliseconds Jul 25 12:20:30 volumio volumio[1364]: info: ------------------------------ 8ms Jul 25 12:20:30 volumio volumio[1364]: info: ------------------------------ 6ms Jul 25 12:20:31 volumio wireless.js[4466]: trying... Jul 25 12:20:31 volumio sudo[4532]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 25 12:20:31 volumio sudo[4532]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 25 12:20:31 volumio sudo[4532]: pam_unix(sudo:session): session closed for user root Jul 25 12:20:31 volumio volumio[1364]: info: Jul 25 12:20:31 volumio volumio[1364]: ---------------------------- MPD announces state update: player Jul 25 12:20:31 volumio volumio[1364]: info: ControllerMpd::getState Jul 25 12:20:31 volumio volumio[1364]: verbose: ControllerMpd::sendMpdCommand status Jul 25 12:20:31 volumio volumio[1364]: info: Jul 25 12:20:31 volumio volumio[1364]: ---------------------------- MPD announces state update: player Jul 25 12:20:31 volumio volumio[1364]: info: ControllerMpd::getState Jul 25 12:20:31 volumio volumio[1364]: verbose: ControllerMpd::sendMpdCommand status Jul 25 12:20:31 volumio volumio[1364]: info: Jul 25 12:20:31 volumio volumio[1364]: ---------------------------- MPD announces state update: player Jul 25 12:20:31 volumio volumio[1364]: info: ControllerMpd::getState Jul 25 12:20:31 volumio volumio[1364]: verbose: ControllerMpd::sendMpdCommand status Jul 25 12:20:31 volumio volumio[1364]: verbose: ControllerMpd::sendMpdCommand clearerror Jul 25 12:20:31 volumio volumio[1364]: info: sendMpdCommand status took 13 milliseconds Jul 25 12:20:31 volumio volumio[1364]: verbose: ControllerMpd::sendMpdCommand clearerror Jul 25 12:20:31 volumio volumio[1364]: info: sendMpdCommand status took 13 milliseconds Jul 25 12:20:31 volumio volumio[1364]: verbose: ControllerMpd::sendMpdCommand clearerror Jul 25 12:20:31 volumio volumio[1364]: info: sendMpdCommand status took 12 milliseconds Jul 25 12:20:31 volumio volumio[1364]: verbose: ControllerMpd::parseState Jul 25 12:20:31 volumio volumio[1364]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 25 12:20:31 volumio volumio[1364]: verbose: ControllerMpd::parseState Jul 25 12:20:31 volumio volumio[1364]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 25 12:20:31 volumio volumio[1364]: verbose: ControllerMpd::parseState Jul 25 12:20:31 volumio volumio[1364]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 25 12:20:31 volumio volumio[1364]: info: sendMpdCommand clearerror took 25 milliseconds Jul 25 12:20:31 volumio volumio[1364]: info: sendMpdCommand clearerror took 24 milliseconds Jul 25 12:20:31 volumio volumio[1364]: info: sendMpdCommand clearerror took 23 milliseconds Jul 25 12:20:31 volumio volumio[1364]: info: sendMpdCommand playlistinfo took 12 milliseconds Jul 25 12:20:31 volumio volumio[1364]: info: sendMpdCommand playlistinfo took 11 milliseconds Jul 25 12:20:31 volumio volumio[1364]: verbose: ControllerMpd::parseTrackInfo Jul 25 12:20:31 volumio volumio[1364]: verbose: ControllerMpd::parseTrackInfo Jul 25 12:20:31 volumio volumio[1364]: info: ControllerMpd::pushState Jul 25 12:20:31 volumio volumio[1364]: info: CoreCommandRouter::servicePushState Jul 25 12:20:31 volumio volumio[1364]: info: CoreStateMachine::pushState Jul 25 12:20:31 volumio volumio[1364]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 25 12:20:31 volumio volumio[1364]: info: CoreCommandRouter::volumioPushState Jul 25 12:20:31 volumio volumio[1364]: info: CorePlayQueue::getTrack 0 Jul 25 12:20:31 volumio volumio[1364]: 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":"retro128","artist":null,"album":null,"uri":"https://retro.hostingradio.ru:8043/retro128","trackType":""} Jul 25 12:20:31 volumio volumio[1364]: verbose: CURRENT POSITION 0 Jul 25 12:20:31 volumio volumio[1364]: info: CoreStateMachine::syncState stateService stop Jul 25 12:20:31 volumio volumio[1364]: info: CoreStateMachine::syncState currentStatus stop Jul 25 12:20:31 volumio volumio[1364]: info: CoreStateMachine::pushState Jul 25 12:20:31 volumio volumio[1364]: info: CoreCommandRouter::volumioPushState Jul 25 12:20:31 volumio volumio[1364]: info: No code Jul 25 12:20:31 volumio volumio[1364]: info: CoreStateMachine::pushState Jul 25 12:20:31 volumio volumio[1364]: info: CoreCommandRouter::volumioPushState Jul 25 12:20:31 volumio volumio[1364]: info: ControllerMpd::pushState Jul 25 12:20:31 volumio volumio[1364]: info: CoreCommandRouter::servicePushState Jul 25 12:20:31 volumio volumio[1364]: info: CoreStateMachine::pushState Jul 25 12:20:31 volumio volumio[1364]: info: CoreCommandRouter::volumioPushState Jul 25 12:20:31 volumio volumio[1364]: info: CorePlayQueue::getTrack 0 Jul 25 12:20:31 volumio volumio[1364]: 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":"retro128","artist":null,"album":null,"uri":"https://retro.hostingradio.ru:8043/retro128","trackType":""} Jul 25 12:20:31 volumio volumio[1364]: verbose: CURRENT POSITION 0 Jul 25 12:20:31 volumio volumio[1364]: info: CoreStateMachine::syncState stateService stop Jul 25 12:20:31 volumio volumio[1364]: info: CoreStateMachine::syncState currentStatus stop Jul 25 12:20:31 volumio volumio[1364]: info: CoreStateMachine::pushState Jul 25 12:20:31 volumio volumio[1364]: info: CoreCommandRouter::volumioPushState Jul 25 12:20:31 volumio volumio[1364]: info: No code Jul 25 12:20:31 volumio volumio[1364]: info: CoreStateMachine::pushState Jul 25 12:20:31 volumio volumio[1364]: info: CoreCommandRouter::volumioPushState Jul 25 12:20:31 volumio volumio[1364]: info: ControllerMpd::pushState Jul 25 12:20:31 volumio volumio[1364]: info: CoreCommandRouter::servicePushState Jul 25 12:20:31 volumio volumio[1364]: info: CoreStateMachine::pushState Jul 25 12:20:31 volumio volumio[1364]: info: CoreCommandRouter::volumioPushState Jul 25 12:20:31 volumio volumio[1364]: info: CorePlayQueue::getTrack 0 Jul 25 12:20:31 volumio volumio[1364]: 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":"retro128","artist":null,"album":null,"uri":"https://retro.hostingradio.ru:8043/retro128","trackType":""} Jul 25 12:20:31 volumio volumio[1364]: verbose: CURRENT POSITION 0 Jul 25 12:20:31 volumio volumio[1364]: info: CoreStateMachine::syncState stateService stop Jul 25 12:20:31 volumio volumio[1364]: info: CoreStateMachine::syncState currentStatus stop Jul 25 12:20:31 volumio volumio[1364]: info: CoreStateMachine::pushState Jul 25 12:20:31 volumio volumio[1364]: info: CoreCommandRouter::volumioPushState Jul 25 12:20:31 volumio volumio[1364]: info: No code Jul 25 12:20:31 volumio volumio[1364]: info: CoreStateMachine::pushState Jul 25 12:20:31 volumio volumio[1364]: info: CoreCommandRouter::volumioPushState Jul 25 12:20:32 volumio volumio[1364]: info: ------------------------------ 228ms Jul 25 12:20:32 volumio volumio[1364]: info: ------------------------------ 227ms Jul 25 12:20:32 volumio volumio[1364]: info: ------------------------------ 231ms Jul 25 12:20:32 volumio wireless.js[4466]: trying... Jul 25 12:20:32 volumio sudo[4535]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 25 12:20:32 volumio sudo[4535]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 25 12:20:32 volumio sudo[4535]: pam_unix(sudo:session): session closed for user root Jul 25 12:20:33 volumio wireless.js[4466]: trying... Jul 25 12:20:33 volumio sudo[4538]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 25 12:20:33 volumio sudo[4538]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 25 12:20:33 volumio sudo[4538]: pam_unix(sudo:session): session closed for user root Jul 25 12:20:34 volumio wireless.js[4466]: trying... Jul 25 12:20:34 volumio sudo[4541]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 25 12:20:34 volumio sudo[4541]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 25 12:20:34 volumio sudo[4541]: pam_unix(sudo:session): session closed for user root Jul 25 12:20:34 volumio kernel: usb 3-1: USB disconnect, device number 2 Jul 25 12:20:34 volumio wpa_supplicant[4521]: wlan0: CTRL-EVENT-DISCONNECTED bssid=ee:1d:7f:ad:53:d8 reason=3 locally_generated=1 Jul 25 12:20:34 volumio kernel: wlan0: deauthenticating from ee:1d:7f:ad:53:d8 by local choice (Reason: 3=DEAUTH_LEAVING) Jul 25 12:20:34 volumio dhcpcd[793]: wlan0: removing interface Jul 25 12:20:34 volumio systemd[1]: Starting Load/Save RF Kill Switch Status... Jul 25 12:20:34 volumio kernel: usb 3-1: rtl8192eu_active_to_emu: Disabling MAC timed out Jul 25 12:20:34 volumio kernel: usb 3-1: disconnecting Jul 25 12:20:34 volumio wpa_supplicant[4521]: nl80211: Failed to open /proc/sys/net/ipv4/conf/wlan0/drop_unicast_in_l2_multicast: No such file or directory Jul 25 12:20:34 volumio wpa_supplicant[4521]: nl80211: Failed to set IPv4 unicast in multicast filter Jul 25 12:20:34 volumio systemd[1]: Started Load/Save RF Kill Switch Status. Jul 25 12:20:34 volumio wpa_supplicant[4521]: nl80211: Failed to open /proc/sys/net/ipv4/conf/wlan0/drop_unicast_in_l2_multicast: No such file or directory Jul 25 12:20:34 volumio wpa_supplicant[4521]: nl80211: Failed to set IPv4 unicast in multicast filter Jul 25 12:20:34 volumio wpa_supplicant[4521]: nl80211: Failed to open /proc/sys/net/ipv4/conf/wlan0/drop_unicast_in_l2_multicast: No such file or directory Jul 25 12:20:34 volumio wpa_supplicant[4521]: nl80211: Failed to set IPv4 unicast in multicast filter Jul 25 12:20:35 volumio wireless.js[4466]: trying... Jul 25 12:20:35 volumio sudo[4569]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 25 12:20:35 volumio sudo[4569]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 25 12:20:35 volumio sudo[4569]: pam_unix(sudo:session): session closed for user root Jul 25 12:20:36 volumio wireless.js[4466]: trying... Jul 25 12:20:36 volumio sudo[4572]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 25 12:20:36 volumio sudo[4572]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 25 12:20:36 volumio sudo[4572]: pam_unix(sudo:session): session closed for user root Jul 25 12:20:36 volumio kernel: usb 3-1: new high-speed USB device number 5 using ehci-pci Jul 25 12:20:36 volumio kernel: usb 3-1: New USB device found, idVendor=2357, idProduct=0108, bcdDevice= 2.00 Jul 25 12:20:36 volumio kernel: usb 3-1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Jul 25 12:20:36 volumio kernel: usb 3-1: Product: 802.11n NIC Jul 25 12:20:36 volumio kernel: usb 3-1: Manufacturer: Realtek Jul 25 12:20:36 volumio kernel: usb 3-1: SerialNumber: 00e04c000001 Jul 25 12:20:36 volumio kernel: usb 3-1: This Realtek USB WiFi dongle (0x2357:0x0108) is untested! Jul 25 12:20:36 volumio kernel: usb 3-1: Please report results to Jes.Sorensen@gmail.com Jul 25 12:20:36 volumio kernel: usb 3-1: Dumping efuse for RTL8192EU (0x200 bytes): Jul 25 12:20:36 volumio kernel: 00000000: 29 81 00 7c 01 40 03 00 40 74 04 50 14 00 00 00 )..|.@..@t.P.... Jul 25 12:20:36 volumio kernel: 00000010: 28 29 2a 2b 2b 2b 29 2a 2a 2b 2b f2 ef ef ff ff ()*+++)**++..... Jul 25 12:20:36 volumio kernel: 00000020: ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ................ Jul 25 12:20:36 volumio kernel: 00000030: ff ff ff ff ff ff ff ff ff ff 28 28 28 29 29 29 ..........((())) Jul 25 12:20:36 volumio kernel: 00000040: 29 29 29 29 29 f2 ef ef ff ff ff ff ff ff ff ff )))))........... Jul 25 12:20:36 volumio kernel: 00000050: ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ................ Jul 25 12:20:36 volumio kernel: 00000060: ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ................ Jul 25 12:20:36 volumio kernel: 00000070: ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ................ Jul 25 12:20:36 volumio kernel: 00000080: ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ................ Jul 25 12:20:36 volumio kernel: 00000090: ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ................ Jul 25 12:20:36 volumio kernel: 000000a0: ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ................ Jul 25 12:20:36 volumio kernel: 000000b0: ff ff ff ff ff ff ff ff a1 2f 1d 00 00 00 ff ff ........./...... Jul 25 12:20:36 volumio kernel: 000000c0: ff 01 00 10 00 00 00 ff 00 00 ff ff ff ff ff ff ................ Jul 25 12:20:36 volumio kernel: 000000d0: 57 23 08 01 e7 47 02 28 ee 52 1d ce d3 0a 03 52 W#...G.(.R.....R Jul 25 12:20:36 volumio kernel: 000000e0: 65 61 6c 74 65 6b 20 0e 03 38 30 32 2e 31 31 6e ealtek ..802.11n Jul 25 12:20:36 volumio kernel: 000000f0: 20 4e 49 43 20 00 00 ff ff ff ff ff ff ff ff ff NIC ........... Jul 25 12:20:36 volumio kernel: 00000100: ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ................ Jul 25 12:20:36 volumio kernel: 00000110: ff ff ff ff ff ff ff 0d 03 00 05 00 30 00 00 00 ............0... Jul 25 12:20:36 volumio kernel: 00000120: 00 93 ff ff ff ff ff ff ff ff ff ff ff ff ff ff ................ Jul 25 12:20:36 volumio kernel: 00000130: f6 a8 98 2d 03 92 98 00 fc 8c 00 11 9b 44 02 0a ...-.........D.. Jul 25 12:20:36 volumio kernel: 00000140: ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ................ Jul 25 12:20:36 volumio kernel: 00000150: ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ................ Jul 25 12:20:36 volumio kernel: 00000160: ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ................ Jul 25 12:20:36 volumio kernel: 00000170: ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ................ Jul 25 12:20:36 volumio kernel: 00000180: ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ................ Jul 25 12:20:36 volumio kernel: 00000190: ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ................ Jul 25 12:20:36 volumio kernel: 000001a0: ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ................ Jul 25 12:20:36 volumio kernel: 000001b0: ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ................ Jul 25 12:20:36 volumio kernel: 000001c0: ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ................ Jul 25 12:20:36 volumio kernel: 000001d0: ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ................ Jul 25 12:20:36 volumio kernel: 000001e0: ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ................ Jul 25 12:20:36 volumio kernel: 000001f0: ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ................ Jul 25 12:20:36 volumio kernel: usb 3-1: RTL8192EU rev B (SMIC) romver 0, 2T2R, TX queues 3, WiFi=1, BT=0, GPS=0, HI PA=0 Jul 25 12:20:36 volumio kernel: usb 3-1: RTL8192EU MAC: 28:ee:52:1d:ce:d3 Jul 25 12:20:36 volumio kernel: usb 3-1: rtl8xxxu: Loading firmware rtlwifi/rtl8192eu_nic.bin Jul 25 12:20:36 volumio kernel: usb 3-1: Firmware revision 35.7 (signature 0x92e1) Jul 25 12:20:37 volumio wireless.js[4466]: trying... Jul 25 12:20:37 volumio sudo[4575]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 25 12:20:37 volumio sudo[4575]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 25 12:20:37 volumio sudo[4575]: pam_unix(sudo:session): session closed for user root Jul 25 12:20:38 volumio systemd-udevd[4546]: Using default interface naming scheme 'v240'. Jul 25 12:20:38 volumio systemd-udevd[4546]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Jul 25 12:20:38 volumio dhcpcd[793]: wlan0: waiting for carrier Jul 25 12:20:38 volumio dhcpcd[793]: wlan0: carrier acquired Jul 25 12:20:38 volumio dhcpcd[793]: DUID 00:04:03:00:02:00:04:00:05:00:00:06:00:07:00:08:00:09 Jul 25 12:20:38 volumio dhcpcd[793]: wlan0: IAID 52:1d:ce:d3 Jul 25 12:20:38 volumio dhcpcd[793]: wlan0: adding address fe80::345d:5c16:5b60:47c0 Jul 25 12:20:38 volumio dhcpcd[793]: ipv6_addaddr1: Permission denied Jul 25 12:20:38 volumio dhcpcd[793]: wlan0: carrier lost Jul 25 12:20:38 volumio wireless.js[4466]: trying... Jul 25 12:20:38 volumio sudo[4605]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 25 12:20:38 volumio sudo[4605]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 25 12:20:38 volumio sudo[4605]: pam_unix(sudo:session): session closed for user root Jul 25 12:20:39 volumio wpa_supplicant[4521]: wlan0: SME: Trying to authenticate with ee:1d:7f:ad:53:d8 (SSID='hometta' freq=2462 MHz) Jul 25 12:20:39 volumio kernel: wlan0: authenticate with ee:1d:7f:ad:53:d8 Jul 25 12:20:39 volumio kernel: wlan0: 80 MHz not supported, disabling VHT Jul 25 12:20:39 volumio wireless.js[4466]: trying... Jul 25 12:20:39 volumio kernel: wlan0: send auth to ee:1d:7f:ad:53:d8 (try 1/3) Jul 25 12:20:39 volumio kernel: wlan0: authenticated Jul 25 12:20:39 volumio wpa_supplicant[4521]: wlan0: Trying to associate with ee:1d:7f:ad:53:d8 (SSID='hometta' freq=2462 MHz) Jul 25 12:20:39 volumio kernel: wlan0: associate with ee:1d:7f:ad:53:d8 (try 1/3) Jul 25 12:20:39 volumio kernel: wlan0: RX AssocResp from ee:1d:7f:ad:53:d8 (capab=0x431 status=0 aid=1) Jul 25 12:20:39 volumio kernel: usb 3-1: rtl8xxxu_bss_info_changed: HT supported Jul 25 12:20:39 volumio kernel: wlan0: associated Jul 25 12:20:39 volumio kernel: ath: EEPROM regdomain: 0x80fa Jul 25 12:20:39 volumio kernel: ath: EEPROM indicates we should expect a country code Jul 25 12:20:39 volumio kernel: ath: doing EEPROM country->regdmn map search Jul 25 12:20:39 volumio kernel: ath: country maps to regdmn code: 0x37 Jul 25 12:20:39 volumio kernel: ath: Country alpha2 being used: FR Jul 25 12:20:39 volumio kernel: ath: Regpair used: 0x37 Jul 25 12:20:39 volumio kernel: ath: regdomain 0x80fa dynamically updated by country element Jul 25 12:20:39 volumio wpa_supplicant[4521]: wlan0: Associated with ee:1d:7f:ad:53:d8 Jul 25 12:20:39 volumio wpa_supplicant[4521]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jul 25 12:20:39 volumio wpa_supplicant[4521]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=FR Jul 25 12:20:39 volumio sudo[4608]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 25 12:20:39 volumio sudo[4608]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 25 12:20:39 volumio sudo[4608]: pam_unix(sudo:session): session closed for user root Jul 25 12:20:39 volumio wpa_supplicant[4521]: wlan0: WPA: Key negotiation completed with ee:1d:7f:ad:53:d8 [PTK=CCMP GTK=TKIP] Jul 25 12:20:39 volumio wpa_supplicant[4521]: wlan0: CTRL-EVENT-CONNECTED - Connection to ee:1d:7f:ad:53:d8 completed [id=0 id_str=] Jul 25 12:20:39 volumio dhcpcd[793]: wlan0: carrier acquired Jul 25 12:20:39 volumio dhcpcd[793]: wlan0: IAID 52:1d:ce:d3 Jul 25 12:20:39 volumio dhcpcd[793]: wlan0: soliciting an IPv6 router Jul 25 12:20:39 volumio volumio[1364]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 25 12:20:39 volumio volumio[1364]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 25 12:20:40 volumio wireless.js[4466]: trying... Jul 25 12:20:40 volumio sudo[4614]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 25 12:20:40 volumio sudo[4614]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 25 12:20:40 volumio sudo[4614]: pam_unix(sudo:session): session closed for user root Jul 25 12:20:40 volumio dhcpcd[793]: wlan0: soliciting a DHCP lease Jul 25 12:20:41 volumio volumio[1364]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Jul 25 12:20:41 volumio sudo[4622]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jul 25 12:20:41 volumio sudo[4622]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 25 12:20:41 volumio sudo[4617]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Jul 25 12:20:41 volumio sudo[4617]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 25 12:20:41 volumio sudo[4622]: pam_unix(sudo:session): session closed for user root Jul 25 12:20:41 volumio wireless.js[4466]: trying... Jul 25 12:20:41 volumio sudo[4628]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jul 25 12:20:41 volumio sudo[4633]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 25 12:20:41 volumio sudo[4633]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 25 12:20:41 volumio sudo[4628]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 25 12:20:41 volumio sudo[4633]: pam_unix(sudo:session): session closed for user root Jul 25 12:20:41 volumio sudo[4628]: pam_unix(sudo:session): session closed for user root Jul 25 12:20:41 volumio sudo[4640]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jul 25 12:20:41 volumio sudo[4640]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 25 12:20:41 volumio sudo[4617]: pam_unix(sudo:session): session closed for user root Jul 25 12:20:41 volumio sudo[4640]: pam_unix(sudo:session): session closed for user root Jul 25 12:20:41 volumio sudo[4653]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 25 12:20:41 volumio sudo[4653]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 25 12:20:41 volumio sudo[4658]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 25 12:20:41 volumio sudo[4658]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 25 12:20:41 volumio sudo[4658]: pam_unix(sudo:session): session closed for user root Jul 25 12:20:41 volumio sudo[4653]: pam_unix(sudo:session): session closed for user root Jul 25 12:20:41 volumio volumio[1364]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Jul 25 12:20:41 volumio volumio[1364]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Jul 25 12:20:41 volumio sudo[4664]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jul 25 12:20:41 volumio sudo[4664]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 25 12:20:42 volumio wireless.js[4466]: trying... Jul 25 12:20:42 volumio sudo[4667]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 25 12:20:42 volumio sudo[4667]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 25 12:20:42 volumio sudo[4667]: pam_unix(sudo:session): session closed for user root Jul 25 12:20:43 volumio systemd[1]: systemd-rfkill.service: Succeeded. Jul 25 12:20:43 volumio wireless.js[4466]: trying... Jul 25 12:20:43 volumio sudo[4670]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 25 12:20:43 volumio sudo[4670]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 25 12:20:43 volumio sudo[4670]: pam_unix(sudo:session): session closed for user root Jul 25 12:20:43 volumio dhcpcd[793]: wlan0: offered 192.168.0.105 from 192.168.0.1 Jul 25 12:20:43 volumio dhcpcd[793]: wlan0: probing address 192.168.0.105/24 Jul 25 12:20:44 volumio wireless.js[4466]: trying... Jul 25 12:20:44 volumio sudo[4673]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 25 12:20:44 volumio sudo[4673]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 25 12:20:44 volumio sudo[4673]: pam_unix(sudo:session): session closed for user root Jul 25 12:20:45 volumio wireless.js[4466]: trying... Jul 25 12:20:45 volumio sudo[4676]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 25 12:20:45 volumio sudo[4676]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 25 12:20:45 volumio sudo[4676]: pam_unix(sudo:session): session closed for user root Jul 25 12:20:46 volumio wireless.js[4466]: trying... Jul 25 12:20:46 volumio sudo[4679]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 25 12:20:46 volumio sudo[4679]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 25 12:20:46 volumio sudo[4679]: pam_unix(sudo:session): session closed for user root Jul 25 12:20:46 volumio sudo[4664]: pam_unix(sudo:session): session closed for user root Jul 25 12:20:47 volumio wireless.js[4466]: trying... Jul 25 12:20:47 volumio sudo[4682]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 25 12:20:47 volumio sudo[4682]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 25 12:20:47 volumio sudo[4682]: pam_unix(sudo:session): session closed for user root Jul 25 12:20:48 volumio wireless.js[4466]: trying... Jul 25 12:20:48 volumio sudo[4685]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 25 12:20:48 volumio sudo[4685]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 25 12:20:48 volumio sudo[4685]: pam_unix(sudo:session): session closed for user root Jul 25 12:20:49 volumio wireless.js[4466]: Overtime, starting plan B Jul 25 12:20:49 volumio dhcpcd[793]: wlan0: leased 192.168.0.105 for 86400 seconds Jul 25 12:20:49 volumio dhcpcd[793]: wlan0: adding route to 192.168.0.0/24 Jul 25 12:20:49 volumio avahi-daemon[689]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.105. Jul 25 12:20:49 volumio avahi-daemon[689]: New relevant interface wlan0.IPv4 for mDNS. Jul 25 12:20:49 volumio avahi-daemon[689]: Registering new address record for 192.168.0.105 on wlan0.IPv4. Jul 25 12:20:49 volumio dhcpcd[793]: wlan0: adding default route via 192.168.0.1 Jul 25 12:20:49 volumio iwconfig[4695]: Error for wireless request "Set Power Management" (8B2C) : Jul 25 12:20:49 volumio iwconfig[4695]: SET failed on device wlan0 ; Operation not supported. Jul 25 12:20:49 volumio systemd[1]: Started Wireless Services. Jul 25 12:20:49 volumio sudo[4464]: pam_unix(sudo:session): session closed for user root Jul 25 12:20:49 volumio ntpd[877]: ntpd exiting on signal 15 (Terminated) Jul 25 12:20:49 volumio systemd[1]: Stopping Network Time Service... Jul 25 12:20:49 volumio systemd[1]: ntp.service: Succeeded. Jul 25 12:20:49 volumio systemd[1]: Stopped Network Time Service. Jul 25 12:20:49 volumio systemd[1]: Starting Network Time Service... Jul 25 12:20:49 volumio ntpd[4724]: ntpd 4.2.8p12@1.3728-o (1): Starting Jul 25 12:20:49 volumio systemd[1]: Started Network Time Service. Jul 25 12:20:49 volumio ntpd[4724]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Jul 25 12:20:49 volumio ntpd[4730]: proto: precision = 0.385 usec (-21) Jul 25 12:20:49 volumio ntpd[4730]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jul 25 12:20:49 volumio ntpd[4730]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Jul 25 12:20:49 volumio ntpd[4730]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 576 days ago Jul 25 12:20:49 volumio ntpd[4730]: Listen and drop on 0 v6wildcard [::]:123 Jul 25 12:20:49 volumio ntpd[4730]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jul 25 12:20:49 volumio ntpd[4730]: Listen normally on 2 lo 127.0.0.1:123 Jul 25 12:20:49 volumio ntpd[4730]: Listen normally on 3 wlan0 192.168.0.105:123 Jul 25 12:20:49 volumio ntpd[4730]: Listening on routing socket on fd #20 for interface updates Jul 25 12:20:49 volumio ntpd[4730]: kernel reports TIME_ERROR: 0x4041: Clock Unsynchronized Jul 25 12:20:49 volumio ntpd[4730]: kernel reports TIME_ERROR: 0x4041: Clock Unsynchronized Jul 25 12:20:50 volumio volumio[1364]: info: Discovery: adding 88a95534-e2cf-47d5-8b21-71abf14da8ad Jul 25 12:20:50 volumio volumio[1364]: info: Discovery: Found device Volumio Jul 25 12:20:50 volumio volumio[1364]: info: CoreCommandRouter::volumioGetState Jul 25 12:20:50 volumio volumio[1364]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNetworkSettings Jul 25 12:20:50 volumio volumio[1364]: info: Saving new wireless network Jul 25 12:20:50 volumio sudo[4733]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/wpa_supplicant/wpa_supplicant.conf Jul 25 12:20:50 volumio sudo[4733]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 25 12:20:50 volumio sudo[4733]: pam_unix(sudo:session): session closed for user root Jul 25 12:20:50 volumio volumio[1364]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Jul 25 12:20:50 volumio volumio[1364]: info: Discovery: Restarting Advertising due to device name change Jul 25 12:20:50 volumio sudo[4736]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Jul 25 12:20:50 volumio sudo[4736]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 25 12:20:50 volumio dhcpcd[793]: wlan0: carrier lost Jul 25 12:20:50 volumio kernel: wlan0: deauthenticating from ee:1d:7f:ad:53:d8 by local choice (Reason: 3=DEAUTH_LEAVING) Jul 25 12:20:50 volumio systemd[1]: Stopping Wireless Services... Jul 25 12:20:50 volumio wpa_supplicant[4521]: wlan0: CTRL-EVENT-DISCONNECTED bssid=ee:1d:7f:ad:53:d8 reason=3 locally_generated=1 Jul 25 12:20:50 volumio avahi-daemon[689]: Withdrawing address record for 192.168.0.105 on wlan0. Jul 25 12:20:50 volumio avahi-daemon[689]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.105. Jul 25 12:20:50 volumio volumio[1364]: info: Discovery: A device disappeared from network Jul 25 12:20:50 volumio volumio[1364]: info: Discovery: Device volumio disappeared from network Jul 25 12:20:50 volumio avahi-daemon[689]: Interface wlan0.IPv4 no longer relevant for mDNS. Jul 25 12:20:50 volumio dhcpcd[793]: wlan0: deleting route to 192.168.0.0/24 Jul 25 12:20:50 volumio dhcpcd[793]: wlan0: deleting default route via 192.168.0.1 Jul 25 12:20:50 volumio wpa_supplicant[4521]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Jul 25 12:20:50 volumio wpa_supplicant[4521]: wlan0: CTRL-EVENT-TERMINATING Jul 25 12:20:50 volumio systemd[1]: wireless.service: Succeeded. Jul 25 12:20:50 volumio systemd[1]: Stopped Wireless Services. Jul 25 12:20:50 volumio systemd[1]: Starting Wireless Services... Jul 25 12:20:51 volumio wireless.js[4762]: Cleaning previous... Jul 25 12:20:51 volumio sudo[4776]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jul 25 12:20:51 volumio sudo[4776]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 25 12:20:51 volumio sudo[4776]: pam_unix(sudo:session): session closed for user root Jul 25 12:20:51 volumio sudo[4778]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jul 25 12:20:51 volumio sudo[4778]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 25 12:20:51 volumio sudo[4778]: pam_unix(sudo:session): session closed for user root Jul 25 12:20:51 volumio wireless.js[4762]: Stopped aP Jul 25 12:20:51 volumio sudo[4785]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jul 25 12:20:51 volumio sudo[4785]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 25 12:20:52 volumio sudo[4785]: pam_unix(sudo:session): session closed for user root Jul 25 12:20:52 volumio sudo[4787]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Jul 25 12:20:52 volumio sudo[4787]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 25 12:20:52 volumio sudo[4787]: pam_unix(sudo:session): session closed for user root Jul 25 12:20:52 volumio sudo[4794]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jul 25 12:20:52 volumio sudo[4794]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 25 12:20:52 volumio sudo[4794]: pam_unix(sudo:session): session closed for user root Jul 25 12:20:52 volumio sudo[4796]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Jul 25 12:20:52 volumio sudo[4796]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 25 12:20:53 volumio sudo[4796]: pam_unix(sudo:session): session closed for user root Jul 25 12:20:53 volumio wireless.js[4762]: SETTING APPROPRIATE REG DOMAIN: FR Jul 25 12:20:53 volumio sudo[4801]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jul 25 12:20:53 volumio sudo[4801]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 25 12:20:53 volumio sudo[4801]: pam_unix(sudo:session): session closed for user root Jul 25 12:20:53 volumio sudo[4803]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set FR Jul 25 12:20:53 volumio sudo[4803]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 25 12:20:53 volumio sudo[4803]: pam_unix(sudo:session): session closed for user root Jul 25 12:20:53 volumio wireless.js[4762]: SUCCESSFULLY SET NEW REGDOMAIN: FR Jul 25 12:20:53 volumio wireless.js[4762]: Start wireless flow Jul 25 12:20:53 volumio wireless.js[4762]: Stopped hotspot (if there).. Jul 25 12:20:53 volumio sudo[4808]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jul 25 12:20:53 volumio sudo[4808]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 25 12:20:53 volumio sudo[4808]: pam_unix(sudo:session): session closed for user root Jul 25 12:20:53 volumio sudo[4811]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jul 25 12:20:53 volumio sudo[4811]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 25 12:20:53 volumio sudo[4811]: pam_unix(sudo:session): session closed for user root Jul 25 12:20:53 volumio wireless.js[4762]: DHCP IP Jul 25 12:20:53 volumio wireless.js[4762]: Start ap Jul 25 12:20:53 volumio wpa_supplicant[4813]: Successfully initialized wpa_supplicant Jul 25 12:20:53 volumio sudo[4814]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Jul 25 12:20:53 volumio sudo[4814]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 25 12:20:53 volumio dhcpcd[4815]: sending commands to master dhcpcd process Jul 25 12:20:53 volumio sudo[4814]: pam_unix(sudo:session): session closed for user root Jul 25 12:20:53 volumio dhcpcd[793]: control command: /sbin/dhcpcd Jul 25 12:20:54 volumio volumio[1364]: info: CoreCommandRouter::executeOnPlugin: wizard , reportWirelessConnection Jul 25 12:20:54 volumio volumio[1364]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessInfo Jul 25 12:20:54 volumio sudo[4818]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 25 12:20:54 volumio sudo[4818]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 25 12:20:54 volumio sudo[4818]: pam_unix(sudo:session): session closed for user root Jul 25 12:20:54 volumio wireless.js[4762]: trying... Jul 25 12:20:54 volumio sudo[4821]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 25 12:20:54 volumio sudo[4821]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 25 12:20:54 volumio sudo[4821]: pam_unix(sudo:session): session closed for user root Jul 25 12:20:54 volumio wpa_supplicant[4816]: wlan0: SME: Trying to authenticate with ee:1d:7f:ad:53:d8 (SSID='hometta' freq=2462 MHz) Jul 25 12:20:54 volumio kernel: wlan0: authenticate with ee:1d:7f:ad:53:d8 Jul 25 12:20:54 volumio kernel: wlan0: 80 MHz not supported, disabling VHT Jul 25 12:20:54 volumio kernel: wlan0: send auth to ee:1d:7f:ad:53:d8 (try 1/3) Jul 25 12:20:54 volumio wpa_supplicant[4816]: wlan0: Trying to associate with ee:1d:7f:ad:53:d8 (SSID='hometta' freq=2462 MHz) Jul 25 12:20:54 volumio kernel: wlan0: authenticated Jul 25 12:20:54 volumio kernel: wlan0: associate with ee:1d:7f:ad:53:d8 (try 1/3) Jul 25 12:20:54 volumio kernel: wlan0: RX AssocResp from ee:1d:7f:ad:53:d8 (capab=0x431 status=0 aid=1) Jul 25 12:20:54 volumio kernel: usb 3-1: rtl8xxxu_bss_info_changed: HT supported Jul 25 12:20:55 volumio kernel: wlan0: associated Jul 25 12:20:55 volumio kernel: ath: EEPROM regdomain: 0x80fa Jul 25 12:20:55 volumio kernel: ath: EEPROM indicates we should expect a country code Jul 25 12:20:55 volumio kernel: ath: doing EEPROM country->regdmn map search Jul 25 12:20:55 volumio kernel: ath: country maps to regdmn code: 0x37 Jul 25 12:20:55 volumio kernel: ath: Country alpha2 being used: FR Jul 25 12:20:55 volumio kernel: ath: Regpair used: 0x37 Jul 25 12:20:55 volumio kernel: ath: regdomain 0x80fa dynamically updated by country element Jul 25 12:20:55 volumio wpa_supplicant[4816]: wlan0: Associated with ee:1d:7f:ad:53:d8 Jul 25 12:20:55 volumio wpa_supplicant[4816]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jul 25 12:20:55 volumio wpa_supplicant[4816]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=FR Jul 25 12:20:55 volumio wpa_supplicant[4816]: wlan0: WPA: Key negotiation completed with ee:1d:7f:ad:53:d8 [PTK=CCMP GTK=TKIP] Jul 25 12:20:55 volumio wpa_supplicant[4816]: wlan0: CTRL-EVENT-CONNECTED - Connection to ee:1d:7f:ad:53:d8 completed [id=0 id_str=] Jul 25 12:20:55 volumio dhcpcd[793]: wlan0: carrier acquired Jul 25 12:20:55 volumio dhcpcd[793]: wlan0: IAID 52:1d:ce:d3 Jul 25 12:20:55 volumio volumio[1364]: info: Discovery: Started advertising with name: Volumio Jul 25 12:20:55 volumio wireless.js[4762]: trying... Jul 25 12:20:55 volumio dhcpcd[793]: wlan0: soliciting an IPv6 router Jul 25 12:20:55 volumio ntpd[4730]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Jul 25 12:20:55 volumio ntpd[4730]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Jul 25 12:20:55 volumio ntpd[4730]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Jul 25 12:20:55 volumio ntpd[4730]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Jul 25 12:20:55 volumio sudo[4826]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 25 12:20:55 volumio sudo[4826]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 25 12:20:55 volumio sudo[4826]: pam_unix(sudo:session): session closed for user root Jul 25 12:20:55 volumio dhcpcd[793]: wlan0: rebinding lease of 192.168.0.105 Jul 25 12:20:55 volumio dhcpcd[793]: wlan0: probing address 192.168.0.105/24 Jul 25 12:20:56 volumio wireless.js[4762]: trying... Jul 25 12:20:56 volumio ntpd[4730]: Deleting interface #3 wlan0, 192.168.0.105#123, interface stats: received=0, sent=0, dropped=0, active_time=7 secs Jul 25 12:20:56 volumio sudo[4829]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 25 12:20:56 volumio sudo[4829]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 25 12:20:56 volumio sudo[4829]: pam_unix(sudo:session): session closed for user root Jul 25 12:20:57 volumio volumio[1364]: info: Preload queue cleared Jul 25 12:20:57 volumio volumio[1364]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 25 12:20:57 volumio volumio[1364]: info: CoreStateMachine::ClearQueue Jul 25 12:20:57 volumio volumio[1364]: info: CoreStateMachine::stop Jul 25 12:20:57 volumio volumio[1364]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 25 12:20:57 volumio volumio[1364]: info: CorePlayQueue::clearPlayQueue Jul 25 12:20:57 volumio volumio[1364]: info: CorePlayQueue::saveQueue Jul 25 12:20:57 volumio volumio[1364]: info: CoreCommandRouter::volumioPushQueue Jul 25 12:20:57 volumio volumio[1364]: info: CoreStateMachine::addQueueItems Jul 25 12:20:57 volumio volumio[1364]: info: CorePlayQueue::addQueueItems Jul 25 12:20:57 volumio volumio[1364]: info: Preload queue cleared Jul 25 12:20:57 volumio volumio[1364]: info: Adding Item to queue: https://retro.hostingradio.ru:8043/retro128 Jul 25 12:20:57 volumio volumio[1364]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jul 25 12:20:57 volumio volumio[1364]: info: CoreCommandRouter::volumioPushQueue Jul 25 12:20:57 volumio volumio[1364]: info: CorePlayQueue::saveQueue Jul 25 12:20:57 volumio volumio[1364]: info: CoreStateMachine::updateTrackBlock Jul 25 12:20:57 volumio volumio[1364]: info: CorePlayQueue::getTrackBlock Jul 25 12:20:57 volumio volumio[1364]: info: CoreCommandRouter::volumioPlay Jul 25 12:20:57 volumio volumio[1364]: info: CoreStateMachine::play index 0 Jul 25 12:20:57 volumio volumio[1364]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 25 12:20:57 volumio volumio[1364]: info: CoreStateMachine::stop Jul 25 12:20:57 volumio volumio[1364]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 25 12:20:57 volumio volumio[1364]: info: CoreStateMachine::play index undefined Jul 25 12:20:57 volumio volumio[1364]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 25 12:20:57 volumio volumio[1364]: info: CorePlayQueue::getTrack 0 Jul 25 12:20:57 volumio volumio[1364]: info: CoreStateMachine::startPlaybackTimer Jul 25 12:20:57 volumio volumio[1364]: info: CorePlayQueue::getTrack 0 Jul 25 12:20:57 volumio volumio[1364]: info: [1721899257605] ControllerWebradio::clearAddPlayTrack Jul 25 12:20:57 volumio volumio[1364]: verbose: ControllerMpd::sendMpdCommand stop Jul 25 12:20:57 volumio volumio[1364]: info: sendMpdCommand stop took 5 milliseconds Jul 25 12:20:57 volumio volumio[1364]: verbose: ControllerMpd::sendMpdCommand clear Jul 25 12:20:57 volumio volumio[1364]: info: Jul 25 12:20:57 volumio volumio[1364]: ---------------------------- MPD announces system playlist update Jul 25 12:20:57 volumio volumio[1364]: info: Ignoring MPD Status Update Jul 25 12:20:57 volumio volumio[1364]: info: sendMpdCommand clear took 8 milliseconds Jul 25 12:20:57 volumio volumio[1364]: verbose: ControllerMpd::sendMpdCommand load "https://retro.hostingradio.ru:8043/retro128" Jul 25 12:20:57 volumio volumio[1364]: info: Jul 25 12:20:57 volumio volumio[1364]: ---------------------------- MPD announces system playlist update Jul 25 12:20:57 volumio volumio[1364]: info: Ignoring MPD Status Update Jul 25 12:20:57 volumio volumio[1364]: info: Jul 25 12:20:57 volumio volumio[1364]: ---------------------------- MPD announces system playlist update Jul 25 12:20:57 volumio volumio[1364]: info: Ignoring MPD Status Update Jul 25 12:20:57 volumio volumio[1364]: error: updateQueue error: null Jul 25 12:20:57 volumio volumio[1364]: info: ------------------------------ 15ms Jul 25 12:20:57 volumio wireless.js[4762]: trying... Jul 25 12:20:57 volumio volumio[1364]: error: updateQueue error: null Jul 25 12:20:57 volumio volumio[1364]: error: updateQueue error: null Jul 25 12:20:57 volumio volumio[1364]: verbose: ControllerMpd::sendMpdCommand add "https://retro.hostingradio.ru:8043/retro128" Jul 25 12:20:57 volumio volumio[1364]: info: ------------------------------ 180ms Jul 25 12:20:57 volumio volumio[1364]: info: ------------------------------ 177ms Jul 25 12:20:57 volumio sudo[4833]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 25 12:20:57 volumio sudo[4833]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 25 12:20:57 volumio volumio[1364]: info: Jul 25 12:20:57 volumio volumio[1364]: ---------------------------- MPD announces system playlist update Jul 25 12:20:57 volumio volumio[1364]: info: Ignoring MPD Status Update Jul 25 12:20:57 volumio volumio[1364]: info: sendMpdCommand add "https://retro.hostingradio.ru:8043/retro128" took 10 milliseconds Jul 25 12:20:57 volumio sudo[4833]: pam_unix(sudo:session): session closed for user root Jul 25 12:20:57 volumio volumio[1364]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 25 12:20:57 volumio volumio[1364]: verbose: ControllerMpd::sendMpdCommand play Jul 25 12:20:57 volumio volumio[1364]: info: Jul 25 12:20:57 volumio volumio[1364]: ---------------------------- MPD announces system playlist update Jul 25 12:20:57 volumio volumio[1364]: info: Ignoring MPD Status Update Jul 25 12:20:57 volumio volumio[1364]: info: Jul 25 12:20:57 volumio volumio[1364]: ---------------------------- MPD announces system playlist update Jul 25 12:20:57 volumio volumio[1364]: info: Ignoring MPD Status Update Jul 25 12:20:57 volumio volumio[1364]: info: ------------------------------ 22ms Jul 25 12:20:57 volumio volumio[1364]: info: sendMpdCommand play took 11 milliseconds Jul 25 12:20:57 volumio volumio[1364]: info: ------------------------------ 10ms Jul 25 12:20:57 volumio volumio[1364]: info: ------------------------------ 7ms Jul 25 12:20:58 volumio wireless.js[4762]: trying... Jul 25 12:20:58 volumio sudo[4837]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 25 12:20:58 volumio volumio[1364]: info: Jul 25 12:20:58 volumio volumio[1364]: ---------------------------- MPD announces state update: player Jul 25 12:20:58 volumio volumio[1364]: info: ControllerMpd::getState Jul 25 12:20:58 volumio volumio[1364]: verbose: ControllerMpd::sendMpdCommand status Jul 25 12:20:58 volumio sudo[4837]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 25 12:20:58 volumio volumio[1364]: info: Jul 25 12:20:58 volumio volumio[1364]: ---------------------------- MPD announces state update: player Jul 25 12:20:58 volumio volumio[1364]: info: ControllerMpd::getState Jul 25 12:20:58 volumio volumio[1364]: verbose: ControllerMpd::sendMpdCommand status Jul 25 12:20:58 volumio volumio[1364]: info: Jul 25 12:20:58 volumio volumio[1364]: ---------------------------- MPD announces state update: player Jul 25 12:20:58 volumio volumio[1364]: info: ControllerMpd::getState Jul 25 12:20:58 volumio volumio[1364]: verbose: ControllerMpd::sendMpdCommand status Jul 25 12:20:58 volumio sudo[4837]: pam_unix(sudo:session): session closed for user root Jul 25 12:20:58 volumio volumio[1364]: verbose: ControllerMpd::sendMpdCommand clearerror Jul 25 12:20:58 volumio volumio[1364]: info: sendMpdCommand status took 22 milliseconds Jul 25 12:20:58 volumio volumio[1364]: verbose: ControllerMpd::sendMpdCommand clearerror Jul 25 12:20:58 volumio volumio[1364]: info: sendMpdCommand status took 19 milliseconds Jul 25 12:20:58 volumio volumio[1364]: verbose: ControllerMpd::sendMpdCommand clearerror Jul 25 12:20:58 volumio volumio[1364]: info: sendMpdCommand status took 16 milliseconds Jul 25 12:20:58 volumio volumio[1364]: verbose: ControllerMpd::parseState Jul 25 12:20:58 volumio volumio[1364]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 25 12:20:58 volumio volumio[1364]: verbose: ControllerMpd::parseState Jul 25 12:20:58 volumio volumio[1364]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 25 12:20:58 volumio volumio[1364]: verbose: ControllerMpd::parseState Jul 25 12:20:58 volumio volumio[1364]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 25 12:20:58 volumio volumio[1364]: info: sendMpdCommand clearerror took 26 milliseconds Jul 25 12:20:58 volumio volumio[1364]: info: sendMpdCommand clearerror took 24 milliseconds Jul 25 12:20:58 volumio volumio[1364]: info: sendMpdCommand clearerror took 24 milliseconds Jul 25 12:20:58 volumio volumio[1364]: info: sendMpdCommand playlistinfo took 10 milliseconds Jul 25 12:20:58 volumio volumio[1364]: info: sendMpdCommand playlistinfo took 9 milliseconds Jul 25 12:20:58 volumio volumio[1364]: info: sendMpdCommand playlistinfo took 6 milliseconds Jul 25 12:20:58 volumio volumio[1364]: verbose: ControllerMpd::parseTrackInfo Jul 25 12:20:58 volumio volumio[1364]: verbose: ControllerMpd::parseTrackInfo Jul 25 12:20:58 volumio volumio[1364]: verbose: ControllerMpd::parseTrackInfo Jul 25 12:20:58 volumio volumio[1364]: info: ControllerMpd::pushState Jul 25 12:20:58 volumio volumio[1364]: info: CoreCommandRouter::servicePushState Jul 25 12:20:58 volumio volumio[1364]: info: CoreStateMachine::pushState Jul 25 12:20:58 volumio volumio[1364]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 25 12:20:58 volumio volumio[1364]: info: CoreCommandRouter::volumioPushState Jul 25 12:20:58 volumio volumio[1364]: info: CorePlayQueue::getTrack 0 Jul 25 12:20:58 volumio volumio[1364]: 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":"retro128","artist":null,"album":null,"uri":"https://retro.hostingradio.ru:8043/retro128","trackType":""} Jul 25 12:20:58 volumio volumio[1364]: verbose: CURRENT POSITION 0 Jul 25 12:20:58 volumio volumio[1364]: info: CoreStateMachine::syncState stateService stop Jul 25 12:20:58 volumio volumio[1364]: info: CoreStateMachine::syncState currentStatus stop Jul 25 12:20:58 volumio volumio[1364]: info: CoreStateMachine::pushState Jul 25 12:20:58 volumio volumio[1364]: info: CoreCommandRouter::volumioPushState Jul 25 12:20:58 volumio volumio[1364]: info: No code Jul 25 12:20:58 volumio volumio[1364]: info: CoreStateMachine::pushState Jul 25 12:20:58 volumio volumio[1364]: info: CoreCommandRouter::volumioPushState Jul 25 12:20:58 volumio volumio[1364]: info: ControllerMpd::pushState Jul 25 12:20:58 volumio volumio[1364]: info: CoreCommandRouter::servicePushState Jul 25 12:20:58 volumio volumio[1364]: info: CoreStateMachine::pushState Jul 25 12:20:58 volumio volumio[1364]: info: CoreCommandRouter::volumioPushState Jul 25 12:20:58 volumio volumio[1364]: info: CorePlayQueue::getTrack 0 Jul 25 12:20:58 volumio volumio[1364]: 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":"retro128","artist":null,"album":null,"uri":"https://retro.hostingradio.ru:8043/retro128","trackType":""} Jul 25 12:20:58 volumio volumio[1364]: verbose: CURRENT POSITION 0 Jul 25 12:20:58 volumio volumio[1364]: info: CoreStateMachine::syncState stateService stop Jul 25 12:20:58 volumio volumio[1364]: info: CoreStateMachine::syncState currentStatus stop Jul 25 12:20:58 volumio volumio[1364]: info: CoreStateMachine::pushState Jul 25 12:20:58 volumio volumio[1364]: info: CoreCommandRouter::volumioPushState Jul 25 12:20:58 volumio volumio[1364]: info: No code Jul 25 12:20:58 volumio volumio[1364]: info: CoreStateMachine::pushState Jul 25 12:20:58 volumio volumio[1364]: info: CoreCommandRouter::volumioPushState Jul 25 12:20:58 volumio volumio[1364]: info: ControllerMpd::pushState Jul 25 12:20:58 volumio volumio[1364]: info: CoreCommandRouter::servicePushState Jul 25 12:20:58 volumio volumio[1364]: info: CoreStateMachine::pushState Jul 25 12:20:58 volumio volumio[1364]: info: CoreCommandRouter::volumioPushState Jul 25 12:20:58 volumio volumio[1364]: info: CorePlayQueue::getTrack 0 Jul 25 12:20:58 volumio volumio[1364]: 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":"retro128","artist":null,"album":null,"uri":"https://retro.hostingradio.ru:8043/retro128","trackType":""} Jul 25 12:20:58 volumio volumio[1364]: verbose: CURRENT POSITION 0 Jul 25 12:20:58 volumio volumio[1364]: info: CoreStateMachine::syncState stateService stop Jul 25 12:20:58 volumio volumio[1364]: info: CoreStateMachine::syncState currentStatus stop Jul 25 12:20:58 volumio volumio[1364]: info: CoreStateMachine::pushState Jul 25 12:20:58 volumio volumio[1364]: info: CoreCommandRouter::volumioPushState Jul 25 12:20:58 volumio volumio[1364]: info: No code Jul 25 12:20:58 volumio volumio[1364]: info: CoreStateMachine::pushState Jul 25 12:20:58 volumio volumio[1364]: info: CoreCommandRouter::volumioPushState Jul 25 12:20:59 volumio volumio[1364]: info: ------------------------------ 220ms Jul 25 12:20:59 volumio volumio[1364]: info: ------------------------------ 217ms Jul 25 12:20:59 volumio volumio[1364]: info: ------------------------------ 215ms Jul 25 12:20:59 volumio volumio[1364]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart Jul 25 12:20:59 volumio volumio[1364]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart Jul 25 12:20:59 volumio volumio[1364]: info: Refreshing Cached IP Addresses Jul 25 12:20:59 volumio sudo[4840]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 25 12:20:59 volumio sudo[4840]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 25 12:20:59 volumio sudo[4840]: pam_unix(sudo:session): session closed for user root Jul 25 12:20:59 volumio sudo[4842]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 25 12:20:59 volumio sudo[4842]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 25 12:20:59 volumio sudo[4842]: pam_unix(sudo:session): session closed for user root Jul 25 12:20:59 volumio wireless.js[4762]: trying... Jul 25 12:20:59 volumio sudo[4848]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 25 12:20:59 volumio sudo[4848]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 25 12:20:59 volumio sudo[4848]: pam_unix(sudo:session): session closed for user root Jul 25 12:21:00 volumio wireless.js[4762]: trying... Jul 25 12:21:00 volumio dhcpcd[793]: wlan0: leased 192.168.0.105 for 86400 seconds Jul 25 12:21:00 volumio dhcpcd[793]: wlan0: adding route to 192.168.0.0/24 Jul 25 12:21:00 volumio dhcpcd[793]: wlan0: adding default route via 192.168.0.1 Jul 25 12:21:00 volumio avahi-daemon[689]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.105. Jul 25 12:21:00 volumio avahi-daemon[689]: New relevant interface wlan0.IPv4 for mDNS. Jul 25 12:21:00 volumio avahi-daemon[689]: Registering new address record for 192.168.0.105 on wlan0.IPv4. Jul 25 12:21:00 volumio sudo[4851]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 25 12:21:00 volumio sudo[4851]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 25 12:21:00 volumio sudo[4851]: pam_unix(sudo:session): session closed for user root Jul 25 12:21:00 volumio wireless.js[4762]: Connected to: ----hometta Jul 25 12:21:00 volumio wireless.js[4762]: ---- Jul 25 12:21:00 volumio sudo[4868]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 25 12:21:00 volumio sudo[4868]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 25 12:21:00 volumio sudo[4868]: pam_unix(sudo:session): session closed for user root Jul 25 12:21:01 volumio wireless.js[4762]: ... joined AP, wlan0 IPv4 is 192.168.0.105, ipV6 is undefined Jul 25 12:21:01 volumio wireless.js[4762]: It's done! AP Jul 25 12:21:01 volumio iwconfig[4886]: Error for wireless request "Set Power Management" (8B2C) : Jul 25 12:21:01 volumio iwconfig[4886]: SET failed on device wlan0 ; Operation not supported. Jul 25 12:21:01 volumio systemd[1]: Started Wireless Services. Jul 25 12:21:01 volumio sudo[4736]: pam_unix(sudo:session): session closed for user root Jul 25 12:21:01 volumio ntpd[4730]: ntpd exiting on signal 15 (Terminated) Jul 25 12:21:01 volumio systemd[1]: Stopping Network Time Service... Jul 25 12:21:01 volumio systemd[1]: ntp.service: Succeeded. Jul 25 12:21:01 volumio systemd[1]: Stopped Network Time Service. Jul 25 12:21:01 volumio systemd[1]: Starting Network Time Service... Jul 25 12:21:01 volumio ntpd[4892]: ntpd 4.2.8p12@1.3728-o (1): Starting Jul 25 12:21:01 volumio ntpd[4892]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Jul 25 12:21:01 volumio ntpd[4898]: proto: precision = 0.385 usec (-21) Jul 25 12:21:01 volumio systemd[1]: Started Network Time Service. Jul 25 12:21:01 volumio ntpd[4898]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jul 25 12:21:01 volumio ntpd[4898]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Jul 25 12:21:01 volumio ntpd[4898]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 576 days ago Jul 25 12:21:01 volumio ntpd[4898]: Listen and drop on 0 v6wildcard [::]:123 Jul 25 12:21:01 volumio ntpd[4898]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jul 25 12:21:01 volumio ntpd[4898]: Listen normally on 2 lo 127.0.0.1:123 Jul 25 12:21:01 volumio ntpd[4898]: Listen normally on 3 wlan0 192.168.0.105:123 Jul 25 12:21:01 volumio ntpd[4898]: Listening on routing socket on fd #20 for interface updates Jul 25 12:21:01 volumio ntpd[4898]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jul 25 12:21:01 volumio ntpd[4898]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jul 25 12:21:01 volumio volumio[1364]: info: Discovery: adding 88a95534-e2cf-47d5-8b21-71abf14da8ad Jul 25 12:21:01 volumio volumio[1364]: info: Discovery: Found device Volumio Jul 25 12:21:01 volumio volumio[1364]: info: CoreCommandRouter::volumioGetState Jul 25 12:21:01 volumio volumio[1364]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jul 25 12:21:01 volumio volumio[1364]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jul 25 12:21:01 volumio volumio[1364]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jul 25 12:21:01 volumio volumio[1364]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jul 25 12:21:01 volumio volumio[1364]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jul 25 12:21:01 volumio volumio[1364]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jul 25 12:21:01 volumio volumio[1364]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jul 25 12:21:01 volumio volumio[1364]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jul 25 12:21:01 volumio volumio[1364]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jul 25 12:21:01 volumio volumio[1364]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jul 25 12:21:01 volumio volumio[1364]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jul 25 12:21:01 volumio volumio[1364]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jul 25 12:21:01 volumio volumio[1364]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jul 25 12:21:01 volumio volumio[1364]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jul 25 12:21:01 volumio volumio[1364]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jul 25 12:21:01 volumio volumio[1364]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jul 25 12:21:01 volumio volumio[1364]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jul 25 12:21:01 volumio volumio[1364]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jul 25 12:21:01 volumio volumio[1364]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jul 25 12:21:01 volumio volumio[1364]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jul 25 12:21:01 volumio volumio[1364]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jul 25 12:21:01 volumio volumio[1364]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jul 25 12:21:01 volumio volumio[1364]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jul 25 12:21:01 volumio volumio[1364]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jul 25 12:21:01 volumio volumio[1364]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jul 25 12:21:01 volumio volumio[1364]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jul 25 12:21:02 volumio volumio[1364]: info: Adding plugin bluetooth to MyMusic Plugins Jul 25 12:21:02 volumio volumio[1364]: info: Adding plugin multiroom to MyMusic Plugins Jul 25 12:21:02 volumio volumio[1364]: info: Adding plugin metavolumio to MyMusic Plugins Jul 25 12:21:02 volumio volumio[1364]: info: Adding plugin cd_controller to MyMusic Plugins Jul 25 12:21:02 volumio volumio[1364]: info: Adding plugin smart_inputs to MyMusic Plugins Jul 25 12:21:02 volumio volumio[1364]: info: Adding plugin tidalconnect to MyMusic Plugins Jul 25 12:21:02 volumio volumio[1364]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jul 25 12:21:02 volumio ntpd[4898]: Soliciting pool server 91.207.136.55 Jul 25 12:21:03 volumio ntpd[4898]: Soliciting pool server 85.21.78.23 Jul 25 12:21:03 volumio ntpd[4898]: Soliciting pool server 185.68.101.2 Jul 25 12:21:04 volumio volumio[1364]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jul 25 12:21:04 volumio volumio[1364]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jul 25 12:21:04 volumio volumio[1364]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 25 12:21:04 volumio volumio[1364]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 25 12:21:04 volumio volumio[1364]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 25 12:21:04 volumio volumio[1364]: info: Starting MyVolumio Remote Streaming Endpoints Jul 25 12:21:04 volumio ntpd[4898]: Soliciting pool server 192.36.143.130 Jul 25 12:21:04 volumio ntpd[4898]: Soliciting pool server 188.225.9.167 Jul 25 12:21:04 volumio volumio[1364]: info: MyVolumio login type: Token Jul 25 12:21:04 volumio ntpd[4898]: Soliciting pool server 162.159.200.123 Jul 25 12:21:04 volumio volumio[1364]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jul 25 12:21:04 volumio volumio[1364]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jul 25 12:21:05 volumio ntpd[4898]: Soliciting pool server 193.192.36.3 Jul 25 12:21:05 volumio ntpd[4898]: Soliciting pool server 162.159.200.1 Jul 25 12:21:05 volumio ntpd[4898]: Soliciting pool server 176.215.178.239 Jul 25 12:21:05 volumio ntpd[4898]: Soliciting pool server 176.215.15.21 Jul 25 12:21:06 volumio ntpd[4898]: Soliciting pool server 5.129.211.151 Jul 25 12:21:06 volumio ntpd[4898]: Soliciting pool server 85.21.78.91 Jul 25 12:21:06 volumio volumio[1364]: info: Starting Streaming Service Transparent Proxy Jul 25 12:21:06 volumio volumio[1364]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jul 25 12:21:06 volumio volumio[1364]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jul 25 12:21:06 volumio volumio[1364]: info: Streaming services startup Jul 25 12:21:06 volumio volumio[1364]: info: Starting Streaming Daemon Jul 25 12:21:06 volumio sudo[4953]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jul 25 12:21:06 volumio sudo[4953]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 25 12:21:06 volumio volumio[1364]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jul 25 12:21:06 volumio sudo[4953]: pam_unix(sudo:session): session closed for user root Jul 25 12:21:07 volumio volumio[1364]: info: CoreCommandRouter::executeOnPlugin: wizard , reportWirelessConnection Jul 25 12:21:07 volumio volumio[1364]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessInfo Jul 25 12:21:07 volumio volumio[1364]: error: Cannot start Volumio Streaming Daemon Jul 25 12:21:07 volumio volumio[1364]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jul 25 12:21:07 volumio volumio[1364]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jul 25 12:21:07 volumio ntpd[4898]: Soliciting pool server 2a00:1390:5:722b:1337:c0de:4:11fe Jul 25 12:21:07 volumio sudo[4961]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 25 12:21:07 volumio sudo[4961]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 25 12:21:07 volumio sudo[4961]: pam_unix(sudo:session): session closed for user root Jul 25 12:21:07 volumio sudo[4964]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jul 25 12:21:07 volumio sudo[4964]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 25 12:21:07 volumio sudo[4964]: pam_unix(sudo:session): session closed for user root Jul 25 12:21:07 volumio volumio[1364]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 25 12:21:07 volumio volumio[1364]: info: Received Get System Info Jul 25 12:21:07 volumio volumio[1364]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 25 12:21:07 volumio volumio[1364]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 25 12:21:07 volumio volumio[1364]: info: Discovery: Getting this device information Jul 25 12:21:07 volumio volumio[1364]: info: CoreCommandRouter::volumioGetState Jul 25 12:21:07 volumio volumio[1364]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 25 12:21:08 volumio volumio[1364]: error: MyVolumio Custom Token format not valid, refreshing it Jul 25 12:21:08 volumio volumio[1364]: STREAMING PROXY: Starting server on port 3245 Jul 25 12:21:08 volumio volumio[1364]: Node JS runtime: 14 Jul 25 12:21:09 volumio volumio[1364]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Jul 25 12:21:09 volumio volumio[1364]: info: MyVolumio login type: Token Jul 25 12:21:10 volumio volumio[1364]: info: MyVolumio login type: Token Jul 25 12:21:10 volumio volumio[1364]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jul 25 12:21:11 volumio volumio[1364]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart Jul 25 12:21:11 volumio volumio[1364]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart Jul 25 12:21:11 volumio volumio[1364]: info: Refreshing Cached IP Addresses Jul 25 12:21:11 volumio sudo[4969]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 25 12:21:11 volumio sudo[4969]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 25 12:21:11 volumio sudo[4969]: pam_unix(sudo:session): session closed for user root Jul 25 12:21:11 volumio sudo[4974]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 25 12:21:11 volumio sudo[4974]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 25 12:21:11 volumio sudo[4974]: pam_unix(sudo:session): session closed for user root Jul 25 12:21:11 volumio volumio[1364]: info: MyVolumio token set successfully Jul 25 12:21:11 volumio volumio[1364]: info: MYVOLUMIO: Adding device Jul 25 12:21:11 volumio volumio[1364]: info: MYVOLUMIO: Evaluating Server Jul 25 12:21:12 volumio volumio[1364]: info: MyVolumio status changed Jul 25 12:21:12 volumio volumio[1364]: info: Streaming services startup Jul 25 12:21:12 volumio volumio[1364]: info: Starting Streaming Daemon Jul 25 12:21:12 volumio volumio[1364]: info: Removing browser output: myVolumio user plan is not superstar Jul 25 12:21:12 volumio volumio[1364]: info: Removing audio output: Jul 25 12:21:12 volumio volumio[1364]: info: Stoppping Tunnel 1 Jul 25 12:21:12 volumio sudo[4994]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jul 25 12:21:12 volumio sudo[4994]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 25 12:21:12 volumio sudo[4994]: pam_unix(sudo:session): session closed for user root Jul 25 12:21:12 volumio sudo[4997]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Jul 25 12:21:12 volumio sudo[4997]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 25 12:21:09 volumio ntpd[4898]: receive: Unexpected origin timestamp 0xea4c9788.664b473d does not match aorg 0000000000.00000000 from server@85.21.78.23 xmt 0xea4c9785.1a62a306 Jul 25 12:21:09 volumio volumio[1364]: error: Cannot start Volumio Streaming Daemon Jul 25 12:21:09 volumio volumio[1364]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jul 25 12:21:09 volumio volumio[1364]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jul 25 12:21:09 volumio ntpd[4898]: receive: Unexpected origin timestamp 0xea4c9788.6642638c does not match aorg 0000000000.00000000 from server@188.225.9.167 xmt 0xea4c9785.19435eba Jul 25 12:21:09 volumio ntpd[4898]: receive: Unexpected origin timestamp 0xea4c9788.662af820 does not match aorg 0000000000.00000000 from server@176.215.15.21 xmt 0xea4c9785.1a55b035 Jul 25 12:21:09 volumio ntpd[4898]: receive: Unexpected origin timestamp 0xea4c9788.6635eaf9 does not match aorg 0000000000.00000000 from server@162.159.200.1 xmt 0xea4c9785.1c769384 Jul 25 12:21:09 volumio ntpd[4898]: receive: Unexpected origin timestamp 0xea4c9788.66461922 does not match aorg 0000000000.00000000 from server@185.68.101.2 xmt 0xea4c9785.1da308d3 Jul 25 12:21:09 volumio volumio[1364]: error: Failed to ping endpoint us2.myvolumio.org : unknown error Jul 25 12:21:09 volumio sudo[4997]: pam_unix(sudo:session): session closed for user root Jul 25 12:21:09 volumio volumio[1364]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 25 12:21:09 volumio volumio[1364]: Error: Unable to resolve or reject the same promise twice Jul 25 12:21:09 volumio volumio[1364]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Jul 25 12:21:09 volumio volumio[1364]: at Socket. (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:32086) Jul 25 12:21:09 volumio volumio[1364]: at Socket.emit (events.js:412:35) Jul 25 12:21:09 volumio volumio[1364]: at endReadableNT (internal/streams/readable.js:1333:12) Jul 25 12:21:09 volumio volumio[1364]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Jul 25 12:21:09 volumio volumio[1364]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 25 12:21:09 volumio sudo[5009]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-25 12:20 Jul 25 12:21:09 volumio sudo[5009]: 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="616c59d0809da875c5b18d0362c4993292d5d04e" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="abe72e92bc7de90ab6193cbc17e21ad2750980a2" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 23 May 2024 08:53:26 PM CEST" VOLUMIO_VERSION="3.694" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="8f07f66f0e02ddec7cc3153ff63e7cb9"