B524's not connecting to cellular network

Connectivity
1

Currently we're trying to claim a bunch of new B524's to our account. This all goes well until the point is reached where the B524 tries to connect to the cloud. At this point, the B524's keep flashing green, indicating it tries to connect, but won't proceed to connect and turn cyan. If we take an older B524 which we activated earlier, it does connect to the network like normal. Then, when we use the web IDE to flash a test sketch (the blink example for instance), it will update once and then become unable to connect to the network like the new B524's.

All our devices are running the 5.8.0 firmware and the problem showed up last weeks. The location of all the devices is in the Netherlands. We also tried making a new account and claiming a new device there to see if it would resolve the issue but this device also keeps flashing green. We're using a particle DEV-board, we confirmed the working of this board using one of the older B524's which does connect to the network.

It starts to feel like something is off with the particle service in the Netherlands but according to the particle website status board, everything should be working as supposed to. We did see that there was a SIM problem last week but it seems to be resolved. Any ideas what can be causing this issue?

2

Can you grab a couple minutes of logs from Cloud Debug | Troubleshooting | Particle and paste it here? Also, please DM me the DeviceID’s so that I can check on the SIMs.
Do you have a battery connected to the devices? If it’s trying 2G/3G it might be struggling to connect without the battery.

There should be no issues with new SIM activations.

3

Currently, we are running without battery, it's powered using a 12V supply on the barrel jack connector. We tried switching to a battery but this didn't resolve the issue unfortunately.

For sending the DeviceID's, the forum doesn't seem to allow for DMs, can i transfer them another way?

Here are some minutes of the cloud debug logs:

