-- Logs begin at Fri 2020-12-11 14:53:56 UTC, end at Sat 2025-06-28 11:00:34 UTC. -- Jun 28 10:59:00 volumio sudo[6245]: pam_unix(sudo:session): session closed for user root Jun 28 10:59:00 volumio volumio[5901]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=aml9xxxarmv7&variante=volumio&version=2.857&uuid=f0469a89c469dd869cdc9a386182c93a" http://updates.volumio.org:7070/downloader-v1/track-device Jun 28 10:59:00 volumio volumio[5901]: % Total % Received % Xferd Average Speed Time Time Time Current Jun 28 10:59:00 volumio volumio[5901]: Dload Upload Total Spent Left Speed Jun 28 10:59:00 volumio volumio[5901]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0curl: (7) Failed to connect to updates.volumio.org port 7070: Connection refused Jun 28 10:59:00 volumio volumio[5901]: retrying in 5 seconds, trial 2 Jun 28 10:59:00 volumio volumio[5901]: Volumio Calling Home Jun 28 10:59:05 volumio dhcpd[5152]: DHCPDISCOVER from 00:90:4c:c5:12:38 (volumio) via wlan0 Jun 28 10:59:06 volumio dhcpd[5152]: DHCPOFFER on 192.168.211.2 to 00:90:4c:c5:12:38 (volumio) via wlan0 Jun 28 10:59:09 volumio volumio[5901]: verbose: ControllerMpd::sendMpdCommand status Jun 28 10:59:09 volumio volumio[5901]: info: Jun 28 10:59:09 volumio volumio[5901]: ---------------------------- MPD announces state update: update Jun 28 10:59:09 volumio volumio[5901]: info: ControllerMpd::getState Jun 28 10:59:09 volumio volumio[5901]: verbose: ControllerMpd::sendMpdCommand status Jun 28 10:59:09 volumio volumio[5901]: verbose: ControllerMpd::sendMpdCommand status Jun 28 10:59:09 volumio volumio[5901]: info: Jun 28 10:59:09 volumio volumio[5901]: ---------------------------- MPD announces state update: update Jun 28 10:59:09 volumio volumio[5901]: info: ControllerMpd::getState Jun 28 10:59:09 volumio volumio[5901]: verbose: ControllerMpd::sendMpdCommand status Jun 28 10:59:09 volumio volumio[5901]: verbose: ControllerMpd::parseState Jun 28 10:59:09 volumio volumio[5901]: info: Command Router : Notfying DB Updatefalse Jun 28 10:59:09 volumio volumio[5901]: info: CoreCommandRouter::Close All Modals sent Jun 28 10:59:09 volumio volumio[5901]: verbose: ControllerMpd::parseState Jun 28 10:59:09 volumio volumio[5901]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 28 10:59:09 volumio volumio[5901]: verbose: ControllerMpd::parseState Jun 28 10:59:09 volumio volumio[5901]: info: Command Router : Notfying DB Updatefalse Jun 28 10:59:09 volumio volumio[5901]: info: CoreCommandRouter::Close All Modals sent Jun 28 10:59:09 volumio volumio[5901]: verbose: ControllerMpd::parseState Jun 28 10:59:09 volumio volumio[5901]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 28 10:59:09 volumio volumio[5901]: verbose: ControllerMpd::parseTrackInfo Jun 28 10:59:09 volumio volumio[5901]: info: ControllerMpd::pushState Jun 28 10:59:09 volumio volumio[5901]: info: CoreCommandRouter::servicePushState Jun 28 10:59:09 volumio volumio[5901]: info: CorePlayQueue::getTrack 0 Jun 28 10:59:09 volumio volumio[5901]: verbose: STATE SERVICE {"status":"play","position":0,"seek":64238,"duration":213,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"You're Beautiful","artist":"James Blunt","album":"Back to Bedlam","uri":"USB/MUSIC/02 - You're Beautiful.mp3","trackType":"mp3"} Jun 28 10:59:09 volumio volumio[5901]: verbose: CURRENT POSITION 0 Jun 28 10:59:09 volumio volumio[5901]: info: CoreStateMachine::syncState stateService play Jun 28 10:59:09 volumio volumio[5901]: info: CoreStateMachine::syncState currentStatus play Jun 28 10:59:09 volumio volumio[5901]: info: Received an update from plugin. extracting info from payload Jun 28 10:59:09 volumio volumio[5901]: info: CoreStateMachine::pushState Jun 28 10:59:09 volumio volumio[5901]: info: CorePlayQueue::getTrack 0 Jun 28 10:59:09 volumio volumio[5901]: info: CoreCommandRouter::volumioPushState Jun 28 10:59:09 volumio volumio[5901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 10:59:09 volumio volumio[5901]: info: CoreStateMachine::pushState Jun 28 10:59:09 volumio volumio[5901]: info: CorePlayQueue::getTrack 0 Jun 28 10:59:09 volumio volumio[5901]: info: CoreCommandRouter::volumioPushState Jun 28 10:59:09 volumio volumio[5901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 10:59:09 volumio volumio[5901]: info: ------------------------------ 80ms Jun 28 10:59:09 volumio volumio[5901]: verbose: ControllerMpd::parseTrackInfo Jun 28 10:59:09 volumio volumio[5901]: info: ControllerMpd::pushState Jun 28 10:59:09 volumio volumio[5901]: info: CoreCommandRouter::servicePushState Jun 28 10:59:09 volumio volumio[5901]: info: CorePlayQueue::getTrack 0 Jun 28 10:59:09 volumio volumio[5901]: verbose: STATE SERVICE {"status":"play","position":0,"seek":64238,"duration":213,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"You're Beautiful","artist":"James Blunt","album":"Back to Bedlam","uri":"USB/MUSIC/02 - You're Beautiful.mp3","trackType":"mp3"} Jun 28 10:59:09 volumio volumio[5901]: verbose: CURRENT POSITION 0 Jun 28 10:59:09 volumio volumio[5901]: info: CoreStateMachine::syncState stateService play Jun 28 10:59:09 volumio volumio[5901]: info: CoreStateMachine::syncState currentStatus play Jun 28 10:59:09 volumio volumio[5901]: info: Received an update from plugin. extracting info from payload Jun 28 10:59:09 volumio volumio[5901]: info: CoreStateMachine::pushState Jun 28 10:59:09 volumio volumio[5901]: info: CorePlayQueue::getTrack 0 Jun 28 10:59:09 volumio volumio[5901]: info: CoreCommandRouter::volumioPushState Jun 28 10:59:09 volumio volumio[5901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 10:59:09 volumio volumio[5901]: info: CoreStateMachine::pushState Jun 28 10:59:09 volumio volumio[5901]: info: CorePlayQueue::getTrack 0 Jun 28 10:59:09 volumio volumio[5901]: info: CoreCommandRouter::volumioPushState Jun 28 10:59:09 volumio volumio[5901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 10:59:09 volumio volumio[5901]: info: ------------------------------ 107ms Jun 28 10:59:13 volumio volumio[5901]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNetworkSettings Jun 28 10:59:13 volumio volumio[5901]: info: Saving new wireless network Jun 28 10:59:13 volumio sudo[6295]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Jun 28 10:59:13 volumio sudo[6295]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 10:59:13 volumio systemd[1]: Stopping Wireless Services... Jun 28 10:59:13 volumio dhcpcd[4994]: received SIGTERM from PID 1, stopping Jun 28 10:59:13 volumio dhcpcd[4994]: wlan0: removing interface Jun 28 10:59:13 volumio dhcpcd[4994]: ip6tnl0: removing interface Jun 28 10:59:13 volumio dhcpcd[4994]: sit0: removing interface Jun 28 10:59:13 volumio dhcpcd[4994]: tunl0: removing interface Jun 28 10:59:13 volumio dhcpcd[4994]: eth0: removing interface Jun 28 10:59:13 volumio dhcpcd[4994]: eth0: deleting host route to 192.168.1.237 via 127.0.0.1 Jun 28 10:59:13 volumio dhcpcd[4994]: eth0: deleting route to 192.168.1.0/24 Jun 28 10:59:13 volumio dhcpcd[4994]: eth0: deleting default route via 192.168.1.1 Jun 28 10:59:13 volumio avahi-daemon[4657]: Withdrawing address record for 192.168.1.237 on eth0. Jun 28 10:59:13 volumio avahi-daemon[4657]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.1.237. Jun 28 10:59:13 volumio avahi-daemon[4657]: Interface eth0.IPv4 no longer relevant for mDNS. Jun 28 10:59:13 volumio volumio[5901]: info: mDNS: A device disapperared from network Jun 28 10:59:13 volumio volumio[5901]: info: mDNS: Device volumio disapperared from network Jun 28 10:59:13 volumio dhcpcd[4994]: exited Jun 28 10:59:13 volumio systemd[1]: wireless.service: main process exited, code=exited, status=1/FAILURE Jun 28 10:59:13 volumio systemd[1]: Unit wireless.service entered failed state. Jun 28 10:59:13 volumio systemd[1]: Starting Wireless Services... Jun 28 10:59:13 volumio sudo[6380]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 set power_save off Jun 28 10:59:13 volumio sudo[6380]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 10:59:13 volumio sudo[6380]: pam_unix(sudo:session): session closed for user root Jun 28 10:59:14 volumio wireless.js[6384]: Cleaning previous... Jun 28 10:59:14 volumio systemd[1]: Stopping dhcpd.service... Jun 28 10:59:14 volumio systemd[1]: Stopping Host and Network Name Lookups. Jun 28 10:59:14 volumio systemd[1]: Stopped target Host and Network Name Lookups. Jun 28 10:59:14 volumio systemd[1]: Stopping dnsmasq.service... Jun 28 10:59:14 volumio dnsmasq[5144]: exiting on receipt of SIGTERM Jun 28 10:59:14 volumio systemd[1]: Stopped dnsmasq.service. Jun 28 10:59:14 volumio systemd[1]: Stopped dhcpd.service. Jun 28 10:59:14 volumio systemd[1]: Stopping hotspot.service... Jun 28 10:59:15 volumio ntpd[4711]: Deleting interface #5 eth0, 192.168.1.237#123, interface stats: received=11, sent=13, dropped=0, active_time=120 secs Jun 28 10:59:15 volumio ntpd[4711]: 193.182.111.143 interface 192.168.1.237 -> (none) Jun 28 10:59:15 volumio ntpd[4711]: 193.182.111.142 interface 192.168.1.237 -> (none) Jun 28 10:59:15 volumio ntpd[4711]: 193.182.111.12 interface 192.168.1.237 -> (none) Jun 28 10:59:15 volumio ntpd[4711]: peers refreshed Jun 28 10:59:19 volumio hotspot[6402]: Killing Hostapd Jun 28 10:59:19 volumio sudo[6421]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall hostapd Jun 28 10:59:19 volumio sudo[6421]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 10:59:19 volumio kernel: CFG80211-ERROR) wl_cfg80211_del_station : Disconnect STA : ff:ff:ff:ff:ff:ff scb_val.val 3 Jun 28 10:59:19 volumio sudo[6421]: pam_unix(sudo:session): session closed for user root Jun 28 10:59:19 volumio hotspot[6402]: Killing Dhcpd Jun 28 10:59:19 volumio sudo[6423]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall dhcpd Jun 28 10:59:19 volumio sudo[6423]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 10:59:19 volumio hotspot[6402]: dhcpd: no process found Jun 28 10:59:19 volumio sudo[6423]: pam_unix(sudo:session): session closed for user root Jun 28 10:59:19 volumio systemd[1]: hotspot.service: control process exited, code=exited status=1 Jun 28 10:59:19 volumio systemd[1]: Stopped hotspot.service. Jun 28 10:59:19 volumio systemd[1]: Unit hotspot.service entered failed state. Jun 28 10:59:19 volumio sudo[6431]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jun 28 10:59:19 volumio sudo[6431]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 10:59:19 volumio avahi-daemon[4657]: Withdrawing address record for 192.168.211.1 on wlan0. Jun 28 10:59:19 volumio avahi-daemon[4657]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Jun 28 10:59:19 volumio avahi-daemon[4657]: Interface wlan0.IPv4 no longer relevant for mDNS. Jun 28 10:59:19 volumio sudo[6431]: pam_unix(sudo:session): session closed for user root Jun 28 10:59:19 volumio sudo[6433]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jun 28 10:59:19 volumio sudo[6433]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 10:59:19 volumio kernel: wl_iw_event: Link Down with BSSID=00:90:4c:c5:12:38 Jun 28 10:59:19 volumio kernel: dhd_stop: Enter ffffffc05173d000 Jun 28 10:59:19 volumio volumio[5901]: verbose: ControllerMpd::sendMpdCommand status Jun 28 10:59:19 volumio volumio[5901]: info: Jun 28 10:59:19 volumio volumio[5901]: ---------------------------- MPD announces state update: update Jun 28 10:59:19 volumio volumio[5901]: info: ControllerMpd::getState Jun 28 10:59:19 volumio volumio[5901]: verbose: ControllerMpd::sendMpdCommand status Jun 28 10:59:19 volumio volumio[5901]: verbose: ControllerMpd::sendMpdCommand status Jun 28 10:59:19 volumio volumio[5901]: info: Jun 28 10:59:19 volumio volumio[5901]: ---------------------------- MPD announces state update: update Jun 28 10:59:19 volumio volumio[5901]: info: ControllerMpd::getState Jun 28 10:59:19 volumio volumio[5901]: verbose: ControllerMpd::sendMpdCommand status Jun 28 10:59:19 volumio volumio[5901]: verbose: ControllerMpd::parseState Jun 28 10:59:19 volumio kernel: wl_event_handler: was terminated Jun 28 10:59:19 volumio kernel: wl_destroy_event_handler(): thread:wl_event_handler:1400 terminated OK Jun 28 10:59:19 volumio kernel: CFGP2P-ERROR) wl_cfgp2p_disable_discovery : do nothing, not initialized Jun 28 10:59:19 volumio kernel: CFGP2P-ERROR) wl_cfgp2p_deinit_priv : In Jun 28 10:59:19 volumio volumio[5901]: info: Command Router : Notfying DB Updatefalse Jun 28 10:59:19 volumio volumio[5901]: info: CoreCommandRouter::Close All Modals sent Jun 28 10:59:19 volumio kernel: wl_android_wifi_off in 1 Jun 28 10:59:19 volumio kernel: wl_android_wifi_off in 2: g_wifi_on=1, on_failure=1 Jun 28 10:59:19 volumio kernel: bcmsdh_oob_intr_unregister: Enter Jun 28 10:59:19 volumio volumio[5901]: verbose: ControllerMpd::parseState Jun 28 10:59:19 volumio volumio[5901]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 28 10:59:19 volumio volumio[5901]: info: mDNS: A device disapperared from network Jun 28 10:59:19 volumio kernel: dhd_txglom_enable: enable 0 Jun 28 10:59:19 volumio volumio[5901]: verbose: ControllerMpd::parseState Jun 28 10:59:19 volumio kernel: dhd_conf_set_txglom_params: swtxglom=0, txglom_ext=0, txglom_bucket_size=0 Jun 28 10:59:19 volumio kernel: dhd_conf_set_txglom_params: txglomsize=0, deferred_tx_len=0, bus_txglom=-1 Jun 28 10:59:19 volumio kernel: dhd_conf_set_txglom_params: tx_in_rx=1, txinrx_thres=-1, dhd_txminmax=1 Jun 28 10:59:19 volumio volumio[5901]: info: Command Router : Notfying DB Updatefalse Jun 28 10:59:19 volumio volumio[5901]: info: CoreCommandRouter::Close All Modals sent Jun 28 10:59:19 volumio volumio[5901]: verbose: ControllerMpd::parseState Jun 28 10:59:19 volumio volumio[5901]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 28 10:59:19 volumio kernel: dhd_conf_set_txglom_params: tx_max_offset=0, txctl_tmo_fix=1 Jun 28 10:59:19 volumio kernel: sdioh_set_mode: set txglom_mode to multi-desc Jun 28 10:59:19 volumio volumio[5901]: verbose: ControllerMpd::parseTrackInfo Jun 28 10:59:19 volumio volumio[5901]: info: ControllerMpd::pushState Jun 28 10:59:19 volumio volumio[5901]: info: CoreCommandRouter::servicePushState Jun 28 10:59:19 volumio volumio[5901]: info: CorePlayQueue::getTrack 0 Jun 28 10:59:19 volumio volumio[5901]: verbose: STATE SERVICE {"status":"play","position":0,"seek":74234,"duration":213,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"You're Beautiful","artist":"James Blunt","album":"Back to Bedlam","uri":"USB/MUSIC/02 - You're Beautiful.mp3","trackType":"mp3"} Jun 28 10:59:19 volumio volumio[5901]: verbose: CURRENT POSITION 0 Jun 28 10:59:19 volumio volumio[5901]: info: CoreStateMachine::syncState stateService play Jun 28 10:59:19 volumio volumio[5901]: info: CoreStateMachine::syncState currentStatus play Jun 28 10:59:19 volumio volumio[5901]: info: Received an update from plugin. extracting info from payload Jun 28 10:59:19 volumio kernel: dhd_bus_devreset: WLAN OFF DONE Jun 28 10:59:19 volumio kernel: wifi_platform_set_power = 0 Jun 28 10:59:19 volumio volumio[5901]: info: CoreStateMachine::pushState Jun 28 10:59:19 volumio volumio[5901]: info: CorePlayQueue::getTrack 0 Jun 28 10:59:19 volumio volumio[5901]: info: CoreCommandRouter::volumioPushState Jun 28 10:59:19 volumio volumio[5901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 10:59:19 volumio volumio[5901]: info: CoreStateMachine::pushState Jun 28 10:59:19 volumio volumio[5901]: info: CorePlayQueue::getTrack 0 Jun 28 10:59:19 volumio volumio[5901]: info: CoreCommandRouter::volumioPushState Jun 28 10:59:19 volumio volumio[5901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 10:59:19 volumio kernel: ======== PULL WL_REG_ON(-1) LOW! ======== Jun 28 10:59:19 volumio kernel: wl_android_wifi_off out Jun 28 10:59:19 volumio volumio[5901]: info: ------------------------------ 117ms Jun 28 10:59:19 volumio volumio[5901]: verbose: ControllerMpd::parseTrackInfo Jun 28 10:59:19 volumio volumio[5901]: info: ControllerMpd::pushState Jun 28 10:59:19 volumio volumio[5901]: info: CoreCommandRouter::servicePushState Jun 28 10:59:19 volumio volumio[5901]: info: CorePlayQueue::getTrack 0 Jun 28 10:59:19 volumio volumio[5901]: verbose: STATE SERVICE {"status":"play","position":0,"seek":74234,"duration":213,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"You're Beautiful","artist":"James Blunt","album":"Back to Bedlam","uri":"USB/MUSIC/02 - You're Beautiful.mp3","trackType":"mp3"} Jun 28 10:59:19 volumio volumio[5901]: verbose: CURRENT POSITION 0 Jun 28 10:59:19 volumio volumio[5901]: info: CoreStateMachine::syncState stateService play Jun 28 10:59:19 volumio volumio[5901]: info: CoreStateMachine::syncState currentStatus play Jun 28 10:59:19 volumio volumio[5901]: info: Received an update from plugin. extracting info from payload Jun 28 10:59:19 volumio volumio[5901]: info: CoreStateMachine::pushState Jun 28 10:59:19 volumio volumio[5901]: info: CorePlayQueue::getTrack 0 Jun 28 10:59:19 volumio volumio[5901]: info: CoreCommandRouter::volumioPushState Jun 28 10:59:19 volumio volumio[5901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 10:59:19 volumio volumio[5901]: info: CoreStateMachine::pushState Jun 28 10:59:19 volumio volumio[5901]: info: CorePlayQueue::getTrack 0 Jun 28 10:59:19 volumio volumio[5901]: info: CoreCommandRouter::volumioPushState Jun 28 10:59:19 volumio volumio[5901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 10:59:19 volumio volumio[5901]: info: ------------------------------ 146ms Jun 28 10:59:20 volumio sudo[6433]: pam_unix(sudo:session): session closed for user root Jun 28 10:59:20 volumio kernel: dhd_stop: Exit Jun 28 10:59:20 volumio wireless.js[6384]: Stopped aP Jun 28 10:59:20 volumio wireless.js[6384]: Start wireless flow Jun 28 10:59:20 volumio wireless.js[6384]: Stopped hotspot (if there).. Jun 28 10:59:20 volumio sudo[6448]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jun 28 10:59:20 volumio sudo[6448]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 10:59:20 volumio sudo[6448]: pam_unix(sudo:session): session closed for user root Jun 28 10:59:20 volumio sudo[6450]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jun 28 10:59:20 volumio sudo[6450]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 10:59:20 volumio sudo[6450]: pam_unix(sudo:session): session closed for user root Jun 28 10:59:20 volumio wireless.js[6384]: DHCP IP Jun 28 10:59:20 volumio wpa_supplicant[6452]: Successfully initialized wpa_supplicant Jun 28 10:59:20 volumio wireless.js[6384]: Start ap Jun 28 10:59:20 volumio kernel: dhd_open: Enter ffffffc05173d000 Jun 28 10:59:20 volumio kernel: Dongle Host Driver, version 1.363.59.144.9 (r) Jun 28 10:59:20 volumio kernel: wl_android_wifi_on in 1 Jun 28 10:59:20 volumio kernel: wl_android_wifi_on in 2: g_wifi_on=0 Jun 28 10:59:20 volumio kernel: wifi_platform_set_power = 1 Jun 28 10:59:20 volumio kernel: ======== PULL WL_REG_ON(-1) HIGH! ======== Jun 28 10:59:20 volumio kernel: aml_wifi wifi.33: [extern_wifi_set_enable] WIFI Disable! 240 Jun 28 10:59:20 volumio kernel: aml_wifi wifi.33: [extern_wifi_set_enable] WIFI Enable! 240 Jun 28 10:59:20 volumio kernel: sdio_reset_comm(): Jun 28 10:59:20 volumio kernel: sdio: clk 200000000 SDR mode tuning start Jun 28 10:59:20 volumio kernel: sdio: rx_tuning_result[0] = 10 Jun 28 10:59:20 volumio kernel: sdio: rx_tuning_result[1] = 10 Jun 28 10:59:20 volumio kernel: sdio: rx_tuning_result[2] = 10 Jun 28 10:59:20 volumio kernel: sdio: rx_tuning_result[3] = 10 Jun 28 10:59:20 volumio kernel: sdio: rx_tuning_result[4] = 10 Jun 28 10:59:20 volumio kernel: sdio: best_win_start =0, best_win_size =5 Jun 28 10:59:20 volumio kernel: sdio: sd_emmc_regs->gclock=0x1000245,sd_emmc_regs->gadjust=0x2000 Jun 28 10:59:20 volumio kernel: sdio: gclock =0x1000245, gdelay=0x0, gadjust=0x2000 Jun 28 10:59:20 volumio kernel: sdioh_start: set sd_f2_blocksize 256 Jun 28 10:59:20 volumio kernel: dhd_bus_devreset: == WLAN ON == Jun 28 10:59:20 volumio kernel: F1 signature read @0x18000000=0x15264345 Jun 28 10:59:21 volumio kernel: F1 signature OK, socitype:0x1 chip:0x4345 rev:0x6 pkg:0x2 Jun 28 10:59:21 volumio kernel: DHD: dongle ram size is set to 819200(orig 819200) at 0x198000 Jun 28 10:59:21 volumio kernel: dhd_os_open_image: /lib/firmware/brcm/config.txt (399 bytes) open success Jun 28 10:59:21 volumio kernel: dhd_conf_read_others: ccode = SG Jun 28 10:59:21 volumio kernel: dhd_conf_read_others: bcn_timeout = 20 Jun 28 10:59:21 volumio kernel: dhd_conf_read_sdio_params: dhd_slpauto = 1 Jun 28 10:59:21 volumio kernel: dhd_conf_read_others: mimo_bw_cap = 1 Jun 28 10:59:21 volumio kernel: dhd_conf_read_pm_params: PM = 0 Jun 28 10:59:21 volumio kernel: dhd_conf_read_nv_by_chip: nv_by_chip_count=12 Jun 28 10:59:21 volumio kernel: dhd_conf_read_nv_by_chip: chip=0xa962, chiprev=0, name=nvram_ap6181.txt Jun 28 10:59:21 volumio kernel: dhd_conf_read_nv_by_chip: chip=0xa962, chiprev=1, name=nvram_ap6210.txt Jun 28 10:59:21 volumio kernel: dhd_conf_read_nv_by_chip: chip=0xa9a6, chiprev=0, name=nvram_ap6212.txt Jun 28 10:59:21 volumio kernel: dhd_conf_read_nv_by_chip: chip=0xa9a6, chiprev=1, name=nvram_ap6212a.txt Jun 28 10:59:21 volumio kernel: dhd_conf_read_nv_by_chip: chip=0x4345, chiprev=6, name=nvram_ap6255.txt Jun 28 10:59:21 volumio kernel: dhd_conf_read_nv_by_chip: chip=0x4330, chiprev=4, name=nvram_ap6330.txt Jun 28 10:59:21 volumio kernel: dhd_conf_read_nv_by_chip: chip=0x4339, chiprev=1, name=nvram_ap6335.txt Jun 28 10:59:21 volumio kernel: dhd_conf_read_nv_by_chip: chip=0x4354, chiprev=2, name=nvram_ap6356.txt Jun 28 10:59:21 volumio kernel: dhd_conf_read_nv_by_chip: chip=0x4335, chiprev=1, name=nvram_bcm4335.txt Jun 28 10:59:21 volumio kernel: dhd_conf_read_nv_by_chip: chip=0xa94c, chiprev=2, name=nvram_ap6234.txt Jun 28 10:59:21 volumio kernel: dhd_conf_read_nv_by_chip: chip=0x4359, chiprev=9, name=nvram_ap6359sa.txt Jun 28 10:59:21 volumio kernel: dhd_conf_read_nv_by_chip: chip=0x4334, chiprev=3, name=nvram_bcm4334.txt Jun 28 10:59:21 volumio kernel: Final fw_path=/lib/firmware/brcm/fw_bcm43455c0_ag.bin Jun 28 10:59:21 volumio kernel: Final nv_path=/lib/firmware/brcm/nvram_ap6255.txt Jun 28 10:59:21 volumio kernel: Final clm_path=/lib/firmware/brcm/clm_bcmdhd.blob Jun 28 10:59:21 volumio wireless.js[6384]: trying... Jun 28 10:59:21 volumio kernel: Final conf_path=/lib/firmware/brcm/config.txt Jun 28 10:59:21 volumio kernel: dhd_set_bus_params: set use_rxchain 0 Jun 28 10:59:21 volumio kernel: dhd_set_bus_params: set txglomsize 36 Jun 28 10:59:21 volumio kernel: dhd_os_open_image: /lib/firmware/brcm/fw_bcm43455c0_ag.bin (483181 bytes) open success Jun 28 10:59:21 volumio kernel: dhd_os_open_image: /lib/firmware/brcm/nvram_ap6255.txt (1644 bytes) open success Jun 28 10:59:21 volumio kernel: NVRAM version: AP6255_NVRAM_V1.0_29052015 Jun 28 10:59:21 volumio kernel: dhdsdio_write_vars: Download, Upload and compare of NVRAM succeeded. Jun 28 10:59:21 volumio kernel: dhd_bus_init: enable 0x06, ready 0x06 (waited 0us) Jun 28 10:59:21 volumio kernel: bcmsdh_oob_intr_register: Enter Jun 28 10:59:21 volumio kernel: bcmsdh_oob_intr_register: HW_OOB enabled Jun 28 10:59:21 volumio kernel: bcmsdh_oob_intr_register OOB irq=100 flags=0x4 Jun 28 10:59:21 volumio kernel: bcmsdh_oob_intr_register change flags to 0x4 Jun 28 10:59:21 volumio kernel: bcmsdh_oob_intr_register: enable_irq_wake Jun 28 10:59:21 volumio kernel: bcmsdh_oob_intr_register: enable_irq_wake failed with -6 Jun 28 10:59:21 volumio kernel: Disable tdls_auto_op failed. -1 Jun 28 10:59:21 volumio kernel: dhd_conf_set_intiovar: set WLC_SET_BAND 142 0 Jun 28 10:59:21 volumio kernel: dhd_preinit_ioctls: Set tcpack_sup_mode 0 Jun 28 10:59:21 volumio kernel: dhd_tcpack_suppress_set 347: already set to 0 Jun 28 10:59:21 volumio kernel: dhd_apply_default_clm: Ignore clm file /lib/firmware/brcm/clm_bcmdhd.blob Jun 28 10:59:21 volumio kernel: Firmware up: op_mode=0x0005, MAC=00:90:4c:c5:12:38 Jun 28 10:59:21 volumio kernel: dhd_conf_set_country: set country SG, revision 0 Jun 28 10:59:21 volumio kernel: Country code: SG (SG/0) Jun 28 10:59:21 volumio kernel: dhd_conf_set_intiovar: set roam_off 1 Jun 28 10:59:21 volumio kernel: dhd_conf_set_intiovar: set mimo_bw_cap 1 Jun 28 10:59:21 volumio kernel: Firmware version = wl0: Aug 25 2015 18:58:57 version 7.45.69 (r581703) FWID 01-24037f6e Jun 28 10:59:21 volumio kernel: Driver: 1.363.59.144.9 (r) Firmware: wl0: Aug 25 2015 18:58:57 version 7.45.69 (r581703) FWID 01-24037f6e Jun 28 10:59:21 volumio kernel: clm = 7.14.8 Jun 28 10:59:21 volumio kernel: dhd_txglom_enable: enable 1 Jun 28 10:59:21 volumio kernel: dhd_conf_set_txglom_params: swtxglom=0, txglom_ext=0, txglom_bucket_size=0 Jun 28 10:59:21 volumio kernel: dhd_conf_set_txglom_params: txglomsize=36, deferred_tx_len=0, bus_txglom=-1 Jun 28 10:59:21 volumio kernel: dhd_conf_set_txglom_params: tx_in_rx=1, txinrx_thres=-1, dhd_txminmax=1 Jun 28 10:59:21 volumio kernel: dhd_conf_set_txglom_params: tx_max_offset=0, txctl_tmo_fix=1 Jun 28 10:59:21 volumio kernel: sdioh_set_mode: set txglom_mode to multi-desc Jun 28 10:59:21 volumio kernel: dhd_conf_get_disable_proptx: fw_proptx=1, disable_proptx=-1 Jun 28 10:59:21 volumio kernel: dhd_wlfc_hostreorder_init(): successful bdcv2 tlv signaling, 64 Jun 28 10:59:21 volumio kernel: dhd_conf_set_intiovar: set ampdu_hostreorder 1 Jun 28 10:59:21 volumio kernel: wl_android_wifi_on: Success Jun 28 10:59:21 volumio kernel: wl_create_event_handler(): thread:wl_event_handler:193a started Jun 28 10:59:21 volumio kernel: tsk Enter, tsk = 0xffffff8001579bb8 Jun 28 10:59:21 volumio kernel: CFG80211-ERROR) wl_update_wiphybands : error reading txbf_bfe_cap (-23) Jun 28 10:59:21 volumio kernel: CFG80211-ERROR) wl_update_wiphybands : error reading txbf_bfr_cap (-23) Jun 28 10:59:21 volumio kernel: dhd_open: Exit ret=0 Jun 28 10:59:21 volumio sudo[6457]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 28 10:59:21 volumio sudo[6453]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Jun 28 10:59:21 volumio sudo[6457]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 10:59:21 volumio sudo[6453]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 10:59:21 volumio sudo[6457]: pam_unix(sudo:session): session closed for user root Jun 28 10:59:21 volumio dhcpcd[6460]: version 6.0.5 starting Jun 28 10:59:21 volumio kernel: Error getting the SSID Jun 28 10:59:21 volumio dhcpcd[6460]: eth0: rebinding lease of 192.168.1.237 Jun 28 10:59:21 volumio dhcpcd[6460]: wlan0: waiting for carrier Jun 28 10:59:21 volumio dhcpcd[6460]: eth0: leased 192.168.1.237 for 86400 seconds Jun 28 10:59:21 volumio dhcpcd[6460]: eth0: adding host route to 192.168.1.237 via 127.0.0.1 Jun 28 10:59:21 volumio dhcpcd[6460]: eth0: adding route to 192.168.1.0/24 Jun 28 10:59:21 volumio dhcpcd[6460]: eth0: adding default route via 192.168.1.1 Jun 28 10:59:21 volumio avahi-daemon[4657]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.237. Jun 28 10:59:21 volumio avahi-daemon[4657]: New relevant interface eth0.IPv4 for mDNS. Jun 28 10:59:21 volumio avahi-daemon[4657]: Registering new address record for 192.168.1.237 on eth0.IPv4. Jun 28 10:59:21 volumio dhcpcd[6460]: forked to background, child pid 6513 Jun 28 10:59:21 volumio sudo[6453]: pam_unix(sudo:session): session closed for user root Jun 28 10:59:22 volumio wireless.js[6384]: trying... Jun 28 10:59:22 volumio sudo[6520]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 28 10:59:22 volumio sudo[6520]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 10:59:22 volumio sudo[6520]: pam_unix(sudo:session): session closed for user root Jun 28 10:59:22 volumio volumio[5901]: Discovery: adding 2784a459-ab5a-44c8-9455-882263df1a9d Jun 28 10:59:22 volumio volumio[5901]: info: mDNS: Found device Volumio Jun 28 10:59:22 volumio volumio[5901]: info: CoreCommandRouter::volumioGetState Jun 28 10:59:22 volumio volumio[5901]: info: CorePlayQueue::getTrack 0 Jun 28 10:59:23 volumio ntpd[4711]: Listen normally on 6 eth0 192.168.1.237 UDP 123 Jun 28 10:59:23 volumio ntpd[4711]: Deleting interface #4 wlan0, 192.168.211.1#123, interface stats: received=0, sent=0, dropped=0, active_time=203 secs Jun 28 10:59:23 volumio ntpd[4711]: peers refreshed Jun 28 10:59:23 volumio wireless.js[6384]: trying... Jun 28 10:59:23 volumio sudo[6525]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 28 10:59:23 volumio sudo[6525]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 10:59:23 volumio sudo[6525]: pam_unix(sudo:session): session closed for user root Jun 28 10:59:24 volumio wireless.js[6384]: trying... Jun 28 10:59:24 volumio sudo[6531]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 28 10:59:24 volumio sudo[6531]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 10:59:24 volumio sudo[6531]: pam_unix(sudo:session): session closed for user root Jun 28 10:59:25 volumio wpa_supplicant[6485]: wlan0: Trying to associate with 88:41:fc:a7:00:60 (SSID='HiWiFi_01B49E' freq=2412 MHz) Jun 28 10:59:25 volumio kernel: Connecting with 88:41:fc:a7:00:60 ssid "HiWiFi_01B49E", len (13) channel=1 Jun 28 10:59:25 volumio wireless.js[6384]: trying... Jun 28 10:59:25 volumio sudo[6537]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 28 10:59:25 volumio sudo[6537]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 10:59:25 volumio sudo[6537]: pam_unix(sudo:session): session closed for user root Jun 28 10:59:26 volumio kernel: connect failed event=0 e->status 1 e->reason 0 Jun 28 10:59:26 volumio wpa_supplicant[6485]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=88:41:fc:a7:00:60 status_code=1 Jun 28 10:59:26 volumio kernel: CFG80211-ERROR) wl_bss_connect_done : Report connect result - connection failed Jun 28 10:59:26 volumio wireless.js[6384]: trying... Jun 28 10:59:26 volumio sudo[6544]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 28 10:59:26 volumio sudo[6544]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 10:59:26 volumio sudo[6544]: pam_unix(sudo:session): session closed for user root Jun 28 10:59:26 volumio wpa_supplicant[6485]: wlan0: Trying to associate with 88:41:fc:a7:00:61 (SSID='HiWiFi_01B49E' freq=5280 MHz) Jun 28 10:59:26 volumio kernel: Connecting with 88:41:fc:a7:00:61 ssid "HiWiFi_01B49E", len (13) channel=56 Jun 28 10:59:27 volumio kernel: wl_iw_event: Link UP with BSSID=88:41:fc:a7:00:61 Jun 28 10:59:27 volumio kernel: wl_bss_connect_done succeeded with 88:41:fc:a7:00:61 Jun 28 10:59:27 volumio kernel: wl_bss_connect_done succeeded with 88:41:fc:a7:00:61 Jun 28 10:59:27 volumio kernel: cfg80211: Calling CRDA for country: NO Jun 28 10:59:27 volumio kernel: cfg80211: Regulatory domain changed to country: NO Jun 28 10:59:27 volumio kernel: cfg80211: DFS Master region: ETSI Jun 28 10:59:27 volumio kernel: cfg80211: (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp) Jun 28 10:59:27 volumio kernel: cfg80211: (2400000 KHz - 2483500 KHz @ 40000 KHz), (N/A, 2000 mBm) Jun 28 10:59:27 volumio kernel: cfg80211: (5150000 KHz - 5250000 KHz @ 80000 KHz), (N/A, 2301 mBm) Jun 28 10:59:27 volumio kernel: cfg80211: (5250000 KHz - 5350000 KHz @ 80000 KHz), (N/A, 2000 mBm) Jun 28 10:59:27 volumio kernel: cfg80211: (5470000 KHz - 5795000 KHz @ 160000 KHz), (N/A, 2698 mBm) Jun 28 10:59:27 volumio kernel: cfg80211: (5815000 KHz - 5850000 KHz @ 35000 KHz), (N/A, 3301 mBm) Jun 28 10:59:27 volumio kernel: cfg80211: (17100000 KHz - 17300000 KHz @ 200000 KHz), (N/A, 2000 mBm) Jun 28 10:59:27 volumio kernel: cfg80211: (57000000 KHz - 66000000 KHz @ 2160000 KHz), (N/A, 4000 mBm) Jun 28 10:59:27 volumio wpa_supplicant[6485]: wlan0: Associated with 88:41:fc:a7:00:61 Jun 28 10:59:27 volumio wpa_supplicant[6485]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=NO Jun 28 10:59:27 volumio wpa_supplicant[6485]: wlan0: WPA: Key negotiation completed with 88:41:fc:a7:00:61 [PTK=CCMP GTK=CCMP] Jun 28 10:59:27 volumio wpa_supplicant[6485]: wlan0: CTRL-EVENT-CONNECTED - Connection to 88:41:fc:a7:00:61 completed [id=0 id_str=] Jun 28 10:59:27 volumio dhcpcd[6513]: wlan0: carrier acquired Jun 28 10:59:27 volumio dhcpcd[6513]: wlan0: soliciting a DHCP lease Jun 28 10:59:27 volumio dhcpcd[6513]: wlan0: offered 192.168.1.238 from 192.168.1.1 Jun 28 10:59:27 volumio dhcpcd[6513]: wlan0: leased 192.168.1.238 for 86400 seconds Jun 28 10:59:27 volumio avahi-daemon[4657]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.238. Jun 28 10:59:27 volumio dhcpcd[6513]: wlan0: adding host route to 192.168.1.238 via 127.0.0.1 Jun 28 10:59:27 volumio dhcpcd[6513]: wlan0: adding route to 192.168.1.0/24 Jun 28 10:59:27 volumio dhcpcd[6513]: wlan0: adding default route via 192.168.1.1 Jun 28 10:59:27 volumio avahi-daemon[4657]: New relevant interface wlan0.IPv4 for mDNS. Jun 28 10:59:27 volumio avahi-daemon[4657]: Registering new address record for 192.168.1.238 on wlan0.IPv4. Jun 28 10:59:27 volumio volumio[5901]: Discovery: this is already registered, 2784a459-ab5a-44c8-9455-882263df1a9d Jun 28 10:59:27 volumio volumio[5901]: info: mDNS: Found device Volumio Jun 28 10:59:27 volumio volumio[5901]: info: CoreCommandRouter::volumioGetState Jun 28 10:59:27 volumio volumio[5901]: info: CorePlayQueue::getTrack 0 Jun 28 10:59:27 volumio wireless.js[6384]: trying... Jun 28 10:59:27 volumio sudo[6591]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 28 10:59:27 volumio sudo[6591]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 10:59:27 volumio sudo[6591]: pam_unix(sudo:session): session closed for user root Jun 28 10:59:27 volumio kernel: Error getting the SSID Jun 28 10:59:28 volumio ntpd[4711]: Listen normally on 7 wlan0 192.168.1.238 UDP 123 Jun 28 10:59:28 volumio ntpd[4711]: peers refreshed Jun 28 10:59:28 volumio wireless.js[6384]: trying... Jun 28 10:59:28 volumio sudo[6597]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 28 10:59:28 volumio sudo[6597]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 10:59:28 volumio sudo[6597]: pam_unix(sudo:session): session closed for user root Jun 28 10:59:28 volumio kernel: Error getting the SSID Jun 28 10:59:29 volumio wireless.js[6384]: trying... Jun 28 10:59:29 volumio sudo[6616]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 28 10:59:29 volumio sudo[6616]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 10:59:29 volumio sudo[6616]: pam_unix(sudo:session): session closed for user root Jun 28 10:59:29 volumio kernel: Error getting the SSID Jun 28 10:59:30 volumio wireless.js[6384]: trying... Jun 28 10:59:30 volumio sudo[6623]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 28 10:59:30 volumio sudo[6623]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 10:59:30 volumio kernel: Error getting the SSID Jun 28 10:59:30 volumio sudo[6623]: pam_unix(sudo:session): session closed for user root Jun 28 10:59:31 volumio wireless.js[6384]: trying... Jun 28 10:59:31 volumio sudo[6628]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 28 10:59:31 volumio sudo[6628]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 10:59:31 volumio sudo[6628]: pam_unix(sudo:session): session closed for user root Jun 28 10:59:31 volumio kernel: Error getting the SSID Jun 28 10:59:32 volumio volumio[5901]: verbose: ControllerMpd::sendMpdCommand status Jun 28 10:59:32 volumio volumio[5901]: info: Jun 28 10:59:32 volumio volumio[5901]: ---------------------------- MPD announces state update: update Jun 28 10:59:32 volumio volumio[5901]: info: ControllerMpd::getState Jun 28 10:59:32 volumio volumio[5901]: verbose: ControllerMpd::sendMpdCommand status Jun 28 10:59:32 volumio volumio[5901]: verbose: ControllerMpd::sendMpdCommand status Jun 28 10:59:32 volumio volumio[5901]: info: Jun 28 10:59:32 volumio volumio[5901]: ---------------------------- MPD announces state update: update Jun 28 10:59:32 volumio volumio[5901]: info: ControllerMpd::getState Jun 28 10:59:32 volumio volumio[5901]: verbose: ControllerMpd::sendMpdCommand status Jun 28 10:59:32 volumio volumio[5901]: verbose: ControllerMpd::parseState Jun 28 10:59:32 volumio volumio[5901]: info: Command Router : Notfying DB Updatetrue Jun 28 10:59:32 volumio volumio[5901]: info: CoreCommandRouter::Close All Modals sent Jun 28 10:59:32 volumio volumio[5901]: verbose: ControllerMpd::parseState Jun 28 10:59:32 volumio volumio[5901]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 28 10:59:32 volumio volumio[5901]: verbose: ControllerMpd::sendMpdCommand status Jun 28 10:59:32 volumio volumio[5901]: info: Jun 28 10:59:32 volumio volumio[5901]: ---------------------------- MPD announces state update: update Jun 28 10:59:32 volumio volumio[5901]: verbose: ControllerMpd::sendMpdCommand status Jun 28 10:59:32 volumio volumio[5901]: info: Jun 28 10:59:32 volumio volumio[5901]: ---------------------------- MPD announces state update: update Jun 28 10:59:32 volumio volumio[5901]: info: ControllerMpd::getState Jun 28 10:59:32 volumio volumio[5901]: verbose: ControllerMpd::sendMpdCommand status Jun 28 10:59:32 volumio volumio[5901]: info: ControllerMpd::getState Jun 28 10:59:32 volumio volumio[5901]: verbose: ControllerMpd::sendMpdCommand status Jun 28 10:59:32 volumio volumio[5901]: verbose: ControllerMpd::parseState Jun 28 10:59:32 volumio volumio[5901]: info: Command Router : Notfying DB Updatetrue Jun 28 10:59:32 volumio volumio[5901]: info: CoreCommandRouter::Close All Modals sent Jun 28 10:59:32 volumio volumio[5901]: verbose: ControllerMpd::parseState Jun 28 10:59:32 volumio volumio[5901]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 28 10:59:32 volumio volumio[5901]: verbose: ControllerMpd::parseTrackInfo Jun 28 10:59:32 volumio volumio[5901]: info: ControllerMpd::pushState Jun 28 10:59:32 volumio volumio[5901]: info: CoreCommandRouter::servicePushState Jun 28 10:59:32 volumio volumio[5901]: info: CorePlayQueue::getTrack 0 Jun 28 10:59:32 volumio volumio[5901]: verbose: STATE SERVICE {"status":"play","position":0,"seek":86738,"duration":213,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"You're Beautiful","artist":"James Blunt","album":"Back to Bedlam","uri":"USB/MUSIC/02 - You're Beautiful.mp3","trackType":"mp3"} Jun 28 10:59:32 volumio volumio[5901]: verbose: CURRENT POSITION 0 Jun 28 10:59:32 volumio volumio[5901]: info: CoreStateMachine::syncState stateService play Jun 28 10:59:32 volumio volumio[5901]: info: CoreStateMachine::syncState currentStatus play Jun 28 10:59:32 volumio volumio[5901]: info: Received an update from plugin. extracting info from payload Jun 28 10:59:32 volumio volumio[5901]: info: CoreStateMachine::pushState Jun 28 10:59:32 volumio volumio[5901]: info: CorePlayQueue::getTrack 0 Jun 28 10:59:32 volumio volumio[5901]: info: CoreCommandRouter::volumioPushState Jun 28 10:59:32 volumio volumio[5901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 10:59:32 volumio volumio[5901]: info: CoreStateMachine::pushState Jun 28 10:59:32 volumio volumio[5901]: info: CorePlayQueue::getTrack 0 Jun 28 10:59:32 volumio volumio[5901]: info: CoreCommandRouter::volumioPushState Jun 28 10:59:32 volumio volumio[5901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 10:59:32 volumio volumio[5901]: info: ------------------------------ 96ms Jun 28 10:59:32 volumio volumio[5901]: verbose: ControllerMpd::parseState Jun 28 10:59:32 volumio volumio[5901]: info: Command Router : Notfying DB Updatefalse Jun 28 10:59:32 volumio volumio[5901]: info: CoreCommandRouter::Close All Modals sent Jun 28 10:59:32 volumio volumio[5901]: verbose: ControllerMpd::parseState Jun 28 10:59:32 volumio volumio[5901]: info: Command Router : Notfying DB Updatefalse Jun 28 10:59:32 volumio volumio[5901]: info: CoreCommandRouter::Close All Modals sent Jun 28 10:59:32 volumio volumio[5901]: verbose: ControllerMpd::parseState Jun 28 10:59:32 volumio volumio[5901]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 28 10:59:32 volumio volumio[5901]: verbose: ControllerMpd::parseState Jun 28 10:59:32 volumio volumio[5901]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 28 10:59:32 volumio volumio[5901]: verbose: ControllerMpd::parseTrackInfo Jun 28 10:59:32 volumio volumio[5901]: info: ControllerMpd::pushState Jun 28 10:59:32 volumio volumio[5901]: info: CoreCommandRouter::servicePushState Jun 28 10:59:32 volumio volumio[5901]: info: CorePlayQueue::getTrack 0 Jun 28 10:59:32 volumio volumio[5901]: verbose: STATE SERVICE {"status":"play","position":0,"seek":86738,"duration":213,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"You're Beautiful","artist":"James Blunt","album":"Back to Bedlam","uri":"USB/MUSIC/02 - You're Beautiful.mp3","trackType":"mp3"} Jun 28 10:59:32 volumio volumio[5901]: verbose: CURRENT POSITION 0 Jun 28 10:59:32 volumio volumio[5901]: info: CoreStateMachine::syncState stateService play Jun 28 10:59:32 volumio volumio[5901]: info: CoreStateMachine::syncState currentStatus play Jun 28 10:59:32 volumio volumio[5901]: info: Received an update from plugin. extracting info from payload Jun 28 10:59:32 volumio volumio[5901]: info: CoreStateMachine::pushState Jun 28 10:59:32 volumio volumio[5901]: info: CorePlayQueue::getTrack 0 Jun 28 10:59:32 volumio volumio[5901]: info: CoreCommandRouter::volumioPushState Jun 28 10:59:32 volumio volumio[5901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 10:59:32 volumio volumio[5901]: info: CoreStateMachine::pushState Jun 28 10:59:32 volumio volumio[5901]: info: CorePlayQueue::getTrack 0 Jun 28 10:59:32 volumio volumio[5901]: info: CoreCommandRouter::volumioPushState Jun 28 10:59:32 volumio volumio[5901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 10:59:32 volumio volumio[5901]: info: ------------------------------ 181ms Jun 28 10:59:32 volumio volumio[5901]: verbose: ControllerMpd::parseTrackInfo Jun 28 10:59:32 volumio volumio[5901]: verbose: ControllerMpd::parseTrackInfo Jun 28 10:59:32 volumio volumio[5901]: info: ControllerMpd::pushState Jun 28 10:59:32 volumio volumio[5901]: info: CoreCommandRouter::servicePushState Jun 28 10:59:32 volumio volumio[5901]: info: CorePlayQueue::getTrack 0 Jun 28 10:59:32 volumio volumio[5901]: verbose: STATE SERVICE {"status":"play","position":0,"seek":86865,"duration":213,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"You're Beautiful","artist":"James Blunt","album":"Back to Bedlam","uri":"USB/MUSIC/02 - You're Beautiful.mp3","trackType":"mp3"} Jun 28 10:59:32 volumio volumio[5901]: verbose: CURRENT POSITION 0 Jun 28 10:59:32 volumio volumio[5901]: info: CoreStateMachine::syncState stateService play Jun 28 10:59:32 volumio volumio[5901]: info: CoreStateMachine::syncState currentStatus play Jun 28 10:59:32 volumio volumio[5901]: info: Received an update from plugin. extracting info from payload Jun 28 10:59:32 volumio volumio[5901]: info: CoreStateMachine::pushState Jun 28 10:59:32 volumio volumio[5901]: info: CorePlayQueue::getTrack 0 Jun 28 10:59:32 volumio volumio[5901]: info: CoreCommandRouter::volumioPushState Jun 28 10:59:32 volumio volumio[5901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 10:59:32 volumio volumio[5901]: info: CoreStateMachine::pushState Jun 28 10:59:32 volumio volumio[5901]: info: CorePlayQueue::getTrack 0 Jun 28 10:59:32 volumio volumio[5901]: info: CoreCommandRouter::volumioPushState Jun 28 10:59:32 volumio volumio[5901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 10:59:32 volumio volumio[5901]: info: ControllerMpd::pushState Jun 28 10:59:32 volumio volumio[5901]: info: CoreCommandRouter::servicePushState Jun 28 10:59:32 volumio volumio[5901]: info: CorePlayQueue::getTrack 0 Jun 28 10:59:32 volumio volumio[5901]: verbose: STATE SERVICE {"status":"play","position":0,"seek":86865,"duration":213,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"You're Beautiful","artist":"James Blunt","album":"Back to Bedlam","uri":"USB/MUSIC/02 - You're Beautiful.mp3","trackType":"mp3"} Jun 28 10:59:32 volumio volumio[5901]: verbose: CURRENT POSITION 0 Jun 28 10:59:32 volumio volumio[5901]: info: CoreStateMachine::syncState stateService play Jun 28 10:59:32 volumio volumio[5901]: info: CoreStateMachine::syncState currentStatus play Jun 28 10:59:32 volumio volumio[5901]: info: Received an update from plugin. extracting info from payload Jun 28 10:59:32 volumio volumio[5901]: info: CoreStateMachine::pushState Jun 28 10:59:32 volumio volumio[5901]: info: CorePlayQueue::getTrack 0 Jun 28 10:59:32 volumio volumio[5901]: info: CoreCommandRouter::volumioPushState Jun 28 10:59:32 volumio volumio[5901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 10:59:32 volumio volumio[5901]: info: CoreStateMachine::pushState Jun 28 10:59:32 volumio volumio[5901]: info: CorePlayQueue::getTrack 0 Jun 28 10:59:32 volumio volumio[5901]: info: CoreCommandRouter::volumioPushState Jun 28 10:59:32 volumio volumio[5901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 10:59:32 volumio volumio[5901]: info: ------------------------------ 188ms Jun 28 10:59:32 volumio volumio[5901]: info: ------------------------------ 189ms Jun 28 10:59:32 volumio volumio[5901]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Jun 28 10:59:32 volumio volumio[5901]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Jun 28 10:59:32 volumio sudo[6646]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 28 10:59:32 volumio sudo[6646]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 10:59:32 volumio sudo[6646]: pam_unix(sudo:session): session closed for user root Jun 28 10:59:32 volumio volumio[5901]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Jun 28 10:59:32 volumio volumio[5901]: wlan0 Interface doesn't support scanning. Jun 28 10:59:32 volumio volumio[5901]: info: Cannot use regular scanning, forcing with ap-force Jun 28 10:59:32 volumio sudo[6649]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Jun 28 10:59:32 volumio sudo[6649]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 10:59:32 volumio wireless.js[6384]: trying... Jun 28 10:59:32 volumio sudo[6652]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 28 10:59:32 volumio sudo[6652]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 10:59:32 volumio sudo[6652]: pam_unix(sudo:session): session closed for user root Jun 28 10:59:32 volumio kernel: Error getting the SSID Jun 28 10:59:33 volumio wireless.js[6384]: trying... Jun 28 10:59:33 volumio sudo[6655]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 28 10:59:33 volumio sudo[6655]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 10:59:33 volumio kernel: Error getting the SSID Jun 28 10:59:33 volumio sudo[6655]: pam_unix(sudo:session): session closed for user root Jun 28 10:59:34 volumio wireless.js[6384]: trying... Jun 28 10:59:34 volumio sudo[6661]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 28 10:59:34 volumio sudo[6661]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 10:59:34 volumio sudo[6661]: pam_unix(sudo:session): session closed for user root Jun 28 10:59:34 volumio kernel: Error getting the SSID Jun 28 10:59:35 volumio wireless.js[6384]: trying... Jun 28 10:59:36 volumio sudo[6664]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 28 10:59:36 volumio sudo[6664]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 10:59:36 volumio sudo[6664]: pam_unix(sudo:session): session closed for user root Jun 28 10:59:36 volumio kernel: Error getting the SSID Jun 28 10:59:36 volumio sudo[6649]: pam_unix(sudo:session): session closed for user root Jun 28 10:59:37 volumio wireless.js[6384]: trying... Jun 28 10:59:37 volumio sudo[6671]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 28 10:59:37 volumio sudo[6671]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 10:59:37 volumio sudo[6671]: pam_unix(sudo:session): session closed for user root Jun 28 10:59:37 volumio kernel: Error getting the SSID Jun 28 10:59:38 volumio wireless.js[6384]: trying... Jun 28 10:59:38 volumio sudo[6676]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 28 10:59:38 volumio sudo[6676]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 10:59:38 volumio sudo[6676]: pam_unix(sudo:session): session closed for user root Jun 28 10:59:38 volumio kernel: Error getting the SSID Jun 28 10:59:39 volumio wireless.js[6384]: trying... Jun 28 10:59:39 volumio sudo[6683]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 28 10:59:39 volumio sudo[6683]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 10:59:39 volumio sudo[6683]: pam_unix(sudo:session): session closed for user root Jun 28 10:59:39 volumio kernel: Error getting the SSID Jun 28 10:59:39 volumio volumio[5901]: verbose: ControllerMpd::sendMpdCommand status Jun 28 10:59:39 volumio volumio[5901]: info: Jun 28 10:59:39 volumio volumio[5901]: ---------------------------- MPD announces state update: update Jun 28 10:59:39 volumio volumio[5901]: info: ControllerMpd::getState Jun 28 10:59:39 volumio volumio[5901]: verbose: ControllerMpd::sendMpdCommand status Jun 28 10:59:39 volumio volumio[5901]: verbose: ControllerMpd::sendMpdCommand status Jun 28 10:59:39 volumio volumio[5901]: info: Jun 28 10:59:39 volumio volumio[5901]: ---------------------------- MPD announces state update: update Jun 28 10:59:39 volumio volumio[5901]: info: ControllerMpd::getState Jun 28 10:59:39 volumio volumio[5901]: verbose: ControllerMpd::sendMpdCommand status Jun 28 10:59:39 volumio volumio[5901]: verbose: ControllerMpd::parseState Jun 28 10:59:39 volumio volumio[5901]: info: Command Router : Notfying DB Updatefalse Jun 28 10:59:39 volumio volumio[5901]: info: CoreCommandRouter::Close All Modals sent Jun 28 10:59:39 volumio volumio[5901]: verbose: ControllerMpd::parseState Jun 28 10:59:39 volumio volumio[5901]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 28 10:59:39 volumio volumio[5901]: verbose: ControllerMpd::parseState Jun 28 10:59:39 volumio volumio[5901]: info: Command Router : Notfying DB Updatefalse Jun 28 10:59:39 volumio volumio[5901]: info: CoreCommandRouter::Close All Modals sent Jun 28 10:59:39 volumio volumio[5901]: verbose: ControllerMpd::parseState Jun 28 10:59:39 volumio volumio[5901]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 28 10:59:39 volumio volumio[5901]: verbose: ControllerMpd::parseTrackInfo Jun 28 10:59:39 volumio volumio[5901]: info: ControllerMpd::pushState Jun 28 10:59:39 volumio volumio[5901]: info: CoreCommandRouter::servicePushState Jun 28 10:59:39 volumio volumio[5901]: info: CorePlayQueue::getTrack 0 Jun 28 10:59:39 volumio volumio[5901]: verbose: STATE SERVICE {"status":"play","position":0,"seek":93866,"duration":213,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"You're Beautiful","artist":"James Blunt","album":"Back to Bedlam","uri":"USB/MUSIC/02 - You're Beautiful.mp3","trackType":"mp3"} Jun 28 10:59:39 volumio volumio[5901]: verbose: CURRENT POSITION 0 Jun 28 10:59:39 volumio volumio[5901]: info: CoreStateMachine::syncState stateService play Jun 28 10:59:39 volumio volumio[5901]: info: CoreStateMachine::syncState currentStatus play Jun 28 10:59:39 volumio volumio[5901]: info: Received an update from plugin. extracting info from payload Jun 28 10:59:39 volumio volumio[5901]: info: CoreStateMachine::pushState Jun 28 10:59:39 volumio volumio[5901]: info: CorePlayQueue::getTrack 0 Jun 28 10:59:39 volumio volumio[5901]: info: CoreCommandRouter::volumioPushState Jun 28 10:59:39 volumio volumio[5901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 10:59:39 volumio volumio[5901]: info: CoreStateMachine::pushState Jun 28 10:59:39 volumio volumio[5901]: info: CorePlayQueue::getTrack 0 Jun 28 10:59:39 volumio volumio[5901]: info: CoreCommandRouter::volumioPushState Jun 28 10:59:39 volumio volumio[5901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 10:59:39 volumio volumio[5901]: info: ------------------------------ 101ms Jun 28 10:59:39 volumio volumio[5901]: verbose: ControllerMpd::parseTrackInfo Jun 28 10:59:39 volumio volumio[5901]: info: ControllerMpd::pushState Jun 28 10:59:39 volumio volumio[5901]: info: CoreCommandRouter::servicePushState Jun 28 10:59:39 volumio volumio[5901]: info: CorePlayQueue::getTrack 0 Jun 28 10:59:39 volumio volumio[5901]: verbose: STATE SERVICE {"status":"play","position":0,"seek":93866,"duration":213,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"You're Beautiful","artist":"James Blunt","album":"Back to Bedlam","uri":"USB/MUSIC/02 - You're Beautiful.mp3","trackType":"mp3"} Jun 28 10:59:39 volumio volumio[5901]: verbose: CURRENT POSITION 0 Jun 28 10:59:39 volumio volumio[5901]: info: CoreStateMachine::syncState stateService play Jun 28 10:59:39 volumio volumio[5901]: info: CoreStateMachine::syncState currentStatus play Jun 28 10:59:39 volumio volumio[5901]: info: Received an update from plugin. extracting info from payload Jun 28 10:59:39 volumio volumio[5901]: info: CoreStateMachine::pushState Jun 28 10:59:39 volumio volumio[5901]: info: CorePlayQueue::getTrack 0 Jun 28 10:59:39 volumio volumio[5901]: info: CoreCommandRouter::volumioPushState Jun 28 10:59:39 volumio volumio[5901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 10:59:39 volumio volumio[5901]: info: CoreStateMachine::pushState Jun 28 10:59:39 volumio volumio[5901]: info: CorePlayQueue::getTrack 0 Jun 28 10:59:39 volumio volumio[5901]: info: CoreCommandRouter::volumioPushState Jun 28 10:59:39 volumio volumio[5901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 10:59:39 volumio volumio[5901]: info: ------------------------------ 128ms Jun 28 10:59:40 volumio wireless.js[6384]: trying... Jun 28 10:59:40 volumio sudo[6695]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 28 10:59:40 volumio sudo[6695]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 10:59:40 volumio kernel: Error getting the SSID Jun 28 10:59:40 volumio sudo[6695]: pam_unix(sudo:session): session closed for user root Jun 28 10:59:41 volumio wireless.js[6384]: trying... Jun 28 10:59:41 volumio sudo[6700]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 28 10:59:41 volumio sudo[6700]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 10:59:41 volumio kernel: Error getting the SSID Jun 28 10:59:41 volumio sudo[6700]: pam_unix(sudo:session): session closed for user root Jun 28 10:59:41 volumio volumio[5901]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNetworkSettings Jun 28 10:59:41 volumio volumio[5901]: info: Saving new wireless network Jun 28 10:59:41 volumio sudo[6703]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Jun 28 10:59:41 volumio sudo[6703]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 10:59:41 volumio systemd[1]: Stopping Wireless Services... Jun 28 10:59:41 volumio systemd[1]: Starting Wireless Services... Jun 28 10:59:41 volumio sudo[6709]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 set power_save off Jun 28 10:59:41 volumio sudo[6709]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 10:59:41 volumio sudo[6709]: pam_unix(sudo:session): session closed for user root Jun 28 10:59:42 volumio wireless.js[6713]: Cleaning previous... Jun 28 10:59:42 volumio systemd[1]: Stopped hotspot.service. Jun 28 10:59:42 volumio sudo[6728]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jun 28 10:59:42 volumio sudo[6728]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 10:59:42 volumio avahi-daemon[4657]: Withdrawing address record for 192.168.1.238 on wlan0. Jun 28 10:59:42 volumio avahi-daemon[4657]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.238. Jun 28 10:59:42 volumio avahi-daemon[4657]: Interface wlan0.IPv4 no longer relevant for mDNS. Jun 28 10:59:42 volumio sudo[6728]: pam_unix(sudo:session): session closed for user root Jun 28 10:59:42 volumio volumio[5901]: info: mDNS: A device disapperared from network Jun 28 10:59:42 volumio volumio[5901]: info: mDNS: Device volumio disapperared from network Jun 28 10:59:42 volumio sudo[6730]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jun 28 10:59:42 volumio sudo[6730]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 10:59:42 volumio kernel: CFG80211-ERROR) wl_cfg80211_disconnect : Reason 3 Jun 28 10:59:42 volumio kernel: dhd_stop: Enter ffffffc05173d000 Jun 28 10:59:42 volumio kernel: wl_iw_event: Link Down with BSSID=88:41:fc:a7:00:61 Jun 28 10:59:42 volumio kernel: wl_event_handler: was terminated Jun 28 10:59:42 volumio kernel: wl_destroy_event_handler(): thread:wl_event_handler:193a terminated OK Jun 28 10:59:42 volumio kernel: CFGP2P-ERROR) wl_cfgp2p_disable_discovery : do nothing, not initialized Jun 28 10:59:42 volumio kernel: CFGP2P-ERROR) wl_cfgp2p_deinit_priv : In Jun 28 10:59:42 volumio kernel: wl_android_wifi_off in 1 Jun 28 10:59:42 volumio kernel: wl_android_wifi_off in 2: g_wifi_on=1, on_failure=1 Jun 28 10:59:42 volumio kernel: bcmsdh_oob_intr_unregister: Enter Jun 28 10:59:42 volumio kernel: dhd_txglom_enable: enable 0 Jun 28 10:59:42 volumio kernel: dhd_conf_set_txglom_params: swtxglom=0, txglom_ext=0, txglom_bucket_size=0 Jun 28 10:59:42 volumio kernel: dhd_conf_set_txglom_params: txglomsize=0, deferred_tx_len=0, bus_txglom=-1 Jun 28 10:59:42 volumio kernel: dhd_conf_set_txglom_params: tx_in_rx=1, txinrx_thres=-1, dhd_txminmax=1 Jun 28 10:59:42 volumio kernel: dhd_conf_set_txglom_params: tx_max_offset=0, txctl_tmo_fix=1 Jun 28 10:59:42 volumio kernel: sdioh_set_mode: set txglom_mode to multi-desc Jun 28 10:59:42 volumio kernel: dhd_bus_devreset: WLAN OFF DONE Jun 28 10:59:42 volumio kernel: wifi_platform_set_power = 0 Jun 28 10:59:42 volumio kernel: ======== PULL WL_REG_ON(-1) LOW! ======== Jun 28 10:59:42 volumio kernel: wl_android_wifi_off out Jun 28 10:59:42 volumio sudo[6730]: pam_unix(sudo:session): session closed for user root Jun 28 10:59:42 volumio kernel: dhd_stop: Exit Jun 28 10:59:42 volumio kernel: cfg80211: Calling CRDA to update world regulatory domain Jun 28 10:59:42 volumio kernel: cfg80211: World regulatory domain updated: Jun 28 10:59:42 volumio kernel: cfg80211: DFS Master region: unset Jun 28 10:59:42 volumio kernel: cfg80211: (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp) Jun 28 10:59:42 volumio kernel: cfg80211: (2402000 KHz - 2472000 KHz @ 40000 KHz), (N/A, 2000 mBm) Jun 28 10:59:42 volumio kernel: cfg80211: (2457000 KHz - 2482000 KHz @ 20000 KHz), (N/A, 2000 mBm) Jun 28 10:59:42 volumio kernel: cfg80211: (2474000 KHz - 2494000 KHz @ 20000 KHz), (N/A, 2000 mBm) Jun 28 10:59:42 volumio kernel: cfg80211: (5170000 KHz - 5250000 KHz @ 80000 KHz), (N/A, 2000 mBm) Jun 28 10:59:42 volumio kernel: cfg80211: (5250000 KHz - 5330000 KHz @ 80000 KHz), (N/A, 2000 mBm) Jun 28 10:59:42 volumio wireless.js[6713]: Stopped aP Jun 28 10:59:42 volumio wireless.js[6713]: Start wireless flow Jun 28 10:59:42 volumio kernel: cfg80211: (5490000 KHz - 5730000 KHz @ 160000 KHz), (N/A, 2000 mBm) Jun 28 10:59:42 volumio kernel: cfg80211: (5735000 KHz - 5835000 KHz @ 80000 KHz), (N/A, 2000 mBm) Jun 28 10:59:42 volumio wireless.js[6713]: Stopped hotspot (if there).. Jun 28 10:59:42 volumio kernel: cfg80211: (57240000 KHz - 63720000 KHz @ 2160000 KHz), (N/A, 0 mBm) Jun 28 10:59:42 volumio sudo[6740]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jun 28 10:59:42 volumio sudo[6740]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 10:59:42 volumio sudo[6740]: pam_unix(sudo:session): session closed for user root Jun 28 10:59:42 volumio sudo[6742]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jun 28 10:59:42 volumio sudo[6742]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 10:59:42 volumio sudo[6742]: pam_unix(sudo:session): session closed for user root Jun 28 10:59:42 volumio wireless.js[6713]: DHCP IP Jun 28 10:59:42 volumio wpa_supplicant[6744]: Successfully initialized wpa_supplicant Jun 28 10:59:42 volumio kernel: dhd_open: Enter ffffffc05173d000 Jun 28 10:59:42 volumio wireless.js[6713]: Start ap Jun 28 10:59:42 volumio kernel: Dongle Host Driver, version 1.363.59.144.9 (r) Jun 28 10:59:42 volumio kernel: wl_android_wifi_on in 1 Jun 28 10:59:42 volumio kernel: wl_android_wifi_on in 2: g_wifi_on=0 Jun 28 10:59:42 volumio kernel: wifi_platform_set_power = 1 Jun 28 10:59:42 volumio kernel: ======== PULL WL_REG_ON(-1) HIGH! ======== Jun 28 10:59:42 volumio kernel: aml_wifi wifi.33: [extern_wifi_set_enable] WIFI Disable! 240 Jun 28 10:59:42 volumio kernel: aml_wifi wifi.33: [extern_wifi_set_enable] WIFI Enable! 240 Jun 28 10:59:43 volumio kernel: sdio_reset_comm(): Jun 28 10:59:43 volumio kernel: sdio: clk 200000000 SDR mode tuning start Jun 28 10:59:43 volumio kernel: sdio: rx_tuning_result[0] = 10 Jun 28 10:59:43 volumio kernel: sdio: rx_tuning_result[1] = 10 Jun 28 10:59:43 volumio kernel: sdio: rx_tuning_result[2] = 10 Jun 28 10:59:43 volumio kernel: sdio: rx_tuning_result[3] = 10 Jun 28 10:59:43 volumio kernel: sdio: rx_tuning_result[4] = 10 Jun 28 10:59:43 volumio kernel: sdio: best_win_start =0, best_win_size =5 Jun 28 10:59:43 volumio kernel: sdio: sd_emmc_regs->gclock=0x1000245,sd_emmc_regs->gadjust=0x2000 Jun 28 10:59:43 volumio kernel: sdio: gclock =0x1000245, gdelay=0x0, gadjust=0x2000 Jun 28 10:59:43 volumio kernel: sdioh_start: set sd_f2_blocksize 256 Jun 28 10:59:43 volumio kernel: dhd_bus_devreset: == WLAN ON == Jun 28 10:59:43 volumio kernel: F1 signature read @0x18000000=0x15264345 Jun 28 10:59:43 volumio kernel: F1 signature OK, socitype:0x1 chip:0x4345 rev:0x6 pkg:0x2 Jun 28 10:59:43 volumio kernel: DHD: dongle ram size is set to 819200(orig 819200) at 0x198000 Jun 28 10:59:43 volumio kernel: dhd_os_open_image: /lib/firmware/brcm/config.txt (399 bytes) open success Jun 28 10:59:43 volumio kernel: dhd_conf_read_others: ccode = SG Jun 28 10:59:43 volumio kernel: dhd_conf_read_others: bcn_timeout = 20 Jun 28 10:59:43 volumio kernel: dhd_conf_read_sdio_params: dhd_slpauto = 1 Jun 28 10:59:43 volumio kernel: dhd_conf_read_others: mimo_bw_cap = 1 Jun 28 10:59:43 volumio kernel: dhd_conf_read_pm_params: PM = 0 Jun 28 10:59:43 volumio kernel: dhd_conf_read_nv_by_chip: nv_by_chip_count=12 Jun 28 10:59:43 volumio kernel: dhd_conf_read_nv_by_chip: chip=0xa962, chiprev=0, name=nvram_ap6181.txt Jun 28 10:59:43 volumio kernel: dhd_conf_read_nv_by_chip: chip=0xa962, chiprev=1, name=nvram_ap6210.txt Jun 28 10:59:43 volumio kernel: dhd_conf_read_nv_by_chip: chip=0xa9a6, chiprev=0, name=nvram_ap6212.txt Jun 28 10:59:43 volumio kernel: dhd_conf_read_nv_by_chip: chip=0xa9a6, chiprev=1, name=nvram_ap6212a.txt Jun 28 10:59:43 volumio kernel: dhd_conf_read_nv_by_chip: chip=0x4345, chiprev=6, name=nvram_ap6255.txt Jun 28 10:59:43 volumio kernel: dhd_conf_read_nv_by_chip: chip=0x4330, chiprev=4, name=nvram_ap6330.txt Jun 28 10:59:43 volumio kernel: dhd_conf_read_nv_by_chip: chip=0x4339, chiprev=1, name=nvram_ap6335.txt Jun 28 10:59:43 volumio kernel: dhd_conf_read_nv_by_chip: chip=0x4354, chiprev=2, name=nvram_ap6356.txt Jun 28 10:59:43 volumio kernel: dhd_conf_read_nv_by_chip: chip=0x4335, chiprev=1, name=nvram_bcm4335.txt Jun 28 10:59:43 volumio kernel: dhd_conf_read_nv_by_chip: chip=0xa94c, chiprev=2, name=nvram_ap6234.txt Jun 28 10:59:43 volumio kernel: dhd_conf_read_nv_by_chip: chip=0x4359, chiprev=9, name=nvram_ap6359sa.txt Jun 28 10:59:43 volumio kernel: dhd_conf_read_nv_by_chip: chip=0x4334, chiprev=3, name=nvram_bcm4334.txt Jun 28 10:59:43 volumio kernel: Final fw_path=/lib/firmware/brcm/fw_bcm43455c0_ag.bin Jun 28 10:59:43 volumio kernel: Final nv_path=/lib/firmware/brcm/nvram_ap6255.txt Jun 28 10:59:43 volumio kernel: Final clm_path=/lib/firmware/brcm/clm_bcmdhd.blob Jun 28 10:59:43 volumio kernel: Final conf_path=/lib/firmware/brcm/config.txt Jun 28 10:59:43 volumio kernel: dhd_set_bus_params: set use_rxchain 0 Jun 28 10:59:43 volumio wireless.js[6713]: trying... Jun 28 10:59:43 volumio kernel: dhd_set_bus_params: set txglomsize 36 Jun 28 10:59:43 volumio kernel: dhd_os_open_image: /lib/firmware/brcm/fw_bcm43455c0_ag.bin (483181 bytes) open success Jun 28 10:59:43 volumio kernel: dhd_os_open_image: /lib/firmware/brcm/nvram_ap6255.txt (1644 bytes) open success Jun 28 10:59:43 volumio kernel: NVRAM version: AP6255_NVRAM_V1.0_29052015 Jun 28 10:59:43 volumio kernel: dhdsdio_write_vars: Download, Upload and compare of NVRAM succeeded. Jun 28 10:59:43 volumio kernel: dhd_bus_init: enable 0x06, ready 0x06 (waited 0us) Jun 28 10:59:43 volumio kernel: bcmsdh_oob_intr_register: Enter Jun 28 10:59:43 volumio kernel: bcmsdh_oob_intr_register: HW_OOB enabled Jun 28 10:59:43 volumio kernel: bcmsdh_oob_intr_register OOB irq=100 flags=0x4 Jun 28 10:59:43 volumio kernel: bcmsdh_oob_intr_register change flags to 0x4 Jun 28 10:59:43 volumio kernel: bcmsdh_oob_intr_register: enable_irq_wake Jun 28 10:59:43 volumio kernel: bcmsdh_oob_intr_register: enable_irq_wake failed with -6 Jun 28 10:59:43 volumio kernel: Disable tdls_auto_op failed. -1 Jun 28 10:59:43 volumio kernel: dhd_conf_set_intiovar: set WLC_SET_BAND 142 0 Jun 28 10:59:43 volumio kernel: dhd_preinit_ioctls: Set tcpack_sup_mode 0 Jun 28 10:59:43 volumio kernel: dhd_tcpack_suppress_set 347: already set to 0 Jun 28 10:59:43 volumio kernel: dhd_apply_default_clm: Ignore clm file /lib/firmware/brcm/clm_bcmdhd.blob Jun 28 10:59:43 volumio kernel: Firmware up: op_mode=0x0005, MAC=00:90:4c:c5:12:38 Jun 28 10:59:43 volumio kernel: dhd_conf_set_country: set country SG, revision 0 Jun 28 10:59:43 volumio kernel: Country code: SG (SG/0) Jun 28 10:59:43 volumio kernel: dhd_conf_set_intiovar: set roam_off 1 Jun 28 10:59:43 volumio kernel: dhd_conf_set_intiovar: set mimo_bw_cap 1 Jun 28 10:59:43 volumio kernel: Firmware version = wl0: Aug 25 2015 18:58:57 version 7.45.69 (r581703) FWID 01-24037f6e Jun 28 10:59:43 volumio kernel: Driver: 1.363.59.144.9 (r) Firmware: wl0: Aug 25 2015 18:58:57 version 7.45.69 (r581703) FWID 01-24037f6e Jun 28 10:59:43 volumio kernel: clm = 7.14.8 Jun 28 10:59:43 volumio kernel: dhd_txglom_enable: enable 1 Jun 28 10:59:43 volumio kernel: dhd_conf_set_txglom_params: swtxglom=0, txglom_ext=0, txglom_bucket_size=0 Jun 28 10:59:43 volumio kernel: dhd_conf_set_txglom_params: txglomsize=36, deferred_tx_len=0, bus_txglom=-1 Jun 28 10:59:43 volumio kernel: dhd_conf_set_txglom_params: tx_in_rx=1, txinrx_thres=-1, dhd_txminmax=1 Jun 28 10:59:43 volumio kernel: dhd_conf_set_txglom_params: tx_max_offset=0, txctl_tmo_fix=1 Jun 28 10:59:43 volumio kernel: sdioh_set_mode: set txglom_mode to multi-desc Jun 28 10:59:43 volumio kernel: dhd_conf_get_disable_proptx: fw_proptx=1, disable_proptx=-1 Jun 28 10:59:43 volumio kernel: dhd_wlfc_hostreorder_init(): successful bdcv2 tlv signaling, 64 Jun 28 10:59:43 volumio kernel: dhd_conf_set_intiovar: set ampdu_hostreorder 1 Jun 28 10:59:43 volumio kernel: wl_android_wifi_on: Success Jun 28 10:59:43 volumio kernel: wl_create_event_handler(): thread:wl_event_handler:1a60 started Jun 28 10:59:43 volumio kernel: tsk Enter, tsk = 0xffffff8001579bb8 Jun 28 10:59:43 volumio kernel: CFG80211-ERROR) wl_update_wiphybands : error reading txbf_bfe_cap (-23) Jun 28 10:59:43 volumio kernel: CFG80211-ERROR) wl_update_wiphybands : error reading txbf_bfr_cap (-23) Jun 28 10:59:43 volumio ntpd[4711]: Deleting interface #7 wlan0, 192.168.1.238#123, interface stats: received=0, sent=0, dropped=0, active_time=15 secs Jun 28 10:59:43 volumio ntpd[4711]: peers refreshed Jun 28 10:59:43 volumio kernel: dhd_open: Exit ret=0 Jun 28 10:59:44 volumio sudo[6749]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 28 10:59:44 volumio sudo[6749]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 10:59:44 volumio sudo[6745]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Jun 28 10:59:44 volumio sudo[6745]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 10:59:44 volumio sudo[6749]: pam_unix(sudo:session): session closed for user root Jun 28 10:59:44 volumio dhcpcd[6754]: version 6.0.5 starting Jun 28 10:59:44 volumio kernel: Error getting the SSID Jun 28 10:59:44 volumio dhcpcd[6754]: eth0: rebinding lease of 192.168.1.237 Jun 28 10:59:44 volumio dhcpcd[6754]: wlan0: waiting for carrier Jun 28 10:59:44 volumio dhcpcd[6754]: eth0: leased 192.168.1.237 for 86400 seconds Jun 28 10:59:44 volumio dhcpcd[6754]: eth0: adding host route to 192.168.1.237 via 127.0.0.1 Jun 28 10:59:44 volumio dhcpcd[6754]: eth0: adding route to 192.168.1.0/24 Jun 28 10:59:44 volumio dhcpcd[6754]: eth0: adding default route via 192.168.1.1 Jun 28 10:59:44 volumio dhcpcd[6754]: forked to background, child pid 6828 Jun 28 10:59:44 volumio dhcpcd[6828]: eth0: removing route to 192.168.1.0/24 Jun 28 10:59:44 volumio dhcpcd[6828]: eth0: removing default route via 192.168.1.1 Jun 28 10:59:44 volumio sudo[6745]: pam_unix(sudo:session): session closed for user root Jun 28 10:59:45 volumio wireless.js[6713]: trying... Jun 28 10:59:45 volumio sudo[6833]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 28 10:59:45 volumio sudo[6833]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 10:59:45 volumio sudo[6833]: pam_unix(sudo:session): session closed for user root Jun 28 10:59:46 volumio wireless.js[6713]: trying... Jun 28 10:59:46 volumio sudo[6838]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 28 10:59:46 volumio sudo[6838]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 10:59:46 volumio sudo[6838]: pam_unix(sudo:session): session closed for user root Jun 28 10:59:47 volumio wireless.js[6713]: trying... Jun 28 10:59:47 volumio sudo[6845]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 28 10:59:47 volumio sudo[6845]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 10:59:47 volumio sudo[6845]: pam_unix(sudo:session): session closed for user root Jun 28 10:59:48 volumio wpa_supplicant[6806]: wlan0: Trying to associate with 88:41:fc:a7:00:60 (SSID='HiWiFi_01B49E' freq=2412 MHz) Jun 28 10:59:48 volumio kernel: Connecting with 88:41:fc:a7:00:60 ssid "HiWiFi_01B49E", len (13) channel=1 Jun 28 10:59:48 volumio wireless.js[6713]: trying... Jun 28 10:59:48 volumio kernel: wl_iw_event: Link UP with BSSID=88:41:fc:a7:00:60 Jun 28 10:59:48 volumio kernel: wl_bss_connect_done succeeded with 88:41:fc:a7:00:60 Jun 28 10:59:48 volumio sudo[6851]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 28 10:59:48 volumio sudo[6851]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 10:59:48 volumio wpa_supplicant[6806]: wlan0: Associated with 88:41:fc:a7:00:60 Jun 28 10:59:48 volumio sudo[6851]: pam_unix(sudo:session): session closed for user root Jun 28 10:59:48 volumio kernel: cfg80211: Calling CRDA for country: NO Jun 28 10:59:48 volumio wpa_supplicant[6806]: wlan0: WPA: Key negotiation completed with 88:41:fc:a7:00:60 [PTK=CCMP GTK=CCMP] Jun 28 10:59:48 volumio wpa_supplicant[6806]: wlan0: CTRL-EVENT-CONNECTED - Connection to 88:41:fc:a7:00:60 completed [id=0 id_str=] Jun 28 10:59:48 volumio dhcpcd[6828]: wlan0: carrier acquired Jun 28 10:59:48 volumio kernel: cfg80211: Regulatory domain changed to country: NO Jun 28 10:59:48 volumio kernel: cfg80211: DFS Master region: ETSI Jun 28 10:59:48 volumio kernel: cfg80211: (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp) Jun 28 10:59:48 volumio kernel: cfg80211: (2400000 KHz - 2483500 KHz @ 40000 KHz), (N/A, 2000 mBm) Jun 28 10:59:48 volumio kernel: cfg80211: (5150000 KHz - 5250000 KHz @ 80000 KHz), (N/A, 2301 mBm) Jun 28 10:59:48 volumio kernel: cfg80211: (5250000 KHz - 5350000 KHz @ 80000 KHz), (N/A, 2000 mBm) Jun 28 10:59:48 volumio dhcpcd[6828]: wlan0: rebinding lease of 192.168.1.238 Jun 28 10:59:48 volumio kernel: cfg80211: (5470000 KHz - 5795000 KHz @ 160000 KHz), (N/A, 2698 mBm) Jun 28 10:59:48 volumio kernel: cfg80211: (5815000 KHz - 5850000 KHz @ 35000 KHz), (N/A, 3301 mBm) Jun 28 10:59:48 volumio kernel: cfg80211: (17100000 KHz - 17300000 KHz @ 200000 KHz), (N/A, 2000 mBm) Jun 28 10:59:48 volumio kernel: wl_bss_connect_done succeeded with 88:41:fc:a7:00:60 Jun 28 10:59:48 volumio kernel: cfg80211: (57000000 KHz - 66000000 KHz @ 2160000 KHz), (N/A, 4000 mBm) Jun 28 10:59:48 volumio wpa_supplicant[6806]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=NO Jun 28 10:59:49 volumio wireless.js[6713]: trying... Jun 28 10:59:49 volumio sudo[6877]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 28 10:59:49 volumio sudo[6877]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 10:59:49 volumio sudo[6877]: pam_unix(sudo:session): session closed for user root Jun 28 10:59:49 volumio volumio[5901]: verbose: ControllerMpd::sendMpdCommand status Jun 28 10:59:49 volumio volumio[5901]: info: Jun 28 10:59:49 volumio volumio[5901]: ---------------------------- MPD announces state update: update Jun 28 10:59:49 volumio volumio[5901]: info: ControllerMpd::getState Jun 28 10:59:49 volumio volumio[5901]: verbose: ControllerMpd::sendMpdCommand status Jun 28 10:59:49 volumio volumio[5901]: verbose: ControllerMpd::parseState Jun 28 10:59:49 volumio volumio[5901]: info: Command Router : Notfying DB Updatetrue Jun 28 10:59:49 volumio volumio[5901]: info: CoreCommandRouter::Close All Modals sent Jun 28 10:59:49 volumio volumio[5901]: verbose: ControllerMpd::parseState Jun 28 10:59:49 volumio volumio[5901]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 28 10:59:49 volumio volumio[5901]: verbose: ControllerMpd::sendMpdCommand status Jun 28 10:59:49 volumio volumio[5901]: info: Jun 28 10:59:49 volumio volumio[5901]: ---------------------------- MPD announces state update: update Jun 28 10:59:49 volumio volumio[5901]: verbose: ControllerMpd::sendMpdCommand status Jun 28 10:59:49 volumio volumio[5901]: info: Jun 28 10:59:49 volumio volumio[5901]: ---------------------------- MPD announces state update: update Jun 28 10:59:49 volumio volumio[5901]: info: ControllerMpd::getState Jun 28 10:59:49 volumio volumio[5901]: verbose: ControllerMpd::sendMpdCommand status Jun 28 10:59:49 volumio volumio[5901]: info: ControllerMpd::getState Jun 28 10:59:49 volumio volumio[5901]: verbose: ControllerMpd::sendMpdCommand status Jun 28 10:59:49 volumio volumio[5901]: verbose: ControllerMpd::parseTrackInfo Jun 28 10:59:49 volumio volumio[5901]: info: ControllerMpd::pushState Jun 28 10:59:49 volumio volumio[5901]: info: CoreCommandRouter::servicePushState Jun 28 10:59:49 volumio volumio[5901]: info: CorePlayQueue::getTrack 0 Jun 28 10:59:49 volumio volumio[5901]: verbose: STATE SERVICE {"status":"play","position":0,"seek":104245,"duration":213,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"You're Beautiful","artist":"James Blunt","album":"Back to Bedlam","uri":"USB/MUSIC/02 - You're Beautiful.mp3","trackType":"mp3"} Jun 28 10:59:49 volumio volumio[5901]: verbose: CURRENT POSITION 0 Jun 28 10:59:49 volumio volumio[5901]: info: CoreStateMachine::syncState stateService play Jun 28 10:59:49 volumio volumio[5901]: info: CoreStateMachine::syncState currentStatus play Jun 28 10:59:49 volumio volumio[5901]: info: Received an update from plugin. extracting info from payload Jun 28 10:59:49 volumio volumio[5901]: info: CoreStateMachine::pushState Jun 28 10:59:49 volumio volumio[5901]: info: CorePlayQueue::getTrack 0 Jun 28 10:59:49 volumio volumio[5901]: info: CoreCommandRouter::volumioPushState Jun 28 10:59:49 volumio volumio[5901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 10:59:49 volumio volumio[5901]: info: CoreStateMachine::pushState Jun 28 10:59:49 volumio volumio[5901]: info: CorePlayQueue::getTrack 0 Jun 28 10:59:49 volumio volumio[5901]: info: CoreCommandRouter::volumioPushState Jun 28 10:59:49 volumio volumio[5901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 10:59:49 volumio volumio[5901]: info: ------------------------------ 65ms Jun 28 10:59:49 volumio volumio[5901]: verbose: ControllerMpd::parseState Jun 28 10:59:49 volumio volumio[5901]: info: Command Router : Notfying DB Updatefalse Jun 28 10:59:49 volumio volumio[5901]: info: CoreCommandRouter::Close All Modals sent Jun 28 10:59:49 volumio volumio[5901]: verbose: ControllerMpd::parseState Jun 28 10:59:49 volumio volumio[5901]: info: Command Router : Notfying DB Updatefalse Jun 28 10:59:49 volumio volumio[5901]: info: CoreCommandRouter::Close All Modals sent Jun 28 10:59:49 volumio volumio[5901]: verbose: ControllerMpd::parseState Jun 28 10:59:49 volumio volumio[5901]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 28 10:59:49 volumio volumio[5901]: verbose: ControllerMpd::parseState Jun 28 10:59:49 volumio volumio[5901]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 28 10:59:49 volumio volumio[5901]: verbose: ControllerMpd::parseTrackInfo Jun 28 10:59:49 volumio volumio[5901]: verbose: ControllerMpd::parseTrackInfo Jun 28 10:59:49 volumio volumio[5901]: info: ControllerMpd::pushState Jun 28 10:59:49 volumio volumio[5901]: info: CoreCommandRouter::servicePushState Jun 28 10:59:49 volumio volumio[5901]: info: CorePlayQueue::getTrack 0 Jun 28 10:59:49 volumio volumio[5901]: verbose: STATE SERVICE {"status":"play","position":0,"seek":104362,"duration":213,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"You're Beautiful","artist":"James Blunt","album":"Back to Bedlam","uri":"USB/MUSIC/02 - You're Beautiful.mp3","trackType":"mp3"} Jun 28 10:59:49 volumio volumio[5901]: verbose: CURRENT POSITION 0 Jun 28 10:59:49 volumio volumio[5901]: info: CoreStateMachine::syncState stateService play Jun 28 10:59:49 volumio volumio[5901]: info: CoreStateMachine::syncState currentStatus play Jun 28 10:59:49 volumio volumio[5901]: info: Received an update from plugin. extracting info from payload Jun 28 10:59:49 volumio volumio[5901]: info: CoreStateMachine::pushState Jun 28 10:59:49 volumio volumio[5901]: info: CorePlayQueue::getTrack 0 Jun 28 10:59:49 volumio volumio[5901]: info: CoreCommandRouter::volumioPushState Jun 28 10:59:49 volumio volumio[5901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 10:59:49 volumio volumio[5901]: info: CoreStateMachine::pushState Jun 28 10:59:50 volumio volumio[5901]: info: CorePlayQueue::getTrack 0 Jun 28 10:59:50 volumio volumio[5901]: info: CoreCommandRouter::volumioPushState Jun 28 10:59:50 volumio volumio[5901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 10:59:50 volumio volumio[5901]: info: ControllerMpd::pushState Jun 28 10:59:50 volumio volumio[5901]: info: CoreCommandRouter::servicePushState Jun 28 10:59:50 volumio volumio[5901]: info: CorePlayQueue::getTrack 0 Jun 28 10:59:50 volumio volumio[5901]: verbose: STATE SERVICE {"status":"play","position":0,"seek":104362,"duration":213,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"You're Beautiful","artist":"James Blunt","album":"Back to Bedlam","uri":"USB/MUSIC/02 - You're Beautiful.mp3","trackType":"mp3"} Jun 28 10:59:50 volumio volumio[5901]: verbose: CURRENT POSITION 0 Jun 28 10:59:50 volumio volumio[5901]: info: CoreStateMachine::syncState stateService play Jun 28 10:59:50 volumio volumio[5901]: info: CoreStateMachine::syncState currentStatus play Jun 28 10:59:50 volumio volumio[5901]: info: Received an update from plugin. extracting info from payload Jun 28 10:59:50 volumio volumio[5901]: info: CoreStateMachine::pushState Jun 28 10:59:50 volumio volumio[5901]: info: CorePlayQueue::getTrack 0 Jun 28 10:59:50 volumio volumio[5901]: info: CoreCommandRouter::volumioPushState Jun 28 10:59:50 volumio volumio[5901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 10:59:50 volumio volumio[5901]: info: CoreStateMachine::pushState Jun 28 10:59:50 volumio volumio[5901]: info: CorePlayQueue::getTrack 0 Jun 28 10:59:50 volumio volumio[5901]: info: CoreCommandRouter::volumioPushState Jun 28 10:59:50 volumio volumio[5901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 10:59:50 volumio volumio[5901]: info: ------------------------------ 139ms Jun 28 10:59:50 volumio volumio[5901]: info: ------------------------------ 140ms Jun 28 10:59:50 volumio wireless.js[6713]: trying... Jun 28 10:59:50 volumio sudo[6894]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 28 10:59:50 volumio sudo[6894]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 10:59:50 volumio sudo[6894]: pam_unix(sudo:session): session closed for user root Jun 28 10:59:51 volumio wireless.js[6713]: trying... Jun 28 10:59:51 volumio sudo[6899]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 28 10:59:51 volumio sudo[6899]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 10:59:51 volumio sudo[6899]: pam_unix(sudo:session): session closed for user root Jun 28 10:59:52 volumio wireless.js[6713]: trying... Jun 28 10:59:52 volumio sudo[6906]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 28 10:59:52 volumio sudo[6906]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 10:59:52 volumio sudo[6906]: pam_unix(sudo:session): session closed for user root Jun 28 10:59:52 volumio dhcpcd[6828]: wlan0: leased 192.168.1.238 for 86400 seconds Jun 28 10:59:52 volumio avahi-daemon[4657]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.238. Jun 28 10:59:52 volumio avahi-daemon[4657]: New relevant interface wlan0.IPv4 for mDNS. Jun 28 10:59:52 volumio dhcpcd[6828]: eth0: adding route to 192.168.1.0/24 Jun 28 10:59:52 volumio avahi-daemon[4657]: Registering new address record for 192.168.1.238 on wlan0.IPv4. Jun 28 10:59:52 volumio dhcpcd[6828]: eth0: adding default route via 192.168.1.1 Jun 28 10:59:52 volumio dhcpcd[6828]: wlan0: adding host route to 192.168.1.238 via 127.0.0.1 Jun 28 10:59:52 volumio dhcpcd[6828]: wlan0: adding route to 192.168.1.0/24 Jun 28 10:59:52 volumio dhcpcd[6828]: wlan0: adding default route via 192.168.1.1 Jun 28 10:59:52 volumio dhcpcd[6828]: eth0: removing route to 192.168.1.0/24 Jun 28 10:59:52 volumio dhcpcd[6828]: eth0: removing default route via 192.168.1.1 Jun 28 10:59:52 volumio volumio[5901]: Discovery: adding 2784a459-ab5a-44c8-9455-882263df1a9d Jun 28 10:59:52 volumio volumio[5901]: info: mDNS: Found device Volumio Jun 28 10:59:52 volumio volumio[5901]: info: CoreCommandRouter::volumioGetState Jun 28 10:59:52 volumio volumio[5901]: info: CorePlayQueue::getTrack 0 Jun 28 10:59:53 volumio wireless.js[6713]: trying... Jun 28 10:59:53 volumio sudo[6933]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 28 10:59:53 volumio sudo[6933]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 10:59:53 volumio kernel: Error getting the SSID Jun 28 10:59:53 volumio sudo[6933]: pam_unix(sudo:session): session closed for user root Jun 28 10:59:53 volumio ntpd[4711]: Listen normally on 8 wlan0 192.168.1.238 UDP 123 Jun 28 10:59:53 volumio ntpd[4711]: peers refreshed Jun 28 10:59:54 volumio wireless.js[6713]: trying... Jun 28 10:59:54 volumio sudo[6939]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 28 10:59:54 volumio sudo[6939]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 10:59:54 volumio kernel: Error getting the SSID Jun 28 10:59:54 volumio sudo[6939]: pam_unix(sudo:session): session closed for user root Jun 28 10:59:55 volumio wireless.js[6713]: trying... Jun 28 10:59:55 volumio sudo[6944]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 28 10:59:55 volumio sudo[6944]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 10:59:55 volumio kernel: Error getting the SSID Jun 28 10:59:55 volumio sudo[6944]: pam_unix(sudo:session): session closed for user root Jun 28 10:59:56 volumio wireless.js[6713]: trying... Jun 28 10:59:56 volumio sudo[6951]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 28 10:59:56 volumio sudo[6951]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 10:59:56 volumio kernel: Error getting the SSID Jun 28 10:59:56 volumio sudo[6951]: pam_unix(sudo:session): session closed for user root Jun 28 10:59:57 volumio wireless.js[6713]: trying... Jun 28 10:59:57 volumio sudo[6956]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 28 10:59:57 volumio sudo[6956]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 10:59:57 volumio kernel: Error getting the SSID Jun 28 10:59:57 volumio sudo[6956]: pam_unix(sudo:session): session closed for user root Jun 28 10:59:57 volumio volumio[5901]: verbose: ControllerMpd::sendMpdCommand status Jun 28 10:59:57 volumio volumio[5901]: info: Jun 28 10:59:57 volumio volumio[5901]: ---------------------------- MPD announces state update: update Jun 28 10:59:57 volumio volumio[5901]: info: ControllerMpd::getState Jun 28 10:59:57 volumio volumio[5901]: verbose: ControllerMpd::sendMpdCommand status Jun 28 10:59:57 volumio volumio[5901]: verbose: ControllerMpd::sendMpdCommand status Jun 28 10:59:57 volumio volumio[5901]: info: Jun 28 10:59:57 volumio volumio[5901]: ---------------------------- MPD announces state update: update Jun 28 10:59:57 volumio volumio[5901]: info: ControllerMpd::getState Jun 28 10:59:57 volumio volumio[5901]: verbose: ControllerMpd::sendMpdCommand status Jun 28 10:59:57 volumio volumio[5901]: verbose: ControllerMpd::parseState Jun 28 10:59:57 volumio volumio[5901]: info: Command Router : Notfying DB Updatetrue Jun 28 10:59:57 volumio volumio[5901]: info: CoreCommandRouter::Close All Modals sent Jun 28 10:59:57 volumio volumio[5901]: verbose: ControllerMpd::parseState Jun 28 10:59:57 volumio volumio[5901]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 28 10:59:57 volumio volumio[5901]: verbose: ControllerMpd::sendMpdCommand status Jun 28 10:59:57 volumio volumio[5901]: info: Jun 28 10:59:57 volumio volumio[5901]: ---------------------------- MPD announces state update: update Jun 28 10:59:57 volumio volumio[5901]: info: ControllerMpd::getState Jun 28 10:59:57 volumio volumio[5901]: verbose: ControllerMpd::sendMpdCommand status Jun 28 10:59:57 volumio volumio[5901]: verbose: ControllerMpd::parseState Jun 28 10:59:57 volumio volumio[5901]: info: Command Router : Notfying DB Updatefalse Jun 28 10:59:57 volumio volumio[5901]: info: CoreCommandRouter::Close All Modals sent Jun 28 10:59:57 volumio volumio[5901]: verbose: ControllerMpd::parseState Jun 28 10:59:57 volumio volumio[5901]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 28 10:59:57 volumio volumio[5901]: verbose: ControllerMpd::parseTrackInfo Jun 28 10:59:57 volumio volumio[5901]: info: ControllerMpd::pushState Jun 28 10:59:57 volumio volumio[5901]: info: CoreCommandRouter::servicePushState Jun 28 10:59:57 volumio volumio[5901]: info: CorePlayQueue::getTrack 0 Jun 28 10:59:57 volumio volumio[5901]: verbose: STATE SERVICE {"status":"play","position":0,"seek":111862,"duration":213,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"You're Beautiful","artist":"James Blunt","album":"Back to Bedlam","uri":"USB/MUSIC/02 - You're Beautiful.mp3","trackType":"mp3"} Jun 28 10:59:57 volumio volumio[5901]: verbose: CURRENT POSITION 0 Jun 28 10:59:57 volumio volumio[5901]: info: CoreStateMachine::syncState stateService play Jun 28 10:59:57 volumio volumio[5901]: info: CoreStateMachine::syncState currentStatus play Jun 28 10:59:57 volumio volumio[5901]: info: Received an update from plugin. extracting info from payload Jun 28 10:59:57 volumio volumio[5901]: info: CoreStateMachine::pushState Jun 28 10:59:57 volumio volumio[5901]: info: CorePlayQueue::getTrack 0 Jun 28 10:59:57 volumio volumio[5901]: info: CoreCommandRouter::volumioPushState Jun 28 10:59:57 volumio volumio[5901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 10:59:57 volumio volumio[5901]: info: CoreStateMachine::pushState Jun 28 10:59:57 volumio volumio[5901]: info: CorePlayQueue::getTrack 0 Jun 28 10:59:57 volumio volumio[5901]: info: CoreCommandRouter::volumioPushState Jun 28 10:59:57 volumio volumio[5901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 10:59:57 volumio volumio[5901]: info: ------------------------------ 83ms Jun 28 10:59:57 volumio volumio[5901]: verbose: ControllerMpd::parseState Jun 28 10:59:57 volumio volumio[5901]: info: Command Router : Notfying DB Updatefalse Jun 28 10:59:57 volumio volumio[5901]: info: CoreCommandRouter::Close All Modals sent Jun 28 10:59:57 volumio volumio[5901]: verbose: ControllerMpd::parseState Jun 28 10:59:57 volumio volumio[5901]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 28 10:59:57 volumio volumio[5901]: verbose: ControllerMpd::parseTrackInfo Jun 28 10:59:57 volumio volumio[5901]: info: ControllerMpd::pushState Jun 28 10:59:57 volumio volumio[5901]: info: CoreCommandRouter::servicePushState Jun 28 10:59:57 volumio volumio[5901]: info: CorePlayQueue::getTrack 0 Jun 28 10:59:57 volumio volumio[5901]: verbose: STATE SERVICE {"status":"play","position":0,"seek":111862,"duration":213,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"You're Beautiful","artist":"James Blunt","album":"Back to Bedlam","uri":"USB/MUSIC/02 - You're Beautiful.mp3","trackType":"mp3"} Jun 28 10:59:57 volumio volumio[5901]: verbose: CURRENT POSITION 0 Jun 28 10:59:57 volumio volumio[5901]: info: CoreStateMachine::syncState stateService play Jun 28 10:59:57 volumio volumio[5901]: info: CoreStateMachine::syncState currentStatus play Jun 28 10:59:57 volumio volumio[5901]: info: Received an update from plugin. extracting info from payload Jun 28 10:59:57 volumio volumio[5901]: info: CoreStateMachine::pushState Jun 28 10:59:57 volumio volumio[5901]: info: CorePlayQueue::getTrack 0 Jun 28 10:59:57 volumio volumio[5901]: info: CoreCommandRouter::volumioPushState Jun 28 10:59:57 volumio volumio[5901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 10:59:57 volumio volumio[5901]: info: CoreStateMachine::pushState Jun 28 10:59:57 volumio volumio[5901]: info: CorePlayQueue::getTrack 0 Jun 28 10:59:57 volumio volumio[5901]: info: CoreCommandRouter::volumioPushState Jun 28 10:59:57 volumio volumio[5901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 10:59:57 volumio volumio[5901]: info: ------------------------------ 134ms Jun 28 10:59:57 volumio volumio[5901]: verbose: ControllerMpd::parseTrackInfo Jun 28 10:59:57 volumio volumio[5901]: info: ControllerMpd::pushState Jun 28 10:59:57 volumio volumio[5901]: info: CoreCommandRouter::servicePushState Jun 28 10:59:57 volumio volumio[5901]: info: CorePlayQueue::getTrack 0 Jun 28 10:59:57 volumio volumio[5901]: verbose: STATE SERVICE {"status":"play","position":0,"seek":111989,"duration":213,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"You're Beautiful","artist":"James Blunt","album":"Back to Bedlam","uri":"USB/MUSIC/02 - You're Beautiful.mp3","trackType":"mp3"} Jun 28 10:59:57 volumio volumio[5901]: verbose: CURRENT POSITION 0 Jun 28 10:59:57 volumio volumio[5901]: info: CoreStateMachine::syncState stateService play Jun 28 10:59:57 volumio volumio[5901]: info: CoreStateMachine::syncState currentStatus play Jun 28 10:59:57 volumio volumio[5901]: info: Received an update from plugin. extracting info from payload Jun 28 10:59:57 volumio volumio[5901]: info: CoreStateMachine::pushState Jun 28 10:59:57 volumio volumio[5901]: info: CorePlayQueue::getTrack 0 Jun 28 10:59:57 volumio volumio[5901]: info: CoreCommandRouter::volumioPushState Jun 28 10:59:57 volumio volumio[5901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 10:59:57 volumio volumio[5901]: info: CoreStateMachine::pushState Jun 28 10:59:57 volumio volumio[5901]: info: CorePlayQueue::getTrack 0 Jun 28 10:59:57 volumio volumio[5901]: info: CoreCommandRouter::volumioPushState Jun 28 10:59:57 volumio volumio[5901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 10:59:57 volumio volumio[5901]: info: ------------------------------ 123ms Jun 28 10:59:58 volumio wireless.js[6713]: trying... Jun 28 10:59:58 volumio sudo[6971]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 28 10:59:58 volumio sudo[6971]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 10:59:58 volumio kernel: Error getting the SSID Jun 28 10:59:58 volumio sudo[6971]: pam_unix(sudo:session): session closed for user root Jun 28 10:59:59 volumio wireless.js[6713]: trying... Jun 28 10:59:59 volumio sudo[6976]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 28 10:59:59 volumio sudo[6976]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 10:59:59 volumio kernel: Error getting the SSID Jun 28 10:59:59 volumio sudo[6976]: pam_unix(sudo:session): session closed for user root Jun 28 11:00:00 volumio wireless.js[6713]: trying... Jun 28 11:00:00 volumio sudo[6983]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 28 11:00:00 volumio sudo[6983]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 11:00:00 volumio kernel: Error getting the SSID Jun 28 11:00:00 volumio sudo[6983]: pam_unix(sudo:session): session closed for user root Jun 28 11:00:01 volumio wireless.js[6713]: trying... Jun 28 11:00:01 volumio sudo[6988]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 28 11:00:01 volumio sudo[6988]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 11:00:01 volumio kernel: Error getting the SSID Jun 28 11:00:01 volumio sudo[6988]: pam_unix(sudo:session): session closed for user root Jun 28 11:00:02 volumio wireless.js[6713]: trying... Jun 28 11:00:02 volumio sudo[6995]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 28 11:00:02 volumio sudo[6995]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 11:00:02 volumio sudo[6995]: pam_unix(sudo:session): session closed for user root Jun 28 11:00:02 volumio kernel: Error getting the SSID Jun 28 11:00:03 volumio wireless.js[6713]: trying... Jun 28 11:00:03 volumio sudo[7000]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 28 11:00:03 volumio sudo[7000]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 11:00:03 volumio sudo[7000]: pam_unix(sudo:session): session closed for user root Jun 28 11:00:03 volumio kernel: Error getting the SSID Jun 28 11:00:04 volumio wireless.js[6713]: Overtime, starting plan B Jun 28 11:00:04 volumio wireless.js[6713]: STARTING HOTSPOT Jun 28 11:00:04 volumio kernel: CFG80211-ERROR) wl_cfg80211_disconnect : Reason 3 Jun 28 11:00:04 volumio wpa_supplicant[6806]: wlan0: CTRL-EVENT-DISCONNECTED bssid=88:41:fc:a7:00:60 reason=3 locally_generated=1 Jun 28 11:00:04 volumio dhcpcd[6828]: wlan0: carrier lost Jun 28 11:00:04 volumio dhcpcd[6828]: eth0: adding route to 192.168.1.0/24 Jun 28 11:00:04 volumio dhcpcd[6828]: eth0: adding default route via 192.168.1.1 Jun 28 11:00:04 volumio dhcpcd[6828]: wlan0: deleting host route to 192.168.1.238 via 127.0.0.1 Jun 28 11:00:04 volumio dhcpcd[6828]: wlan0: deleting route to 192.168.1.0/24 Jun 28 11:00:04 volumio dhcpcd[6828]: wlan0: deleting default route via 192.168.1.1 Jun 28 11:00:04 volumio avahi-daemon[4657]: Withdrawing address record for 192.168.1.238 on wlan0. Jun 28 11:00:04 volumio avahi-daemon[4657]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.238. Jun 28 11:00:04 volumio avahi-daemon[4657]: Interface wlan0.IPv4 no longer relevant for mDNS. Jun 28 11:00:04 volumio kernel: wl_iw_event: Link Down with BSSID=88:41:fc:a7:00:60 Jun 28 11:00:04 volumio kernel: CFG80211-ERROR) wl_is_linkdown : Link down Reason : WLC_E_LINK Jun 28 11:00:04 volumio kernel: link down if wlan0 may call cfg80211_disconnected. event : 16, reason=2 from 88:41:fc:a7:00:60 Jun 28 11:00:04 volumio volumio[5901]: info: mDNS: A device disapperared from network Jun 28 11:00:04 volumio volumio[5901]: info: mDNS: Device volumio disapperared from network Jun 28 11:00:04 volumio dhcpcd[6828]: eth0: removing route to 192.168.1.0/24 Jun 28 11:00:04 volumio dhcpcd[6828]: eth0: removing default route via 192.168.1.1 Jun 28 11:00:04 volumio volumio[5901]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 28 11:00:04 volumio volumio[5901]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 28 11:00:04 volumio volumio[5901]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Jun 28 11:00:04 volumio dhcpcd[6828]: wlan0: carrier acquired Jun 28 11:00:04 volumio kernel: CFG80211-ERROR) wl_cfg80211_disconnect : Reason 3 Jun 28 11:00:04 volumio kernel: dhd_stop: Enter ffffffc05173d000 Jun 28 11:00:04 volumio kernel: wl_event_handler: was terminated Jun 28 11:00:04 volumio kernel: wl_destroy_event_handler(): thread:wl_event_handler:1a60 terminated OK Jun 28 11:00:04 volumio kernel: CFGP2P-ERROR) wl_cfgp2p_disable_discovery : do nothing, not initialized Jun 28 11:00:04 volumio kernel: CFGP2P-ERROR) wl_cfgp2p_deinit_priv : In Jun 28 11:00:04 volumio kernel: wl_android_wifi_off in 1 Jun 28 11:00:04 volumio kernel: wl_android_wifi_off in 2: g_wifi_on=1, on_failure=1 Jun 28 11:00:04 volumio kernel: bcmsdh_oob_intr_unregister: Enter Jun 28 11:00:04 volumio kernel: dhd_txglom_enable: enable 0 Jun 28 11:00:04 volumio kernel: dhd_conf_set_txglom_params: swtxglom=0, txglom_ext=0, txglom_bucket_size=0 Jun 28 11:00:04 volumio kernel: dhd_conf_set_txglom_params: txglomsize=0, deferred_tx_len=0, bus_txglom=-1 Jun 28 11:00:04 volumio dhcpcd[6828]: wlan0: carrier lost Jun 28 11:00:04 volumio dhcpcd[6828]: eth0: adding route to 192.168.1.0/24 Jun 28 11:00:04 volumio kernel: dhd_conf_set_txglom_params: tx_in_rx=1, txinrx_thres=-1, dhd_txminmax=1 Jun 28 11:00:04 volumio kernel: dhd_conf_set_txglom_params: tx_max_offset=0, txctl_tmo_fix=1 Jun 28 11:00:04 volumio kernel: sdioh_set_mode: set txglom_mode to multi-desc Jun 28 11:00:04 volumio kernel: dhd_bus_devreset: WLAN OFF DONE Jun 28 11:00:04 volumio kernel: wifi_platform_set_power = 0 Jun 28 11:00:04 volumio kernel: ======== PULL WL_REG_ON(-1) LOW! ======== Jun 28 11:00:04 volumio volumio[5901]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Jun 28 11:00:04 volumio volumio[5901]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Jun 28 11:00:04 volumio kernel: wl_android_wifi_off out Jun 28 11:00:05 volumio kernel: dhd_stop: Exit Jun 28 11:00:05 volumio kernel: cfg80211: Calling CRDA to update world regulatory domain Jun 28 11:00:05 volumio dhcpcd[6828]: eth0: adding default route via 192.168.1.1 Jun 28 11:00:05 volumio sudo[7047]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jun 28 11:00:05 volumio sudo[7066]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jun 28 11:00:05 volumio sudo[7066]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 11:00:05 volumio wpa_supplicant[6806]: wlan0: CTRL-EVENT-TERMINATING Jun 28 11:00:05 volumio sudo[7038]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Jun 28 11:00:05 volumio sudo[7038]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 11:00:05 volumio sudo[7047]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 11:00:05 volumio sudo[7073]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 28 11:00:05 volumio sudo[7073]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 11:00:05 volumio kernel: cfg80211: World regulatory domain updated: Jun 28 11:00:05 volumio kernel: cfg80211: DFS Master region: unset Jun 28 11:00:05 volumio kernel: cfg80211: (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp) Jun 28 11:00:05 volumio sudo[7057]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jun 28 11:00:05 volumio sudo[7057]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 11:00:05 volumio sudo[7071]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 28 11:00:05 volumio sudo[7071]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 11:00:05 volumio kernel: cfg80211: (2402000 KHz - 2472000 KHz @ 40000 KHz), (N/A, 2000 mBm) Jun 28 11:00:05 volumio kernel: cfg80211: (2457000 KHz - 2482000 KHz @ 20000 KHz), (N/A, 2000 mBm) Jun 28 11:00:05 volumio kernel: cfg80211: (2474000 KHz - 2494000 KHz @ 20000 KHz), (N/A, 2000 mBm) Jun 28 11:00:05 volumio kernel: cfg80211: (5170000 KHz - 5250000 KHz @ 80000 KHz), (N/A, 2000 mBm) Jun 28 11:00:05 volumio kernel: cfg80211: (5250000 KHz - 5330000 KHz @ 80000 KHz), (N/A, 2000 mBm) Jun 28 11:00:05 volumio kernel: cfg80211: (5490000 KHz - 5730000 KHz @ 160000 KHz), (N/A, 2000 mBm) Jun 28 11:00:05 volumio dhcpcd[6828]: wlan0: waiting for carrier Jun 28 11:00:05 volumio dhcpcd[6828]: eth0: removing route to 192.168.1.0/24 Jun 28 11:00:05 volumio dhcpcd[6828]: eth0: removing default route via 192.168.1.1 Jun 28 11:00:05 volumio kernel: cfg80211: (5735000 KHz - 5835000 KHz @ 80000 KHz), (N/A, 2000 mBm) Jun 28 11:00:05 volumio kernel: cfg80211: (57240000 KHz - 63720000 KHz @ 2160000 KHz), (N/A, 0 mBm) Jun 28 11:00:05 volumio sudo[7073]: pam_unix(sudo:session): session closed for user root Jun 28 11:00:05 volumio kernel: dhd_ioctl_entry: Interface is down Jun 28 11:00:05 volumio kernel: dhd_ioctl_entry: Interface is down Jun 28 11:00:05 volumio kernel: dhd_ioctl_entry: Interface is down Jun 28 11:00:05 volumio kernel: dhd_ioctl_entry: Interface is down Jun 28 11:00:05 volumio kernel: dhd_ioctl_entry: Interface is down Jun 28 11:00:05 volumio kernel: dhd_ioctl_entry: Interface is down Jun 28 11:00:05 volumio kernel: Error getting the SSID Jun 28 11:00:05 volumio kernel: dhd_ioctl_entry: Interface is down Jun 28 11:00:05 volumio kernel: dhd_ioctl_entry: Interface is down Jun 28 11:00:05 volumio kernel: dhd_ioctl_entry: Interface is down Jun 28 11:00:05 volumio kernel: dhd_ioctl_entry: Interface is down Jun 28 11:00:05 volumio sudo[7038]: pam_unix(sudo:session): session closed for user root Jun 28 11:00:05 volumio sudo[7071]: pam_unix(sudo:session): session closed for user root Jun 28 11:00:05 volumio kernel: dhd_ioctl_entry: Interface is down Jun 28 11:00:05 volumio kernel: dhd_ioctl_entry: Interface is down Jun 28 11:00:05 volumio kernel: dhd_ioctl_entry: Interface is down Jun 28 11:00:05 volumio kernel: dhd_ioctl_entry: Interface is down Jun 28 11:00:05 volumio kernel: dhd_ioctl_entry: Interface is down Jun 28 11:00:05 volumio sudo[7075]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 28 11:00:05 volumio kernel: Error getting the SSID Jun 28 11:00:05 volumio kernel: dhd_ioctl_entry: Interface is down Jun 28 11:00:05 volumio kernel: dhd_ioctl_entry: Interface is down Jun 28 11:00:05 volumio sudo[7075]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 11:00:05 volumio kernel: dhd_ioctl_entry: Interface is down Jun 28 11:00:05 volumio kernel: dhd_ioctl_entry: Interface is down Jun 28 11:00:05 volumio kernel: dhd_ioctl_entry: Interface is down Jun 28 11:00:05 volumio kernel: dhd_ioctl_entry: Interface is down Jun 28 11:00:05 volumio kernel: dhd_ioctl_entry: Interface is down Jun 28 11:00:05 volumio kernel: dhd_ioctl_entry: Interface is down Jun 28 11:00:05 volumio sudo[7075]: pam_unix(sudo:session): session closed for user root Jun 28 11:00:05 volumio kernel: dhd_ioctl_entry: Interface is down Jun 28 11:00:05 volumio volumio[5901]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Jun 28 11:00:05 volumio volumio[5901]: wlan0 Interface doesn't support scanning. Jun 28 11:00:05 volumio volumio[5901]: info: Cannot use regular scanning, forcing with ap-force Jun 28 11:00:05 volumio kernel: dhd_ioctl_entry: Interface is down Jun 28 11:00:05 volumio kernel: Error getting the SSID Jun 28 11:00:05 volumio kernel: dhd_ioctl_entry: Interface is down Jun 28 11:00:05 volumio kernel: dhd_ioctl_entry: Interface is down Jun 28 11:00:05 volumio kernel: dhd_ioctl_entry: Interface is down Jun 28 11:00:05 volumio kernel: dhd_ioctl_entry: Interface is down Jun 28 11:00:05 volumio kernel: dhd_ioctl_entry: Interface is down Jun 28 11:00:05 volumio kernel: dhd_ioctl_entry: Interface is down Jun 28 11:00:05 volumio kernel: dhd_ioctl_entry: Interface is down Jun 28 11:00:05 volumio kernel: dhd_ioctl_entry: Interface is down Jun 28 11:00:05 volumio kernel: dhd_ioctl_entry: Interface is down Jun 28 11:00:05 volumio sudo[7066]: pam_unix(sudo:session): session closed for user root Jun 28 11:00:05 volumio kernel: dhd_ioctl_entry: Interface is down Jun 28 11:00:05 volumio kernel: dhd_ioctl_entry: Interface is down Jun 28 11:00:05 volumio kernel: dhd_ioctl_entry: Interface is down Jun 28 11:00:05 volumio sudo[7057]: pam_unix(sudo:session): session closed for user root Jun 28 11:00:05 volumio kernel: dhd_ioctl_entry: Interface is down Jun 28 11:00:05 volumio kernel: dhd_ioctl_entry: Interface is down Jun 28 11:00:05 volumio sudo[7047]: pam_unix(sudo:session): session closed for user root Jun 28 11:00:05 volumio kernel: dhd_ioctl_entry: Interface is down Jun 28 11:00:05 volumio sudo[7107]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Jun 28 11:00:05 volumio sudo[7107]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 11:00:05 volumio sudo[7107]: pam_unix(sudo:session): session closed for user root Jun 28 11:00:05 volumio volumio[5901]: command failed: Network is down (-100) Jun 28 11:00:05 volumio volumio[5901]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Jun 28 11:00:05 volumio volumio[5901]: command failed: Network is down (-100) Jun 28 11:00:06 volumio ntpd[4711]: Deleting interface #8 wlan0, 192.168.1.238#123, interface stats: received=0, sent=0, dropped=0, active_time=13 secs Jun 28 11:00:06 volumio ntpd[4711]: peers refreshed Jun 28 11:00:07 volumio systemd[1]: Stopped hotspot.service. Jun 28 11:00:07 volumio sudo[7119]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jun 28 11:00:07 volumio sudo[7119]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 11:00:07 volumio sudo[7119]: pam_unix(sudo:session): session closed for user root Jun 28 11:00:07 volumio sudo[7121]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jun 28 11:00:07 volumio sudo[7121]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 11:00:07 volumio sudo[7121]: pam_unix(sudo:session): session closed for user root Jun 28 11:00:07 volumio kernel: dhd_open: Enter ffffffc05173d000 Jun 28 11:00:07 volumio kernel: Dongle Host Driver, version 1.363.59.144.9 (r) Jun 28 11:00:07 volumio kernel: wl_android_wifi_on in 1 Jun 28 11:00:07 volumio kernel: wl_android_wifi_on in 2: g_wifi_on=0 Jun 28 11:00:07 volumio kernel: wifi_platform_set_power = 1 Jun 28 11:00:07 volumio kernel: ======== PULL WL_REG_ON(-1) HIGH! ======== Jun 28 11:00:07 volumio kernel: aml_wifi wifi.33: [extern_wifi_set_enable] WIFI Disable! 240 Jun 28 11:00:07 volumio kernel: aml_wifi wifi.33: [extern_wifi_set_enable] WIFI Enable! 240 Jun 28 11:00:08 volumio kernel: sdio_reset_comm(): Jun 28 11:00:08 volumio kernel: sdio: clk 200000000 SDR mode tuning start Jun 28 11:00:08 volumio kernel: sdio: rx_tuning_result[0] = 10 Jun 28 11:00:08 volumio kernel: sdio: rx_tuning_result[1] = 10 Jun 28 11:00:08 volumio kernel: sdio: rx_tuning_result[2] = 10 Jun 28 11:00:08 volumio kernel: sdio: rx_tuning_result[3] = 10 Jun 28 11:00:08 volumio kernel: sdio: rx_tuning_result[4] = 10 Jun 28 11:00:08 volumio kernel: sdio: best_win_start =0, best_win_size =5 Jun 28 11:00:08 volumio kernel: sdio: sd_emmc_regs->gclock=0x1000245,sd_emmc_regs->gadjust=0x2000 Jun 28 11:00:08 volumio kernel: sdio: gclock =0x1000245, gdelay=0x0, gadjust=0x2000 Jun 28 11:00:08 volumio kernel: sdioh_start: set sd_f2_blocksize 256 Jun 28 11:00:08 volumio kernel: dhd_bus_devreset: == WLAN ON == Jun 28 11:00:08 volumio kernel: F1 signature read @0x18000000=0x15264345 Jun 28 11:00:08 volumio kernel: F1 signature OK, socitype:0x1 chip:0x4345 rev:0x6 pkg:0x2 Jun 28 11:00:08 volumio kernel: DHD: dongle ram size is set to 819200(orig 819200) at 0x198000 Jun 28 11:00:08 volumio kernel: dhd_os_open_image: /lib/firmware/brcm/config.txt (399 bytes) open success Jun 28 11:00:08 volumio kernel: dhd_conf_read_others: ccode = SG Jun 28 11:00:08 volumio kernel: dhd_conf_read_others: bcn_timeout = 20 Jun 28 11:00:08 volumio kernel: dhd_conf_read_sdio_params: dhd_slpauto = 1 Jun 28 11:00:08 volumio kernel: dhd_conf_read_others: mimo_bw_cap = 1 Jun 28 11:00:08 volumio kernel: dhd_conf_read_pm_params: PM = 0 Jun 28 11:00:08 volumio kernel: dhd_conf_read_nv_by_chip: nv_by_chip_count=12 Jun 28 11:00:08 volumio kernel: dhd_conf_read_nv_by_chip: chip=0xa962, chiprev=0, name=nvram_ap6181.txt Jun 28 11:00:08 volumio kernel: dhd_conf_read_nv_by_chip: chip=0xa962, chiprev=1, name=nvram_ap6210.txt Jun 28 11:00:08 volumio kernel: dhd_conf_read_nv_by_chip: chip=0xa9a6, chiprev=0, name=nvram_ap6212.txt Jun 28 11:00:08 volumio kernel: dhd_conf_read_nv_by_chip: chip=0xa9a6, chiprev=1, name=nvram_ap6212a.txt Jun 28 11:00:08 volumio kernel: dhd_conf_read_nv_by_chip: chip=0x4345, chiprev=6, name=nvram_ap6255.txt Jun 28 11:00:08 volumio kernel: dhd_conf_read_nv_by_chip: chip=0x4330, chiprev=4, name=nvram_ap6330.txt Jun 28 11:00:08 volumio kernel: dhd_conf_read_nv_by_chip: chip=0x4339, chiprev=1, name=nvram_ap6335.txt Jun 28 11:00:08 volumio kernel: dhd_conf_read_nv_by_chip: chip=0x4354, chiprev=2, name=nvram_ap6356.txt Jun 28 11:00:08 volumio kernel: dhd_conf_read_nv_by_chip: chip=0x4335, chiprev=1, name=nvram_bcm4335.txt Jun 28 11:00:08 volumio kernel: dhd_conf_read_nv_by_chip: chip=0xa94c, chiprev=2, name=nvram_ap6234.txt Jun 28 11:00:08 volumio kernel: dhd_conf_read_nv_by_chip: chip=0x4359, chiprev=9, name=nvram_ap6359sa.txt Jun 28 11:00:08 volumio kernel: dhd_conf_read_nv_by_chip: chip=0x4334, chiprev=3, name=nvram_bcm4334.txt Jun 28 11:00:08 volumio kernel: Final fw_path=/lib/firmware/brcm/fw_bcm43455c0_ag.bin Jun 28 11:00:08 volumio kernel: Final nv_path=/lib/firmware/brcm/nvram_ap6255.txt Jun 28 11:00:08 volumio kernel: Final clm_path=/lib/firmware/brcm/clm_bcmdhd.blob Jun 28 11:00:08 volumio kernel: Final conf_path=/lib/firmware/brcm/config.txt Jun 28 11:00:08 volumio kernel: dhd_set_bus_params: set use_rxchain 0 Jun 28 11:00:08 volumio kernel: dhd_set_bus_params: set txglomsize 36 Jun 28 11:00:08 volumio kernel: dhd_os_open_image: /lib/firmware/brcm/fw_bcm43455c0_ag.bin (483181 bytes) open success Jun 28 11:00:08 volumio kernel: dhd_os_open_image: /lib/firmware/brcm/nvram_ap6255.txt (1644 bytes) open success Jun 28 11:00:08 volumio kernel: NVRAM version: AP6255_NVRAM_V1.0_29052015 Jun 28 11:00:08 volumio kernel: dhdsdio_write_vars: Download, Upload and compare of NVRAM succeeded. Jun 28 11:00:08 volumio kernel: dhd_bus_init: enable 0x06, ready 0x06 (waited 0us) Jun 28 11:00:08 volumio kernel: bcmsdh_oob_intr_register: Enter Jun 28 11:00:08 volumio kernel: bcmsdh_oob_intr_register: HW_OOB enabled Jun 28 11:00:08 volumio kernel: bcmsdh_oob_intr_register OOB irq=100 flags=0x4 Jun 28 11:00:08 volumio kernel: bcmsdh_oob_intr_register change flags to 0x4 Jun 28 11:00:08 volumio kernel: bcmsdh_oob_intr_register: enable_irq_wake Jun 28 11:00:08 volumio kernel: bcmsdh_oob_intr_register: enable_irq_wake failed with -6 Jun 28 11:00:08 volumio kernel: Disable tdls_auto_op failed. -1 Jun 28 11:00:08 volumio kernel: dhd_conf_set_intiovar: set WLC_SET_BAND 142 0 Jun 28 11:00:08 volumio kernel: dhd_preinit_ioctls: Set tcpack_sup_mode 0 Jun 28 11:00:08 volumio kernel: dhd_tcpack_suppress_set 347: already set to 0 Jun 28 11:00:08 volumio kernel: dhd_apply_default_clm: Ignore clm file /lib/firmware/brcm/clm_bcmdhd.blob Jun 28 11:00:08 volumio kernel: Firmware up: op_mode=0x0005, MAC=00:90:4c:c5:12:38 Jun 28 11:00:08 volumio kernel: dhd_conf_set_country: set country SG, revision 0 Jun 28 11:00:08 volumio kernel: Country code: SG (SG/0) Jun 28 11:00:08 volumio kernel: dhd_conf_set_intiovar: set roam_off 1 Jun 28 11:00:08 volumio kernel: dhd_conf_set_intiovar: set mimo_bw_cap 1 Jun 28 11:00:08 volumio kernel: Firmware version = wl0: Aug 25 2015 18:58:57 version 7.45.69 (r581703) FWID 01-24037f6e Jun 28 11:00:08 volumio kernel: Driver: 1.363.59.144.9 (r) Firmware: wl0: Aug 25 2015 18:58:57 version 7.45.69 (r581703) FWID 01-24037f6e Jun 28 11:00:08 volumio kernel: clm = 7.14.8 Jun 28 11:00:08 volumio kernel: dhd_txglom_enable: enable 1 Jun 28 11:00:08 volumio kernel: dhd_conf_set_txglom_params: swtxglom=0, txglom_ext=0, txglom_bucket_size=0 Jun 28 11:00:08 volumio kernel: dhd_conf_set_txglom_params: txglomsize=36, deferred_tx_len=0, bus_txglom=-1 Jun 28 11:00:09 volumio kernel: dhd_conf_set_txglom_params: tx_in_rx=1, txinrx_thres=-1, dhd_txminmax=1 Jun 28 11:00:09 volumio kernel: dhd_conf_set_txglom_params: tx_max_offset=0, txctl_tmo_fix=1 Jun 28 11:00:09 volumio kernel: sdioh_set_mode: set txglom_mode to multi-desc Jun 28 11:00:09 volumio kernel: dhd_conf_get_disable_proptx: fw_proptx=1, disable_proptx=-1 Jun 28 11:00:09 volumio kernel: dhd_wlfc_hostreorder_init(): successful bdcv2 tlv signaling, 64 Jun 28 11:00:09 volumio kernel: dhd_conf_set_intiovar: set ampdu_hostreorder 1 Jun 28 11:00:09 volumio kernel: wl_android_wifi_on: Success Jun 28 11:00:09 volumio kernel: wl_create_event_handler(): thread:wl_event_handler:1bdb started Jun 28 11:00:09 volumio kernel: tsk Enter, tsk = 0xffffff8001579bb8 Jun 28 11:00:09 volumio kernel: CFG80211-ERROR) wl_update_wiphybands : error reading txbf_bfe_cap (-23) Jun 28 11:00:09 volumio kernel: CFG80211-ERROR) wl_update_wiphybands : error reading txbf_bfr_cap (-23) Jun 28 11:00:09 volumio kernel: dhd_open: Exit ret=0 Jun 28 11:00:09 volumio dhcpcd[6828]: wlan0: carrier acquired Jun 28 11:00:09 volumio avahi-daemon[4657]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Jun 28 11:00:09 volumio avahi-daemon[4657]: New relevant interface wlan0.IPv4 for mDNS. Jun 28 11:00:09 volumio avahi-daemon[4657]: Registering new address record for 192.168.211.1 on wlan0.IPv4. Jun 28 11:00:09 volumio systemd[1]: Starting hotspot.service... Jun 28 11:00:09 volumio systemd[1]: Started hotspot.service. Jun 28 11:00:09 volumio systemd[1]: Starting dhcpd.service... Jun 28 11:00:09 volumio systemd[1]: Starting dnsmasq.service... Jun 28 11:00:09 volumio dhcpd[7146]: Internet Systems Consortium DHCP Server 4.3.1 Jun 28 11:00:09 volumio hotspot[7146]: Internet Systems Consortium DHCP Server 4.3.1 Jun 28 11:00:09 volumio dhcpd[7146]: Copyright 2004-2014 Internet Systems Consortium. Jun 28 11:00:09 volumio systemd[1]: Started Wireless Services. Jun 28 11:00:09 volumio hotspot[7146]: Copyright 2004-2014 Internet Systems Consortium. Jun 28 11:00:09 volumio sudo[6295]: pam_unix(sudo:session): session closed for user root Jun 28 11:00:09 volumio dhcpd[7146]: All rights reserved. Jun 28 11:00:09 volumio hotspot[7146]: All rights reserved. Jun 28 11:00:09 volumio dhcpd[7146]: For info, please visit https://www.isc.org/software/dhcp/ Jun 28 11:00:09 volumio dhcpd[7146]: Config file: /etc/dhcp/dhcpd.conf Jun 28 11:00:09 volumio dhcpd[7146]: Database file: /var/lib/dhcp/dhcpd.leases Jun 28 11:00:09 volumio dhcpd[7146]: PID file: /var/run/dhcpd.pid Jun 28 11:00:09 volumio dnsmasq[7154]: started, version 2.72 cachesize 150 Jun 28 11:00:09 volumio dnsmasq[7154]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect Jun 28 11:00:09 volumio dnsmasq[7154]: reading /etc/resolv.conf Jun 28 11:00:09 volumio dnsmasq[7154]: using nameserver 192.168.1.1#53 Jun 28 11:00:09 volumio dnsmasq[7154]: using nameserver 208.67.222.222#53 Jun 28 11:00:09 volumio dnsmasq[7154]: using nameserver 208.67.220.220#53 Jun 28 11:00:09 volumio dnsmasq[7154]: read /etc/hosts - 4 addresses Jun 28 11:00:09 volumio sudo[6703]: pam_unix(sudo:session): session closed for user root Jun 28 11:00:09 volumio systemd[1]: Started dnsmasq.service. Jun 28 11:00:09 volumio hotspot[7146]: For info, please visit https://www.isc.org/software/dhcp/ Jun 28 11:00:09 volumio hotspot[7146]: Config file: /etc/dhcp/dhcpd.conf Jun 28 11:00:09 volumio hotspot[7146]: Database file: /var/lib/dhcp/dhcpd.leases Jun 28 11:00:09 volumio hotspot[7146]: PID file: /var/run/dhcpd.pid Jun 28 11:00:09 volumio dhcpd[7146]: Internet Systems Consortium DHCP Server 4.3.1 Jun 28 11:00:09 volumio dhcpd[7146]: Copyright 2004-2014 Internet Systems Consortium. Jun 28 11:00:09 volumio dhcpd[7146]: All rights reserved. Jun 28 11:00:09 volumio dhcpd[7146]: For info, please visit https://www.isc.org/software/dhcp/ Jun 28 11:00:09 volumio dhcpd[7146]: Wrote 0 leases to leases file. Jun 28 11:00:09 volumio systemd[1]: Starting Host and Network Name Lookups. Jun 28 11:00:09 volumio systemd[1]: Reached target Host and Network Name Lookups. Jun 28 11:00:09 volumio hotspot[7146]: Wrote 0 leases to leases file. Jun 28 11:00:09 volumio dhcpd[7146]: Listening on LPF/wlan0/00:90:4c:c5:12:38/192.168.211.0/24 Jun 28 11:00:09 volumio dhcpd[7146]: Sending on LPF/wlan0/00:90:4c:c5:12:38/192.168.211.0/24 Jun 28 11:00:09 volumio dhcpd[7146]: Jun 28 11:00:09 volumio dhcpd[7146]: No subnet declaration for eth0 (192.168.1.237). Jun 28 11:00:09 volumio dhcpd[7146]: ** Ignoring requests on eth0. If this is not what Jun 28 11:00:09 volumio dhcpd[7146]: you want, please write a subnet declaration Jun 28 11:00:09 volumio dhcpd[7146]: in your dhcpd.conf file for the network segment Jun 28 11:00:09 volumio dhcpd[7146]: to which interface eth0 is attached. ** Jun 28 11:00:09 volumio dhcpd[7146]: Jun 28 11:00:09 volumio dhcpd[7146]: Sending on Socket/fallback/fallback-net Jun 28 11:00:09 volumio dhcpd[7156]: Server starting service. Jun 28 11:00:09 volumio systemd[1]: Started dhcpd.service. Jun 28 11:00:09 volumio hotspot[7146]: Listening on LPF/wlan0/00:90:4c:c5:12:38/192.168.211.0/24 Jun 28 11:00:09 volumio hotspot[7146]: Sending on LPF/wlan0/00:90:4c:c5:12:38/192.168.211.0/24 Jun 28 11:00:09 volumio hotspot[7146]: No subnet declaration for eth0 (192.168.1.237). Jun 28 11:00:09 volumio hotspot[7146]: ** Ignoring requests on eth0. If this is not what Jun 28 11:00:09 volumio hotspot[7146]: you want, please write a subnet declaration Jun 28 11:00:09 volumio hotspot[7146]: in your dhcpd.conf file for the network segment Jun 28 11:00:09 volumio hotspot[7146]: to which interface eth0 is attached. ** Jun 28 11:00:09 volumio hotspot[7146]: Sending on Socket/fallback/fallback-net Jun 28 11:00:09 volumio dhcpcd[6828]: wlan0: rebinding lease of 192.168.1.238 Jun 28 11:00:09 volumio dhcpd[7156]: DHCPREQUEST for 192.168.1.238 from 00:90:4c:c5:12:38 via wlan0: ignored (not authoritative). Jun 28 11:00:09 volumio hotspot[7142]: basename: missing operand Jun 28 11:00:09 volumio hotspot[7142]: Try 'basename --help' for more information. Jun 28 11:00:09 volumio hotspot[7142]: Launching Ordinary Hostapd Jun 28 11:00:09 volumio kernel: CFG80211-ERROR) wl_cfg80211_del_station : Disconnect STA : ff:ff:ff:ff:ff:ff scb_val.val 3 Jun 28 11:00:09 volumio kernel: netdev_ifidx(6), chan_type(1) target channel(4) Jun 28 11:00:09 volumio kernel: wl_iw_event: Link UP with BSSID=00:90:4c:c5:12:38 Jun 28 11:00:09 volumio kernel: CFG80211-ERROR) wl_notify_connect_status_ap : ** AP/GO Link up event ** Jun 28 11:00:09 volumio volumio[5901]: Discovery: adding 2784a459-ab5a-44c8-9455-882263df1a9d Jun 28 11:00:09 volumio volumio[5901]: info: mDNS: Found device Volumio Jun 28 11:00:09 volumio volumio[5901]: info: CoreCommandRouter::volumioGetState Jun 28 11:00:09 volumio volumio[5901]: info: CorePlayQueue::getTrack 0 Jun 28 11:00:10 volumio ntpd[4711]: Listen normally on 9 wlan0 192.168.211.1 UDP 123 Jun 28 11:00:10 volumio ntpd[4711]: peers refreshed Jun 28 11:00:13 volumio dhcpd[7156]: DHCPREQUEST for 192.168.1.238 from 00:90:4c:c5:12:38 via wlan0: ignored (not authoritative). Jun 28 11:00:14 volumio volumio[5901]: info: CoreCommandRouter::executeOnPlugin: wizard , reportWirelessConnection Jun 28 11:00:14 volumio volumio[5901]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessInfo Jun 28 11:00:14 volumio volumio[5901]: info: CoreCommandRouter::executeOnPlugin: wizard , reportWirelessConnection Jun 28 11:00:14 volumio volumio[5901]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessInfo Jun 28 11:00:14 volumio sudo[7185]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 28 11:00:14 volumio sudo[7185]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 11:00:14 volumio sudo[7185]: pam_unix(sudo:session): session closed for user root Jun 28 11:00:14 volumio sudo[7188]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 28 11:00:14 volumio sudo[7188]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 11:00:14 volumio sudo[7188]: pam_unix(sudo:session): session closed for user root Jun 28 11:00:14 volumio dhcpcd[6828]: wlan0: soliciting a DHCP lease Jun 28 11:00:14 volumio dhcpd[7156]: DHCPDISCOVER from 00:90:4c:c5:12:38 via wlan0 Jun 28 11:00:14 volumio volumio[5901]: verbose: ControllerMpd::sendMpdCommand status Jun 28 11:00:14 volumio volumio[5901]: info: Jun 28 11:00:14 volumio volumio[5901]: ---------------------------- MPD announces state update: update Jun 28 11:00:14 volumio volumio[5901]: info: ControllerMpd::getState Jun 28 11:00:14 volumio volumio[5901]: verbose: ControllerMpd::sendMpdCommand status Jun 28 11:00:14 volumio volumio[5901]: verbose: ControllerMpd::sendMpdCommand status Jun 28 11:00:14 volumio volumio[5901]: info: Jun 28 11:00:14 volumio volumio[5901]: ---------------------------- MPD announces state update: update Jun 28 11:00:14 volumio volumio[5901]: info: ControllerMpd::getState Jun 28 11:00:14 volumio volumio[5901]: verbose: ControllerMpd::sendMpdCommand status Jun 28 11:00:14 volumio volumio[5901]: verbose: ControllerMpd::parseState Jun 28 11:00:14 volumio volumio[5901]: info: Command Router : Notfying DB Updatetrue Jun 28 11:00:14 volumio volumio[5901]: info: CoreCommandRouter::Close All Modals sent Jun 28 11:00:14 volumio volumio[5901]: verbose: ControllerMpd::parseState Jun 28 11:00:14 volumio volumio[5901]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 28 11:00:14 volumio volumio[5901]: verbose: ControllerMpd::sendMpdCommand status Jun 28 11:00:14 volumio volumio[5901]: info: Jun 28 11:00:14 volumio volumio[5901]: ---------------------------- MPD announces state update: update Jun 28 11:00:14 volumio volumio[5901]: info: ControllerMpd::getState Jun 28 11:00:14 volumio volumio[5901]: verbose: ControllerMpd::sendMpdCommand status Jun 28 11:00:14 volumio volumio[5901]: verbose: ControllerMpd::parseState Jun 28 11:00:14 volumio volumio[5901]: info: Command Router : Notfying DB Updatefalse Jun 28 11:00:14 volumio volumio[5901]: info: CoreCommandRouter::Close All Modals sent Jun 28 11:00:14 volumio volumio[5901]: verbose: ControllerMpd::parseState Jun 28 11:00:14 volumio volumio[5901]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 28 11:00:14 volumio volumio[5901]: verbose: ControllerMpd::parseTrackInfo Jun 28 11:00:14 volumio volumio[5901]: info: ControllerMpd::pushState Jun 28 11:00:14 volumio volumio[5901]: info: CoreCommandRouter::servicePushState Jun 28 11:00:14 volumio volumio[5901]: info: CorePlayQueue::getTrack 0 Jun 28 11:00:14 volumio volumio[5901]: verbose: STATE SERVICE {"status":"play","position":0,"seek":128743,"duration":213,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"You're Beautiful","artist":"James Blunt","album":"Back to Bedlam","uri":"USB/MUSIC/02 - You're Beautiful.mp3","trackType":"mp3"} Jun 28 11:00:14 volumio volumio[5901]: verbose: CURRENT POSITION 0 Jun 28 11:00:14 volumio volumio[5901]: info: CoreStateMachine::syncState stateService play Jun 28 11:00:14 volumio volumio[5901]: info: CoreStateMachine::syncState currentStatus play Jun 28 11:00:14 volumio volumio[5901]: info: Received an update from plugin. extracting info from payload Jun 28 11:00:14 volumio volumio[5901]: info: CoreStateMachine::pushState Jun 28 11:00:14 volumio volumio[5901]: info: CorePlayQueue::getTrack 0 Jun 28 11:00:14 volumio volumio[5901]: info: CoreCommandRouter::volumioPushState Jun 28 11:00:14 volumio volumio[5901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 11:00:14 volumio volumio[5901]: info: CoreStateMachine::pushState Jun 28 11:00:14 volumio volumio[5901]: info: CorePlayQueue::getTrack 0 Jun 28 11:00:14 volumio volumio[5901]: info: CoreCommandRouter::volumioPushState Jun 28 11:00:14 volumio volumio[5901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 11:00:14 volumio volumio[5901]: info: ------------------------------ 90ms Jun 28 11:00:14 volumio volumio[5901]: verbose: ControllerMpd::parseState Jun 28 11:00:14 volumio volumio[5901]: info: Command Router : Notfying DB Updatefalse Jun 28 11:00:14 volumio volumio[5901]: info: CoreCommandRouter::Close All Modals sent Jun 28 11:00:14 volumio volumio[5901]: verbose: ControllerMpd::parseState Jun 28 11:00:14 volumio volumio[5901]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 28 11:00:14 volumio volumio[5901]: verbose: ControllerMpd::parseTrackInfo Jun 28 11:00:14 volumio volumio[5901]: info: ControllerMpd::pushState Jun 28 11:00:14 volumio volumio[5901]: info: CoreCommandRouter::servicePushState Jun 28 11:00:14 volumio volumio[5901]: info: CorePlayQueue::getTrack 0 Jun 28 11:00:14 volumio volumio[5901]: verbose: STATE SERVICE {"status":"play","position":0,"seek":128743,"duration":213,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"You're Beautiful","artist":"James Blunt","album":"Back to Bedlam","uri":"USB/MUSIC/02 - You're Beautiful.mp3","trackType":"mp3"} Jun 28 11:00:14 volumio volumio[5901]: verbose: CURRENT POSITION 0 Jun 28 11:00:14 volumio volumio[5901]: info: CoreStateMachine::syncState stateService play Jun 28 11:00:14 volumio volumio[5901]: info: CoreStateMachine::syncState currentStatus play Jun 28 11:00:14 volumio volumio[5901]: info: Received an update from plugin. extracting info from payload Jun 28 11:00:14 volumio volumio[5901]: info: CoreStateMachine::pushState Jun 28 11:00:14 volumio volumio[5901]: info: CorePlayQueue::getTrack 0 Jun 28 11:00:14 volumio volumio[5901]: info: CoreCommandRouter::volumioPushState Jun 28 11:00:14 volumio volumio[5901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 11:00:14 volumio volumio[5901]: info: CoreStateMachine::pushState Jun 28 11:00:14 volumio volumio[5901]: info: CorePlayQueue::getTrack 0 Jun 28 11:00:14 volumio volumio[5901]: info: CoreCommandRouter::volumioPushState Jun 28 11:00:14 volumio volumio[5901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 11:00:14 volumio volumio[5901]: info: ------------------------------ 141ms Jun 28 11:00:14 volumio volumio[5901]: verbose: ControllerMpd::parseTrackInfo Jun 28 11:00:14 volumio volumio[5901]: info: ControllerMpd::pushState Jun 28 11:00:14 volumio volumio[5901]: info: CoreCommandRouter::servicePushState Jun 28 11:00:14 volumio volumio[5901]: info: CorePlayQueue::getTrack 0 Jun 28 11:00:14 volumio volumio[5901]: verbose: STATE SERVICE {"status":"play","position":0,"seek":128743,"duration":213,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"You're Beautiful","artist":"James Blunt","album":"Back to Bedlam","uri":"USB/MUSIC/02 - You're Beautiful.mp3","trackType":"mp3"} Jun 28 11:00:14 volumio volumio[5901]: verbose: CURRENT POSITION 0 Jun 28 11:00:14 volumio volumio[5901]: info: CoreStateMachine::syncState stateService play Jun 28 11:00:14 volumio volumio[5901]: info: CoreStateMachine::syncState currentStatus play Jun 28 11:00:14 volumio volumio[5901]: info: Received an update from plugin. extracting info from payload Jun 28 11:00:14 volumio volumio[5901]: info: CoreStateMachine::pushState Jun 28 11:00:14 volumio volumio[5901]: info: CorePlayQueue::getTrack 0 Jun 28 11:00:14 volumio volumio[5901]: info: CoreCommandRouter::volumioPushState Jun 28 11:00:14 volumio volumio[5901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 11:00:14 volumio volumio[5901]: info: CoreStateMachine::pushState Jun 28 11:00:14 volumio volumio[5901]: info: CorePlayQueue::getTrack 0 Jun 28 11:00:14 volumio volumio[5901]: info: CoreCommandRouter::volumioPushState Jun 28 11:00:14 volumio volumio[5901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 11:00:14 volumio volumio[5901]: info: ------------------------------ 134ms Jun 28 11:00:15 volumio dhcpd[7156]: DHCPOFFER on 192.168.211.2 to 00:90:4c:c5:12:38 (volumio) via wlan0 Jun 28 11:00:18 volumio dhcpd[7156]: DHCPDISCOVER from 00:90:4c:c5:12:38 (volumio) via wlan0 Jun 28 11:00:18 volumio dhcpd[7156]: DHCPOFFER on 192.168.211.2 to 00:90:4c:c5:12:38 (volumio) via wlan0 Jun 28 11:00:19 volumio volumio[5901]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart Jun 28 11:00:19 volumio volumio[5901]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart Jun 28 11:00:19 volumio volumio[5901]: info: Refreshing Cached IP Addresses Jun 28 11:00:19 volumio sudo[7214]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Jun 28 11:00:19 volumio sudo[7214]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 11:00:19 volumio sudo[7217]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 28 11:00:19 volumio sudo[7214]: pam_unix(sudo:session): session closed for user root Jun 28 11:00:19 volumio sudo[7217]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 11:00:19 volumio sudo[7217]: pam_unix(sudo:session): session closed for user root Jun 28 11:00:19 volumio volumio[5901]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart Jun 28 11:00:19 volumio sudo[7221]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 28 11:00:19 volumio sudo[7221]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 11:00:19 volumio sudo[7221]: pam_unix(sudo:session): session closed for user root Jun 28 11:00:19 volumio volumio[5901]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart Jun 28 11:00:19 volumio volumio[5901]: info: Refreshing Cached IP Addresses Jun 28 11:00:19 volumio sudo[7224]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Jun 28 11:00:19 volumio sudo[7224]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 11:00:19 volumio sudo[7224]: pam_unix(sudo:session): session closed for user root Jun 28 11:00:19 volumio sudo[7227]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 28 11:00:19 volumio sudo[7227]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 11:00:19 volumio volumio[5901]: error: Cannot kill upmpdcli Error: Command failed: /usr/bin/sudo /usr/bin/killall upmpdcli Jun 28 11:00:19 volumio volumio[5901]: upmpdcli: no process found Jun 28 11:00:19 volumio sudo[7227]: pam_unix(sudo:session): session closed for user root Jun 28 11:00:19 volumio sudo[7229]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 28 11:00:19 volumio sudo[7229]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 11:00:19 volumio sudo[7229]: pam_unix(sudo:session): session closed for user root Jun 28 11:00:19 volumio sudo[7233]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 28 11:00:19 volumio sudo[7233]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 11:00:19 volumio sudo[7233]: pam_unix(sudo:session): session closed for user root Jun 28 11:00:19 volumio sudo[7235]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 28 11:00:19 volumio sudo[7235]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 11:00:19 volumio sudo[7235]: pam_unix(sudo:session): session closed for user root Jun 28 11:00:19 volumio volumio[5901]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 28 11:00:19 volumio volumio[5901]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 28 11:00:19 volumio volumio[5901]: info: CoreCommandRouter::volumioGetState Jun 28 11:00:19 volumio volumio[5901]: info: CorePlayQueue::getTrack 0 Jun 28 11:00:19 volumio volumio[5901]: info: CoreCommandRouter::volumioGetVisibleSources Jun 28 11:00:19 volumio volumio[5901]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 28 11:00:19 volumio volumio[5901]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 28 11:00:19 volumio volumio[5901]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 28 11:00:19 volumio volumio[5901]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 28 11:00:19 volumio volumio[5901]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 28 11:00:19 volumio volumio[5901]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 28 11:00:19 volumio volumio[5901]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Jun 28 11:00:19 volumio sudo[7246]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Jun 28 11:00:19 volumio sudo[7246]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 11:00:19 volumio sudo[7246]: pam_unix(sudo:session): session closed for user root Jun 28 11:00:19 volumio sudo[7250]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jun 28 11:00:19 volumio sudo[7250]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 11:00:19 volumio kernel: Error getting the SSID Jun 28 11:00:19 volumio sudo[7250]: pam_unix(sudo:session): session closed for user root Jun 28 11:00:19 volumio sudo[7258]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jun 28 11:00:19 volumio sudo[7258]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 11:00:19 volumio sudo[7263]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jun 28 11:00:19 volumio sudo[7263]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 11:00:19 volumio kernel: Error getting the SSID Jun 28 11:00:19 volumio kernel: Error getting the SSID Jun 28 11:00:19 volumio sudo[7258]: pam_unix(sudo:session): session closed for user root Jun 28 11:00:19 volumio sudo[7263]: pam_unix(sudo:session): session closed for user root Jun 28 11:00:19 volumio sudo[7269]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 28 11:00:19 volumio sudo[7269]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 11:00:19 volumio volumio[5901]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Jun 28 11:00:19 volumio volumio[5901]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Jun 28 11:00:19 volumio sudo[7269]: pam_unix(sudo:session): session closed for user root Jun 28 11:00:19 volumio sudo[7273]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 28 11:00:19 volumio sudo[7273]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 11:00:19 volumio sudo[7273]: pam_unix(sudo:session): session closed for user root Jun 28 11:00:19 volumio sudo[7279]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 28 11:00:19 volumio sudo[7279]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 11:00:19 volumio sudo[7279]: pam_unix(sudo:session): session closed for user root Jun 28 11:00:19 volumio volumio[5901]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Jun 28 11:00:19 volumio volumio[5901]: wlan0 Interface doesn't support scanning. Jun 28 11:00:19 volumio volumio[5901]: info: Cannot use regular scanning, forcing with ap-force Jun 28 11:00:19 volumio sudo[7282]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Jun 28 11:00:19 volumio sudo[7282]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 11:00:24 volumio sudo[7282]: pam_unix(sudo:session): session closed for user root Jun 28 11:00:24 volumio volumio[5901]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 28 11:00:26 volumio dhcpd[7156]: DHCPDISCOVER from 00:90:4c:c5:12:38 (volumio) via wlan0 Jun 28 11:00:26 volumio dhcpd[7156]: DHCPOFFER on 192.168.211.2 to 00:90:4c:c5:12:38 (volumio) via wlan0 Jun 28 11:00:29 volumio sudo[7294]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 28 11:00:29 volumio sudo[7294]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 11:00:29 volumio sudo[7294]: pam_unix(sudo:session): session closed for user root Jun 28 11:00:29 volumio sudo[7296]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 28 11:00:29 volumio sudo[7296]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 11:00:29 volumio sudo[7296]: pam_unix(sudo:session): session closed for user root Jun 28 11:00:29 volumio sudo[7300]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jun 28 11:00:29 volumio sudo[7300]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 11:00:29 volumio systemd[1]: Starting UPnP Renderer front-end to MPD... Jun 28 11:00:29 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Jun 28 11:00:29 volumio sudo[7303]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 28 11:00:29 volumio sudo[7303]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 11:00:29 volumio sudo[7300]: pam_unix(sudo:session): session closed for user root Jun 28 11:00:29 volumio sudo[7303]: pam_unix(sudo:session): session closed for user root Jun 28 11:00:29 volumio sudo[7307]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 28 11:00:29 volumio sudo[7307]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 11:00:29 volumio sudo[7307]: pam_unix(sudo:session): session closed for user root Jun 28 11:00:29 volumio volumio[5901]: info: Upmpdcli Daemon Started Jun 28 11:00:29 volumio sudo[7313]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jun 28 11:00:29 volumio sudo[7313]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 11:00:29 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Jun 28 11:00:29 volumio sudo[7313]: pam_unix(sudo:session): session closed for user root Jun 28 11:00:29 volumio volumio[5901]: info: Upmpdcli Daemon Started Jun 28 11:00:30 volumio volumio[5901]: verbose: ControllerMpd::sendMpdCommand status Jun 28 11:00:30 volumio volumio[5901]: info: Jun 28 11:00:30 volumio volumio[5901]: ---------------------------- MPD announces state update: update Jun 28 11:00:30 volumio volumio[5901]: info: ControllerMpd::getState Jun 28 11:00:30 volumio volumio[5901]: verbose: ControllerMpd::sendMpdCommand status Jun 28 11:00:30 volumio volumio[5901]: verbose: ControllerMpd::sendMpdCommand status Jun 28 11:00:30 volumio volumio[5901]: info: Jun 28 11:00:30 volumio volumio[5901]: ---------------------------- MPD announces state update: update Jun 28 11:00:30 volumio volumio[5901]: info: ControllerMpd::getState Jun 28 11:00:30 volumio volumio[5901]: verbose: ControllerMpd::sendMpdCommand status Jun 28 11:00:30 volumio volumio[5901]: verbose: ControllerMpd::parseState Jun 28 11:00:30 volumio volumio[5901]: info: Command Router : Notfying DB Updatefalse Jun 28 11:00:30 volumio volumio[5901]: info: CoreCommandRouter::Close All Modals sent Jun 28 11:00:30 volumio volumio[5901]: verbose: ControllerMpd::parseState Jun 28 11:00:30 volumio volumio[5901]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 28 11:00:30 volumio volumio[5901]: verbose: ControllerMpd::parseState Jun 28 11:00:30 volumio volumio[5901]: info: Command Router : Notfying DB Updatefalse Jun 28 11:00:30 volumio volumio[5901]: info: CoreCommandRouter::Close All Modals sent Jun 28 11:00:30 volumio volumio[5901]: verbose: ControllerMpd::parseState Jun 28 11:00:30 volumio volumio[5901]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 28 11:00:30 volumio volumio[5901]: verbose: ControllerMpd::parseTrackInfo Jun 28 11:00:30 volumio volumio[5901]: info: ControllerMpd::pushState Jun 28 11:00:30 volumio volumio[5901]: info: CoreCommandRouter::servicePushState Jun 28 11:00:30 volumio volumio[5901]: info: CorePlayQueue::getTrack 0 Jun 28 11:00:30 volumio volumio[5901]: verbose: STATE SERVICE {"status":"play","position":0,"seek":144497,"duration":213,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"You're Beautiful","artist":"James Blunt","album":"Back to Bedlam","uri":"USB/MUSIC/02 - You're Beautiful.mp3","trackType":"mp3"} Jun 28 11:00:30 volumio volumio[5901]: verbose: CURRENT POSITION 0 Jun 28 11:00:30 volumio volumio[5901]: info: CoreStateMachine::syncState stateService play Jun 28 11:00:30 volumio volumio[5901]: info: CoreStateMachine::syncState currentStatus play Jun 28 11:00:30 volumio volumio[5901]: info: Received an update from plugin. extracting info from payload Jun 28 11:00:30 volumio volumio[5901]: info: CoreStateMachine::pushState Jun 28 11:00:30 volumio volumio[5901]: info: CorePlayQueue::getTrack 0 Jun 28 11:00:30 volumio volumio[5901]: info: CoreCommandRouter::volumioPushState Jun 28 11:00:30 volumio volumio[5901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 11:00:30 volumio volumio[5901]: info: CoreStateMachine::pushState Jun 28 11:00:30 volumio volumio[5901]: info: CorePlayQueue::getTrack 0 Jun 28 11:00:30 volumio volumio[5901]: info: CoreCommandRouter::volumioPushState Jun 28 11:00:30 volumio volumio[5901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 11:00:30 volumio volumio[5901]: info: ------------------------------ 98ms Jun 28 11:00:30 volumio volumio[5901]: verbose: ControllerMpd::parseTrackInfo Jun 28 11:00:30 volumio volumio[5901]: info: ControllerMpd::pushState Jun 28 11:00:30 volumio volumio[5901]: info: CoreCommandRouter::servicePushState Jun 28 11:00:30 volumio volumio[5901]: info: CorePlayQueue::getTrack 0 Jun 28 11:00:30 volumio volumio[5901]: verbose: STATE SERVICE {"status":"play","position":0,"seek":144625,"duration":213,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"You're Beautiful","artist":"James Blunt","album":"Back to Bedlam","uri":"USB/MUSIC/02 - You're Beautiful.mp3","trackType":"mp3"} Jun 28 11:00:30 volumio volumio[5901]: verbose: CURRENT POSITION 0 Jun 28 11:00:30 volumio volumio[5901]: info: CoreStateMachine::syncState stateService play Jun 28 11:00:30 volumio volumio[5901]: info: CoreStateMachine::syncState currentStatus play Jun 28 11:00:30 volumio volumio[5901]: info: Received an update from plugin. extracting info from payload Jun 28 11:00:30 volumio volumio[5901]: info: CoreStateMachine::pushState Jun 28 11:00:30 volumio volumio[5901]: info: CorePlayQueue::getTrack 0 Jun 28 11:00:30 volumio volumio[5901]: info: CoreCommandRouter::volumioPushState Jun 28 11:00:30 volumio volumio[5901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 11:00:30 volumio volumio[5901]: info: CoreStateMachine::pushState Jun 28 11:00:30 volumio volumio[5901]: info: CorePlayQueue::getTrack 0 Jun 28 11:00:30 volumio volumio[5901]: info: CoreCommandRouter::volumioPushState Jun 28 11:00:30 volumio volumio[5901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 11:00:30 volumio volumio[5901]: info: ------------------------------ 126ms Jun 28 11:00:33 volumio volumio[5901]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 28 11:00:33 volumio volumio[5901]: { Error: connect ETIMEDOUT 31.13.71.19:443 Jun 28 11:00:33 volumio volumio[5901]: at Object._errnoException (util.js:1022:11) Jun 28 11:00:33 volumio volumio[5901]: at _exceptionWithHostPort (util.js:1044:20) Jun 28 11:00:33 volumio volumio[5901]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Jun 28 11:00:33 volumio volumio[5901]: code: 'ETIMEDOUT', Jun 28 11:00:33 volumio volumio[5901]: errno: 'ETIMEDOUT', Jun 28 11:00:33 volumio volumio[5901]: syscall: 'connect', Jun 28 11:00:33 volumio volumio[5901]: address: '31.13.71.19', Jun 28 11:00:33 volumio volumio[5901]: port: 443 } Jun 28 11:00:33 volumio volumio[5901]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 28 11:00:34 volumio sudo[7364]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-28 10:59 Jun 28 11:00:34 volumio sudo[7364]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 8 (jessie)" NAME="Debian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" ID=debian HOME_URL="http://www.debian.org/" SUPPORT_URL="http://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="168ba7444df98cd868db72f24faec0b6346489cc" VOLUMIO_FE_VERSION="847a48ecf32d35cc502a0053d585d1a69236e391" VOLUMIO_BE_VERSION="99dfb342fe07851f1e26bd1d8046d0234eda91dd" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 11 15:53:56 CET 2020" VOLUMIO_VERSION="2.857" VOLUMIO_HARDWARE="aml9xxxarmv7" VOLUMIO_HASH="5e595833ee6075a27fef1ba35f928abb"