Electron- Endless cyan fast blink

Troubleshooting
1

New to the Electron board.

Set it up well and managed to do quite a bit of OTA stuff last night.

Left it connected/powered on overnight with just the tinker app running (just forgot to turn it off!)

This morning I found it stuck on fast cyan blinking. I’ve left it running like that for several hours, left it fully unplugged and tried again, but cannot progress beyond fast cyan.

I’ve done particle doctor from CLI several times and verified that I’ve managed to update firmware versions to the latest stable (0.6.2).

The sim is correctly inserted and activated, the antenna is connected.

I’m a bit stuck. Help!

2

Bump. Anyone got any ideas?

3

@bomtarnes, have you checked your SIM data usage and status?

1 Like
4

Data usage is at zero.

Can’t seem to see a way to check sim status- don’t have access to the sim management tab beta.

5

So I deactivated sim and reactivated- during deactivation the board LED changed to white (rather than the green I expected), then when reactivated went back through rapid green flashing to rapid cyan again.

7

Was this ever resolved? I’m having the same issue on an Electron.

8

There are a few different causes of this, and a cloud debugging log will determine the actual reason.

9

Wow, Thanks so much! I uploaded and ran the test, but I can't seem to track down the exact issue, even after reviewing the readme.

These lines in particular seem to be bad. Thoughts?
../crypto/mbedtls/library/ssl_tls.c:3679: bad message length
../crypto/mbedtls/library/ssl_tls.c:4012: discarding invalid record (header)
../crypto/mbedtls/library/ssl_tls.c:7075: bad application data message

Here is the printout data if anyone can point me in the right direction.