Cellular Global Identity: 
0000000584 [system.nm] TRACE: Request to power on the interface
0000000585 [ncp.client] TRACE: Powering modem on, ncpId: 0x62
0000000585 [net.pppncp] TRACE: NCP event 3
0000000586 [net.pppncp] TRACE: NCP power state changed: IF_POWER_STATE_POWERING_UP
0000000587 [system.nm] INFO: State changed: DISABLED -> IFACE_DOWN
0000005300 [app] INFO: Auto-connect disabled
0000011587 [net.pppncp] TRACE: NCP event 3
0000011588 [net.pppncp] TRACE: NCP power state changed: IF_POWER_STATE_UP
0000011588 [system.nm] TRACE: Interface 4 power state changed: 2
0000011589 [ncp.client] TRACE: Modem powered on
0000012590 [ncp.at] TRACE: > AT
0000012593 [ncp.at] TRACE: < OK
0000012593 [ncp.client] TRACE: NCP ready to accept AT commands
0000012594 [ncp.at] TRACE: > AT+CFUN=1,0
0000012597 [ncp.at] TRACE: < OK
0000012598 [ncp.at] TRACE: > AT+IFC=2,2
0000012602 [ncp.at] TRACE: < OK
0000012602 [ncp.at] TRACE: > AT
0000012604 [ncp.at] TRACE: < OK
0000012605 [ncp.at] TRACE: > AT+IPR=460800
0000012608 [ncp.at] TRACE: < OK
0000013609 [ncp.at] TRACE: > AT
0000013611 [ncp.at] TRACE: < OK
0000013611 [ncp.at] TRACE: > AT+CPIN?
0000013613 [ncp.at] TRACE: < +CPIN: READY
0000013614 [ncp.at] TRACE: < OK
0000013614 [ncp.at] TRACE: > AT+CCID
0000013616 [ncp.at] TRACE: < +CCID: 89883070000013786452
0000013616 [ncp.at] TRACE: < OK
0000013617 [ncp.at] TRACE: > AT+QDSIM=0
0000013618 [ncp.at] TRACE: < OK
0000013618 [ncp.at] TRACE: > AT+CMUX=0,0,7,1509,,,,,
0000013620 [ncp.at] TRACE: < OK
0000013620 [mux] INFO: Starting GSM07.10 muxer
0000013621 [mux] INFO: Openning mux channel 0
0000013622 [mux] INFO: GSM07.10 muxer thread started
0000013724 [mux] INFO: Openning mux channel 1
0000013726 [ncp.at] TRACE: > AT
0000013728 [ncp.at] TRACE: < OK
0000013728 [ncp.client] TRACE: NCP state changed: 1
0000013729 [net.pppncp] TRACE: NCP event 1
0000013730 [ncp.at] TRACE: > AT+QCFG="cmux/urcport",1
0000013732 [ncp.at] TRACE: < OK
0000013733 [ncp.at] TRACE: > AT+CGEREP=1,0
0000013735 [ncp.at] TRACE: < OK
0000013736 [ncp.at] TRACE: > AT+CFUN?
0000013739 [ncp.at] TRACE: < +CFUN: 1
0000013740 [ncp.at] TRACE: < OK
0000013741 [ncp.at] TRACE: > AT+CCID
0000013745 [app] INFO: cellular is on
0000013748 [ncp.at] TRACE: < +CCID: 89883070000013786452
0000013749 [ncp.at] TRACE: < OK
0000013751 [ncp.at] TRACE: > AT+CGSN
0000013753 [ncp.at] TRACE: < 862632048651993
0000013754 [ncp.at] TRACE: < OK
0000013756 [ncp.at] TRACE: > AT+CGMR
0000013757 [ncp.at] TRACE: < EG91EFBR06A07M4G
0000013758 [ncp.at] TRACE: < OK
0000013759 [ncp.at] TRACE: > AT+CCID
0000013762 [ncp.at] TRACE: < +CCID: 89883070000013786452
0000013763 [ncp.at] TRACE: < OK
0000013766 [ncp.at] TRACE: > AT+CGMI
0000013767 [ncp.at] TRACE: < Quectel
0000013768 [ncp.at] TRACE: < OK
0000013770 [ncp.at] TRACE: > AT+CGMM
0000013772 [ncp.at] TRACE: < EG91
0000013773 [ncp.at] TRACE: < OK
0000013775 [ncp.at] TRACE: > AT+CGMR
0000013777 [ncp.at] TRACE: < EG91EFBR06A07M4G
0000013778 [ncp.at] TRACE: < OK
0000013780 [ncp.at] TRACE: > AT+CGSN
0000013782 [ncp.at] TRACE: < 862632048651993
0000013783 [ncp.at] TRACE: < OK
0000014235 [ncp.at] TRACE: < +QIND: PB DONE
0000014766 [system.nm] INFO: State changed: IFACE_DOWN -> IFACE_REQUEST_UP
0000014769 [net.ppp.client] TRACE: PPP thread event ADM_UP data=0
0000014770 [net.ppp.client] TRACE: State NONE -> READY
0000014772 [ncp.at] TRACE: > AT+CFUN=1,0
0000014773 [system.nm] INFO: State changed: IFACE_REQUEST_UP -> IFACE_UP
0000014776 [ncp.at] TRACE: < OK
0000014777 [ncp.at] TRACE: > AT+CCID
0000014779 [ncp.at] TRACE: < +CCID: 89883070000013786452
0000014780 [ncp.at] TRACE: < OK
0000014781 [ncp.at] TRACE: > AT+CGDCONT=1,"IP","super"
0000014785 [ncp.at] TRACE: < OK
0000014786 [ncp.at] TRACE: > AT+CFUN=1,0
0000014787 [ncp.at] TRACE: < OK
0000014788 [ncp.at] TRACE: > AT+CREG=2
0000014789 [ncp.at] TRACE: < OK
0000014790 [ncp.at] TRACE: > AT+CGREG=2
0000014792 [ncp.at] TRACE: < OK
0000014793 [ncp.at] TRACE: > AT+CEREG=2
0000014794 [ncp.at] TRACE: < OK
0000014795 [ncp.client] TRACE: NCP connection state changed: 1
0000014795 [net.pppncp] TRACE: NCP event 2
0000014796 [net.pppncp] TRACE: State changed event: 1
0000014797 [ncp.at] TRACE: > AT+COPS?
0000014797 [net.ppp.client] TRACE: PPP thread event LOWER_DOWN data=0
0000014799 [ncp.at] TRACE: < +COPS: 0
0000014799 [ncp.at] TRACE: < OK
0000014800 [ncp.at] TRACE: > AT+CREG?
0000014802 [ncp.at] TRACE: < +CREG: 2,2
0000014803 [ncp.at] TRACE: < OK
0000014804 [ncp.at] TRACE: > AT+CGREG?
0000014805 [ncp.at] TRACE: < +CGREG: 2,2
0000014806 [ncp.at] TRACE: < OK
0000014807 [ncp.at] TRACE: > AT+CEREG?
0000014809 [ncp.at] TRACE: < +CEREG: 2,2
0000014809 [ncp.at] TRACE: < OK
0000017809 [ncp.at] TRACE: < +CREG: 3
0000017909 [ncp.at] TRACE: < +CEREG: 2
0000029910 [ncp.at] TRACE: > AT+CEER
0000029914 [ncp.at] TRACE: < +CEER: 0,-1
0000029914 [ncp.at] TRACE: < OK
0000029915 [ncp.at] TRACE: > AT+CMEE?
0000029917 [ncp.at] TRACE: < +CMEE: 1
0000029917 [ncp.at] TRACE: < OK
0000029918 [ncp.at] TRACE: > AT+CREG?
0000029920 [ncp.at] TRACE: < +CREG: 2,3
0000029920 [ncp.at] TRACE: < OK
0000029921 [ncp.at] TRACE: > AT+CEREG?
0000029923 [ncp.at] TRACE: < +CEREG: 2,2
0000029924 [ncp.at] TRACE: < OK
0000029924 [ncp.at] TRACE: > AT+QCSQ
0000029927 [ncp.at] TRACE: < +QCSQ: "WCDMA",77,-80,-6
0000029927 [ncp.at] TRACE: < OK
0000044928 [ncp.at] TRACE: > AT+CEER
0000044933 [ncp.at] TRACE: < +CEER: 0,-1
0000044933 [ncp.at] TRACE: < OK
0000044934 [ncp.at] TRACE: > AT+CMEE?
0000044936 [ncp.at] TRACE: < +CMEE: 1
0000044936 [ncp.at] TRACE: < OK
0000044937 [ncp.at] TRACE: > AT+CREG?
0000044939 [ncp.at] TRACE: < +CREG: 2,3
0000044939 [ncp.at] TRACE: < OK
0000044940 [ncp.at] TRACE: > AT+CEREG?
0000044942 [ncp.at] TRACE: < +CEREG: 2,2
0000044942 [ncp.at] TRACE: < OK
0000044943 [ncp.at] TRACE: > AT+QCSQ
0000044945 [ncp.at] TRACE: < +QCSQ: "WCDMA",77,-79,-5
0000044946 [ncp.at] TRACE: < OK
0000059947 [ncp.at] TRACE: > AT+CEER
0000059951 [ncp.at] TRACE: < +CEER: 0,-1
0000059951 [ncp.at] TRACE: < OK
0000059952 [ncp.at] TRACE: > AT+CMEE?
0000059954 [ncp.at] TRACE: < +CMEE: 1
0000059954 [ncp.at] TRACE: < OK
0000059955 [ncp.at] TRACE: > AT+CREG?
0000059957 [ncp.at] TRACE: < +CREG: 2,3
0000059958 [ncp.at] TRACE: < OK
0000059958 [ncp.at] TRACE: > AT+CEREG?
0000059960 [ncp.at] TRACE: < +CEREG: 2,2
0000059961 [ncp.at] TRACE: < OK
0000059962 [ncp.at] TRACE: > AT+QCSQ
0000059964 [ncp.at] TRACE: < +QCSQ: "WCDMA",70,-69,-4
0000059964 [ncp.at] TRACE: < OK
0000075065 [ncp.at] TRACE: > AT+CEER
0000075082 [ncp.at] TRACE: < +CEER: 0,-1
0000075083 [ncp.at] TRACE: < OK
0000075084 [ncp.at] TRACE: > AT+CMEE?
0000075085 [ncp.at] TRACE: < +CMEE: 1
0000075086 [ncp.at] TRACE: < OK
0000075087 [ncp.at] TRACE: > AT+CREG?
0000075088 [ncp.at] TRACE: < +CREG: 2,3
0000075089 [ncp.at] TRACE: < OK
0000075090 [ncp.at] TRACE: > AT+CEREG?
0000075091 [ncp.at] TRACE: < +CEREG: 2,2
0000075092 [ncp.at] TRACE: < OK
0000075093 [ncp.at] TRACE: > AT+QCSQ
0000075095 [ncp.at] TRACE: < +QCSQ: "WCDMA",70,-70,-3
0000075096 [ncp.at] TRACE: < OK
0000090196 [ncp.at] TRACE: > AT+CEER
0000090198 [ncp.at] TRACE: < +CEER: 0,-1
0000090198 [ncp.at] TRACE: < OK
0000090199 [ncp.at] TRACE: > AT+CMEE?
0000090201 [ncp.at] TRACE: < +CMEE: 1
0000090201 [ncp.at] TRACE: < OK
0000090202 [ncp.at] TRACE: > AT+CREG?
0000090204 [ncp.at] TRACE: < +CREG: 2,3
0000090204 [ncp.at] TRACE: < OK
0000090205 [ncp.at] TRACE: > AT+CEREG?
0000090207 [ncp.at] TRACE: < +CEREG: 2,2
0000090207 [ncp.at] TRACE: < OK
0000090208 [ncp.at] TRACE: > AT+QCSQ
0000090210 [ncp.at] TRACE: < +QCSQ: "WCDMA",70,-70,-3
0000090211 [ncp.at] TRACE: < OK
0000105212 [ncp.at] TRACE: > AT+CEER
0000105217 [ncp.at] TRACE: < +CEER: 0,-1
0000105218 [ncp.at] TRACE: < OK
0000105218 [ncp.at] TRACE: > AT+CMEE?
0000105220 [ncp.at] TRACE: < +CMEE: 1
0000105221 [ncp.at] TRACE: < OK
0000105221 [ncp.at] TRACE: > AT+CREG?
0000105223 [ncp.at] TRACE: < +CREG: 2,3
0000105224 [ncp.at] TRACE: < OK
0000105225 [ncp.at] TRACE: > AT+CEREG?
0000105226 [ncp.at] TRACE: < +CEREG: 2,2
0000105227 [ncp.at] TRACE: < OK
0000105228 [ncp.at] TRACE: > AT+QCSQ
0000105230 [ncp.at] TRACE: < +QCSQ: "WCDMA",70,-70,-3
0000105230 [ncp.at] TRACE: < OK
0000120331 [ncp.at] TRACE: > AT+CEER
0000120335 [ncp.at] TRACE: < +CEER: 5,38
0000120335 [ncp.at] TRACE: < OK
0000120336 [ncp.at] TRACE: > AT+CMEE?
0000120338 [ncp.at] TRACE: < +CMEE: 1
0000120338 [ncp.at] TRACE: < OK
0000120339 [ncp.at] TRACE: > AT+CREG?
0000120341 [ncp.at] TRACE: < +CREG: 2,3
0000120341 [ncp.at] TRACE: < OK
0000120342 [ncp.at] TRACE: > AT+CEREG?
0000120344 [ncp.at] TRACE: < +CEREG: 2,2
0000120344 [ncp.at] TRACE: < OK
0000120345 [ncp.at] TRACE: > AT+QCSQ
0000120347 [ncp.at] TRACE: < +QCSQ: "WCDMA",70,-70,-3
0000120348 [ncp.at] TRACE: < OK
0000135349 [ncp.at] TRACE: > AT+CEER
0000135355 [ncp.at] TRACE: < +CEER: 5,38
0000135355 [ncp.at] TRACE: < OK
0000135356 [ncp.at] TRACE: > AT+CMEE?
0000135358 [ncp.at] TRACE: < +CMEE: 1
0000135358 [ncp.at] TRACE: < OK
0000135359 [ncp.at] TRACE: > AT+CREG?
0000135361 [ncp.at] TRACE: < +CREG: 2,3
0000135361 [ncp.at] TRACE: < OK
0000135362 [ncp.at] TRACE: > AT+CEREG?
0000135364 [ncp.at] TRACE: < +CEREG: 2,2
0000135364 [ncp.at] TRACE: < OK
0000135365 [ncp.at] TRACE: > AT+QCSQ
0000135367 [ncp.at] TRACE: < +QCSQ: "WCDMA",70,-70,-3
0000135368 [ncp.at] TRACE: < OK
0000150369 [ncp.at] TRACE: > AT+CEER
0000150374 [ncp.at] TRACE: < +CEER: 5,38
0000150374 [ncp.at] TRACE: < OK
0000150375 [ncp.at] TRACE: > AT+CMEE?
0000150377 [ncp.at] TRACE: < +CMEE: 1
0000150377 [ncp.at] TRACE: < OK
0000150378 [ncp.at] TRACE: > AT+CREG?
0000150380 [ncp.at] TRACE: < +CREG: 2,3
0000150380 [ncp.at] TRACE: < OK
0000150381 [ncp.at] TRACE: > AT+CEREG?
0000150383 [ncp.at] TRACE: < +CEREG: 2,2
0000150384 [ncp.at] TRACE: < OK
0000150384 [ncp.at] TRACE: > AT+QCSQ
0000150387 [ncp.at] TRACE: < +QCSQ: "WCDMA",70,-70,-3
0000150387 [ncp.at] TRACE: < OK
0000152587 [ncp.at] TRACE: < +CREG: 5,"0C4E","3A69",0
0000152588 [ncp.at] TRACE: > AT+CIMI
0000152589 [ncp.at] TRACE: < +CGEV: NW DETACH
0000152592 [ncp.at] TRACE: < 234500029252236
0000152592 [ncp.at] TRACE: < OK
0000165393 [ncp.at] TRACE: > AT+CEER
0000165397 [ncp.at] TRACE: < +CEER: 5,38
0000165398 [ncp.at] TRACE: < OK
0000165398 [ncp.at] TRACE: > AT+CMEE?
0000165400 [ncp.at] TRACE: < +CMEE: 1
0000165401 [ncp.at] TRACE: < OK
0000165401 [ncp.at] TRACE: > AT+CREG?
0000165404 [ncp.at] TRACE: < +CREG: 2,5,"0C4E","3A69",0
0000165405 [ncp.at] TRACE: < OK
0000165406 [ncp.at] TRACE: > AT+CEREG?
0000165408 [ncp.at] TRACE: < +CEREG: 2,4
0000165408 [ncp.at] TRACE: < OK
0000165409 [ncp.at] TRACE: > AT+QCSQ
0000165411 [ncp.at] TRACE: < +QCSQ: "GSM",55
0000165412 [ncp.at] TRACE: < OK
0000172412 [ncp.at] TRACE: < +CGREG: 5,"0C4E","3A69",0
0000172413 [ncp.client] TRACE: NCP connection state changed: 2
0000172413 [mux] INFO: Openning mux channel 2
0000172455 [net.pppncp] TRACE: NCP event 100
0000172456 [net.pppncp] TRACE: New auth info
0000172458 [net.pppncp] TRACE: NCP event 2
0000172458 [net.pppncp] TRACE: State changed event: 2
0000172459 [net.ppp.client] TRACE: PPP thread event LOWER_UP data=0
0000172460 [net.ppp.client] TRACE: State READY -> CONNECT
0000172460 [net.ppp.client] TRACE: State CONNECT -> CONNECTING
0000172460 [ncp.at] TRACE: > AT+CIMI
0000172464 [ncp.at] TRACE: < 234500029252236
0000172464 [ncp.at] TRACE: < OK
0000173467 [ncp.at] TRACE: > AT
0000173468 [ncp.at] TRACE: < OK
0000173469 [ncp.at] TRACE: > ATO
0000173472 [ncp.at] TRACE: < NO CARRIER
0000173473 [ncp.at] TRACE: > ATD*99***1#
0000173487 [ncp.at] TRACE: < CONNECT 150000000
0000173488 [net.ppp.client] TRACE: PPP phase -> Initialize
0000173488 [net.ppp.client] TRACE: PPP phase -> Establish
0000173489 [lwip.ppp] TRACE: sent [LCP ConfReq id=0x1 <asyncmap 0x0> <magic 0x58549891> <pcomp> <ac
0000173497 [lwip.ppp] TRACE: rcvd [LCP ConfReq id=0x0 <asyncmap 0x0> <auth chap MD5> <magic 0x5006b
0000173499 [lwip.ppp] TRACE: sent [LCP ConfNak id=0x0 <auth pap>]
0000173500 [lwip.ppp] TRACE: rcvd [LCP ConfAck id=0x1 <asyncmap 0x0> <magic 0x58549891> <pcomp> <ac
0000173504 [lwip.ppp] TRACE: rcvd [LCP ConfReq id=0x1 <asyncmap 0x0> <auth pap> <magic 0x5006b53d> 
0000173505 [lwip.ppp] TRACE: sent [LCP ConfAck id=0x1 <asyncmap 0x0> <auth pap> <magic 0x5006b53d> 
0000173506 [lwip.ppp] TRACE: sent [LCP EchoReq id=0x0 magic=0x58549891]
0000173507 [net.ppp.client] TRACE: PPP phase -> Authenticate
0000173508 [lwip.ppp] TRACE: sent [PAP AuthReq id=0x1 user="" password=""]
0000173511 [lwip.ppp] TRACE: rcvd [LCP DiscReq id=0x2 magic=0x5006b53d]
0000173513 [lwip.ppp] TRACE: rcvd [LCP EchoRep id=0x0 magic=0x5006b53d 58 54 98 91]
0000173515 [lwip.ppp] TRACE: rcvd [PAP AuthAck id=0x1 ""]
0000173515 [net.ppp.client] TRACE: PPP phase -> Network
0000173516 [lwip.ppp] TRACE: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0> <msdns1 8.8.8.8> <msdns2 8.8.
0000178506 [lwip.ppp] TRACE: sent [LCP EchoReq id=0x1 magic=0x58549891]
0000179516 [lwip.ppp] TRACE: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0> <msdns1 8.8.8.8> <msdns2 8.8.
0000183506 [lwip.ppp] TRACE: sent [LCP EchoReq id=0x2 magic=0x58549891]
0000185517 [lwip.ppp] TRACE: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0> <msdns1 8.8.8.8> <msdns2 8.8.
0000188506 [lwip.ppp] TRACE: sent [LCP EchoReq id=0x3 magic=0x58549891]
0000191518 [lwip.ppp] TRACE: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0> <msdns1 8.8.8.8> <msdns2 8.8.
0000193506 [lwip.ppp] TRACE: sent [LCP EchoReq id=0x4 magic=0x58549891]
0000197519 [lwip.ppp] TRACE: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0> <msdns1 8.8.8.8> <msdns2 8.8.
0000198506 [lwip.ppp] TRACE: sent [LCP EchoReq id=0x5 magic=0x58549891]
0000203506 [lwip.ppp] TRACE: sent [LCP EchoReq id=0x6 magic=0x58549891]
0000203520 [lwip.ppp] TRACE: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0> <msdns1 8.8.8.8> <msdns2 8.8.
0000208506 [lwip.ppp] TRACE: sent [LCP EchoReq id=0x7 magic=0x58549891]
0000209521 [lwip.ppp] TRACE: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0> <msdns1 8.8.8.8> <msdns2 8.8.
0000213506 [lwip.ppp] TRACE: sent [LCP EchoReq id=0x8 magic=0x58549891]
0000215522 [lwip.ppp] TRACE: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0> <msdns1 8.8.8.8> <msdns2 8.8.
0000218506 [lwip.ppp] TRACE: sent [LCP EchoReq id=0x9 magic=0x58549891]
0000221523 [lwip.ppp] TRACE: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0> <msdns1 8.8.8.8> <msdns2 8.8.
0000223506 [lwip.ppp] TRACE: sent [LCP EchoReq id=0xa magic=0x58549891]
0000227524 [lwip.ppp] TRACE: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0> <msdns1 8.8.8.8> <msdns2 8.8.
0000228506 [net.ppp.client] TRACE: PPP phase -> Terminate
0000228507 [net.ppp.client] TRACE: PPP phase -> Establish
0000228508 [lwip.ppp] TRACE: sent [LCP TermReq id=0x2 "Peer not responding"]
0000234508 [lwip.ppp] TRACE: sent [LCP TermReq id=0x3 "Peer not responding"]
0000235588 [ncp.at] TRACE: < +CGEV: NW DETACH
0000237888 [ncp.at] TRACE: < +CGREG: 2
0000237889 [ncp.client] TRACE: NCP connection state changed: 1
0000237889 [net.pppncp] TRACE: NCP event 2
0000237890 [net.pppncp] TRACE: State changed event: 1
0000237891 [net.ppp.client] TRACE: PPP thread event LOWER_DOWN data=0
0000237891 [net.ppp.client] TRACE: State CONNECTING -> DISCONNECT
0000237892 [net.ppp.client] TRACE: State DISCONNECT -> DISCONNECTING
0000237892 [net.ppp.client] TRACE: PPP phase -> Terminate
0000240509 [net.ppp.client] TRACE: PPP phase -> Disconnect
0000240510 [net.ppp.client] TRACE: PPP phase -> Dead
0000240510 [net.ppp.client] TRACE: PPP thread event ERROR data=5
0000240511 [net.pppncp] ERROR: PPP error event data=5
0000240511 [net.ppp.client] TRACE: PPP thread event DOWN data=0
0000240512 [net.ppp.client] TRACE: State DISCONNECTING -> DISCONNECTED
0000240513 [net.ppp.client] TRACE: State DISCONNECTED -> READY
0000242390 [ncp.at] TRACE: < +CGREG: 5,"0C4E","3A69",0
0000242391 [ncp.at] TRACE: > AT+CIMI
0000242396 [ncp.at] TRACE: < 234500029252236
0000242396 [ncp.at] TRACE: < OK
0000252897 [ncp.at] TRACE: > AT+CEER
0000252901 [ncp.at] TRACE: < +CEER: 5,38
0000252902 [ncp.at] TRACE: < OK
0000252902 [ncp.at] TRACE: > AT+CMEE?
0000252904 [ncp.at] TRACE: < +CMEE: 1
0000252905 [ncp.at] TRACE: < OK
0000252905 [ncp.at] TRACE: > AT+CREG?
0000252908 [ncp.at] TRACE: < +CREG: 2,5,"0C4E","3A69",0
0000252909 [ncp.at] TRACE: < OK
0000252909 [ncp.at] TRACE: > AT+CEREG?
0000252911 [ncp.at] TRACE: < +CEREG: 2,4
0000252912 [ncp.at] TRACE: < OK
0000252912 [ncp.at] TRACE: > AT+QCSQ
0000252914 [ncp.at] TRACE: < +QCSQ: "GSM",55
0000252915 [ncp.at] TRACE: < OK
0000253015 [ncp.client] TRACE: NCP connection state changed: 2
0000253016 [mux] INFO: Mux channel 2 already opened
0000253016 [net.pppncp] TRACE: NCP event 100
0000253017 [net.pppncp] TRACE: New auth info
0000253018 [net.pppncp] TRACE: NCP event 2
0000253019 [net.pppncp] TRACE: State changed event: 2
0000253020 [ncp.at] TRACE: > AT+CIMI
0000253020 [net.ppp.client] TRACE: PPP thread event LOWER_UP data=0
0000253022 [net.ppp.client] TRACE: State READY -> CONNECT
0000253023 [net.ppp.client] TRACE: State CONNECT -> CONNECTING
0000253024 [ncp.at] TRACE: < 234500029252236
0000253024 [ncp.at] TRACE: < OK
0000254027 [ncp.at] TRACE: > AT
0000254028 [ncp.at] TRACE: < OK
0000254029 [ncp.at] TRACE: > ATO
0000254031 [ncp.at] TRACE: < NO CARRIER
0000254032 [ncp.at] TRACE: > ATD*99***1#
0000254044 [ncp.at] TRACE: < CONNECT 150000000
0000254045 [net.ppp.client] TRACE: PPP phase -> Initialize
0000254045 [net.ppp.client] TRACE: PPP phase -> Establish
0000254046 [lwip.ppp] TRACE: sent [LCP ConfReq id=0x4 <asyncmap 0x0> <magic 0x40c7eaf4> <pcomp> <ac
0000254052 [lwip.ppp] TRACE: rcvd [LCP ConfReq id=0x3 <asyncmap 0x0> <auth chap MD5> <magic 0x5007e
0000254054 [lwip.ppp] TRACE: sent [LCP ConfNak id=0x3 <auth pap>]
0000254055 [lwip.ppp] TRACE: rcvd [LCP ConfAck id=0x4 <asyncmap 0x0> <magic 0x40c7eaf4> <pcomp> <ac
0000254059 [lwip.ppp] TRACE: rcvd [LCP ConfReq id=0x4 <asyncmap 0x0> <auth pap> <magic 0x5007efea> 
0000254060 [lwip.ppp] TRACE: sent [LCP ConfAck id=0x4 <asyncmap 0x0> <auth pap> <magic 0x5007efea> 
0000254061 [lwip.ppp] TRACE: sent [LCP EchoReq id=0x0 magic=0x40c7eaf4]
0000254062 [net.ppp.client] TRACE: PPP phase -> Authenticate
0000254062 [lwip.ppp] TRACE: sent [PAP AuthReq id=0x2 user="" password=""]
0000254065 [lwip.ppp] TRACE: rcvd [LCP DiscReq id=0x5 magic=0x5007efea]
0000254067 [lwip.ppp] TRACE: rcvd [LCP EchoRep id=0x0 magic=0x5007efea 40 c7 ea f4]
0000254069 [lwip.ppp] TRACE: rcvd [PAP AuthAck id=0x2 ""]
0000254070 [net.ppp.client] TRACE: PPP phase -> Network
0000254071 [lwip.ppp] TRACE: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0> <msdns1 8.8.8.8> <msdns2 8.8.
0000259061 [lwip.ppp] TRACE: sent [LCP EchoReq id=0x1 magic=0x40c7eaf4]
0000260071 [lwip.ppp] TRACE: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0> <msdns1 8.8.8.8> <msdns2 8.8.
0000264061 [lwip.ppp] TRACE: sent [LCP EchoReq id=0x2 magic=0x40c7eaf4]
0000266072 [lwip.ppp] TRACE: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0> <msdns1 8.8.8.8> <msdns2 8.8.
0000269061 [lwip.ppp] TRACE: sent [LCP EchoReq id=0x3 magic=0x40c7eaf4]
0000272073 [lwip.ppp] TRACE: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0> <msdns1 8.8.8.8> <msdns2 8.8.
0000274061 [lwip.ppp] TRACE: sent [LCP EchoReq id=0x4 magic=0x40c7eaf4]
0000278074 [lwip.ppp] TRACE: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0> <msdns1 8.8.8.8> <msdns2 8.8.
0000279061 [lwip.ppp] TRACE: sent [LCP EchoReq id=0x5 magic=0x40c7eaf4]
0000281645 [ncp.at] TRACE: < +CGEV: NW DETACH
0000283645 [ncp.at] TRACE: < +CGREG: 2
0000283646 [ncp.client] TRACE: NCP connection state changed: 1
0000283646 [net.pppncp] TRACE: NCP event 2
0000283647 [net.pppncp] TRACE: State changed event: 1
0000283647 [net.ppp.client] TRACE: PPP thread event LOWER_DOWN data=0
0000283648 [net.ppp.client] TRACE: State CONNECTING -> DISCONNECT
0000283649 [net.ppp.client] TRACE: State DISCONNECT -> DISCONNECTING
0000283649 [net.ppp.client] TRACE: PPP phase -> Terminate
0000283650 [net.ppp.client] TRACE: PPP phase -> Establish
0000283650 [lwip.ppp] TRACE: sent [LCP TermReq id=0x5 "User request"]
0000289650 [lwip.ppp] TRACE: sent [LCP TermReq id=0x6 "User request"]
0000293047 [ncp.at] TRACE: < +CGREG: 5,"0C4E","3A69",0
0000293048 [ncp.at] TRACE: > AT+CIMI
0000293053 [ncp.at] TRACE: < 234500029252236
0000293053 [ncp.at] TRACE: < OK
0000295650 [net.ppp.client] TRACE: PPP phase -> Disconnect
0000295651 [net.ppp.client] TRACE: PPP phase -> Dead
0000295651 [net.ppp.client] TRACE: PPP thread event ERROR data=5
0000295652 [net.pppncp] ERROR: PPP error event data=5
0000295652 [net.ppp.client] TRACE: PPP thread event DOWN data=0
0000295653 [net.ppp.client] TRACE: State DISCONNECTING -> DISCONNECTED
0000295654 [net.ppp.client] TRACE: State DISCONNECTED -> READY
0000298654 [ncp.at] TRACE: > AT+CEER
0000298656 [ncp.at] TRACE: < +CEER: 5,38
0000298657 [ncp.at] TRACE: < OK
0000298657 [ncp.at] TRACE: > AT+CMEE?
0000298659 [ncp.at] TRACE: < +CMEE: 1
0000298660 [ncp.at] TRACE: < OK
0000298660 [ncp.at] TRACE: > AT+CREG?
0000298663 [ncp.at] TRACE: < +CREG: 2,5,"0C4E","3A69",0
0000298663 [ncp.at] TRACE: < OK
0000298664 [ncp.at] TRACE: > AT+CEREG?
0000298666 [ncp.at] TRACE: < +CEREG: 2,4
0000298667 [ncp.at] TRACE: < OK
0000298667 [ncp.at] TRACE: > AT+QCSQ
4

I enabled the ability for your account to send DMs.

2 Likes
5

I was able to grab the ICCID from the logs above.
I can see connections to the carriers, but it seems like power is an issue.
WCDMA indicates 3G is being used - and that does draw a lot of power. 12V is often enough, but a battery is sometimes required depending on the signal.
I do see 4G connections on KPN which were accepted.
I would suggest leaving the device a little bit to give it some time, or adding a battery to help it connect. 10mins allows DeviceOS to reset the modem - which often helps give it the kick it needs.
SIM profile changes can sometimes take a few minutes/attempts before it succeeds.

The SIM is active and has the correct carriers available.

1 Like
6

Thanks for checking the SIM and confirming it is active. We now let it hang for around 15 minutes with a battery, monitoring the power draw from the battery while using the device doctor. Below we included the log. What we noted is that when we connect the dev board to the device doctor, it no longer draws power from the battery. When we don't have the device doctor attached, we see a power draw ranging from 0.09A, to 0.35A but no connecting is established.

If we replace the B524 with one of the "older" ones, it connects in <30s.

Cellular Global Identity: 
0000000277 [system.nm] TRACE: Request to power on the interface
0000000277 [ncp.client] TRACE: Powering modem on, ncpId: 0x62
0000000278 [ncp.client] TRACE: Modem already on
0000000346 [mux] INFO: Starting GSM07.10 muxer
0000000348 [mux] INFO: GSM07.10 muxer thread started
0000000348 [mux] INFO: Openning mux channel 0
0000000350 [system.nm] INFO: State changed: DISABLED -> IFACE_DOWN
0000000398 [mux] INFO: Openning mux channel 1
0000000450 [ncp.at] TRACE: > AT
0000000451 [ncp.at] TRACE: < OK
0000000452 [ncp.client] TRACE: NCP ready to accept AT commands
0000000453 [ncp.at] TRACE: > AT+CFUN=1,0
0000000455 [ncp.at] TRACE: < OK
0000000456 [mux] INFO: Mux channel 1 already opened
0000000457 [ncp.at] TRACE: > AT
0000000458 [ncp.at] TRACE: < OK
0000000459 [ncp.client] TRACE: NCP state changed: 1
0000000459 [net.pppncp] TRACE: NCP event 1
0000000460 [ncp.at] TRACE: > AT+QCFG="cmux/urcport",1
0000000463 [ncp.at] TRACE: < OK
0000000463 [ncp.at] TRACE: > AT+CGEREP=1,0
0000000465 [ncp.at] TRACE: < OK
0000000470 [app] INFO: cellular is on
0000000472 [ncp.at] TRACE: > AT+CCID
0000000479 [ncp.at] TRACE: < +CCID: 
0000000479 [ncp.at] TRACE: < OK
0000000481 [ncp.at] TRACE: > AT+CGMI
0000000483 [ncp.at] TRACE: < Quectel
0000000485 [ncp.at] TRACE: < OK
0000000486 [ncp.at] TRACE: > AT+CGMM
0000000488 [ncp.at] TRACE: < EG91
0000000488 [ncp.at] TRACE: < OK
0000000490 [ncp.at] TRACE: > AT+CGMR
0000000494 [ncp.at] TRACE: < EG91EFBR06A07M4G
0000000494 [ncp.at] TRACE: < OK
0000000496 [ncp.at] TRACE: > AT+CGSN
0000000500 [ncp.at] TRACE: < 868986060032241
0000000500 [ncp.at] TRACE: < OK
0000008187 [app] INFO: Auto-connect disabled
0000008631 [ncp.at] TRACE: > AT+CFUN?
0000008634 [ncp.at] TRACE: < +CFUN: 1
0000008636 [ncp.at] TRACE: < OK
0000008637 [ncp.at] TRACE: > AT+CCID
0000008639 [ncp.at] TRACE: < +CCID: 89883070000013787807
0000008640 [ncp.at] TRACE: < OK
0000008641 [ncp.at] TRACE: > AT+CGSN
0000008643 [ncp.at] TRACE: < 868986060032241
0000008643 [ncp.at] TRACE: < OK
0000008644 [ncp.at] TRACE: > AT+CGMR
0000008646 [ncp.at] TRACE: < EG91EFBR06A07M4G
0000008646 [ncp.at] TRACE: < OK
0000009246 [ncp.at] TRACE: > AT+CFUN?
0000009248 [ncp.at] TRACE: < +CFUN: 1
0000009250 [ncp.at] TRACE: < OK
0000009252 [ncp.at] TRACE: > AT+CCID
0000009255 [ncp.at] TRACE: < +CCID: 89883070000013787807
0000009255 [ncp.at] TRACE: < OK
0000009257 [ncp.at] TRACE: > AT+CGSN
0000009259 [ncp.at] TRACE: < 868986060032241
0000009261 [ncp.at] TRACE: < OK
0000009262 [ncp.at] TRACE: > AT+CGMR
0000009263 [ncp.at] TRACE: < EG91EFBR06A07M4G
0000009264 [ncp.at] TRACE: < OK
0000009914 [system.nm] INFO: State changed: IFACE_DOWN -> IFACE_REQUEST_UP
0000009917 [net.ppp.client] TRACE: PPP thread event ADM_UP data=0
0000009917 [net.ppp.client] TRACE: State NONE -> READY
0000009919 [ncp.at] TRACE: > AT+CFUN=1,0
0000009921 [ncp.at] TRACE: < OK
0000009922 [ncp.at] TRACE: > AT+CCID
0000009924 [ncp.at] TRACE: < +CCID: 89883070000013787807
0000009925 [ncp.at] TRACE: < OK
0000009926 [ncp.at] TRACE: > AT+CGDCONT=1,"IP","super"
0000009928 [ncp.at] TRACE: < OK
0000009929 [ncp.at] TRACE: > AT+CFUN=1,0
0000009931 [ncp.at] TRACE: < OK
0000009929 [system.nm] INFO: State changed: IFACE_REQUEST_UP -> IFACE_UP
0000009932 [ncp.at] TRACE: > AT+CREG=2
0000009934 [ncp.at] TRACE: < OK
0000009935 [ncp.at] TRACE: > AT+CGREG=2
0000009936 [ncp.at] TRACE: < OK
0000009937 [ncp.at] TRACE: > AT+CEREG=2
0000009938 [ncp.at] TRACE: < OK
0000009939 [ncp.client] TRACE: NCP connection state changed: 1
0000009939 [net.pppncp] TRACE: NCP event 2
0000009940 [net.pppncp] TRACE: State changed event: 1
0000009941 [ncp.at] TRACE: > AT+COPS?
0000009941 [net.ppp.client] TRACE: PPP thread event LOWER_DOWN data=0
0000009943 [ncp.at] TRACE: < +COPS: 0
0000009944 [ncp.at] TRACE: < OK
0000009945 [ncp.at] TRACE: > AT+CREG?
0000009947 [ncp.at] TRACE: < +CREG: 2,5,"0C4E","29ED",0
0000009948 [ncp.at] TRACE: < OK
0000009949 [ncp.at] TRACE: > AT+CGREG?
0000009951 [ncp.at] TRACE: < +CGREG: 2,5,"0C4E","29ED",0
0000009951 [ncp.at] TRACE: < OK
0000009952 [ncp.at] TRACE: > AT+CEREG?
0000009954 [ncp.at] TRACE: < +CEREG: 2,4
0000009955 [ncp.at] TRACE: < OK
0000009955 [ncp.client] TRACE: NCP connection state changed: 2
0000009956 [mux] INFO: Openning mux channel 2
0000010001 [net.pppncp] TRACE: NCP event 100
0000010001 [net.pppncp] TRACE: New auth info
0000010004 [net.pppncp] TRACE: NCP event 2
0000010004 [net.pppncp] TRACE: State changed event: 2
0000010005 [ncp.at] TRACE: > AT+CIMI
0000010006 [net.ppp.client] TRACE: PPP thread event LOWER_UP data=0
0000010011 [net.ppp.client] TRACE: State READY -> CONNECT
0000010012 [net.ppp.client] TRACE: State CONNECT -> CONNECTING
0000010012 [ncp.at] TRACE: < 234500029252371
0000010013 [ncp.at] TRACE: < OK
0000011016 [ncp.at] TRACE: > AT
0000011017 [ncp.at] TRACE: < OK
0000011018 [ncp.at] TRACE: > ATO
0000011020 [ncp.at] TRACE: < NO CARRIER
0000011021 [ncp.at] TRACE: > ATD*99***1#
0000011033 [ncp.at] TRACE: < CONNECT 150000000
0000011034 [net.ppp.client] TRACE: PPP phase -> Initialize
0000011034 [net.ppp.client] TRACE: PPP phase -> Establish
0000011035 [lwip.ppp] TRACE: sent [LCP ConfReq id=0x1 <asyncmap 0x0> <magic 0x58521f2e> <pcomp> <ac
0000011042 [lwip.ppp] TRACE: rcvd [LCP ConfReq id=0xf <asyncmap 0x0> <auth chap MD5> <magic 0x503d6
0000011044 [lwip.ppp] TRACE: sent [LCP ConfNak id=0xf <auth pap>]
0000011045 [lwip.ppp] TRACE: rcvd [LCP ConfAck id=0x1 <asyncmap 0x0> <magic 0x58521f2e> <pcomp> <ac
0000011048 [lwip.ppp] TRACE: rcvd [LCP ConfReq id=0x10 <asyncmap 0x0> <auth pap> <magic 0x503d6534>
0000011049 [lwip.ppp] TRACE: sent [LCP ConfAck id=0x10 <asyncmap 0x0> <auth pap> <magic 0x503d6534>
0000011051 [lwip.ppp] TRACE: sent [LCP EchoReq id=0x0 magic=0x58521f2e]
0000011052 [net.ppp.client] TRACE: PPP phase -> Authenticate
0000011052 [lwip.ppp] TRACE: sent [PAP AuthReq id=0x1 user="" password=""]
0000011056 [lwip.ppp] TRACE: rcvd [LCP DiscReq id=0x11 magic=0x503d6534]
0000011058 [lwip.ppp] TRACE: rcvd [LCP EchoRep id=0x0 magic=0x503d6534 58 52 1f 2e]
0000011059 [lwip.ppp] TRACE: rcvd [PAP AuthAck id=0x1 ""]
0000011060 [net.ppp.client] TRACE: PPP phase -> Network
0000011060 [lwip.ppp] TRACE: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0> <msdns1 8.8.8.8> <msdns2 8.8.
0000016051 [lwip.ppp] TRACE: sent [LCP EchoReq id=0x1 magic=0x58521f2e]
0000017061 [lwip.ppp] TRACE: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0> <msdns1 8.8.8.8> <msdns2 8.8.
0000021051 [lwip.ppp] TRACE: sent [LCP EchoReq id=0x2 magic=0x58521f2e]
0000023062 [lwip.ppp] TRACE: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0> <msdns1 8.8.8.8> <msdns2 8.8.
0000026051 [lwip.ppp] TRACE: sent [LCP EchoReq id=0x3 magic=0x58521f2e]
0000028235 [ncp.at] TRACE: < +CREG: 5,"0C4E","3A69",0
0000028335 [ncp.at] TRACE: < +CGREG: 5,"0C4E","3A69",0
0000029063 [lwip.ppp] TRACE: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0> <msdns1 8.8.8.8> <msdns2 8.8.
0000030635 [ncp.at] TRACE: < +CGEV: NW DETACH
0000031051 [lwip.ppp] TRACE: sent [LCP EchoReq id=0x4 magic=0x58521f2e]
0000032735 [ncp.at] TRACE: < +CGREG: 2
0000032735 [ncp.client] TRACE: NCP connection state changed: 1
0000032736 [net.pppncp] TRACE: NCP event 2
0000032736 [net.pppncp] TRACE: State changed event: 1
0000032737 [net.ppp.client] TRACE: PPP thread event LOWER_DOWN data=0
0000032737 [net.ppp.client] TRACE: State CONNECTING -> DISCONNECT
0000032738 [net.ppp.client] TRACE: State DISCONNECT -> DISCONNECTING
0000032739 [net.ppp.client] TRACE: PPP phase -> Terminate
0000032739 [net.ppp.client] TRACE: PPP phase -> Establish
0000032740 [lwip.ppp] TRACE: sent [LCP TermReq id=0x2 "User request"]
0000036837 [ncp.at] TRACE: < +CGREG: 5,"0C4E","3A69",0
0000036838 [ncp.at] TRACE: > AT+CIMI
0000036842 [ncp.at] TRACE: < 234500029252371
0000036842 [ncp.at] TRACE: < OK
0000038740 [lwip.ppp] TRACE: sent [LCP TermReq id=0x3 "User request"]
0000044740 [net.ppp.client] TRACE: PPP phase -> Disconnect
0000044740 [net.ppp.client] TRACE: PPP phase -> Dead
0000044741 [net.ppp.client] TRACE: PPP thread event ERROR data=5
0000044741 [net.pppncp] ERROR: PPP error event data=5
0000044742 [net.ppp.client] TRACE: PPP thread event DOWN data=0
0000044742 [net.ppp.client] TRACE: State DISCONNECTING -> DISCONNECTED
0000044743 [net.ppp.client] TRACE: State DISCONNECTED -> READY
0000047743 [ncp.at] TRACE: > AT+CEER
0000047748 [ncp.at] TRACE: < +CEER: 5,38
0000047748 [ncp.at] TRACE: < OK
0000047749 [ncp.at] TRACE: > AT+CMEE?
0000047751 [ncp.at] TRACE: < +CMEE: 1
0000047751 [ncp.at] TRACE: < OK
0000047752 [ncp.at] TRACE: > AT+CREG?
0000047754 [ncp.at] TRACE: < +CREG: 2,5,"0C4E","3A69",0
0000047755 [ncp.at] TRACE: < OK
0000047756 [ncp.at] TRACE: > AT+CEREG?
0000047758 [ncp.at] TRACE: < +CEREG: 2,4
0000047758 [ncp.at] TRACE: < OK
0000047759 [ncp.at] TRACE: > AT+QCSQ
0000047761 [ncp.at] TRACE: < +QCSQ: "GSM",75
0000047761 [ncp.at] TRACE: < OK
0000047862 [ncp.client] TRACE: NCP connection state changed: 2
0000047862 [mux] INFO: Mux channel 2 already opened
0000047863 [net.pppncp] TRACE: NCP event 100
0000047863 [net.pppncp] TRACE: New auth info
0000047865 [net.pppncp] TRACE: NCP event 2
0000047865 [net.pppncp] TRACE: State changed event: 2
0000047866 [ncp.at] TRACE: > AT+CIMI
0000047867 [net.ppp.client] TRACE: PPP thread event LOWER_UP data=0
0000047867 [net.ppp.client] TRACE: State READY -> CONNECT
0000047869 [net.ppp.client] TRACE: State CONNECT -> CONNECTING
0000047870 [ncp.at] TRACE: < 234500029252371
0000047870 [ncp.at] TRACE: < OK
0000048873 [ncp.at] TRACE: > AT
0000048874 [ncp.at] TRACE: < OK
0000048875 [ncp.at] TRACE: > ATO
0000048879 [ncp.at] TRACE: < NO CARRIER
0000048880 [ncp.at] TRACE: > ATD*99***1#
0000048894 [ncp.at] TRACE: < CONNECT 150000000
0000048895 [net.ppp.client] TRACE: PPP phase -> Initialize
0000048895 [net.ppp.client] TRACE: PPP phase -> Establish
0000048896 [lwip.ppp] TRACE: sent [LCP ConfReq id=0x4 <asyncmap 0x0> <magic 0x40b37907> <pcomp> <ac
0000048903 [lwip.ppp] TRACE: rcvd [LCP ConfReq id=0x12 <asyncmap 0x0> <auth chap MD5> <magic 0x503d
0000048905 [lwip.ppp] TRACE: sent [LCP ConfNak id=0x12 <auth pap>]
0000048906 [lwip.ppp] TRACE: rcvd [LCP ConfAck id=0x4 <asyncmap 0x0> <magic 0x40b37907> <pcomp> <ac
0000048909 [lwip.ppp] TRACE: rcvd [LCP ConfReq id=0x13 <asyncmap 0x0> <auth pap> <magic 0x503df919>
0000048910 [lwip.ppp] TRACE: sent [LCP ConfAck id=0x13 <asyncmap 0x0> <auth pap> <magic 0x503df919>
0000048911 [lwip.ppp] TRACE: sent [LCP EchoReq id=0x0 magic=0x40b37907]
0000048912 [net.ppp.client] TRACE: PPP phase -> Authenticate
0000048913 [lwip.ppp] TRACE: sent [PAP AuthReq id=0x2 user="" password=""]
0000048916 [lwip.ppp] TRACE: rcvd [LCP DiscReq id=0x14 magic=0x503df919]
0000048918 [lwip.ppp] TRACE: rcvd [LCP EchoRep id=0x0 magic=0x503df919 40 b3 79 07]
0000048920 [lwip.ppp] TRACE: rcvd [PAP AuthAck id=0x2 ""]
0000048920 [net.ppp.client] TRACE: PPP phase -> Network
0000048921 [lwip.ppp] TRACE: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0> <msdns1 8.8.8.8> <msdns2 8.8.
0000053912 [lwip.ppp] TRACE: sent [LCP EchoReq id=0x1 magic=0x40b37907]
0000054922 [lwip.ppp] TRACE: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0> <msdns1 8.8.8.8> <msdns2 8.8.
0000058912 [lwip.ppp] TRACE: sent [LCP EchoReq id=0x2 magic=0x40b37907]
0000060923 [lwip.ppp] TRACE: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0> <msdns1 8.8.8.8> <msdns2 8.8.
0000063912 [lwip.ppp] TRACE: sent [LCP EchoReq id=0x3 magic=0x40b37907]
0000066924 [lwip.ppp] TRACE: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0> <msdns1 8.8.8.8> <msdns2 8.8.
0000068912 [lwip.ppp] TRACE: sent [LCP EchoReq id=0x4 magic=0x40b37907]
0000069695 [ncp.at] TRACE: < +CGEV: NW DETACH
0000072495 [ncp.at] TRACE: < +CGREG: 2
0000072495 [ncp.client] TRACE: NCP connection state changed: 1
0000072496 [net.pppncp] TRACE: NCP event 2
0000072496 [net.pppncp] TRACE: State changed event: 1
0000072497 [net.ppp.client] TRACE: PPP thread event LOWER_DOWN data=0
0000072497 [net.ppp.client] TRACE: State CONNECTING -> DISCONNECT
0000072498 [net.ppp.client] TRACE: State DISCONNECT -> DISCONNECTING
0000072499 [net.ppp.client] TRACE: PPP phase -> Terminate
0000072499 [net.ppp.client] TRACE: PPP phase -> Establish
0000072500 [lwip.ppp] TRACE: sent [LCP TermReq id=0x5 "User request"]
0000077097 [ncp.at] TRACE: < +CGREG: 5,"0C4E","3A69",0
0000077098 [ncp.at] TRACE: > AT+CIMI
0000077102 [ncp.at] TRACE: < 234500029252371
0000077102 [ncp.at] TRACE: < OK
0000078500 [lwip.ppp] TRACE: sent [LCP TermReq id=0x6 "User request"]
0000084500 [net.ppp.client] TRACE: PPP phase -> Disconnect
0000084500 [net.ppp.client] TRACE: PPP phase -> Dead
0000084501 [net.ppp.client] TRACE: PPP thread event ERROR data=5
0000084501 [net.pppncp] ERROR: PPP error event data=5
0000084502 [net.ppp.client] TRACE: PPP thread event DOWN data=0
0000084502 [net.ppp.client] TRACE: State DISCONNECTING -> DISCONNECTED
0000084503 [net.ppp.client] TRACE: State DISCONNECTED -> READY
0000087508 [ncp.at] TRACE: > AT+CEER
0000087513 [ncp.at] TRACE: < +CEER: 5,38
0000087514 [ncp.at] TRACE: < OK
0000087515 [ncp.at] TRACE: > AT+CMEE?
0000087516 [ncp.at] TRACE: < +CMEE: 1
0000087517 [ncp.at] TRACE: < OK
0000087518 [ncp.at] TRACE: > AT+CREG?
0000087525 [ncp.at] TRACE: < +CREG: 2,5,"0C4E","3A69",0
0000087526 [ncp.at] TRACE: < OK
0000087527 [ncp.at] TRACE: > AT+CEREG?
0000087529 [ncp.at] TRACE: < +CEREG: 2,4
0000087529 [ncp.at] TRACE: < OK
0000087530 [ncp.at] TRACE: > AT+QCSQ
0000087532 [ncp.at] TRACE: < +QCSQ: "GSM",63
0000087532 [ncp.at] TRACE: < OK
0000087633 [ncp.client] TRACE: NCP connection state changed: 2
0000087633 [mux] INFO: Mux channel 2 already opened
0000087634 [net.pppncp] TRACE: NCP event 100
0000087634 [net.pppncp] TRACE: New auth info
0000087636 [net.pppncp] TRACE: NCP event 2
0000087636 [net.pppncp] TRACE: State changed event: 2
0000087637 [ncp.at] TRACE: > AT+CIMI
0000087638 [net.ppp.client] TRACE: PPP thread event LOWER_UP data=0
0000087638 [net.ppp.client] TRACE: State READY -> CONNECT
0000087639 [net.ppp.client] TRACE: State CONNECT -> CONNECTING
0000087640 [ncp.at] TRACE: < 234500029252371
0000087641 [ncp.at] TRACE: < OK
0000088643 [ncp.at] TRACE: > AT
0000088644 [ncp.at] TRACE: < OK
0000088645 [ncp.at] TRACE: > ATO
0000088647 [ncp.at] TRACE: < NO CARRIER
0000088648 [ncp.at] TRACE: > ATD*99***1#
0000088661 [ncp.at] TRACE: < CONNECT 150000000
0000088661 [net.ppp.client] TRACE: PPP phase -> Initialize
0000088662 [net.ppp.client] TRACE: PPP phase -> Establish
0000088662 [lwip.ppp] TRACE: sent [LCP ConfReq id=0x7 <asyncmap 0x0> <magic 0x4bbdb668> <pcomp> <ac
0000088670 [lwip.ppp] TRACE: rcvd [LCP ConfReq id=0x15 <asyncmap 0x0> <auth chap MD5> <magic 0x503e
0000088671 [lwip.ppp] TRACE: sent [LCP ConfNak id=0x15 <auth pap>]
0000088672 [lwip.ppp] TRACE: rcvd [LCP ConfAck id=0x7 <asyncmap 0x0> <magic 0x4bbdb668> <pcomp> <ac
0000088676 [lwip.ppp] TRACE: rcvd [LCP ConfReq id=0x16 <asyncmap 0x0> <auth pap> <magic 0x503e9470>
0000088677 [lwip.ppp] TRACE: sent [LCP ConfAck id=0x16 <asyncmap 0x0> <auth pap> <magic 0x503e9470>
0000088678 [lwip.ppp] TRACE: sent [LCP EchoReq id=0x0 magic=0x4bbdb668]
0000088679 [net.ppp.client] TRACE: PPP phase -> Authenticate
0000088680 [lwip.ppp] TRACE: sent [PAP AuthReq id=0x3 user="" password=""]
0000088683 [lwip.ppp] TRACE: rcvd [LCP DiscReq id=0x17 magic=0x503e9470]
0000088685 [lwip.ppp] TRACE: rcvd [LCP EchoRep id=0x0 magic=0x503e9470 4b bd b6 68]
0000088687 [lwip.ppp] TRACE: rcvd [PAP AuthAck id=0x3 ""]
0000088687 [net.ppp.client] TRACE: PPP phase -> Network
0000088688 [lwip.ppp] TRACE: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0> <msdns1 8.8.8.8> <msdns2 8.8.
0000093679 [lwip.ppp] TRACE: sent [LCP EchoReq id=0x1 magic=0x4bbdb668]
0000094689 [lwip.ppp] TRACE: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0> <msdns1 8.8.8.8> <msdns2 8.8.
0000098679 [lwip.ppp] TRACE: sent [LCP EchoReq id=0x2 magic=0x4bbdb668]
0000100690 [lwip.ppp] TRACE: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0> <msdns1 8.8.8.8> <msdns2 8.8.
0000103679 [lwip.ppp] TRACE: sent [LCP EchoReq id=0x3 magic=0x4bbdb668]
0000106691 [lwip.ppp] TRACE: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0> <msdns1 8.8.8.8> <msdns2 8.8.
0000108679 [lwip.ppp] TRACE: sent [LCP EchoReq id=0x4 magic=0x4bbdb668]
0000112692 [lwip.ppp] TRACE: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0> <msdns1 8.8.8.8> <msdns2 8.8.
0000113679 [lwip.ppp] TRACE: sent [LCP EchoReq id=0x5 magic=0x4bbdb668]
0000118679 [lwip.ppp] TRACE: sent [LCP EchoReq id=0x6 magic=0x4bbdb668]
0000118693 [lwip.ppp] TRACE: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0> <msdns1 8.8.8.8> <msdns2 8.8.
0000123679 [lwip.ppp] TRACE: sent [LCP EchoReq id=0x7 magic=0x4bbdb668]
0000124694 [lwip.ppp] TRACE: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0> <msdns1 8.8.8.8> <msdns2 8.8.
7

This part is strange:

Have you tried different antennas on this device(s)?

8

We first tried it with the included antenna, then switched to an outdoor antenna and lastly a “long range” antenna. I also took one home with me to see if a different cellular tower might change things, but unfortunately without results. The “no carrier” makes me think that the esim is not activated correctly. Given that this log is from a different B524 in the batch, can you maybe confirm if this sim is activated correctly?

9

Your SIMs are activated - I've seen connection attempts to several carriers, but reports have been coming in about a regional outage that we're following up on. The Particle status page should be updated soon.

1 Like
10

According to the Particle status page, the problem has been resolved. For our devices however, the problem seems to persist. We currently have 6 B524's in 3 different kind of boards with 3 different kind of antennas all showing the same problem. Judging from the power consumption, it looks like the boards are trying to connect but still can't establish a connection.

11

@Support, any update on the situation yet?