-- 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"