cyw43439
cyw43439 copied to clipboard
Unstable DHCP connection
Hello! I've been testing my new Pico W I've started with DHCP example and it works exactly 1/2 of the times (after success follows fail and after success again)
I understand that issue may be with my TP-Link router DHCP configuration, but I didn't notice any issues with any of my other devices on the network, would appreciate any help with debugging, thank you!
Successful connection:
Connected to COM4. Press Ctrl-C to exit.
starting program
time=1970-01-01T00:00:02.032Z level=INFO msg="initializing pico W device..."
time=1970-01-01T00:00:02.303Z level=DEBUG msg="read back bus ctl" got=177
time=1970-01-01T00:00:02.304Z level=DEBUG msg="current bus ctl" val=65584 got=4276993709
time=1970-01-01T00:00:02.305Z level=DEBUG msg=Init:alp
time=1970-01-01T00:00:02.309Z level=DEBUG msg="flashing firmware" chip_id=43439 fwlen=230321
time=1970-01-01T00:00:02.309Z level=DEBUG msg=bp_write addr=0 len=230321
time=1970-01-01T00:00:02.487Z level=DEBUG msg="flashing nvram"
time=1970-01-01T00:00:02.488Z level=DEBUG msg=bp_write addr=523540 len=742
time=1970-01-01T00:00:02.490Z level=DEBUG msg=Init:start-core
time=1970-01-01T00:00:02.493Z level=DEBUG msg="core up"
time=1970-01-01T00:00:02.512Z level=DEBUG msg=Init:intr-mask
time=1970-01-01T00:00:02.596Z level=DEBUG msg="base init done"
time=1970-01-01T00:00:02.596Z level=DEBUG msg=initControl clm_len=4752
time=1970-01-01T00:00:02.597Z level=DEBUG msg=sendIoctl kind=2 cmd=SET_VAR len=1044
time=1970-01-01T00:00:02.598Z level=DEBUG msg=rx len=256 hdr=asyncev
time=1970-01-01T00:00:02.599Z level=DEBUG msg=tryPoll:ignore_spurious err="BDC header invalid length"
time=1970-01-01T00:00:02.610Z level=DEBUG msg=rx len=256 hdr=asyncev
time=1970-01-01T00:00:02.611Z level=DEBUG msg=tryPoll:ignore_spurious err="BDC header invalid length"
time=1970-01-01T00:00:02.622Z level=DEBUG msg=rx len=1072 hdr=ctl
time=1970-01-01T00:00:02.623Z level=DEBUG msg=sendIoctl kind=2 cmd=SET_VAR len=1044
time=1970-01-01T00:00:02.634Z level=DEBUG msg=rx len=1072 hdr=ctl
time=1970-01-01T00:00:02.635Z level=DEBUG msg=sendIoctl kind=2 cmd=SET_VAR len=1044
time=1970-01-01T00:00:02.647Z level=DEBUG msg=rx len=1072 hdr=ctl
time=1970-01-01T00:00:02.648Z level=DEBUG msg=sendIoctl kind=2 cmd=SET_VAR len=1044
time=1970-01-01T00:00:02.660Z level=DEBUG msg=rx len=1072 hdr=ctl
time=1970-01-01T00:00:02.661Z level=DEBUG msg=sendIoctl kind=2 cmd=SET_VAR len=676
time=1970-01-01T00:00:02.672Z level=DEBUG msg=rx len=704 hdr=ctl
time=1970-01-01T00:00:02.673Z level=DEBUG msg=clmload:done
time=1970-01-01T00:00:02.674Z level=DEBUG msg=sendIoctl kind=0 cmd=GET_VAR len=15
time=1970-01-01T00:00:02.685Z level=DEBUG msg=rx len=259 hdr=ctl
time=1970-01-01T00:00:02.685Z level=DEBUG msg=sendIoctl kind=2 cmd=SET_VAR len=15
time=1970-01-01T00:00:02.686Z level=DEBUG msg=rx len=259 hdr=ctl
time=1970-01-01T00:00:02.687Z level=DEBUG msg=sendIoctl kind=2 cmd=SET_VAR len=10
time=1970-01-01T00:00:02.698Z level=DEBUG msg=rx len=258 hdr=ctl
time=1970-01-01T00:00:02.699Z level=DEBUG msg=sendIoctl kind=0 cmd=GET_VAR len=14
time=1970-01-01T00:00:02.710Z level=DEBUG msg=rx len=258 hdr=ctl
time=1970-01-01T00:00:02.711Z level=DEBUG msg=MAC mac=28:cd:c1:11:70:8d
time=1970-01-01T00:00:02.711Z level=DEBUG msg=sendIoctl kind=2 cmd=SET_VAR len=20
time=1970-01-01T00:00:02.742Z level=DEBUG msg=rx len=256 hdr=ctl
time=1970-01-01T00:00:02.843Z level=DEBUG msg=sendIoctl kind=2 cmd=SET_ANTDIV len=4
time=1970-01-01T00:00:02.854Z level=DEBUG msg=rx len=256 hdr=ctl
time=1970-01-01T00:00:02.855Z level=DEBUG msg=sendIoctl kind=2 cmd=SET_VAR len=15
time=1970-01-01T00:00:02.866Z level=DEBUG msg=rx len=259 hdr=ctl
time=1970-01-01T00:00:02.967Z level=DEBUG msg=sendIoctl kind=2 cmd=SET_VAR len=19
time=1970-01-01T00:00:02.978Z level=DEBUG msg=rx len=259 hdr=ctl
time=1970-01-01T00:00:03.078Z level=DEBUG msg=sendIoctl kind=2 cmd=SET_VAR len=15
time=1970-01-01T00:00:03.089Z level=DEBUG msg=rx len=259 hdr=ctl
time=1970-01-01T00:00:03.190Z level=DEBUG msg=sendIoctl kind=2 cmd=SET_VAR len=46
time=1970-01-01T00:00:03.201Z level=DEBUG msg=rx len=258 hdr=ctl
time=1970-01-01T00:00:03.302Z level=DEBUG msg=sendIoctl kind=2 cmd=UP len=0
time=1970-01-01T00:00:03.313Z level=DEBUG msg=rx len=256 hdr=ctl
time=1970-01-01T00:00:03.414Z level=DEBUG msg=sendIoctl kind=2 cmd=SET_GMODE len=4
time=1970-01-01T00:00:03.425Z level=DEBUG msg=rx len=256 hdr=ctl
time=1970-01-01T00:00:03.426Z level=DEBUG msg=sendIoctl kind=2 cmd=SET_BAND len=4
time=1970-01-01T00:00:03.437Z level=DEBUG msg=rx len=256 hdr=ctl
time=1970-01-01T00:00:03.537Z level=DEBUG msg=set_power_management mode=PowerSave
time=1970-01-01T00:00:03.538Z level=DEBUG msg=sendIoctl kind=2 cmd=SET_VAR len=18
time=1970-01-01T00:00:03.549Z level=DEBUG msg=rx len=258 hdr=ctl
time=1970-01-01T00:00:03.550Z level=DEBUG msg=sendIoctl kind=2 cmd=SET_VAR len=15
time=1970-01-01T00:00:03.561Z level=DEBUG msg=rx len=259 hdr=ctl
time=1970-01-01T00:00:03.562Z level=DEBUG msg=sendIoctl kind=2 cmd=SET_VAR len=16
time=1970-01-01T00:00:03.573Z level=DEBUG msg=rx len=256 hdr=ctl
time=1970-01-01T00:00:03.573Z level=DEBUG msg=sendIoctl kind=2 cmd=SET_VAR len=17
time=1970-01-01T00:00:03.584Z level=DEBUG msg=rx len=257 hdr=ctl
time=1970-01-01T00:00:03.585Z level=DEBUG msg=sendIoctl kind=2 cmd=SET_PM len=4
time=1970-01-01T00:00:03.596Z level=DEBUG msg=rx len=256 hdr=ctl
time=1970-01-01T00:00:03.597Z level=INFO msg=Init:done took=1.563752s
time=1970-01-01T00:00:03.598Z level=INFO msg=cyw43439:Init duration=1.5645s
time=1970-01-01T00:00:03.598Z level=INFO msg="joining WPA secure network" ssid=TP-Link_DAA9 passlen=15
time=1970-01-01T00:00:03.599Z level=INFO msg=joinWpa2 ssid=TP-Link_DAA9 len(pass)=15
time=1970-01-01T00:00:03.599Z level=DEBUG msg=sendIoctl kind=2 cmd=SET_VAR len=19
time=1970-01-01T00:00:03.610Z level=DEBUG msg=rx len=259 hdr=ctl
time=1970-01-01T00:00:03.611Z level=DEBUG msg=sendIoctl kind=2 cmd=SET_WSEC len=4
time=1970-01-01T00:00:03.622Z level=DEBUG msg=rx len=256 hdr=ctl
time=1970-01-01T00:00:03.623Z level=DEBUG msg=sendIoctl kind=2 cmd=SET_VAR len=23
time=1970-01-01T00:00:03.634Z level=DEBUG msg=rx len=259 hdr=ctl
time=1970-01-01T00:00:03.635Z level=DEBUG msg=sendIoctl kind=2 cmd=SET_VAR len=31
time=1970-01-01T00:00:03.646Z level=DEBUG msg=rx len=259 hdr=ctl
time=1970-01-01T00:00:03.647Z level=DEBUG msg=sendIoctl kind=2 cmd=SET_VAR len=27
time=1970-01-01T00:00:03.658Z level=DEBUG msg=rx len=259 hdr=ctl
time=1970-01-01T00:00:03.758Z level=DEBUG msg=sendIoctl kind=2 cmd=SET_WSEC_PMK len=68
time=1970-01-01T00:00:03.769Z level=DEBUG msg=rx len=256 hdr=ctl
time=1970-01-01T00:00:03.770Z level=DEBUG msg=sendIoctl kind=2 cmd=SET_INFRA len=4
time=1970-01-01T00:00:03.781Z level=DEBUG msg=rx len=256 hdr=ctl
time=1970-01-01T00:00:03.782Z level=DEBUG msg=sendIoctl kind=2 cmd=SET_AUTH len=4
time=1970-01-01T00:00:03.793Z level=DEBUG msg=rx len=256 hdr=ctl
time=1970-01-01T00:00:03.794Z level=DEBUG msg=sendIoctl kind=2 cmd=SET_WPA_AUTH len=4
time=1970-01-01T00:00:03.805Z level=DEBUG msg=rx len=256 hdr=ctl
time=1970-01-01T00:00:03.806Z level=DEBUG msg=sendIoctl kind=2 cmd=SET_SSID len=36
time=1970-01-01T00:00:03.817Z level=DEBUG msg=rx len=256 hdr=ctl
time=1970-01-01T00:00:07.058Z level=DEBUG msg=rx len=260 hdr=asyncev
time=1970-01-01T00:00:07.059Z level=DEBUG msg=rx len=260 hdr=asyncev
time=1970-01-01T00:00:07.060Z level=INFO msg=rxEvent event=AUTH status=0 reason=0 flags=0 dev.linkstate=1
time=1970-01-01T00:00:07.061Z level=DEBUG msg=rx len=260 hdr=asyncev
time=1970-01-01T00:00:07.061Z level=DEBUG msg=rx len=258 hdr=asyncev
time=1970-01-01T00:00:07.062Z level=DEBUG msg=rx len=258 hdr=asyncev
time=1970-01-01T00:00:07.063Z level=DEBUG msg=rx len=260 hdr=asyncev
time=1970-01-01T00:00:07.064Z level=DEBUG msg=rx len=260 hdr=asyncev
time=1970-01-01T00:00:07.064Z level=INFO msg=rxEvent event=SET_SSID status=0 reason=0 flags=0 dev.linkstate=2
time=1970-01-01T00:00:07.065Z level=DEBUG msg=rx len=260 hdr=asyncev
time=1970-01-01T00:00:07.066Z level=INFO msg="wifi join success!" mac=28:cd:c1:11:70:8d
time=1970-01-01T00:00:07.068Z level=INFO msg="DHCP ongoing..."
time=1970-01-01T00:00:07.070Z level=INFO msg=DHCP:tx msg=Discover
time=1970-01-01T00:00:07.070Z level=DEBUG msg=UDP:send plen=325
time=1970-01-01T00:00:07.071Z level=DEBUG msg=tx len=325
time=1970-01-01T00:00:07.273Z level=DEBUG msg=rx len=260 hdr=data
time=1970-01-01T00:00:07.375Z level=DEBUG msg=rx len=260 hdr=data
time=1970-01-01T00:00:07.477Z level=DEBUG msg=rx len=260 hdr=data
time=1970-01-01T00:00:07.681Z level=DEBUG msg=rx len=260 hdr=data
time=1970-01-01T00:00:07.783Z level=DEBUG msg=rx len=612 hdr=data
time=1970-01-01T00:00:07.784Z level=DEBUG msg=UDP:recv plen=548
time=1970-01-01T00:00:07.785Z level=DEBUG msg=DHCP:rx opt=MessageType data=2
time=1970-01-01T00:00:07.785Z level=DEBUG msg=DHCP:rx opt=ServerIdentification data=192,168,0,1
time=1970-01-01T00:00:07.786Z level=DEBUG msg=DHCP:rx opt=IPAddressLeaseTime data=255,255,255,255
time=1970-01-01T00:00:07.787Z level=DEBUG msg=DHCP:rx opt=DNSServers data=192,168,0,1
time=1970-01-01T00:00:07.787Z level=DEBUG msg=DHCP:rx opt=SubnetMask data=255,255,255,0
time=1970-01-01T00:00:07.788Z level=DEBUG msg=DHCP:rx opt=Router data=192,168,0,1
time=1970-01-01T00:00:07.788Z level=INFO msg=DHCP:rx msg=Offer
time=1970-01-01T00:00:07.789Z level=INFO msg=DHCP:tx msg=Request
time=1970-01-01T00:00:07.790Z level=DEBUG msg=UDP:send plen=309
time=1970-01-01T00:00:07.790Z level=DEBUG msg=tx len=309
time=1970-01-01T00:00:07.834Z level=DEBUG msg=rx len=612 hdr=data
time=1970-01-01T00:00:07.835Z level=DEBUG msg=UDP:recv plen=548
time=1970-01-01T00:00:07.836Z level=DEBUG msg=DHCP:rx opt=MessageType data=5
time=1970-01-01T00:00:07.836Z level=DEBUG msg=DHCP:rx opt=ServerIdentification data=192,168,0,1
time=1970-01-01T00:00:07.837Z level=DEBUG msg=DHCP:rx opt=IPAddressLeaseTime data=255,255,255,255
time=1970-01-01T00:00:07.838Z level=DEBUG msg=DHCP:rx opt=DNSServers data=192,168,0,1
time=1970-01-01T00:00:07.838Z level=DEBUG msg=DHCP:rx opt=SubnetMask data=255,255,255,0
time=1970-01-01T00:00:07.839Z level=DEBUG msg=DHCP:rx opt=Router data=192,168,0,1
time=1970-01-01T00:00:07.839Z level=INFO msg=DHCP:rx msg=Ack
time=1970-01-01T00:00:08.069Z level=INFO msg="DHCP complete" cidrbits=24 ourIP=192.168.0.101 dns=192.168.0.1 broadcast="invalid IP" gateway="invalid IP" router=192.168.0.1 dhcp=192.168.0.1 hostname="" lease=1193046h28m15s renewal=0s rebinding=0s
Unsuccessful connection:
Connected to COM4. Press Ctrl-C to exit.
starting program
time=1970-01-01T00:00:02.032Z level=INFO msg="initializing pico W device..."
time=1970-01-01T00:00:02.303Z level=DEBUG msg="read back bus ctl" got=177
time=1970-01-01T00:00:02.304Z level=DEBUG msg="current bus ctl" val=65584 got=4276993709
time=1970-01-01T00:00:02.305Z level=DEBUG msg=Init:alp
time=1970-01-01T00:00:02.308Z level=DEBUG msg="flashing firmware" chip_id=43439 fwlen=230321
time=1970-01-01T00:00:02.309Z level=DEBUG msg=bp_write addr=0 len=230321
time=1970-01-01T00:00:02.487Z level=DEBUG msg="flashing nvram"
time=1970-01-01T00:00:02.488Z level=DEBUG msg=bp_write addr=523540 len=742
time=1970-01-01T00:00:02.490Z level=DEBUG msg=Init:start-core
time=1970-01-01T00:00:02.493Z level=DEBUG msg="core up"
time=1970-01-01T00:00:02.512Z level=DEBUG msg=Init:intr-mask
time=1970-01-01T00:00:02.595Z level=DEBUG msg="base init done"
time=1970-01-01T00:00:02.596Z level=DEBUG msg=initControl clm_len=4752
time=1970-01-01T00:00:02.597Z level=DEBUG msg=sendIoctl kind=2 cmd=SET_VAR len=1044
time=1970-01-01T00:00:02.598Z level=DEBUG msg=rx len=256 hdr=asyncev
time=1970-01-01T00:00:02.599Z level=DEBUG msg=tryPoll:ignore_spurious err="BDC header invalid length"
time=1970-01-01T00:00:02.610Z level=DEBUG msg=rx len=256 hdr=asyncev
time=1970-01-01T00:00:02.611Z level=DEBUG msg=tryPoll:ignore_spurious err="BDC header invalid length"
time=1970-01-01T00:00:02.622Z level=DEBUG msg=rx len=1072 hdr=ctl
time=1970-01-01T00:00:02.623Z level=DEBUG msg=sendIoctl kind=2 cmd=SET_VAR len=1044
time=1970-01-01T00:00:02.634Z level=DEBUG msg=rx len=1072 hdr=ctl
time=1970-01-01T00:00:02.635Z level=DEBUG msg=sendIoctl kind=2 cmd=SET_VAR len=1044
time=1970-01-01T00:00:02.647Z level=DEBUG msg=rx len=1072 hdr=ctl
time=1970-01-01T00:00:02.648Z level=DEBUG msg=sendIoctl kind=2 cmd=SET_VAR len=1044
time=1970-01-01T00:00:02.660Z level=DEBUG msg=rx len=1072 hdr=ctl
time=1970-01-01T00:00:02.661Z level=DEBUG msg=sendIoctl kind=2 cmd=SET_VAR len=676
time=1970-01-01T00:00:02.672Z level=DEBUG msg=rx len=704 hdr=ctl
time=1970-01-01T00:00:02.673Z level=DEBUG msg=clmload:done
time=1970-01-01T00:00:02.673Z level=DEBUG msg=sendIoctl kind=0 cmd=GET_VAR len=15
time=1970-01-01T00:00:02.684Z level=DEBUG msg=rx len=259 hdr=ctl
time=1970-01-01T00:00:02.685Z level=DEBUG msg=sendIoctl kind=2 cmd=SET_VAR len=15
time=1970-01-01T00:00:02.696Z level=DEBUG msg=rx len=259 hdr=ctl
time=1970-01-01T00:00:02.697Z level=DEBUG msg=sendIoctl kind=2 cmd=SET_VAR len=10
time=1970-01-01T00:00:02.708Z level=DEBUG msg=rx len=258 hdr=ctl
time=1970-01-01T00:00:02.709Z level=DEBUG msg=sendIoctl kind=0 cmd=GET_VAR len=14
time=1970-01-01T00:00:02.720Z level=DEBUG msg=rx len=258 hdr=ctl
time=1970-01-01T00:00:02.721Z level=DEBUG msg=MAC mac=28:cd:c1:11:70:8d
time=1970-01-01T00:00:02.721Z level=DEBUG msg=sendIoctl kind=2 cmd=SET_VAR len=20
time=1970-01-01T00:00:02.752Z level=DEBUG msg=rx len=256 hdr=ctl
time=1970-01-01T00:00:02.853Z level=DEBUG msg=sendIoctl kind=2 cmd=SET_ANTDIV len=4
time=1970-01-01T00:00:02.864Z level=DEBUG msg=rx len=256 hdr=ctl
time=1970-01-01T00:00:02.865Z level=DEBUG msg=sendIoctl kind=2 cmd=SET_VAR len=15
time=1970-01-01T00:00:02.876Z level=DEBUG msg=rx len=259 hdr=ctl
time=1970-01-01T00:00:02.977Z level=DEBUG msg=sendIoctl kind=2 cmd=SET_VAR len=19
time=1970-01-01T00:00:02.988Z level=DEBUG msg=rx len=259 hdr=ctl
time=1970-01-01T00:00:03.089Z level=DEBUG msg=sendIoctl kind=2 cmd=SET_VAR len=15
time=1970-01-01T00:00:03.100Z level=DEBUG msg=rx len=259 hdr=ctl
time=1970-01-01T00:00:03.200Z level=DEBUG msg=sendIoctl kind=2 cmd=SET_VAR len=46
time=1970-01-01T00:00:03.211Z level=DEBUG msg=rx len=258 hdr=ctl
time=1970-01-01T00:00:03.312Z level=DEBUG msg=sendIoctl kind=2 cmd=UP len=0
time=1970-01-01T00:00:03.323Z level=DEBUG msg=rx len=256 hdr=ctl
time=1970-01-01T00:00:03.424Z level=DEBUG msg=sendIoctl kind=2 cmd=SET_GMODE len=4
time=1970-01-01T00:00:03.435Z level=DEBUG msg=rx len=256 hdr=ctl
time=1970-01-01T00:00:03.436Z level=DEBUG msg=sendIoctl kind=2 cmd=SET_BAND len=4
time=1970-01-01T00:00:03.447Z level=DEBUG msg=rx len=256 hdr=ctl
time=1970-01-01T00:00:03.548Z level=DEBUG msg=set_power_management mode=PowerSave
time=1970-01-01T00:00:03.548Z level=DEBUG msg=sendIoctl kind=2 cmd=SET_VAR len=18
time=1970-01-01T00:00:03.559Z level=DEBUG msg=rx len=258 hdr=ctl
time=1970-01-01T00:00:03.560Z level=DEBUG msg=sendIoctl kind=2 cmd=SET_VAR len=15
time=1970-01-01T00:00:03.571Z level=DEBUG msg=rx len=259 hdr=ctl
time=1970-01-01T00:00:03.572Z level=DEBUG msg=sendIoctl kind=2 cmd=SET_VAR len=16
time=1970-01-01T00:00:03.583Z level=DEBUG msg=rx len=256 hdr=ctl
time=1970-01-01T00:00:03.583Z level=DEBUG msg=sendIoctl kind=2 cmd=SET_VAR len=17
time=1970-01-01T00:00:03.594Z level=DEBUG msg=rx len=257 hdr=ctl
time=1970-01-01T00:00:03.595Z level=DEBUG msg=sendIoctl kind=2 cmd=SET_PM len=4
time=1970-01-01T00:00:03.606Z level=DEBUG msg=rx len=256 hdr=ctl
time=1970-01-01T00:00:03.607Z level=INFO msg=Init:done took=1.573916s
time=1970-01-01T00:00:03.608Z level=INFO msg=cyw43439:Init duration=1.57467s
time=1970-01-01T00:00:03.608Z level=INFO msg="joining WPA secure network" ssid=TP-Link_DAA9 passlen=15
time=1970-01-01T00:00:03.609Z level=INFO msg=joinWpa2 ssid=TP-Link_DAA9 len(pass)=15
time=1970-01-01T00:00:03.610Z level=DEBUG msg=sendIoctl kind=2 cmd=SET_VAR len=19
time=1970-01-01T00:00:03.620Z level=DEBUG msg=rx len=259 hdr=ctl
time=1970-01-01T00:00:03.621Z level=DEBUG msg=sendIoctl kind=2 cmd=SET_WSEC len=4
time=1970-01-01T00:00:03.632Z level=DEBUG msg=rx len=256 hdr=ctl
time=1970-01-01T00:00:03.633Z level=DEBUG msg=sendIoctl kind=2 cmd=SET_VAR len=23
time=1970-01-01T00:00:03.644Z level=DEBUG msg=rx len=259 hdr=ctl
time=1970-01-01T00:00:03.645Z level=DEBUG msg=sendIoctl kind=2 cmd=SET_VAR len=31
time=1970-01-01T00:00:03.656Z level=DEBUG msg=rx len=259 hdr=ctl
time=1970-01-01T00:00:03.657Z level=DEBUG msg=sendIoctl kind=2 cmd=SET_VAR len=27
time=1970-01-01T00:00:03.668Z level=DEBUG msg=rx len=259 hdr=ctl
time=1970-01-01T00:00:03.768Z level=DEBUG msg=sendIoctl kind=2 cmd=SET_WSEC_PMK len=68
time=1970-01-01T00:00:03.780Z level=DEBUG msg=rx len=256 hdr=ctl
time=1970-01-01T00:00:03.780Z level=DEBUG msg=sendIoctl kind=2 cmd=SET_INFRA len=4
time=1970-01-01T00:00:03.791Z level=DEBUG msg=rx len=256 hdr=ctl
time=1970-01-01T00:00:03.792Z level=DEBUG msg=sendIoctl kind=2 cmd=SET_AUTH len=4
time=1970-01-01T00:00:03.803Z level=DEBUG msg=rx len=256 hdr=ctl
time=1970-01-01T00:00:03.804Z level=DEBUG msg=sendIoctl kind=2 cmd=SET_WPA_AUTH len=4
time=1970-01-01T00:00:03.815Z level=DEBUG msg=rx len=256 hdr=ctl
time=1970-01-01T00:00:03.816Z level=DEBUG msg=sendIoctl kind=2 cmd=SET_SSID len=36
time=1970-01-01T00:00:03.827Z level=DEBUG msg=rx len=256 hdr=ctl
time=1970-01-01T00:00:07.068Z level=DEBUG msg=rx len=260 hdr=asyncev
time=1970-01-01T00:00:07.069Z level=DEBUG msg=rx len=260 hdr=asyncev
time=1970-01-01T00:00:07.070Z level=INFO msg=rxEvent event=AUTH status=0 reason=0 flags=0 dev.linkstate=1
time=1970-01-01T00:00:07.071Z level=DEBUG msg=rx len=260 hdr=asyncev
time=1970-01-01T00:00:07.071Z level=DEBUG msg=rx len=258 hdr=asyncev
time=1970-01-01T00:00:07.072Z level=DEBUG msg=rx len=258 hdr=asyncev
time=1970-01-01T00:00:07.073Z level=DEBUG msg=rx len=260 hdr=asyncev
time=1970-01-01T00:00:07.074Z level=DEBUG msg=rx len=260 hdr=asyncev
time=1970-01-01T00:00:07.075Z level=INFO msg=rxEvent event=SET_SSID status=0 reason=0 flags=0 dev.linkstate=2
time=1970-01-01T00:00:07.075Z level=INFO msg="wifi join success!" mac=28:cd:c1:11:70:8d
time=1970-01-01T00:00:07.079Z level=INFO msg=DHCP:tx msg=Discover
time=1970-01-01T00:00:07.080Z level=DEBUG msg=UDP:send plen=325
time=1970-01-01T00:00:07.080Z level=DEBUG msg=tx len=325
time=1970-01-01T00:00:07.282Z level=DEBUG msg=rx len=260 hdr=asyncev
time=1970-01-01T00:00:08.078Z level=INFO msg="DHCP ongoing..."
time=1970-01-01T00:00:09.078Z level=INFO msg="DHCP ongoing..."
time=1970-01-01T00:00:09.322Z level=DEBUG msg=rx len=260 hdr=asyncev
time=1970-01-01T00:00:09.373Z level=DEBUG msg=rx len=260 hdr=asyncev
time=1970-01-01T00:00:10.078Z level=INFO msg="DHCP ongoing..."
time=1970-01-01T00:00:10.342Z level=DEBUG msg=rx len=260 hdr=asyncev
time=1970-01-01T00:00:11.078Z level=INFO msg="DHCP ongoing..."
time=1970-01-01T00:00:11.260Z level=DEBUG msg=rx len=260 hdr=asyncev
time=1970-01-01T00:00:12.078Z level=INFO msg="DHCP ongoing..."
time=1970-01-01T00:00:13.078Z level=INFO msg="DHCP ongoing..."
time=1970-01-01T00:00:14.078Z level=INFO msg="DHCP ongoing..."
time=1970-01-01T00:00:14.371Z level=DEBUG msg=rx len=260 hdr=asyncev
time=1970-01-01T00:00:15.078Z level=INFO msg="DHCP ongoing..."
time=1970-01-01T00:00:15.289Z level=DEBUG msg=rx len=260 hdr=asyncev
time=1970-01-01T00:00:15.340Z level=DEBUG msg=rx len=260 hdr=asyncev
time=1970-01-01T00:00:15.391Z level=DEBUG msg=rx len=260 hdr=asyncev
time=1970-01-01T00:00:16.078Z level=INFO msg="DHCP ongoing..."
time=1970-01-01T00:00:16.309Z level=DEBUG msg=rx len=260 hdr=asyncev
time=1970-01-01T00:00:16.360Z level=DEBUG msg=rx len=260 hdr=asyncev
time=1970-01-01T00:00:16.411Z level=DEBUG msg=rx len=260 hdr=asyncev
time=1970-01-01T00:00:16.462Z level=DEBUG msg=rx len=260 hdr=asyncev
time=1970-01-01T00:00:17.078Z level=INFO msg="DHCP ongoing..."
time=1970-01-01T00:00:17.329Z level=DEBUG msg=rx len=260 hdr=asyncev
time=1970-01-01T00:00:17.380Z level=DEBUG msg=rx len=260 hdr=asyncev
time=1970-01-01T00:00:17.431Z level=DEBUG msg=rx len=260 hdr=asyncev
time=1970-01-01T00:00:18.078Z level=INFO msg="DHCP ongoing..."
time=1970-01-01T00:00:18.349Z level=DEBUG msg=rx len=260 hdr=asyncev
time=1970-01-01T00:00:18.400Z level=DEBUG msg=rx len=260 hdr=asyncev
time=1970-01-01T00:00:19.078Z level=INFO msg="DHCP ongoing..."
time=1970-01-01T00:00:20.078Z level=INFO msg="DHCP ongoing..."
time=1970-01-01T00:00:21.078Z level=INFO msg="DHCP ongoing..."
time=1970-01-01T00:00:22.078Z level=INFO msg="DHCP ongoing..."
time=1970-01-01T00:00:23.078Z level=INFO msg="DHCP did not complete, assigning static IP" ip=192.168.0.101