starting tests...
turning cellular on...
deviceID=[redacted]
manufacturer=u-blox
model=SARA-U260
firmware version=23.20
ordering code=SARA-U260-00S-01
IMEI=[redacted]
IMSI=u-blox
ICCID=[redacted]
0000020652 [app] INFO: enabling trace logging
attempting to connect to the cellular network...
0000020652 [system] INFO: Sim Ready
0000020652 [system] INFO: Sim Ready
0000020654 [system] INFO: ARM_WLAN_WD 1
0000020654 [system] INFO: ARM_WLAN_WD 1
[ Modem::init ] = = = = = = = = = = = = = = =
20.653 AT send 9 "AT+CGSN\r\n"
20.674 AT read UNK 19 "\r\n352580080838373\r\n"
20.684 AT read OK 6 "\r\nOK\r\n"
20.684 AT send 9 "AT+CGMI\r\n"
20.694 AT read UNK 10 "\r\nu-blox\r\n"
20.704 AT read OK 6 "\r\nOK\r\n"
20.704 AT send 9 "AT+CGMM\r\n"
20.714 AT read UNK 13 "\r\nSARA-U260\r\n"
20.724 AT read OK 6 "\r\nOK\r\n"
20.724 AT send 9 "AT+CGMR\r\n"
20.734 AT read UNK 9 "\r\n23.20\r\n"
20.744 AT read OK 6 "\r\nOK\r\n"
20.744 AT send 9 "AT+CCID\r\n"
20.754 AT read + 30 "\r\n+CCID: 8934075500003351823\r\n"
20.764 AT read OK 6 "\r\nOK\r\n"
20.764 AT send 11 "AT+UPSV=1\r\n"
20.774 AT read OK 6 "\r\nOK\r\n"
20.774 AT send 11 "AT+CMGF=1\r\n"
20.784 AT read OK 6 "\r\nOK\r\n"
20.784 AT send 13 "AT+CNMI=2,1\r\n"
20.794 AT read OK 6 "\r\nOK\r\n"
20.794 AT send 9 "AT+CIMI\r\n"
20.804 AT read UNK 19 "\r\n214074303086653\r\n"
20.814 AT read OK 6 "\r\nOK\r\n"
[ Modem::register ] = = = = = = = = = = = = = =
20.814 AT send 10 "AT+CREG?\r\n"
20.825 AT read + 33 "\r\n+CREG: 2,5,"3E5B","C792B0F",6\r\n"
20.836 AT read OK 6 "\r\nOK\r\n"
20.836 AT send 11 "AT+CGREG?\r\n"
20.846 AT read + 39 "\r\n+CGREG: 2,5,"3E5B","C792B0F",6,"3F"\r\n"
20.857 AT read OK 6 "\r\nOK\r\n"
20.857 AT send 10 "AT+COPS?\r\n"
20.907 AT read + 23 "\r\n+COPS: 0,0,"AT&T",2\r\n"
20.917 AT read OK 6 "\r\nOK\r\n"
20.917 AT send 9 "AT+CNUM\r\n"
20.957 AT read OK 6 "\r\nOK\r\n"
20.957 AT send 8 "AT+CSQ\r\n"
20.967 AT read + 14 "\r\n+CSQ: 10,3\r\n"
20.977 AT read OK 6 "\r\nOK\r\n"
[ Modem::join ] = = = = = = = = = = = = = = = =
20.977 AT send 12 "AT+CGATT=1\r\n"
20.988 AT read OK 6 "\r\nOK\r\n"
20.988 AT send 14 "AT+UPSND=0,8\r\n"
20.998 AT read + 17 "\r\n+UPSND: 0,8,1\r\n"
21.008 AT read OK 6 "\r\nOK\r\n"
21.008 AT send 14 "AT+UPSND=0,0\r\n"
21.018 AT read + 30 "\r\n+UPSND: 0,0,"10.46.101.29"\r\n"
21.029 AT read OK 6 "\r\nOK\r\n"
0000021030 [system] INFO: ARM_WLAN_WD 2
0000021030 [system] INFO: ARM_WLAN_WD 2
0000021030 [system] INFO: CLR_WLAN_WD 1, DHCP success
0000021030 [system] INFO: CLR_WLAN_WD 1, DHCP success
connected to the cellular network in 3 milliseconds
connected to cellular network!
21.033 AT send 12 "AT+UDOPN=9\r\n"
21.084 AT read + 20 "\r\n+UDOPN: 6,"AT&T"\r\n"
21.094 AT read OK 6 "\r\nOK\r\n"
operator name=AT&T
21.094 AT send 8 "AT+CSQ\r\n"
21.105 AT read + 14 "\r\n+CSQ: 10,3\r\n"
21.115 AT read OK 6 "\r\nOK\r\n"
rssi=-93, qual=3
21.115 AT send 11 "AT+CGED=5\r\n"
21.126 AT read ERR 39 "\r\n+CME ERROR: operation not supported\r\n"
21.127 AT send 11 "AT+CGED=3\r\n"
21.147 AT read + 22 "\r\n+CGED: RAT:"UMTS",\r\n"
21.157 AT read UNK 61 "\r\nMCC:310, MNC:410, LAC:3e5b, CI:c792b0f, DLF:1087, ULF:862\r\n"
21.168 AT read OK 6 "\r\nOK\r\n"
0000021169 [app] INFO: service rat=UMTS mcc=310, mnc=410, lac=3e5b ci=c792b0f band=DCS 1800 rssi=0 dlf=1087 ulf=862
0000021169 [app] INFO: service rat=UMTS mcc=310, mnc=410, lac=3e5b ci=c792b0f band=DCS 1800 rssi=0 dlf=1087 ulf=862
21.170 AT send 20 "AT+UPING="8.8.8.8"\r\n"
21.180 AT read OK 6 "\r\nOK\r\n"
ping addr 8.8.8.8=1
21.180 AT send 31 "AT+UDNSRN=0,"device.spark.io"\r\n"
21.191 AT read + 29 "\r\n+UDNSRN: "34.207.234.253"\r\n"
21.201 AT read OK 6 "\r\nOK\r\n"
device.spark.io=34.207.234.253
connecting to cloud
0000021205 [system] INFO: Cloud: connecting
0000021205 [system] INFO: Cloud: connecting
0000021205 [system] TRACE: sparkSocket Now =-1
0000021207 [system] WARN: Public Server Address was blank, restoring.
0000021207 [system] WARN: Public Server Address was blank, restoring.
0000021208 [system] INFO: Read Server Address = type:1,domain:$id.udp.particle.io
0000021208 [system] INFO: Read Server Address = type:1,domain:$id.udp.particle.io
21.208 AT send 56 "AT+UDNSRN=0,"2d0037001847373037383634.udp.particle.io"\r\n"
23.900 AT read + 28 "\r\n+UDNSRN: "54.205.74.202"\r\n"
23.911 AT read OK 6 "\r\nOK\r\n"
0000023913 [system] INFO: Resolved host 2d0037001847373037383634.udp.particle.io to 54.205.74.202
0000023913 [system] INFO: Resolved host 2d0037001847373037383634.udp.particle.io to 54.205.74.202
On first socketSocket use, free all open sockets
23.913 AT send 15 "AT+USOCTL=0,1\r\n"
23.924 AT read + 18 "\r\n+USOCTL: 0,1,0\r\n"
23.934 AT read OK 6 "\r\nOK\r\n"
Socket handle 0 was open, now closing...
23.934 AT send 12 "AT+USOCL=0\r\n"
24.205 AT read OK 6 "\r\nOK\r\n"
Socket handle 0 was closed.
24.205 AT send 15 "AT+USOCTL=1,1\r\n"
24.206 AT read + 14 "\r\n+CIEV: 2,1\r\n"
24.216 AT read ERR 37 "\r\n+CME ERROR: operation not allowed\r\n"
24.217 AT send 15 "AT+USOCTL=2,1\r\n"
24.227 AT read ERR 37 "\r\n+CME ERROR: operation not allowed\r\n"
24.228 AT send 15 "AT+USOCTL=3,1\r\n"
24.238 AT read ERR 37 "\r\n+CME ERROR: operation not allowed\r\n"
24.239 AT send 15 "AT+USOCTL=4,1\r\n"
24.249 AT read ERR 37 "\r\n+CME ERROR: operation not allowed\r\n"
24.250 AT send 15 "AT+USOCTL=5,1\r\n"
24.260 AT read ERR 37 "\r\n+CME ERROR: operation not allowed\r\n"
24.261 AT send 15 "AT+USOCTL=6,1\r\n"
24.271 AT read ERR 37 "\r\n+CME ERROR: operation not allowed\r\n"
socketSocket(UDP)
24.272 AT send 18 "AT+USOCR=17,5684\r\n"
24.462 AT read + 13 "\r\n+USOCR: 0\r\n"
24.472 AT read OK 6 "\r\nOK\r\n"
Socket 0: handle 0 was created
0000024473 [system] TRACE: socketed udp=1, sparkSocket=0, 1
0000024473 [system] TRACE: connection attempt to 54.205.74.202:5684
0000024474 [system] INFO: Cloud socket connected
0000024474 [system] INFO: Cloud socket connected
0000024475 [system] INFO: Starting handshake: presense_announce=0
0000024475 [system] INFO: Starting handshake: presense_announce=0
0000024476 [comm.protocol.handshake] INFO: Establish secure connection
0000024476 [comm.protocol.handshake] INFO: Establish secure connection
0000024485 [comm.dtls] TRACE: restore size mismatch 1: 0/220
0000024486 [comm.dtls] INFO: (CMPL,RENEG,NO_SESS,ERR) restoreStatus=2
0000024486 [comm.dtls] INFO: (CMPL,RENEG,NO_SESS,ERR) restoreStatus=2
0000024488 [system] TRACE: send 115
socketSendTo(0,54.205.74.202,5684,,115)
24.488 AT send 37 "AT+USOST=0,"54.205.74.202",5684,115\r\n"
24.489 AT read + 67 "\r\n+UUPING: 1,32,"google-public-dns-a.google.com","8.8.8.8",54,313\r\n"
24.501 AT read > 3 "\r\n@"
24.551 AT send 115 "\x16\xfe\xfd\x00\x00\x00\x00\x00\x00\x00\x00\x00f\x01\x00\x00Z\x00\x00\x00\x00\x00\x00\x00Z\xfe\xfd\xc8\xfd\x9c\xb2\xef+\xae\xbc\x885\x03\xe2\xbc\x93\xb8;A\x1d4\xaa\x10\xe3\x88XL\xd2F\xbb\x9c\xd6\x0ef\x00\x00\x00\x04\xc0\xae\x00\xff\x01\x00\x00,\x00\r\x00\x0e\x00\f\x04\x03\x04\x01\x03\x03\x03\x01\x02\x03\x02\x01\x00\n\x00\x04\x00\x02\x00\x17\x00\v\x00\x02\x01\x00\x00\x13\x00\x02\x01\x02\x00\x14\x00\x02\x01\x02"
24.705 AT read + 17 "\r\n+USOST: 0,115\r\n"
24.715 AT read OK 6 "\r\nOK\r\n"
24.975 AT read + 67 "\r\n+UUPING: 2,32,"google-public-dns-a.google.com","8.8.8.8",54,250\r\n"
24.986 AT read + 17 "\r\n+UUSORD: 0,60\r\n"
Socket 0: handle 0 has 60 bytes pending
24.997 AT send 17 "AT+USORF=0,1024\r\n"
25.017 AT read + 98 "\r\n+USORF: 0,"54.205.74.202",5684,60,"\x16\xfe\xfd\x00\x00\x00\x00\x00\x00\x00\x00\x00/\x03\x00\x00#\x00\x00\x00\x00\x00\x00\x00#\xfe\xfd [1\xc9y\x89,KP\xf1\xb3\xbd\xd7\x14Ph@T9\x87\xd4f\\x18\xaa.\xdc\x8d0\x13\x94\xdc\x13""
25.030 AT read OK 6 "\r\nOK\r\n"
0000025031 [system] TRACE: received 60
0000025031 [system] TRACE: send 147
socketSendTo(0,54.205.74.202,5684,,147)
25.031 AT send 37 "AT+USOST=0,"54.205.74.202",5684,147\r\n"
25.042 AT read > 3 "\r\n@"
25.092 AT send 147 "\x16\xfe\xfd\x00\x00\x00\x00\x00\x00\x00\x01\x00\x86\x01\x00\x00z\x00\x01\x00\x00\x00\x00\x00z\xfe\xfd\xc8\xfd\x9c\xb2\xef+\xae\xbc\x885\x03\xe2\xbc\x93\xb8;A\x1d4\xaa\x10\xe3\x88XL\xd2F\xbb\x9c\xd6\x0ef\x00 [1\xc9y\x89,KP\xf1\xb3\xbd\xd7\x14Ph@T9\x87\xd4f\\x18\xaa.\xdc\x8d0\x13\x94\xdc\x13\x00\x04\xc0\xae\x00\xff\x01\x00\x00,\x00\r\x00\x0e\x00\f\x04\x03\x04\x01\x03\x03\x03\x01\x02\x03\x02\x01\x00\n\x00\x04\x00\x02\x00\x17\x00\v\x00\x02\x01\x00\x00\x13\x00\x02\x01\x02\x00\x14\x00\x02\x01\x02"
25.247 AT read + 17 "\r\n+USOST: 0,147\r\n"
25.257 AT read OK 6 "\r\nOK\r\n"
25.257 AT read + 14 "\r\n+CIEV: 2,2\r\n"
25.478 AT read + 67 "\r\n+UUPING: 3,32,"google-public-dns-a.google.com","8.8.8.8",54,244\r\n"
25.499 AT read + 18 "\r\n+UUSORD: 0,345\r\n"
Socket 0: handle 0 has 345 bytes pending
25.510 AT send 17 "AT+USORF=0,1024\r\n"
25.530 AT read + 157 "\r\n+USORF: 0,"54.205.74.202",5684,118,"\x16\xfe\xfd\x00\x00\x00\x00\x00\x00\x00\x01\x00i\x02\x00\x00]\x00\x01\x00\x00\x00\x00\x00]\xfe\xfd[1\xc9z\x7f0rG\x19\xd17\x8a\xbe\xc0\x91\xc4\xef*)JI\x8c1\xbe\xbcj\r\xdf-\x86\xc4\xdd 3\xe2\x98G8\x8a\x9b\x8d\x11\xd1\x82h\x1a\xd7\xda{P\x9e\x8e]?\xc9\xc4\x87\x10M\xcc\x80\xec\xaf\x8a\xc0\xae\x00\x00\x15\xff\x01\x00\x01\x00\x00\v\x00\x02\x01\x00\x00\x13\x00\x01\x02\x00\x14\x00\x01\x02\"" 25.546 AT read OK 6 "\r\nOK\r\n" 0000025547 [system] TRACE: received 118 25.546 AT read + 18 "\r\n+UUSORF: 0,227\r\n" Socket 0: handle 0 has 227 bytes pending 25.557 AT send 17 "AT+USORF=0,1024\r\n" 25.587 AT read + 208 "\r\n+USORF: 0,\"54.205.74.202\",5684,169,\"\x16\xfe\xfd\x00\x00\x00\x00\x00\x00\x00\x02\x00\x9c\f\x00\x00\x90\x00\x02\x00\x00\x00\x00\x00\x90\x03\x00\x17A\x04\nX[\x83\xe45{\x1e:\x1c\xb7\xf0\x18& \xb6\xc9\xe6\x0f\xa3N\xb6\xa7\xd4\xb2soEIn.\x87\x7f\x06#4P\xf0\x9cm\xaf\x9bT\xcd\xad\xe0\xff\xa8KO\xe6\x82s\x1e\x18\xd4\xfb\x7f\xfc\xd0t\x90\x04\x03\x00G0E\x02 '\xaeam\x9a\xa0\x03\xa9\x9bRI\xcc\xf7\xe8\xed\xa5\xba\xaeLG\xb2\x8c\xdb\x91\xc7z\x950\x84\b\xf2\x12\x02!\x00\xb3\x1au\x1cL+\xac\xf0/hhF\xc7y\x00O\xf0\xb2\x11\x02V\xb34\xb1\xf09\xc2)\xbf\xc6\xa3\xc7\"" 25.604 AT read OK 6 "\r\nOK\r\n" 0000025605 [system] TRACE: received 169 26.448 AT read + 17 "\r\n+UUSORF: 0,58\r\n" Socket 0: handle 0 has 58 bytes pending 26.459 AT send 17 "AT+USORF=0,1024\r\n" 26.459 AT read + 67 "\r\n+UUPING: 4,32,\"google-public-dns-a.google.com\",\"8.8.8.8\",54,273\r\n" 26.471 AT read + 71 "\r\n+USORF: 0,\"54.205.74.202\",5684,33,\"\x16\xfe\xfd\x00\x00\x00\x00\x00\x00\x00\x03\x00\x14\r\x00\x00\b\x00\x03\x00\x00\x00\x00\x00\b\x01@\x00\x02\x04\x03\x00\x00\"" 26.483 AT read OK 6 "\r\nOK\r\n" 0000026484 [system] TRACE: received 33 26.483 AT read + 17 "\r\n+UUSORF: 0,25\r\n" Socket 0: handle 0 has 25 bytes pending 26.494 AT send 17 "AT+USORF=0,1024\r\n" 26.504 AT read + 63 "\r\n+USORF: 0,\"54.205.74.202\",5684,25,\"\x16\xfe\xfd\x00\x00\x00\x00\x00\x00\x00\x04\x00\f\x0e\x00\x00\x00\x00\x04\x00\x00\x00\x00\x00\x00\"" 26.516 AT read OK 6 "\r\nOK\r\n" 0000026517 [system] TRACE: received 25 0000026517 [system] TRACE: send 119 socketSendTo(0,54.205.74.202,5684,,119) 26.517 AT send 37 "AT+USOST=0,\"54.205.74.202\",5684,119\r\n" 26.528 AT read > 3 "\r\n@" 26.578 AT send 119 "\x16\xfe\xfd\x00\x00\x00\x00\x00\x00\x00\x02\x00j\v\x00\x00^\x00\x02\x00\x00\x00\x00\x00^\x00\x00[0Y0\x13\x06\a*\x86H\xce=\x02\x01\x06\b*\x86H\xce=\x03\x01\a\x03B\x00\x04\xb0;\x82\xffR\x01|\x8d.l5=\xd7^P\xc2\xd4\xe6t\x14\x1a\xebs\xca\x85\xcd\x90FaZ\v\x99\xdb\xf3 \xc6\xa7\xb95\xfd\xa3\x9e\xcay\b\x99\xcf\x1d\xefl\xa1\xea8\x19\xc0v\xcbB\xaco=\xdda\x06" 26.722 AT read + 17 "\r\n+USOST: 0,119\r\n" 26.732 AT read OK 6 "\r\nOK\r\n" 0000027558 [system] TRACE: send 91 socketSendTo(0,54.205.74.202,5684,,91) 27.557 AT send 36 "AT+USOST=0,\"54.205.74.202\",5684,91\r\n" 27.568 AT read > 3 "\r\n@" 27.618 AT send 91 "\x16\xfe\xfd\x00\x00\x00\x00\x00\x00\x00\x03\x00N\x10\x00\x00B\x00\x03\x00\x00\x00\x00\x00BA\x04>A\xf8\xd4\x1c\x0f5\x1d\xcfnDp\x81\x95\xb3H\xd6\xfc\x8e5\x17\x85\x9d\xaa\xb0a\xda5\xdcA\x88\x11\xf3\x15\xf9*\r\xb0\xb9I\x9c\x8f\aq\xd0\xb5\xcf\xb3 \xcaP\xfb=\xba\xb4\xba\xda>\xde\x90\x9f\x1c\fv" 27.761 AT read + 16 "\r\n+USOST: 0,91\r\n" 27.771 AT read OK 6 "\r\nOK\r\n" 0000028213 [system] TRACE: send 100 socketSendTo(0,54.205.74.202,5684,,100) 28.212 AT send 37 "AT+USOST=0,\"54.205.74.202\",5684,100\r\n" 28.223 AT read > 3 "\r\n@" 28.273 AT send 100 "\x16\xfe\xfd\x00\x00\x00\x00\x00\x00\x00\x04\x00W\x0f\x00\x00K\x00\x04\x00\x00\x00\x00\x00K\x04\x03\x00G0E\x02 H\x84\xb7\f\xa6\x9e(2\x8bYd\xe7w\xe3\xa8\x97);\xb4\xa1M\xf4\x80\x02\xa9QNl\xc6\xe3\"\xd8\x02!\x00\x8c\x9f\n\xe9\xcf\xe0\xa3^/\x01\xc0\x15\xa7N\xa4H\xc9\x8fB\xc2\x9fx\fb\x86\x97L\xf0Xy\xc9\x84" 28.416 AT read + 17 "\r\n+USOST: 0,100\r\n" 28.426 AT read OK 6 "\r\nOK\r\n" 0000028427 [system] TRACE: send 14 socketSendTo(0,54.205.74.202,5684,,14) 28.426 AT send 36 "AT+USOST=0,\"54.205.74.202\",5684,14\r\n" 28.438 AT read > 3 "\r\n@" 28.488 AT send 14 "\x14\xfe\xfd\x00\x00\x00\x00\x00\x00\x00\x05\x00\x01\x01" 28.628 AT read + 16 "\r\n+USOST: 0,14\r\n" 28.638 AT read OK 6 "\r\nOK\r\n" 0000028640 [system] TRACE: send 53 socketSendTo(0,54.205.74.202,5684,,53) 28.639 AT send 36 "AT+USOST=0,\"54.205.74.202\",5684,53\r\n" 28.651 AT read > 3 "\r\n@" 28.701 AT send 53 "\x16\xfe\xfd\x00\x01\x00\x00\x00\x00\x00\x00\x00(\x00\x01\x00\x00\x00\x00\x00\x00\x9bA\xd9\xc1\xa8l\x80\x04\xa4\x87\xb4.\x8bu\xbem7c\x83\x00TO\x9e.\xcd\xf2L\xcc\x86\xe9\x1c\xf7" 28.843 AT read + 16 "\r\n+USOST: 0,53\r\n" 28.853 AT read OK 6 "\r\nOK\r\n" 28.853 AT read + 18 "\r\n+UUSORD: 0,345\r\n" Socket 0: handle 0 has 345 bytes pending 28.864 AT send 17 "AT+USORF=0,1024\r\n" 28.884 AT read + 157 "\r\n+USORF: 0,\"54.205.74.202\",5684,118,\"\x16\xfe\xfd\x00\x00\x00\x00\x00\x00\x00\x05\x00i\x02\x00\x00]\x00\x01\x00\x00\x00\x00\x00]\xfe\xfd[1\xc9z\x7f0rG\x19\xd17\x8a\xbe\xc0\x91\xc4\xef*)JI\x8c1\xbe\xbcj\r\xdf-\x86\xc4\xdd 3\xe2\x98G8\x8a\x9b\x8d\x11\xd1\x82h\x1a\xd7\xda{P\x9e\x8e]?\xc9\xc4\x87\x10M\xcc\x80\xec\xaf\x8a\xc0\xae\x00\x00\x15\xff\x01\x00\x01\x00\x00\v\x00\x02\x01\x00\x00\x13\x00\x01\x02\x00\x14\x00\x01\x02""
28.899 AT read OK 6 "\r\nOK\r\n"
0000028900 [system] TRACE: received 118
28.899 AT read + 18 "\r\n+UUSORF: 0,227\r\n"
Socket 0: handle 0 has 227 bytes pending
28.910 AT send 17 "AT+USORF=0,1024\r\n"
28.940 AT read + 208 "\r\n+USORF: 0,"54.205.74.202",5684,169,"\x16\xfe\xfd\x00\x00\x00\x00\x00\x00\x00\x06\x00\x9c\f\x00\x00\x90\x00\x02\x00\x00\x00\x00\x00\x90\x03\x00\x17A\x04\nX[\x83\xe45{\x1e:\x1c\xb7\xf0\x18& \xb6\xc9\xe6\x0f\xa3N\xb6\xa7\xd4\xb2soEIn.\x87\x7f\x06#4P\xf0\x9cm\xaf\x9bT\xcd\xad\xe0\xff\xa8KO\xe6\x82s\x1e\x18\xd4\xfb\x7f\xfc\xd0t\x90\x04\x03\x00G0E\x02 '\xaeam\x9a\xa0\x03\xa9\x9bRI\xcc\xf7\xe8\xed\xa5\xba\xaeLG\xb2\x8c\xdb\x91\xc7z\x950\x84\b\xf2\x12\x02!\x00\xb3\x1au\x1cL+\xac\xf0/hhF\xc7y\x00O\xf0\xb2\x11\x02V\xb34\xb1\xf09\xc2)\xbf\xc6\xa3\xc7""
28.956 AT read OK 6 "\r\nOK\r\n"
0000028957 [system] TRACE: received 169
28.956 AT read + 17 "\r\n+UUSORF: 0,58\r\n"
Socket 0: handle 0 has 58 bytes pending
28.967 AT send 17 "AT+USORF=0,1024\r\n"
28.987 AT read + 71 "\r\n+USORF: 0,"54.205.74.202",5684,33,"\x16\xfe\xfd\x00\x00\x00\x00\x00\x00\x00\a\x00\x14\r\x00\x00\b\x00\x03\x00\x00\x00\x00\x00\b\x01@\x00\x02\x04\x03\x00\x00""
28.999 AT read OK 6 "\r\nOK\r\n"
0000029000 [system] TRACE: received 33
28.999 AT read + 17 "\r\n+UUSORF: 0,25\r\n"
Socket 0: handle 0 has 25 bytes pending
29.010 AT send 17 "AT+USORF=0,1024\r\n"
29.020 AT read + 63 "\r\n+USORF: 0,"54.205.74.202",5684,25,"\x16\xfe\xfd\x00\x00\x00\x00\x00\x00\x00\b\x00\f\x0e\x00\x00\x00\x00\x04\x00\x00\x00\x00\x00\x00""
29.032 AT read OK 6 "\r\nOK\r\n"
0000029033 [system] TRACE: received 25
0000029033 [system] TRACE: send 119
socketSendTo(0,54.205.74.202,5684,,119)
29.033 AT send 37 "AT+USOST=0,"54.205.74.202",5684,119\r\n"
29.044 AT read > 3 "\r\n@"
29.094 AT send 119 "\x16\xfe\xfd\x00\x00\x00\x00\x00\x00\x00\x06\x00j\v\x00\x00^\x00\x02\x00\x00\x00\x00\x00^\x00\x00[0Y0\x13\x06\a*\x86H\xce=\x02\x01\x06\b*\x86H\xce=\x03\x01\a\x03B\x00\x04\xb0;\x82\xffR\x01|\x8d.l5=\xd7^P\xc2\xd4\xe6t\x14\x1a\xebs\xca\x85\xcd\x90FaZ\v\x99\xdb\xf3 \xc6\xa7\xb95\xfd\xa3\x9e\xcay\b\x99\xcf\x1d\xefl\xa1\xea8\x19\xc0v\xcbB\xaco=\xdda\x06"
29.238 AT read + 17 "\r\n+USOST: 0,119\r\n"
29.248 AT read OK 6 "\r\nOK\r\n"
0000029249 [system] TRACE: send 91
socketSendTo(0,54.205.74.202,5684,,91)
29.248 AT send 36 "AT+USOST=0,"54.205.74.202",5684,91\r\n"
29.250 AT read + 17 "\r\n+UUSORD: 0,67\r\n"
Socket 0: handle 0 has 67 bytes pending
29.261 AT read > 3 "\r\n@"
29.311 AT send 91 "\x16\xfe\xfd\x00\x00\x00\x00\x00\x00\x00\a\x00N\x10\x00\x00B\x00\x03\x00\x00\x00\x00\x00BA\x04>A\xf8\xd4\x1c\x0f5\x1d\xcfnDp\x81\x95\xb3H\xd6\xfc\x8e5\x17\x85\x9d\xaa\xb0a\xda5\xdcA\x88\x11\xf3\x15\xf9*\r\xb0\xb9I\x9c\x8f\aq\xd0\xb5\xcf\xb3 \xcaP\xfb=\xba\xb4\xba\xda>\xde\x90\x9f\x1c\fv"
29.454 AT read + 16 "\r\n+USOST: 0,91\r\n"
29.464 AT read OK 6 "\r\nOK\r\n"
0000029465 [system] TRACE: send 100
socketSendTo(0,54.205.74.202,5684,,100)
29.464 AT send 37 "AT+USOST=0,"54.205.74.202",5684,100\r\n"
29.475 AT read > 3 "\r\n@"
29.525 AT send 100 "\x16\xfe\xfd\x00\x00\x00\x00\x00\x00\x00\b\x00W\x0f\x00\x00K\x00\x04\x00\x00\x00\x00\x00K\x04\x03\x00G0E\x02 H\x84\xb7\f\xa6\x9e(2\x8bYd\xe7w\xe3\xa8\x97);\xb4\xa1M\xf4\x80\x02\xa9QNl\xc6\xe3"\xd8\x02!\x00\x8c\x9f\n\xe9\xcf\xe0\xa3^/\x01\xc0\x15\xa7N\xa4H\xc9\x8fB\xc2\x9fx\fb\x86\x97L\xf0Xy\xc9\x84"
29.668 AT read + 17 "\r\n+USOST: 0,100\r\n"
29.678 AT read OK 6 "\r\nOK\r\n"
0000029679 [system] TRACE: send 14
socketSendTo(0,54.205.74.202,5684,,14)
29.678 AT send 36 "AT+USOST=0,"54.205.74.202",5684,14\r\n"
29.689 AT read > 3 "\r\n@"
29.739 AT send 14 "\x14\xfe\xfd\x00\x00\x00\x00\x00\x00\x00\t\x00\x01\x01"
29.879 AT read + 16 "\r\n+USOST: 0,14\r\n"
29.889 AT read OK 6 "\r\nOK\r\n"
0000029890 [system] TRACE: send 53
socketSendTo(0,54.205.74.202,5684,,53)
29.890 AT send 36 "AT+USOST=0,"54.205.74.202",5684,53\r\n"
29.901 AT read > 3 "\r\n@"
29.951 AT send 53 "\x16\xfe\xfd\x00\x01\x00\x00\x00\x00\x00\x01\x00(\x00\x01\x00\x00\x00\x00\x00\x01\xd6\x7f\xcb\xc3d-Z\x1a\xde\x84\x19B\xecP\x8e\xc9\xafHr\x14"<3\xf0\xbc6)M\xddP\xdb\x8c"
30.093 AT read + 16 "\r\n+USOST: 0,53\r\n"
30.103 AT read OK 6 "\r\nOK\r\n"
30.113 AT send 17 "AT+USORF=0,1024\r\n"
30.123 AT read + 52 "\r\n+USORF: 0,"54.205.74.202",5684,14,"\x14\xfe\xfd\x00\x00\x00\x00\x00\x00\x00\t\x00\x01\x01""
30.134 AT read OK 6 "\r\nOK\r\n"
0000030135 [system] TRACE: received 14
30.135 AT read + 17 "\r\n+UUSORF: 0,53\r\n"
Socket 0: handle 0 has 53 bytes pending
30.146 AT send 17 "AT+USORF=0,1024\r\n"
30.166 AT read + 91 "\r\n+USORF: 0,"54.205.74.202",5684,53,"\x16\xfe\xfd\x00\x01\x00\x00\x00\x00\x00\x00\x00(\x00\x01\x00\x00\x00\x00\x00\x00\xc8o\xd5#S\x19\xe6\xff\x94F\x93e\x97\xeb\xbb\xf9U\xae\x9b\xc3\xbcf-V\x00'\xbe\xef\x05\xb9\x9d\xc2""
30.179 AT read OK 6 "\r\nOK\r\n"
0000030180 [system] TRACE: received 53
0000030181 [comm.protocol.handshake] INFO: Sending HELLO message
0000030181 [comm.protocol.handshake] INFO: Sending HELLO message
0000030182 [comm.coap] TRACE: sending message id=1 synchronously
0000030182 [comm.coap] TRACE: sending message id=1
0000030183 [system] TRACE: send 58
socketSendTo(0,54.205.74.202,5684,,58)
30.182 AT send 36 "AT+USOST=0,"54.205.74.202",5684,58\r\n"
30.193 AT read > 3 "\r\n@"
30.243 AT send 58 "\x17\xfe\xfd\x00\x01\x00\x00\x00\x00\x00\x02\x00-\x00\x01\x00\x00\x00\x00\x00\x02\x86\x8a\xffah\x06\xc6\xb5I1\xa7\xd9\x15\xb3t4\x06\xe41\x1ao\x9f\x02\x04t\xed\x1fw\x86*nJ\xe2\xef\x11\xff\xe6"
30.385 AT read + 16 "\r\n+USOST: 0,58\r\n"
30.395 AT read OK 6 "\r\nOK\r\n"
30.395 AT read + 17 "\r\n+UUSORD: 0,67\r\n"
Socket 0: handle 0 has 67 bytes pending
30.406 AT send 17 "AT+USORF=0,1024\r\n"
30.416 AT read + 52 "\r\n+USORF: 0,"54.205.74.202",5684,14,"\x14\xfe\xfd\x00\x00\x00\x00\x00\x00\x00\n\x00\x01\x01""
30.427 AT read OK 6 "\r\nOK\r\n"
0000030428 [system] TRACE: received 14
../crypto/mbedtls/library/ssl_tls.c:3679: bad message length
../crypto/mbedtls/library/ssl_tls.c:4012: discarding invalid record (header)
30.428 AT read + 17 "\r\n+UUSORF: 0,53\r\n"
Socket 0: handle 0 has 53 bytes pending
30.439 AT send 17 "AT+USORF=0,1024\r\n"
30.459 AT read + 91 "\r\n+USORF: 0,"54.205.74.202",5684,53,"\x16\xfe\xfd\x00\x01\x00\x00\x00\x00\x00\x01\x00(\x00\x01\x00\x00\x00\x00\x00\x01\xd0\xa0\xf8\xe7\x99j\xdb\xad\tQn\x8c\x7f\xd3\x8e](\x9f]\xd3&\xf1\x98\x88\x82~\x8e\t\x114\xbdJ""
30.472 AT read OK 6 "\r\nOK\r\n"
0000030473 [system] TRACE: received 53
../crypto/mbedtls/library/ssl_tls.c:7075: bad application data message
0000034534 [system] TRACE: send 58
socketSendTo(0,54.205.74.202,5684,,58)
34.533 AT send 36 "AT+USOST=0,"54.205.74.202",5684,58\r\n"
34.544 AT read > 3 "\r\n@"
34.594 AT send 58 "\x17\xfe\xfd\x00\x01\x00\x00\x00\x00\x00\x03\x00-\x00\x01\x00\x00\x00\x00\x00\x03\x9f\xe4\x7f\x12\x8c#\x0103.:A\xb3c?\x0e\xb2\x1e0\x1e\xe6\xec\xca9b\xd7\xfeW\xfb\xe5I\xc8\xbaa\xb1\xc5" 34.736 AT read + 16 "\r\n+USOST: 0,58\r\n" 34.746 AT read OK 6 "\r\nOK\r\n" 0000045777 [system] TRACE: send 58 socketSendTo(0,54.205.74.202,5684,,58) 45.776 AT send 36 "AT+USOST=0,\"54.205.74.202\",5684,58\r\n" 46.367 AT read > 3 "\r\n@" 46.417 AT send 58 "\x17\xfe\xfd\x00\x01\x00\x00\x00\x00\x00\x04\x00-\x00\x01\x00\x00\x00\x00\x00\x04j\xe3\xf8mh\xd7\xc5\x96\xa4\x90\xdc\x9b\bqOG\xfd\x82\x98H\xe7\xa0j\xa7\xa1$\xa5\xe0\xb7\xc0\x89F\x0f\xaaz{\x17" 46.559 AT read + 16 "\r\n+USOST: 0,58\r\n" 46.569 AT read OK 6 "\r\nOK\r\n" 0000063970 [system] TRACE: send 58 socketSendTo(0,54.205.74.202,5684,,58) 63.969 AT send 36 "AT+USOST=0,\"54.205.74.202\",5684,58\r\n" 64.910 AT read > 3 "\r\n@" 64.960 AT send 58 "\x17\xfe\xfd\x00\x01\x00\x00\x00\x00\x00\x05\x00-\x00\x01\x00\x00\x00\x00\x00\x05\x8a\t}\x16c\xb0F\xcc\xee<R\xe4q\xc0\xcf\xa8M\xfaa\xa3T"\xd0\x00\x17\xb5\xa4\xa8\x8d6\xe7\x97\x05\xb2\x90X"
65.102 AT read + 16 "\r\n+USOST: 0,58\r\n"
65.112 AT read OK 6 "\r\nOK\r\n"

10

bump, same problem here

11

this fixed it for me:
particle keys doctor 44006000055135343xxxxxxx

(put in Electron DFU first)

1 Like
12

If you haven’t already use device doctor to reset your device keys. I’ve found that to often fix that issue for me. The blinking cyan is the device attempting the handshake with the cloud, and if there is a problem with the key, it cannot complete the process. Use:

particle keys doctor DEVICE_ID
particle keys server

To perform the necessary change on the device. You’ll need usb access and to put the device into DFU mode (blinking yellow, by holding down mode, momentarily pressing the reset button, and waiting for 5 seconds while holding down mode still) for both commands.

13

==> ANY IDEAS WHAT IS GOING ON?

Well, I get to join the crowd! Electron was working well - online for burn-in testing of attached peripherals for the last 5 days and all happy sending data up to the cloud every 2 minutes. Went to battery mode (unplugged the USB cable) for an hour and all still happy. Plugged the USB cable into the computer and noticed fast blinking CYAN. Left the Electron in this state for an half hour or so. Battery recharged (red LED is off) but the Electron will not connect.

Tried: particle keys doctor DEVICE_ID and I received the following output

c:\Particle>particle keys doctor 2a002a001951343334363036
New Key Created!
Saved!
Saved!
attempting to add a new public key for device 2a002a001951343334363036
submitting public key succeeded!
Okay! New keys in place, your device should restart.

=> still no help

-> Firmware version 0.70
-> Tried electron-clouddebug and the following is a log

clouddebug: press letter corresponding to the command
a - enter APN for 3rd-party SIM card
k - set keep-alive value
c - show carriers at this location
t - run normal tests (occurs automatically after 10 seconds)
starting tests...
turning cellular on...
deviceID=2a002a001951343334363036
manufacturer=u-blox
model=SARA-U260
firmware version=23.20
ordering code=SARA-U260-00S-00
IMEI=353162071126016
IMSI=u-blox
ICCID=8934076500002632289
0000036811 [app] INFO: enabling trace logging
attempting to connect to the cellular network...
0000036811 [system] INFO: Sim Ready
0000036811 [system] INFO: Sim Ready
0000036812 [system] INFO: ARM_WLAN_WD 1
0000036812 [system] INFO: ARM_WLAN_WD 1

[ Modem::init ] = = = = = = = = = = = = = = =
    36.812 AT send       9 "AT+CGSN\r\n"
    36.833 AT read UNK  19 "\r\n353162071126016\r\n"
    36.843 AT read OK    6 "\r\nOK\r\n"
    36.843 AT send       9 "AT+CGMI\r\n"
    36.853 AT read UNK  10 "\r\nu-blox\r\n"
    36.863 AT read OK    6 "\r\nOK\r\n"
    36.863 AT send       9 "AT+CGMM\r\n"
    36.873 AT read UNK  13 "\r\nSARA-U260\r\n"
    36.883 AT read OK    6 "\r\nOK\r\n"
    36.883 AT send       9 "AT+CGMR\r\n"
    36.893 AT read UNK   9 "\r\n23.20\r\n"
    36.903 AT read OK    6 "\r\nOK\r\n"
    36.903 AT send       9 "AT+CCID\r\n"
    36.913 AT read  +   30 "\r\n+CCID: 8934076500002632289\r\n"
    36.924 AT read OK    6 "\r\nOK\r\n"
    36.924 AT send      11 "AT+UPSV=1\r\n"
    36.934 AT read OK    6 "\r\nOK\r\n"
    36.934 AT send      11 "AT+CMGF=1\r\n"
    36.944 AT read OK    6 "\r\nOK\r\n"
    36.944 AT send      13 "AT+CNMI=2,1\r\n"
    36.954 AT read OK    6 "\r\nOK\r\n"
    36.954 AT send       9 "AT+CIMI\r\n"
    36.964 AT read UNK  19 "\r\n214074300553624\r\n"
    36.974 AT read OK    6 "\r\nOK\r\n"

[ Modem::register ] = = = = = = = = = = = = = =
    36.974 AT send      10 "AT+CREG?\r\n"
    36.985 AT read  +   14 "\r\n+CREG: 0,0\r\n"
    36.995 AT read OK    6 "\r\nOK\r\n"
    36.995 AT send      11 "AT+CGREG?\r\n"
    37.005 AT read  +   15 "\r\n+CGREG: 0,0\r\n"
    37.015 AT read OK    6 "\r\nOK\r\n"
    37.015 AT send      12 "AT+CGREG=2\r\n"
    37.025 AT read OK    6 "\r\nOK\r\n"
    37.025 AT send      11 "AT+CREG=2\r\n"
    37.035 AT read OK    6 "\r\nOK\r\n"
    37.035 AT send      10 "AT+CREG?\r\n"
    37.045 AT read  +   14 "\r\n+CREG: 2,0\r\n"
    37.055 AT read OK    6 "\r\nOK\r\n"
    37.055 AT send      11 "AT+CGREG?\r\n"
    37.065 AT read  +   15 "\r\n+CGREG: 2,0\r\n"
    37.075 AT read OK    6 "\r\nOK\r\n"
    52.075 AT send      10 "AT+CREG?\r\n"
    52.075 AT read  +   14 "\r\n+CIEV: 2,2\r\n"
    52.086 AT read  +   31 "\r\n+CREG: 5,\"9846\",\"275313B\",6\r\n"
    52.097 AT read  +   37 "\r\n+CGREG: 5,\"9846\",\"275313B\",6,\"01\"\r\n"
    52.108 AT read  +   14 "\r\n+CIEV: 3,1\r\n"
    52.118 AT read  +   14 "\r\n+CIEV: 7,1\r\n"
    52.128 AT read  +   14 "\r\n+CIEV: 9,2\r\n"
CIEV matched: 9,2
    53.858 AT read  +   33 "\r\n+CREG: 2,5,\"9846\",\"275313B\",6\r\n"
    53.869 AT read OK    6 "\r\nOK\r\n"
    53.869 AT send      11 "AT+CGREG?\r\n"
    53.879 AT read  +   39 "\r\n+CGREG: 2,5,\"9846\",\"275313B\",6,\"01\"\r\n"
    53.890 AT read OK    6 "\r\nOK\r\n"
    53.890 AT send      10 "AT+COPS?\r\n"
    53.930 AT read  +   35 "\r\n+COPS: 0,0,\"0041005400260054\",2\r\n"
    53.941 AT read OK    6 "\r\nOK\r\n"
    53.941 AT send       9 "AT+CNUM\r\n"
    53.981 AT read OK    6 "\r\nOK\r\n"
    53.981 AT send       8 "AT+CSQ\r\n"
    53.991 AT read  +   14 "\r\n+CSQ: 13,3\r\n"
    54.001 AT read OK    6 "\r\nOK\r\n"

[ Modem::join ] = = = = = = = = = = = = = = = =
    54.001 AT send      12 "AT+CGATT=1\r\n"
    54.012 AT read OK    6 "\r\nOK\r\n"
    54.012 AT send      14 "AT+UPSND=0,8\r\n"
    54.022 AT read  +   17 "\r\n+UPSND: 0,8,0\r\n"
    54.032 AT read OK    6 "\r\nOK\r\n"
    54.032 AT send      23 "AT+UPSD=0,7,\"0.0.0.0\"\r\n"
    54.043 AT read OK    6 "\r\nOK\r\n"
    54.043 AT send      36 "AT+UPSD=0,1,\"spark.telefonica.com\"\r\n"
    54.054 AT read OK    6 "\r\nOK\r\n"
    54.054 AT send      15 "AT+UPSD=0,6,0\r\n"
    54.064 AT read OK    6 "\r\nOK\r\n"
    54.064 AT send      14 "AT+UPSDA=0,3\r\n"
    56.654 AT read ERR  42 "\r\n+CME ERROR: PDP authentication failure\r\n"
    56.655 AT send      15 "AT+UPSD=0,6,1\r\n"
    56.665 AT read OK    6 "\r\nOK\r\n"
    56.665 AT send      14 "AT+UPSDA=0,3\r\n"
    61.715 AT read ERR  42 "\r\n+CME ERROR: PDP authentication failure\r\n"
    61.716 AT send      15 "AT+UPSD=0,6,2\r\n"
    61.726 AT read OK    6 "\r\nOK\r\n"
    61.726 AT send      14 "AT+UPSDA=0,3\r\n"
    66.497 AT read OK    6 "\r\nOK\r\n"
    66.497 AT send      14 "AT+UPSND=0,0\r\n"
    66.507 AT read  +   31 "\r\n+UPSND: 0,0,\"10.42.160.137\"\r\n"
    66.518 AT read OK    6 "\r\nOK\r\n"
0000066519 [system] INFO: ARM_WLAN_WD 2
0000066519 [system] INFO: ARM_WLAN_WD 2
0000066519 [system] INFO: CLR_WLAN_WD 1, DHCP success
0000066519 [system] INFO: CLR_WLAN_WD 1, DHCP success
connected to the cellular network in 0 milliseconds
connected to cellular network!
    66.520 AT send      12 "AT+UDOPN=9\r\n"
    66.560 AT read  +   32 "\r\n+UDOPN: 6,\"0041005400260054\"\r\n"
    66.571 AT read OK    6 "\r\nOK\r\n"
operator name=0041005400260054
    66.571 AT send       8 "AT+CSQ\r\n"
    66.582 AT read  +   14 "\r\n+CSQ: 13,3\r\n"
    66.592 AT read OK    6 "\r\nOK\r\n"
rssi=-87, qual=3
    66.592 AT send      11 "AT+CGED=5\r\n"
    66.603 AT read ERR  39 "\r\n+CME ERROR: operation not supported\r\n"
    66.604 AT send      11 "AT+CGED=3\r\n"
    66.614 AT read  +   22 "\r\n+CGED: RAT:\"UMTS\",\r\n"
    66.624 AT read UNK  62 "\r\nMCC:310, MNC:410, LAC:9846, CI:275313b, DLF:4384, ULF:4159\r\n"
    66.635 AT read OK    6 "\r\nOK\r\n"
0000066636 [app] INFO: service rat=UMTS mcc=310, mnc=410, lac=9846 ci=275313b band=UMTS 850 rssi=0 dlf=4384 ulf=4159
0000066636 [app] INFO: service rat=UMTS mcc=310, mnc=410, lac=9846 ci=275313b band=UMTS 850 rssi=0 dlf=4384 ulf=4159
    66.637 AT send      20 "AT+UPING=\"8.8.8.8\"\r\n"
    66.647 AT read OK    6 "\r\nOK\r\n"
ping addr 8.8.8.8=1
    66.647 AT send      31 "AT+UDNSRN=0,\"device.spark.io\"\r\n"
device.spark.io=0.0.0.0
connecting to cloud
0000076659 [system] INFO: Cloud: connecting
0000076659 [system] INFO: Cloud: connecting
0000076659 [system] TRACE: sparkSocket Now =-1
0000076661 [system] INFO: Read Server Address = type:1,domain:$id.udp.particle.io
0000076661 [system] INFO: Read Server Address = type:1,domain:$id.udp.particle.io
    76.662 AT send      56 "AT+UDNSRN=0,\"2a002a001951343334363036.udp.particle.io\"\r\n"
   106.674 AT send      56 "AT+UDNSRN=0,\"2a002a001951343334363036.udp.particle.io\"\r\n"
   128.565 AT read ERR   9 "\r\nERROR\r\n"
   128.566 AT send      56 "AT+UDNSRN=0,\"2a002a001951343334363036.udp.particle.io\"\r\n"
0000158579 [system] ERROR: Cloud: unable to resolve IP for 2a002a001951343334363036.udp.particle.io
0000158579 [system] ERROR: Cloud: unable to resolve IP for 2a002a001951343334363036.udp.particle.io
0000158580 [system] WARN: Cloud socket connection failed: -1
0000158580 [system] WARN: Cloud socket connection failed: -1
0000158580 [system] TRACE: Internet test socket
On first socketSocket use, free all open sockets
   158.580 AT send      15 "AT+USOCTL=0,1\r\n"
   168.591 AT send      15 "AT+USOCTL=1,1\r\n"
   178.601 AT send      15 "AT+USOCTL=2,1\r\n"
   188.611 AT send      15 "AT+USOCTL=3,1\r\n"
   190.001 AT read ERR   9 "\r\nERROR\r\n"
   190.001 AT send      15 "AT+USOCTL=4,1\r\n"
   190.002 AT read  +   35 "\r\n+UUPING: 1,32,\"\",\"8.8.8.8\",0,-1\r\n"
   190.013 AT read  +   35 "\r\n+UUPING: 2,32,\"\",\"8.8.8.8\",0,-1\r\n"
   190.024 AT read  +   35 "\r\n+UUPING: 3,32,\"\",\"8.8.8.8\",0,-1\r\n"
   190.035 AT read  +   35 "\r\n+UUPING: 4,32,\"\",\"8.8.8.8\",0,-1\r\n"
   190.046 AT read ERR  37 "\r\n+CME ERROR: operation not allowed\r\n"
   190.047 AT send      15 "AT+USOCTL=5,1\r\n"
   190.047 AT read ERR  37 "\r\n+CME ERROR: operation not allowed\r\n"
   190.049 AT send      15 "AT+USOCTL=6,1\r\n"
   192.209 AT read ERR  37 "\r\n+CME ERROR: operation not allowed\r\n"
socketSocket(TCP)
   192.210 AT send      12 "AT+USOCR=6\r\n"
   192.220 AT read  +   13 "\r\n+USOCR: 0\r\n"
   192.230 AT read OK    6 "\r\nOK\r\n"
Socket 0: handle 0 was created
0000192231 [system] TRACE: socketed testSocket=0
0000192231 [system] TRACE: Connect Attempt
socketConnect(0,port:53)
   192.231 AT send      25 "AT+USOCO=0,\"8.8.8.8\",53\r\n"
0000202243 [system] TRACE: socket_connect()=fail
0000202243 [system] TRACE: Close
socketClose(0)
   202.242 AT send      12 "AT+USOCL=0\r\n"
   210.763 AT read ERR   9 "\r\nERROR\r\n"
   210.763 AT send       9 "AT+CEER\r\n"
   210.773 AT read  +   32 "\r\n+CEER: \"No report available\"\r\n"
   210.784 AT read OK    6 "\r\nOK\r\n"
socketFree(0)
0000210785 [system] WARN: Internet available, Cloud not reachable!
0000210785 [system] WARN: Internet available, Cloud not reachable!
0000210786 [system] WARN: Handling cloud error: 3
0000210786 [system] WARN: Handling cloud error: 3
0000210786 [system] INFO: Cloud: connecting
0000210786 [system] INFO: Cloud: connecting
0000210787 [system] TRACE: sparkSocket Now =-1
0000210788 [system] INFO: Read Server Address = type:1,domain:$id.udp.particle.io
0000210788 [system] INFO: Read Server Address = type:1,domain:$id.udp.particle.io
   210.789 AT send      56 "AT+UDNSRN=0,\"2a002a001951343334363036.udp.particle.io\"\r\n"
   240.801 AT send      56 "AT+UDNSRN=0,\"2a002a001951343334363036.udp.particle.io\"\r\n"
   270.813 AT send      56 "AT+UDNSRN=0,\"2a002a001951343334363036.udp.particle.io\"\r\n"
   272.034 AT read ERR   9 "\r\nERROR\r\n"
0000272036 [system] ERROR: Cloud: unable to resolve IP for 2a002a001951343334363036.udp.particle.io
0000272036 [system] ERROR: Cloud: unable to resolve IP for 2a002a001951343334363036.udp.particle.io
0000272037 [system] WARN: Cloud socket connection failed: -1
0000272037 [system] WARN: Cloud socket connection failed: -1
0000272037 [system] WARN: Resetting WLAN due to 2 failed connect attempts
0000272037 [system] WARN: Resetting WLAN due to 2 failed connect attempts
0000272038 [system] TRACE: Internet test socket
socketSocket(TCP)
   272.038 AT send      12 "AT+USOCR=6\r\n"
   282.048 AT send      15 "AT+USOCTL=0,1\r\n"
   292.058 AT send      15 "AT+USOCTL=1,1\r\n"
   302.068 AT send      15 "AT+USOCTL=2,1\r\n"
   312.078 AT send      15 "AT+USOCTL=3,1\r\n"
   322.088 AT send      15 "AT+USOCTL=4,1\r\n"
   332.098 AT send      15 "AT+USOCTL=5,1\r\n"
   333.058 AT read ERR   9 "\r\nERROR\r\n"
   333.058 AT send      15 "AT+USOCTL=6,1\r\n"
   333.068 AT read ERR  37 "\r\n+CME ERROR: operation not allowed\r\n"
0000333070 [system] TRACE: socketed testSocket=-1
0000333070 [system] WARN: Internet Test Failed!
0000333070 [system] WARN: Internet Test Failed!
0000333070 [system] WARN: Resetting WLAN due to 2 failed connect attempts
0000333070 [system] WARN: Resetting WLAN due to 2 failed connect attempts
0000333071 [system] WARN: Handling cloud error: 2
0000333071 [system] WARN: Handling cloud error: 2
0000333072 [system] WARN: Resetting WLAN due to SPARK_WLAN_RESET
0000333072 [system] WARN: Resetting WLAN due to SPARK_WLAN_RESET

[ Modem::disconnect ] = = = = = = = = = = = = =
   333.072 AT send      14 "AT+UPSDA=0,4\r\n"
   333.603 AT read OK    6 "\r\nOK\r\n"

[ Modem::detach ] = = = = = = = = = = = = = = =
   333.603 AT send      12 "AT+CGATT=0\r\n"
   335.964 AT read OK    6 "\r\nOK\r\n"

[ Modem::detach ] = = = = = = = = = = = = = = =
   335.964 AT send      12 "AT+CGATT=0\r\n"
   335.965 AT read  +   13 "\r\n+CGREG: 0\r\n"
   335.975 AT read  +   14 "\r\n+CIEV: 9,1\r\n"
CIEV matched: 9,1
   335.985 AT read OK    6 "\r\nOK\r\n"

[ Modem::powerOff ] = = = = = = = = = = = = = =
   335.985 AT send      12 "AT+CPWROFF\r\n"
   337.456 AT read OK    6 "\r\nOK\r\n"

[ Modem::powerOn ] = = = = = = = = = = = = = =
   337.766 AT send       4 "AT\r\n"
   339.086 AT send       4 "AT\r\n"
   340.406 AT send       4 "AT\r\n"
   341.726 AT send       4 "AT\r\n"
   341.736 AT read UNK   3 "AT\r"
   341.746 AT read OK    6 "\r\nOK\r\n"
   341.956 AT send       7 "AT E0\r\n"
   341.966 AT read UNK   6 "AT E0\r"
   341.976 AT read OK    6 "\r\nOK\r\n"
   341.976 AT send      11 "AT+CMEE=2\r\n"
   341.986 AT read OK    6 "\r\nOK\r\n"
   341.986 AT send      19 "AT+CMER=1,0,0,2,1\r\n"
   341.997 AT read OK    6 "\r\nOK\r\n"
   341.997 AT send      15 "AT+IPR=115200\r\n"
   342.007 AT read OK    6 "\r\nOK\r\n"
   342.107 AT send      10 "AT+CPIN?\r\n"
   342.117 AT read ERR  24 "\r\n+CME ERROR: SIM busy\r\n"
   343.127 AT send      10 "AT+CPIN?\r\n"
   343.137 AT read  +   16 "\r\n+CPIN: READY\r\n"
   343.147 AT read OK    6 "\r\nOK\r\n"
0000343148 [system] INFO: Sim Ready
0000343148 [system] INFO: Sim Ready
0000343149 [system] INFO: ARM_WLAN_WD 1
0000343149 [system] INFO: ARM_WLAN_WD 1

[ Modem::init ] = = = = = = = = = = = = = = =
   343.148 AT send       9 "AT+CGSN\r\n"
   343.169 AT read UNK  19 "\r\n353162071126016\r\n"
   343.179 AT read OK    6 "\r\nOK\r\n"
   343.179 AT send       9 "AT+CGMI\r\n"
   343.189 AT read UNK  10 "\r\nu-blox\r\n"
   343.199 AT read OK    6 "\r\nOK\r\n"
   343.199 AT send       9 "AT+CGMM\r\n"
   343.209 AT read UNK  13 "\r\nSARA-U260\r\n"
   343.219 AT read OK    6 "\r\nOK\r\n"
   343.219 AT send       9 "AT+CGMR\r\n"
   343.229 AT read UNK   9 "\r\n23.20\r\n"
   343.239 AT read OK    6 "\r\nOK\r\n"
   343.239 AT send       9 "AT+CCID\r\n"
   343.249 AT read  +   30 "\r\n+CCID: 8934076500002632289\r\n"
   343.260 AT read OK    6 "\r\nOK\r\n"
   343.260 AT send      11 "AT+UPSV=1\r\n"
   343.270 AT read OK    6 "\r\nOK\r\n"
   343.270 AT send      11 "AT+CMGF=1\r\n"
   343.280 AT read OK    6 "\r\nOK\r\n"
   343.280 AT send      13 "AT+CNMI=2,1\r\n"
   343.290 AT read OK    6 "\r\nOK\r\n"
   343.290 AT send       9 "AT+CIMI\r\n"
   343.300 AT read UNK  19 "\r\n214074300553624\r\n"
   343.310 AT read OK    6 "\r\nOK\r\n"

[ Modem::register ] = = = = = = = = = = = = = =
   343.310 AT send      10 "AT+CREG?\r\n"
   343.321 AT read  +   14 "\r\n+CREG: 0,0\r\n"
   343.331 AT read OK    6 "\r\nOK\r\n"
   343.331 AT send      11 "AT+CGREG?\r\n"
   343.341 AT read  +   15 "\r\n+CGREG: 0,0\r\n"
   343.351 AT read OK    6 "\r\nOK\r\n"
   343.351 AT send      12 "AT+CGREG=2\r\n"
   343.361 AT read OK    6 "\r\nOK\r\n"
   343.361 AT send      11 "AT+CREG=2\r\n"
   343.371 AT read OK    6 "\r\nOK\r\n"
   343.371 AT send      10 "AT+CREG?\r\n"
   343.381 AT read  +   14 "\r\n+CREG: 2,0\r\n"
   343.391 AT read OK    6 "\r\nOK\r\n"
   343.391 AT send      11 "AT+CGREG?\r\n"
   343.401 AT read  +   15 "\r\n+CGREG: 2,0\r\n"
   343.411 AT read OK    6 "\r\nOK\r\n"
   358.411 AT send      10 "AT+CREG?\r\n"
   358.411 AT read  +   14 "\r\n+CIEV: 2,2\r\n"
   358.422 AT read  +   31 "\r\n+CREG: 5,\"9846\",\"275313B\",6\r\n"
   358.433 AT read  +   37 "\r\n+CGREG: 5,\"9846\",\"275313B\",6,\"01\"\r\n"
   358.444 AT read  +   14 "\r\n+CIEV: 3,1\r\n"
   358.454 AT read  +   14 "\r\n+CIEV: 7,1\r\n"
   358.464 AT read  +   14 "\r\n+CIEV: 9,2\r\n"
CIEV matched: 9,2
   359.784 AT read  +   33 "\r\n+CREG: 2,5,\"9846\",\"275313B\",6\r\n"
   359.795 AT read OK    6 "\r\nOK\r\n"
   359.795 AT send      11 "AT+CGREG?\r\n"
   359.805 AT read  +   39 "\r\n+CGREG: 2,5,\"9846\",\"275313B\",6,\"01\"\r\n"
   359.816 AT read OK    6 "\r\nOK\r\n"
   359.816 AT send      10 "AT+COPS?\r\n"
   359.856 AT read  +   35 "\r\n+COPS: 0,0,\"0041005400260054\",2\r\n"
   359.867 AT read OK    6 "\r\nOK\r\n"
   359.867 AT send       9 "AT+CNUM\r\n"
   359.907 AT read OK    6 "\r\nOK\r\n"
   359.907 AT send       8 "AT+CSQ\r\n"
   359.917 AT read  +   14 "\r\n+CSQ: 14,3\r\n"
   359.927 AT read OK    6 "\r\nOK\r\n"

[ Modem::join ] = = = = = = = = = = = = = = = =
   359.927 AT send      12 "AT+CGATT=1\r\n"
   359.938 AT read OK    6 "\r\nOK\r\n"
   359.938 AT send      14 "AT+UPSND=0,8\r\n"
   359.948 AT read  +   17 "\r\n+UPSND: 0,8,0\r\n"
   359.958 AT read OK    6 "\r\nOK\r\n"
   359.958 AT send      23 "AT+UPSD=0,7,\"0.0.0.0\"\r\n"
   359.969 AT read OK    6 "\r\nOK\r\n"
   359.969 AT send      36 "AT+UPSD=0,1,\"spark.telefonica.com\"\r\n"
   359.980 AT read OK    6 "\r\nOK\r\n"
   359.980 AT send      15 "AT+UPSD=0,6,0\r\n"
   359.990 AT read OK    6 "\r\nOK\r\n"
   359.990 AT send      14 "AT+UPSDA=0,3\r\n"
   364.070 AT read OK    6 "\r\nOK\r\n"
   364.070 AT send      14 "AT+UPSND=0,0\r\n"
   364.080 AT read  +   31 "\r\n+UPSND: 0,0,\"10.41.182.195\"\r\n"
   364.091 AT read OK    6 "\r\nOK\r\n"
0000364092 [system] INFO: ARM_WLAN_WD 2
0000364092 [system] INFO: ARM_WLAN_WD 2
0000364092 [system] INFO: CLR_WLAN_WD 1, DHCP success
0000364092 [system] INFO: CLR_WLAN_WD 1, DHCP success
0000364093 [system] INFO: Cloud: connecting
0000364093 [system] INFO: Cloud: connecting
0000364094 [system] TRACE: sparkSocket Now =-1
0000364095 [system] INFO: Read Server Address = type:1,domain:$id.udp.particle.io
0000364095 [system] INFO: Read Server Address = type:1,domain:$id.udp.particle.io
   364.096 AT send      56 "AT+UDNSRN=0,\"2a002a001951343334363036.udp.particle.io\"\r\n"
   394.108 AT send      56 "AT+UDNSRN=0,\"2a002a001951343334363036.udp.particle.io\"\r\n"
   424.120 AT send      56 "AT+UDNSRN=0,\"2a002a001951343334363036.udp.particle.io\"\r\n"
   426.631 AT read ERR   9 "\r\nERROR\r\n"
0000426633 [system] ERROR: Cloud: unable to resolve IP for 2a002a001951343334363036.udp.particle.io
0000426633 [system] ERROR: Cloud: unable to resolve IP for 2a002a001951343334363036.udp.particle.io
0000426634 [system] WARN: Cloud socket connection failed: -1
0000426634 [system] WARN: Cloud socket connection failed: -1
0000426635 [system] TRACE: Internet test socket
socketSocket(TCP)
   426.634 AT send      12 "AT+USOCR=6\r\n"
   426.645 AT read  +   13 "\r\n+USOCR: 0\r\n"
   426.655 AT read OK    6 "\r\nOK\r\n"
Socket 0: handle 0 was created
0000426656 [system] TRACE: socketed testSocket=0
0000426656 [system] TRACE: Connect Attempt
socketConnect(0,port:53)
   426.656 AT send      25 "AT+USOCO=0,\"8.8.8.8\",53\r\n"
0000436668 [system] TRACE: socket_connect()=fail
0000436668 [system] TRACE: Close
socketClose(0)
   436.667 AT send      12 "AT+USOCL=0\r\n"
   445.188 AT read ERR   9 "\r\nERROR\r\n"
   445.188 AT send       9 "AT+CEER\r\n"
   445.198 AT read  +   32 "\r\n+CEER: \"No report available\"\r\n"
   445.208 AT read OK    6 "\r\nOK\r\n"
socketFree(0)
0000445209 [system] WARN: Internet available, Cloud not reachable!
0000445209 [system] WARN: Internet available, Cloud not reachable!
0000445210 [system] WARN: Handling cloud error: 3
0000445210 [system] WARN: Handling cloud error: 3
0000445211 [system] INFO: Cloud: connecting
0000445211 [system] INFO: Cloud: connecting
0000445211 [system] TRACE: sparkSocket Now =-1
0000445213 [system] INFO: Read Server Address = type:1,domain:$id.udp.particle.io
0000445213 [system] INFO: Read Server Address = type:1,domain:$id.udp.particle.io
   445.213 AT send      56 "AT+UDNSRN=0,\"2a002a001951343334363036.udp.particle.io\"\r\n"
   475.226 AT send      56 "AT+UDNSRN=0,\"2a002a001951343334363036.udp.particle.io\"\r\n"
   505.238 AT send      56 "AT+UDNSRN=0,\"2a002a001951343334363036.udp.particle.io\"\r\n"
   507.739 AT read ERR   9 "\r\nERROR\r\n"
0000507741 [system] ERROR: Cloud: unable to resolve IP for 2a002a001951343334363036.udp.particle.io
0000507741 [system] ERROR: Cloud: unable to resolve IP for 2a002a001951343334363036.udp.particle.io
0000507742 [system] WARN: Cloud socket connection failed: -1
0000507742 [system] WARN: Cloud socket connection failed: -1
0000507743 [system] WARN: Resetting WLAN due to 2 failed connect attempts
0000507743 [system] WARN: Resetting WLAN due to 2 failed connect attempts
0000507743 [system] TRACE: Internet test socket
socketSocket(TCP)
   507.743 AT send      12 "AT+USOCR=6\r\n"
   507.753 AT read  +   13 "\r\n+USOCR: 0\r\n"
   507.763 AT read OK    6 "\r\nOK\r\n"
Socket 0: handle 0 was created
0000507764 [system] TRACE: socketed testSocket=0
0000507764 [system] TRACE: Connect Attempt
socketConnect(0,port:53)
   507.764 AT send      25 "AT+USOCO=0,\"8.8.8.8\",53\r\n"
0000517776 [system] TRACE: socket_connect()=fail
0000517776 [system] TRACE: Close
socketClose(0)
   517.775 AT send      12 "AT+USOCL=0\r\n"
   526.286 AT read ERR   9 "\r\nERROR\r\n"
   526.286 AT send       9 "AT+CEER\r\n"
   526.296 AT read  +   32 "\r\n+CEER: \"No report available\"\r\n"
   526.307 AT read OK    6 "\r\nOK\r\n"
socketFree(0)
0000526308 [system] WARN: Internet available, Cloud not reachable!
0000526308 [system] WARN: Internet available, Cloud not reachable!
0000526309 [system] WARN: Handling cloud error: 3
0000526309 [system] WARN: Handling cloud error: 3
0000526310 [system] WARN: Resetting WLAN due to SPARK_WLAN_RESET
0000526310 [system] WARN: Resetting WLAN due to SPARK_WLAN_RESET

[ Modem::disconnect ] = = = = = = = = = = = = =
   526.310 AT send      14 "AT+UPSDA=0,4\r\n"
   526.850 AT read OK    6 "\r\nOK\r\n"

[ Modem::detach ] = = = = = = = = = = = = = = =
   526.850 AT send      12 "AT+CGATT=0\r\n"
   526.851 AT read  +   14 "\r\n+UUSOCL: 0\r\n"
Socket -1: handle 0 closed by remote host
   529.262 AT read OK    6 "\r\nOK\r\n"

[ Modem::detach ] = = = = = = = = = = = = = = =
   529.262 AT send      12 "AT+CGATT=0\r\n"
   529.263 AT read  +   13 "\r\n+CGREG: 0\r\n"
   529.273 AT read  +   14 "\r\n+CIEV: 9,1\r\n"
CIEV matched: 9,1
   529.283 AT read OK    6 "\r\nOK\r\n"

[ Modem::powerOff ] = = = = = = = = = = = = = =
   529.283 AT send      12 "AT+CPWROFF\r\n"
   530.744 AT read OK    6 "\r\nOK\r\n"

[ Modem::powerOn ] = = = = = = = = = = = = = =
   531.054 AT send       4 "AT\r\n"
   532.374 AT send       4 "AT\r\n"
   533.694 AT send       4 "AT\r\n"
   535.014 AT send       4 "AT\r\n"
   535.024 AT read UNK   3 "AT\r"
   535.034 AT read OK    6 "\r\nOK\r\n"
   535.244 AT send       7 "AT E0\r\n"
   535.254 AT read UNK   6 "AT E0\r"
   535.264 AT read OK    6 "\r\nOK\r\n"
   535.264 AT send      11 "AT+CMEE=2\r\n"
   535.274 AT read OK    6 "\r\nOK\r\n"
   535.274 AT send      19 "AT+CMER=1,0,0,2,1\r\n"
   535.285 AT read OK    6 "\r\nOK\r\n"
   535.285 AT send      15 "AT+IPR=115200\r\n"
   535.296 AT read OK    6 "\r\nOK\r\n"
   535.396 AT send      10 "AT+CPIN?\r\n"
   535.406 AT read ERR  24 "\r\n+CME ERROR: SIM busy\r\n"
   536.416 AT send      10 "AT+CPIN?\r\n"
   536.426 AT read  +   16 "\r\n+CPIN: READY\r\n"
   536.436 AT read OK    6 "\r\nOK\r\n"
0000536437 [system] INFO: Sim Ready
0000536437 [system] INFO: Sim Ready
0000536438 [system] INFO: ARM_WLAN_WD 1
0000536438 [system] INFO: ARM_WLAN_WD 1

[ Modem::init ] = = = = = = = = = = = = = = =
   536.437 AT send       9 "AT+CGSN\r\n"
   536.458 AT read UNK  19 "\r\n353162071126016\r\n"
   536.468 AT read OK    6 "\r\nOK\r\n"
   536.468 AT send       9 "AT+CGMI\r\n"
   536.478 AT read UNK  10 "\r\nu-blox\r\n"
   536.488 AT read OK    6 "\r\nOK\r\n"
   536.488 AT send       9 "AT+CGMM\r\n"
   536.498 AT read UNK  13 "\r\nSARA-U260\r\n"
   536.508 AT read OK    6 "\r\nOK\r\n"
   536.508 AT send       9 "AT+CGMR\r\n"
   536.518 AT read UNK   9 "\r\n23.20\r\n"
   536.528 AT read OK    6 "\r\nOK\r\n"
   536.528 AT send       9 "AT+CCID\r\n"
   536.538 AT read  +   30 "\r\n+CCID: 8934076500002632289\r\n"
   536.549 AT read OK    6 "\r\nOK\r\n"
   536.549 AT send      11 "AT+UPSV=1\r\n"
   536.559 AT read OK    6 "\r\nOK\r\n"
   536.559 AT send      11 "AT+CMGF=1\r\n"
   536.569 AT read OK    6 "\r\nOK\r\n"
   536.569 AT send      13 "AT+CNMI=2,1\r\n"
   536.579 AT read OK    6 "\r\nOK\r\n"
   536.579 AT send       9 "AT+CIMI\r\n"
   536.589 AT read UNK  19 "\r\n214074300553624\r\n"
   536.599 AT read OK    6 "\r\nOK\r\n"

[ Modem::register ] = = = = = = = = = = = = = =
   536.599 AT send      10 "AT+CREG?\r\n"
   536.610 AT read  +   14 "\r\n+CREG: 0,0\r\n"
   536.620 AT read OK    6 "\r\nOK\r\n"
   536.620 AT send      11 "AT+CGREG?\r\n"
   536.630 AT read  +   15 "\r\n+CGREG: 0,0\r\n"
   536.640 AT read OK    6 "\r\nOK\r\n"
   536.640 AT send      12 "AT+CGREG=2\r\n"
   536.650 AT read OK    6 "\r\nOK\r\n"
   536.650 AT send      11 "AT+CREG=2\r\n"
   536.660 AT read OK    6 "\r\nOK\r\n"
   536.660 AT send      10 "AT+CREG?\r\n"
   536.670 AT read  +   14 "\r\n+CREG: 2,0\r\n"
   536.680 AT read OK    6 "\r\nOK\r\n"
   536.680 AT send      11 "AT+CGREG?\r\n"
   536.690 AT read  +   15 "\r\n+CGREG: 2,0\r\n"
   536.700 AT read OK    6 "\r\nOK\r\n"
   551.700 AT send      10 "AT+CREG?\r\n"
   551.700 AT read  +   14 "\r\n+CIEV: 2,2\r\n"
   551.711 AT read  +   31 "\r\n+CREG: 5,\"9846\",\"275313B\",6\r\n"
   551.722 AT read  +   37 "\r\n+CGREG: 5,\"9846\",\"275313B\",6,\"01\"\r\n"
   551.733 AT read  +   14 "\r\n+CIEV: 3,1\r\n"
   551.743 AT read  +   14 "\r\n+CIEV: 7,1\r\n"
   551.753 AT read  +   14 "\r\n+CIEV: 9,2\r\n"
CIEV matched: 9,2
   553.083 AT read  +   33 "\r\n+CREG: 2,5,\"9846\",\"275313B\",6\r\n"
   553.094 AT read OK    6 "\r\nOK\r\n"
   553.094 AT send      11 "AT+CGREG?\r\n"
   553.104 AT read  +   39 "\r\n+CGREG: 2,5,\"9846\",\"275313B\",6,\"01\"\r\n"
   553.115 AT read OK    6 "\r\nOK\r\n"
   553.115 AT send      10 "AT+COPS?\r\n"
   553.155 AT read  +   35 "\r\n+COPS: 0,0,\"0041005400260054\",2\r\n"
   553.166 AT read OK    6 "\r\nOK\r\n"
   553.166 AT send       9 "AT+CNUM\r\n"
   553.206 AT read OK    6 "\r\nOK\r\n"
   553.206 AT send       8 "AT+CSQ\r\n"
   553.216 AT read  +   14 "\r\n+CSQ: 13,3\r\n"
   553.226 AT read OK    6 "\r\nOK\r\n"

[ Modem::join ] = = = = = = = = = = = = = = = =
   553.226 AT send      12 "AT+CGATT=1\r\n"
   553.237 AT read OK    6 "\r\nOK\r\n"
   553.237 AT send      14 "AT+UPSND=0,8\r\n"
   553.247 AT read  +   17 "\r\n+UPSND: 0,8,0\r\n"
   553.257 AT read OK    6 "\r\nOK\r\n"
   553.257 AT send      23 "AT+UPSD=0,7,\"0.0.0.0\"\r\n"
   553.268 AT read OK    6 "\r\nOK\r\n"
   553.268 AT send      36 "AT+UPSD=0,1,\"spark.telefonica.com\"\r\n"
   553.279 AT read OK    6 "\r\nOK\r\n"
   553.279 AT send      15 "AT+UPSD=0,6,0\r\n"
   553.290 AT read OK    6 "\r\nOK\r\n"
   553.290 AT send      14 "AT+UPSDA=0,3\r\n"
   555.730 AT read ERR  42 "\r\n+CME ERROR: PDP authentication failure\r\n"
   555.731 AT send      15 "AT+UPSD=0,6,1\r\n"
   555.741 AT read OK    6 "\r\nOK\r\n"
   555.741 AT send      14 "AT+UPSDA=0,3\r\n"
   560.071 AT read OK    6 "\r\nOK\r\n"
   560.071 AT send      14 "AT+UPSND=0,0\r\n"
   560.081 AT read  +   31 "\r\n+UPSND: 0,0,\"10.41.182.195\"\r\n"
   560.092 AT read OK    6 "\r\nOK\r\n"
0000560093 [system] INFO: ARM_WLAN_WD 2
0000560093 [system] INFO: ARM_WLAN_WD 2
0000560093 [system] INFO: CLR_WLAN_WD 1, DHCP success
0000560093 [system] INFO: CLR_WLAN_WD 1, DHCP success
0000560094 [system] INFO: Cloud: connecting
0000560094 [system] INFO: Cloud: connecting
0000560095 [system] TRACE: sparkSocket Now =-1
0000560096 [system] INFO: Read Server Address = type:1,domain:$id.udp.particle.io
0000560096 [system] INFO: Read Server Address = type:1,domain:$id.udp.particle.io
   560.096 AT send      56 "AT+UDNSRN=0,\"2a002a001951343334363036.udp.particle.io\"\r\n"
   590.109 AT send      56 "AT+UDNSRN=0,\"2a002a001951343334363036.udp.particle.io\"\r\n"
   620.121 AT send      56 "AT+UDNSRN=0,\"2a002a001951343334363036.udp.particle.io\"\r\n"
   621.622 AT read ERR   9 "\r\nERROR\r\n"
0000621624 [system] ERROR: Cloud: unable to resolve IP for 2a002a001951343334363036.udp.particle.io
0000621624 [system] ERROR: Cloud: unable to resolve IP for 2a002a001951343334363036.udp.particle.io
0000621625 [system] WARN: Cloud socket connection failed: -1
0000621625 [system] WARN: Cloud socket connection failed: -1
0000621625 [system] TRACE: Internet test socket
socketSocket(TCP)
   621.625 AT send      12 "AT+USOCR=6\r\n"
   621.635 AT read  +   13 "\r\n+USOCR: 0\r\n"
   621.645 AT read OK    6 "\r\nOK\r\n"
Socket 0: handle 0 was created
0000621646 [system] TRACE: socketed testSocket=0
0000621646 [system] TRACE: Connect Attempt
socketConnect(0,port:53)
   621.646 AT send      25 "AT+USOCO=0,\"8.8.8.8\",53\r\n"
0000631658 [system] TRACE: socket_connect()=fail
0000631658 [system] TRACE: Close
socketClose(0)
   631.657 AT send      12 "AT+USOCL=0\r\n"
   640.178 AT read ERR   9 "\r\nERROR\r\n"
   640.178 AT send       9 "AT+CEER\r\n"
   640.188 AT read  +   32 "\r\n+CEER: \"No report available\"\r\n"
   640.199 AT read OK    6 "\r\nOK\r\n"
socketFree(0)
0000640200 [system] WARN: Internet available, Cloud not reachable!
0000640200 [system] WARN: Internet available, Cloud not reachable!
0000640201 [system] WARN: Handling cloud error: 3
0000640201 [system] WARN: Handling cloud error: 3
0000640201 [system] INFO: Cloud: connecting
0000640201 [system] INFO: Cloud: connecting
0000640202 [system] TRACE: sparkSocket Now =-1
0000640203 [system] INFO: Read Server Address = type:1,domain:$id.udp.particle.io
0000640203 [system] INFO: Read Server Address = type:1,domain:$id.udp.particle.io
   640.204 AT send      56 "AT+UDNSRN=0,\"2a002a001951343334363036.udp.particle.io\"\r\n"
   670.217 AT send      56 "AT+UDNSRN=0,\"2a002a001951343334363036.udp.particle.io\"\r\n"
   700.229 AT send      56 "AT+UDNSRN=0,\"2a002a001951343334363036.udp.particle.io\"\r\n"
   702.650 AT read ERR   9 "\r\nERROR\r\n"
0000702652 [system] ERROR: Cloud: unable to resolve IP for 2a002a001951343334363036.udp.particle.io
0000702652 [system] ERROR: Cloud: unable to resolve IP for 2a002a001951343334363036.udp.particle.io
0000702653 [system] WARN: Cloud socket connection failed: -1
0000702653 [system] WARN: Cloud socket connection failed: -1
0000702653 [system] WARN: Resetting WLAN due to 2 failed connect attempts
0000702653 [system] WARN: Resetting WLAN due to 2 failed connect attempts
0000702654 [system] TRACE: Internet test socket
socketSocket(TCP)
   702.654 AT send      12 "AT+USOCR=6\r\n"
   702.664 AT read  +   13 "\r\n+USOCR: 0\r\n"
   702.674 AT read OK    6 "\r\nOK\r\n"
Socket 0: handle 0 was created
0000702675 [system] TRACE: socketed testSocket=0
0000702675 [system] TRACE: Connect Attempt
socketConnect(0,port:53)
   702.675 AT send      25 "AT+USOCO=0,\"8.8.8.8\",53\r\n"
0000712687 [system] TRACE: socket_connect()=fail
0000712687 [system] TRACE: Close
socketClose(0)
   712.686 AT send      12 "AT+USOCL=0\r\n"
   721.237 AT read ERR   9 "\r\nERROR\r\n"
   721.237 AT send       9 "AT+CEER\r\n"
   721.247 AT read  +   32 "\r\n+CEER: \"No report available\"\r\n"
   721.258 AT read OK    6 "\r\nOK\r\n"
socketFree(0)
0000721259 [system] WARN: Internet available, Cloud not reachable!
0000721259 [system] WARN: Internet available, Cloud not reachable!
0000721260 [system] WARN: Handling cloud error: 3
0000721260 [system] WARN: Handling cloud error: 3
0000721260 [system] WARN: Resetting WLAN due to SPARK_WLAN_RESET
0000721260 [system] WARN: Resetting WLAN due to SPARK_WLAN_RESET

-=-=-=-=-=-=-=-=

=> Powered off the Electron for an hour and retried with a similar log output (too long for the post)

==> ANY IDEAS WHAT IS GOING ON?

14

Could it be that you were in the area of this incident?
status.particle.io

image
image1795×833 86 KB

15

Could have been. Left the Electron running overnight and looking at UbiDots, it started logging data again about 11:58pm MST (10:58pm PST). Previous logged data was 4:06pm MST (3:06pm MST) so the outage was for approx 8 hours! Particle.io reported the outage at 12:47am MST (11:47pm PST). I checked the status on Particle.io about 11pm MST and there was no listing of the outage.

As an IoT gateway to the cloud, Particle needs to be on the ball and report incidents when they occur and alert customers immediately. Their first posting on the outage was approx 7 hours after the Electron stopped posting to UbiDots … not good when a customer wants a reliable connection to the cloud and the device is remotely deployed.

Working on a alpha release (post EVT) of a product and getting ready to deploy units to customers in the field for initial testing and review. Doesn’t look good when a developer promotes Particle’s platform as robust and the path to follow for a reliable, secure path to the cloud … and incidents like July 10th’s, with delayed reporting, occur. The charts in UbiDots show the outage and it will not be fun explaining to potential customers that outage. The Photons under test had no problem (they shouldn’t have since they are connected to the cloud via the internet).

Plan is a beta release (post DVT) with Boron for cost savings … porting of code should be minimal (I hope) and I do not want to flip hardware midstream during beta from Electron to Boron … and hoping that the September shipments are more stable than that of the initial run.

I subscribed this morning to Particle’s incident feed and will be following it

Particle needs to be “on the ball” with their services and the services provided by their partners. Particle has nice hardware but it (the hardware) is only as good as the services (customer and partner) that it depends on for delivering an IoT package that can be whole-heartedly endorsed

16

That's hard to do when the point of failure is not in their own area - which it was in this case. A 3rd party cell service provider had the issues and probably didn't inform Particle immediately.
And since Electrons are meant to not to issue a heart beat and they are also expected to be offline quite extensively it's hard to tell if the radio silence is deliberate or due to some "hidden" outage.

Actually the first entry for this incidence was at 9:58pm PDT.

Have you also scrolled down to the reported incidences area or just looked at the status bullets?
While the directly monitored services may be fully operational, Particle has no insight beyond the immedate boarders to 3rd party services other than what these 3rd party services provide. Since the incident report seems to suggest that the actual mobile carrier wasn't offline at any time but only some secondary roaming services which probably are not within Particle's reach Particle has to rely on the information provided to them.

2 Likes
17

Hi All,

Thank you for the ping, and I’m sorry about any lag in posting outage information on our status page. We have quite a few systems of continuous monitoring and metrics and alerting that will page our on-duty engineering team and update the status page automatically, but sometimes an issue misses those early-warning tests.

Last night we become aware that one of our cellular partners suffered an incident that impacted connectivity on their highly-available architecture. During this time, some electrons using impacted SIM cards were unable to connect or send data. We’re talking internally about how we can best mitigate these kinds of risks, and what other alerting systems we can put in place to detect this specific new kind of issue in the future.

Please do always feel free to ping us on the forums if you suspect an issue that isn’t reflected on the status page yet.

Thanks,
David

5 Likes