Boron unable to connect to Cloud via NB-IoT, but successfully gets connected to NB-IoT network

Hello,

Could you please help to investigate and fix issue where Boron unable to get connected to Particle cloud (LED fast blink with Green). I also installed boron-clouddebug and get the following debug messages:


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)
or tap the MODE button once to show carriers
starting tests...
turning cellular on...
0000010710 [system.nm] INFO: State changed: DISABLED -> IFACE_DOWN
0000016878 [hal] INFO: Using external Nano SIM card
0000016929 [gsm0710muxer] INFO: Starting GSM07.10 muxer
0000016930 [gsm0710muxer] INFO: Openning mux channel 0
0000016931 [gsm0710muxer] INFO: GSM07.10 muxer thread started
deviceID=e00fce68b98851038090c10c
0000016982 [gsm0710muxer] INFO: Resuming channel 0
0000016983 [gsm0710muxer] INFO: Openning mux channel 1
0000017083 [gsm0710muxer] INFO: Resuming channel 1
0000017083 [gsm0710muxer] INFO: Resuming channel 1
manufacturer=u-blox
model=SARA-R410M-02B
firmware version=L0.0.00.00.05.06 [Feb 03 2018 13:00:41]
ordering code=SARA-R410M-02B
IMEI=352...
IMSI=u-blox
ICCID=...
0000017486 [app] INFO: enabling trace logging
attempting to connect to the cellular network...
0000017488 [system.nm] INFO: State changed: IFACE_DOWN -> IFACE_REQUEST_UP
0000017488 [system.nm] INFO: State changed: IFACE_DOWN -> IFACE_REQUEST_UP
0000017491 [hal] TRACE: PPP netif -> 8
0000017492 [net.ifapi] INFO: Netif pp3 state UP
0000017492 [net.ifapi] INFO: Netif pp3 state UP
0000017495 [hal] TRACE: PPP thread event LOWER_DOWN
0000017495 [hal] TRACE: PPP thread event ADM_DOWN
0000017497 [hal] TRACE: PPP thread event ADM_UP
0000017499 [hal] TRACE: State NONE -> READY
0000017498 [ncp.at] TRACE: > AT+CGDCONT=1,"IP","NBIOT"
0000017502 [system.nm] INFO: State changed: IFACE_REQUEST_UP -> IFACE_UP
0000017502 [system.nm] INFO: State changed: IFACE_REQUEST_UP -> IFACE_UP
0000017535 [ncp.at] TRACE: < OK
0000017536 [ncp.at] TRACE: > AT+CEREG=2
0000017585 [ncp.at] TRACE: < OK
0000017585 [hal] TRACE: NCP connection state changed: 1
0000017586 [net.pppncp] TRACE: NCP event 2
0000017587 [net.pppncp] TRACE: State changed event: 1
0000017588 [hal] TRACE: PPP thread event LOWER_DOWN
0000017588 [ncp.at] TRACE: > AT+COPS=0
0000017635 [ncp.at] TRACE: < OK
0000017635 [ncp.at] TRACE: > AT+CEREG?
0000017685 [ncp.at] TRACE: < +CEREG: 2,0
0000017685 [ncp.at] TRACE: < OK
0000017686 [ncp.at] TRACE: < +CEREG: 2
0000024287 [ncp.at] TRACE: < +CEREG: 1,"10","83D0D",9
0000024287 [hal] TRACE: NCP connection state changed: 2
0000024288 [gsm0710muxer] INFO: Openning mux channel 2
0000024288 [gsm0710muxer] INFO: Openning mux channel 2
0000024385 [gsm0710muxer] INFO: Resuming channel 2
0000024385 [gsm0710muxer] INFO: Resuming channel 2
0000024386 [net.pppncp] TRACE: NCP event 100
0000024386 [net.pppncp] TRACE: New auth info
0000024387 [net.pppncp] TRACE: NCP event 2
0000024388 [net.pppncp] TRACE: State changed event: 2
0000024388 [hal] TRACE: PPP thread event LOWER_UP
0000024389 [hal] TRACE: State READY -> CONNECT
0000024390 [net.ppp.ipcp] TRACE: IPCP: init
0000024391 [net.ppp.ipcp] TRACE: IPCP: disable 0 0 0
0000024392 [net.ppp.ipcp] TRACE: IPCP: enable 0 0 0
0000024393 [hal] TRACE: Outputing 13 bytes
0000024394 [hal] TRACE: State CONNECT -> CONNECTING
0000024395 [lwip] TRACE: ppp_connect[3]: holdoff=1
0000024397 [lwip] TRACE: ppp phase changed[3]: phase=2
0000024398 [hal] TRACE: PPP phase -> 2
0000024437 [lwip] TRACE: pppos_input[3]: got 12 bytes
0000024438 [lwip] TRACE: pppos_input[3]: got 21 bytes
0000025399 [lwip] TRACE: ppp phase changed[3]: phase=3
0000025400 [hal] TRACE: PPP phase -> 3
0000025400 [lwip] TRACE: pppos_connect: unit 3: connecting
0000025401 [lwip] TRACE: ppp_start[3]
0000025402 [lwip] TRACE: ppp phase changed[3]: phase=6
0000025402 [hal] TRACE: PPP phase -> 6
0000025403 [lwip] TRACE: pppos_send_config[3]: out_accm=FF FF FF FF
0000025404 [lwip] TRACE: ppp_send_config[3]
0000025405 [lwip] TRACE: pppos_recv_config[3]: in_accm=FF FF FF FF
0000025407 [lwip] TRACE: ppp_recv_config[3]
0000025408 [lwip] TRACE: ppp: auth protocols:
0000025409 [lwip] TRACE:  PAP=1
0000025410 [lwip] TRACE:  CHAP=1 CHAP_MD5=1
0000025411 [lwip] TRACE:
0000025411 [hal] TRACE: Outputing 46 bytes
0000025413 [lwip] TRACE: pppos_write[3]: len=24
0000025414 [lwip] TRACE: ppp_start[3]: finished
0000025438 [lwip] TRACE: pppos_input[3]: got 54 bytes
0000025439 [hal] TRACE: Outputing 52 bytes
0000025439 [lwip] TRACE: pppos_write[3]: len=29
0000025440 [lwip] TRACE: pppos_input[3]: got 47 bytes
0000025441 [lwip] TRACE: netif_set_mtu[3]: mtu=1500
0000025442 [lwip] TRACE: pppos_send_config[3]: out_accm=0 0 0 0
0000025443 [lwip] TRACE: ppp_send_config[3]
0000025444 [lwip] TRACE: pppos_recv_config[3]: in_accm=0 0 0 0
0000025446 [lwip] TRACE: ppp_recv_config[3]
0000025447 [hal] TRACE: Outputing 15 bytes
0000025448 [lwip] TRACE: pppos_write[3]: len=12
0000025449 [net.ppp.ipcp] TRACE: IPCP: lowerUp
0000025450 [lwip] TRACE: ppp phase changed[3]: phase=7
0000025451 [hal] TRACE: PPP phase -> 7
0000025502 [lwip] TRACE: pppos_input[3]: got 21 bytes
0000025503 [lwip] TRACE: pppos_input[3]: got 41 bytes
0000025504 [lwip] TRACE: No CHAP secret found for authenticating us to UMTS_CHAP_SRVR
0000025506 [hal] TRACE: Outputing 28 bytes
0000025506 [lwip] TRACE: pppos_write[3]: len=25
0000025508 [lwip] TRACE: pppos_input[3]: got 27 bytes
0000025559 [lwip] TRACE: pppos_input[3]: got 10 bytes
0000025559 [lwip] TRACE: CHAP authentication succeeded
0000025560 [lwip] TRACE: CHAP authentication succeeded
0000025561 [lwip] TRACE: ppp phase changed[3]: phase=9
0000025562 [hal] TRACE: PPP phase -> 9
0000025563 [net.ppp.ipcp] TRACE: IPCP: open
0000025563 [net.ppp.ipcp] TRACE: IPCP: reset ci
0000025564 [net.ppp.ipcp] TRACE: IPCP: get ci length
0000025565 [net.ppp.ipcp] TRACE: IPCP: Our CI length: 24
0000025567 [hal] TRACE: Outputing 35 bytes
0000025568 [lwip] TRACE: pppos_write[3]: len=32
0000025569 [hal] TRACE: Outputing 21 bytes
0000025570 [lwip] TRACE: pppos_write[3]: len=18
0000025621 [lwip] TRACE: pppos_input[3]: got 10 bytes
0000025621 [net.ppp.ipcp] TRACE: IPCP: input 4
0000025622 [net.ppp.ipcp] TRACE: IPCP: request ci
0000025622 [hal] TRACE: Outputing 11 bytes
0000025623 [lwip] TRACE: pppos_write[3]: len=8
0000025624 [lwip] TRACE: pppos_input[3]: got 28 bytes
0000025625 [net.ppp.ipcp] TRACE: IPCP: input 22
0000025625 [net.ppp.ipcp] TRACE: IPCP: reject ci
0000025626 [net.ppp.ipcp] TRACE: IPCP: get ci length
0000025626 [net.ppp.ipcp] TRACE: IPCP: Our CI length: 6
0000025628 [hal] TRACE: Outputing 17 bytes
0000025629 [lwip] TRACE: pppos_write[3]: len=14
0000025680 [lwip] TRACE: pppos_input[3]: got 16 bytes
0000025681 [net.ppp.ipcp] TRACE: IPCP: input 10
0000025681 [net.ppp.ipcp] TRACE: IPCP: nak ci
0000025682 [net.ppp.ipcp] TRACE: IPCP: get ci length
0000025683 [net.ppp.ipcp] TRACE: IPCP: Our CI length: 6
0000025684 [hal] TRACE: Outputing 17 bytes
0000025686 [lwip] TRACE: pppos_write[3]: len=14
0000025737 [lwip] TRACE: pppos_input[3]: got 16 bytes
0000025737 [net.ppp.ipcp] TRACE: IPCP: input 10
0000025738 [net.ppp.ipcp] TRACE: IPCP: ack ci
0000025738 [net.ppp.ipcp] TRACE: IPCP: up
0000025739 [hal] TRACE: PPP netif -> 4
0000025740 [net.ifapi] INFO: Netif pp3 link UP
0000025740 [net.ifapi] INFO: Netif pp3 link UP
0000025742 [system.nm] INFO: State changed: IFACE_UP -> IFACE_LINK_UP
0000025742 [system.nm] INFO: State changed: IFACE_UP -> IFACE_LINK_UP
0000025745 [lwip] TRACE: sifup[3]: err_code=0
0000025746 [hal] TRACE: PPP status -> 0
0000025747 [hal] TRACE: PPP netif -> b0
0000025748 [hal] TRACE: PPP thread event UP
0000025749 [hal] TRACE: State CONNECTING -> CONNECTED
0000025749 [net.ifapi] TRACE: Netif pp3 ipv4 configuration changed
0000025752 [lwip] TRACE: ppp phase changed[3]: phase=10
0000025753 [hal] TRACE: PPP phase -> 10
0000025754 [system.nm] TRACE: br_enabled=0, br_permitted=0
connected to the cellular network in 8251 milliseconds
connected to cellular network!
0000025758 [ncp.at] TRACE: > AT+UDOPN=9
0000025804 [ncp.at] TRACE: < ERROR
operator name=
0000025805 [ncp.at] TRACE: > AT+CSQ
0000025854 [ncp.at] TRACE: < +CSQ: 14,99
0000025854 [ncp.at] TRACE: < OK
rssi=-85, qual=99
0000025856 [ncp.at] TRACE: > AT+CGED=5
0000025904 [ncp.at] TRACE: < ERROR
0000025905 [ncp.at] TRACE: > AT+CGED=3
0000025954 [ncp.at] TRACE: < ERROR
0000025955 [app] INFO: service rat=GSM mcc=65535, mnc=255, lac=0 ci=0 band=GSM 900 rssi=-121 bsic=0 arfcn=0 rxlev=0
0000025955 [app] INFO: service rat=GSM mcc=65535, mnc=255, lac=0 ci=0 band=GSM 900 rssi=-121 bsic=0 arfcn=0 rxlev=0
0000025959 [ncp.at] TRACE: > AT+UPING="8.8.8.8"
0000026004 [ncp.at] TRACE: < ERROR
ping addr 8.8.8.8=0
0000026005 [ncp.at] TRACE: > AT+UDNSRN=0,"device.spark.io"
0000026054 [ncp.at] TRACE: < ERROR
device.spark.io=0.0.0.0
connecting to cloud
0000026055 [system.nm] TRACE: br_enabled=0, br_permitted=0
0000030449 [hal] TRACE: Outputing 16 bytes
0000030449 [lwip] TRACE: pppos_write[3]: len=12
0000030504 [lwip] TRACE: pppos_input[3]: got 27 bytes
0000031571 [hal] TRACE: Outputing 22 bytes
0000031572 [lwip] TRACE: pppos_write[3]: len=18
0000035450 [hal] TRACE: Outputing 16 bytes
0000035450 [lwip] TRACE: pppos_write[3]: len=12
0000035504 [lwip] TRACE: pppos_input[3]: got 26 bytes
0000037573 [hal] TRACE: Outputing 22 bytes
0000037573 [lwip] TRACE: pppos_write[3]: len=18
0000040451 [hal] TRACE: Outputing 16 bytes
0000040452 [lwip] TRACE: pppos_write[3]: len=12
0000040505 [lwip] TRACE: pppos_input[3]: got 26 bytes
0000043574 [hal] TRACE: Outputing 22 bytes
0000043574 [lwip] TRACE: pppos_write[3]: len=18
0