MQTT Client disconnect in MQTT_Sample Example

[Nahan_Trogn]

Hi Devzone,

I use the original MQTT_Sample example, just change "Client ID", "Alive Time" = 120s, use NBIoT + GPS.

Development set up:

ncs v1.5.1, ubuntu, thingy91

1. After a short time, the device receives CEREG notification, such as:

Is this normal or there is something wrong with my network?

2. After that, the Client keeps disconnect and connect continuously.

 Sorry for uploading lots of images, because my log file didn't contain enough log infor.

Maybe the NBIoT signal is weak, is there any solution for the device to work in a weak NBIoT area? I don't want the MQTT client to be disconnected so often.

Fullscreen 2021-06-02T02_24_59.400Z-log.txt Download

2021-06-02T02:24:59.805Z INFO Application data folder: /home/ubuntu/.config/nrfconnect/pc-nrfconnect-linkmonitor
2021-06-02T02:24:59.870Z DEBUG App pc-nrfconnect-linkmonitor v1.1.10 official
2021-06-02T02:24:59.870Z DEBUG App path: /home/ubuntu/.nrfconnect-apps/node_modules/pc-nrfconnect-linkmonitor
2021-06-02T02:24:59.870Z DEBUG nRFConnect 3.6.1 is supported by the app (^3.6.0)
2021-06-02T02:24:59.870Z DEBUG nRFConnect path: /tmp/.mount_nrfcon69sATx/resources/app.asar
2021-06-02T02:24:59.871Z DEBUG HomeDir: /home/ubuntu
2021-06-02T02:24:59.871Z DEBUG TmpDir: /tmp
2021-06-02T02:38:44.656Z INFO Modem port is opened
2021-06-02T02:38:44.660Z DEBUG modem >> AT+CFUN?
2021-06-02T02:38:44.676Z DEBUG modem << +CFUN: 1
2021-06-02T02:38:44.677Z DEBUG modem << OK
2021-06-02T02:38:44.690Z DEBUG modem >> AT+CGSN=1
2021-06-02T02:38:44.698Z DEBUG modem << +CGSN: "352656101397258"
2021-06-02T02:38:44.700Z DEBUG modem << OK
2021-06-02T02:38:44.709Z DEBUG modem >> AT+CGMI
2021-06-02T02:38:44.724Z DEBUG modem << Nordic Semiconductor ASA
2021-06-02T02:38:44.726Z DEBUG modem << OK
2021-06-02T02:38:44.728Z DEBUG modem >> AT+CGMM
2021-06-02T02:38:44.742Z DEBUG modem << nRF9160-SICA
2021-06-02T02:38:44.743Z DEBUG modem << OK
2021-06-02T02:38:44.746Z DEBUG modem >> AT+CGMR
2021-06-02T02:38:44.759Z DEBUG modem << mfw_nrf9160_1.2.3
2021-06-02T02:38:44.761Z DEBUG modem << OK
2021-06-02T02:38:44.762Z INFO Nordic Semiconductor ASA nRF9160-SICA [mfw_nrf9160_1.2.3] SerNr: 352656101397258
2021-06-02T02:38:44.763Z DEBUG modem >> AT+CEMODE?
2021-06-02T02:38:44.775Z DEBUG modem << +CEMODE: 0
2021-06-02T02:38:44.777Z DEBUG modem << OK
2021-06-02T02:38:44.797Z DEBUG modem >> AT%XCBAND=?
2021-06-02T02:38:44.808Z DEBUG modem << %XCBAND: (1,2,3,4,5,8,12,13,17,19,20,25,26,28,66)
2021-06-02T02:38:44.810Z DEBUG modem << OK
2021-06-02T02:38:44.815Z DEBUG modem >> AT+CMEE?
2021-06-02T02:38:44.827Z DEBUG modem << +CMEE: 0
2021-06-02T02:38:44.829Z DEBUG modem << OK
2021-06-02T02:38:44.831Z DEBUG modem >> AT+CMEE=1
2021-06-02T02:38:44.844Z DEBUG modem << OK
2021-06-02T02:38:44.847Z DEBUG modem >> AT+CNEC?
2021-06-02T02:38:44.857Z DEBUG modem << +CNEC: 0
2021-06-02T02:38:44.858Z DEBUG modem << OK
2021-06-02T02:38:44.860Z DEBUG modem >> AT+CNEC=24
2021-06-02T02:38:44.876Z DEBUG modem << OK
2021-06-02T02:38:44.878Z DEBUG modem >> AT+CGEREP?
2021-06-02T02:38:44.892Z DEBUG modem << +CGEREP: 0,0
2021-06-02T02:38:44.893Z DEBUG modem << OK
2021-06-02T02:38:44.896Z DEBUG modem >> AT+CGDCONT?
2021-06-02T02:38:44.908Z DEBUG modem << OK
2021-06-02T02:38:44.911Z DEBUG modem >> AT+CGACT?
2021-06-02T02:38:44.936Z DEBUG modem << OK
2021-06-02T02:38:44.939Z DEBUG modem >> AT+CGEREP=1
2021-06-02T02:38:44.946Z DEBUG modem << OK
2021-06-02T02:38:44.969Z DEBUG modem >> AT+CIND=1,1,1
2021-06-02T02:38:44.976Z DEBUG modem << OK
2021-06-02T02:38:44.979Z DEBUG modem >> AT+CEREG=5
2021-06-02T02:38:44.993Z DEBUG modem << OK
2021-06-02T02:38:44.996Z DEBUG modem >> AT+CEREG?
2021-06-02T02:38:45.008Z DEBUG modem << +CEREG: 5,4,"FFFE","FFFFFFFF",9,0,0,"00000000","00000000"
2021-06-02T02:38:45.010Z DEBUG modem << OK
2021-06-02T02:38:45.015Z DEBUG modem >> AT%CESQ=1
2021-06-02T02:38:45.027Z DEBUG modem << OK
2021-06-02T02:38:45.030Z DEBUG modem >> AT+CESQ
2021-06-02T02:38:45.042Z DEBUG modem << +CESQ: 99,99,255,255,255,255
2021-06-02T02:38:45.044Z DEBUG modem << OK
2021-06-02T02:38:45.048Z DEBUG modem >> AT%XSIM=1
2021-06-02T02:38:45.062Z DEBUG modem << OK
2021-06-02T02:38:45.065Z DEBUG modem >> AT%XSIM?
2021-06-02T02:38:45.075Z DEBUG modem << %XSIM: 1
2021-06-02T02:38:45.077Z DEBUG modem << OK
2021-06-02T02:38:45.081Z DEBUG modem >> AT+CPIN?
2021-06-02T02:38:45.095Z DEBUG modem << +CPIN: READY
2021-06-02T02:38:45.096Z DEBUG modem << OK
2021-06-02T02:38:45.109Z DEBUG modem >> AT+CPINR="SIM PIN"
2021-06-02T02:38:45.127Z DEBUG modem << +CPINR: "SIM PIN",3
2021-06-02T02:38:45.128Z DEBUG modem << OK
2021-06-02T02:38:45.143Z DEBUG modem >> AT+CIMI
2021-06-02T02:38:45.158Z DEBUG modem << 452040325353031
2021-06-02T02:38:45.159Z DEBUG modem << OK
2021-06-02T02:38:45.160Z INFO IMSIdentity: 452040325353031
2021-06-02T02:42:43.531Z INFO Modem port is opened
2021-06-02T02:42:43.533Z DEBUG modem >> AT+CFUN?
2021-06-02T02:42:43.558Z DEBUG modem << +CFUN: 0
2021-06-02T02:42:43.560Z DEBUG modem << OK
2021-06-02T02:42:47.964Z DEBUG modem << [00:00:05.232,574] [0m<inf> mqtt_simple: The MQTT simple sample started[0m
2021-06-02T02:42:47.967Z DEBUG modem << [00:00:05.232,574] [0m<inf> mqtt_simple: Disabling PSM and eDRX[0m
2021-06-02T02:42:47.969Z DEBUG modem << [00:00:05.248,474] [0m<inf> mqtt_simple: LTE Link Connecting...[0m
2021-06-02T02:42:48.946Z DEBUG modem << +CEREG: 2,"61E6","090A8C16",9,0,0,"11100000","11100000"
2021-06-02T02:42:49.343Z DEBUG modem << +CSCON: 1
2021-06-02T02:42:50.746Z DEBUG modem << +CEREG: 1,"61E6","090A8C16",9,,,"11100000","11100000"
2021-06-02T02:42:50.767Z DEBUG modem >> AT+COPS=3,2
2021-06-02T02:42:50.773Z DEBUG modem << OK
2021-06-02T02:42:50.776Z DEBUG modem >> AT+COPS?
2021-06-02T02:42:50.783Z DEBUG modem << +COPS: 0,2,"45204",9
2021-06-02T02:42:50.785Z DEBUG modem << OK
2021-06-02T02:42:50.801Z DEBUG modem >> AT%XCBAND
2021-06-02T02:42:50.807Z DEBUG modem << %XCBAND: 3
2021-06-02T02:42:50.809Z DEBUG modem << OK
2021-06-02T02:42:50.817Z DEBUG modem >> AT+CGDCONT?
2021-06-02T02:42:50.829Z DEBUG modem << +CGDCONT: 0,"IP","nbiot","9.75.31.91",0,0
2021-06-02T02:42:50.831Z DEBUG modem << OK
2021-06-02T02:42:50.844Z DEBUG modem >> AT+CGACT?
2021-06-02T02:42:50.862Z DEBUG modem << +CGACT: 0,1
2021-06-02T02:42:50.864Z DEBUG modem << OK
2021-06-02T02:42:51.758Z DEBUG modem << [00:00:09.040,222] [0m<inf> mqtt_simple: LTE Link Connected![0m
2021-06-02T02:42:51.762Z DEBUG modem << [00:00:09.960,784] [0m<inf> mqtt_simple: IPv4 Address found 137.135.83.217[0m
2021-06-02T02:42:53.776Z DEBUG modem << [00:00:11.051,849] [0m<inf> mqtt_simple: MQTT client connected[0m
2021-06-02T02:42:53.779Z DEBUG modem << [00:00:11.051,849] [0m<inf> mqtt_simple: Subscribing to: my/subscribe/topic len 18[0m
2021-06-02T02:42:53.780Z DEBUG modem << [00:00:11.566,894] [0m<inf> mqtt_simple: SUBACK packet id: 1234[0m
2021-06-02T02:42:55.133Z DEBUG modem << [00:00:12.397,888] [0m<inf> mqtt_simple: MQTT PUBLISH result=0 len=5[0m
2021-06-02T02:42:55.137Z DEBUG modem << [00:00:12.397,979] [0m<inf> mqtt_simple: Received: 45664[0m
2021-06-02T02:42:55.141Z DEBUG modem << [00:00:12.443,420] [0m<inf> mqtt_simple: Publishing: 45664[0m
2021-06-02T02:42:55.144Z DEBUG modem << [00:00:12.443,420] [0m<inf> mqtt_simple: to topic: my/publish/topic len: 16[0m
2021-06-02T02:42:55.147Z DEBUG modem << [00:00:12.885,894] [0m<inf> mqtt_simple: PUBACK packet id: 26503[0m
2021-06-02T02:43:24.972Z DEBUG modem << +CSCON: 0
2021-06-02T02:44:54.462Z DEBUG modem << +CSCON: 1
2021-06-02T02:45:25.949Z DEBUG modem << +CSCON: 0
2021-06-02T02:46:54.453Z DEBUG modem << +CSCON: 1
2021-06-02T02:47:25.958Z DEBUG modem << +CSCON: 0
2021-06-02T02:48:54.451Z DEBUG modem << +CSCON: 1
2021-06-02T02:49:25.956Z DEBUG modem << +CSCON: 0
2021-06-02T02:50:54.770Z DEBUG modem << +CSCON: 1
2021-06-02T02:50:56.963Z DEBUG modem >> AT
2021-06-02T02:50:56.996Z DEBUG modem << OK
2021-06-02T02:51:26.906Z DEBUG modem << +CSCON: 0
2021-06-02T02:52:54.450Z DEBUG modem << +CSCON: 1
2021-06-02T02:53:25.946Z DEBUG modem << +CSCON: 0
2021-06-02T02:54:54.769Z DEBUG modem << +CSCON: 1
2021-06-02T02:55:26.915Z DEBUG modem << +CSCON: 0
2021-06-02T02:56:54.769Z DEBUG modem << +CSCON: 1
2021-06-02T02:57:26.905Z DEBUG modem << +CSCON: 0
2021-06-02T02:58:54.768Z DEBUG modem << +CSCON: 1
2021-06-02T02:59:26.916Z DEBUG modem << +CSCON: 0
2021-06-02T03:00:54.778Z DEBUG modem << +CSCON: 1
2021-06-02T03:01:26.930Z DEBUG modem << +CSCON: 0
2021-06-02T03:02:54.449Z DEBUG modem << +CSCON: 1
2021-06-02T03:03:25.955Z DEBUG modem << +CSCON: 0
2021-06-02T03:04:54.354Z DEBUG modem << +CEREG: 1,"FFFE","FFFFFFFF",9,,,"11100000","11100000"
2021-06-02T03:04:54.390Z DEBUG modem >> AT+COPS=3,2
2021-06-02T03:04:54.396Z DEBUG modem << OK
2021-06-02T03:04:54.398Z DEBUG modem >> AT+COPS?
2021-06-02T03:04:54.405Z DEBUG modem << +COPS: 0
2021-06-02T03:04:54.406Z DEBUG modem << OK
2021-06-02T03:04:54.424Z DEBUG modem >> AT%XCBAND
2021-06-02T03:04:54.431Z DEBUG modem << %XCBAND: 0
2021-06-02T03:04:54.433Z DEBUG modem << OK
2021-06-02T03:04:54.442Z DEBUG modem >> AT+CGDCONT?
2021-06-02T03:04:54.460Z DEBUG modem << +CGDCONT: 0,"IP","nbiot","9.75.31.91",0,0
2021-06-02T03:04:54.462Z DEBUG modem << OK
2021-06-02T03:04:54.471Z DEBUG modem >> AT+CGACT?
2021-06-02T03:04:54.489Z DEBUG modem << +CGACT: 0,1
2021-06-02T03:04:54.490Z DEBUG modem << OK
2021-06-02T03:04:54.834Z DEBUG modem << +CEREG: 1,"61E6","090A8C16",9,,,"11100000","11100000"
2021-06-02T03:04:54.866Z DEBUG modem >> AT+COPS=3,2
2021-06-02T03:04:54.872Z DEBUG modem << OK
2021-06-02T03:04:54.874Z DEBUG modem >> AT+COPS?
2021-06-02T03:04:54.890Z DEBUG modem << +COPS: 0,2,"45204",9
2021-06-02T03:04:54.892Z DEBUG modem << OK
2021-06-02T03:04:54.911Z DEBUG modem >> AT%XCBAND
2021-06-02T03:04:54.917Z DEBUG modem << %XCBAND: 3
2021-06-02T03:04:54.919Z DEBUG modem << OK
2021-06-02T03:04:54.936Z DEBUG modem >> AT+CGDCONT?
2021-06-02T03:04:54.956Z DEBUG modem << +CGDCONT: 0,"IP","nbiot","9.75.31.91",0,0
2021-06-02T03:04:54.958Z DEBUG modem << OK
2021-06-02T03:04:54.974Z DEBUG modem >> AT+CGACT?
2021-06-02T03:04:54.988Z DEBUG modem << +CGACT: 0,1
2021-06-02T03:04:54.989Z DEBUG modem << OK
2021-06-02T03:04:55.014Z DEBUG modem << +CEREG: 1,"FFFE","FFFFFFFF",9,,,"11100000","11100000"
2021-06-02T03:04:55.019Z DEBUG modem >> AT+COPS=3,2
2021-06-02T03:04:55.027Z DEBUG modem << OK
2021-06-02T03:04:55.059Z DEBUG modem >> AT+COPS?
2021-06-02T03:04:55.074Z DEBUG modem << +COPS: 0
2021-06-02T03:04:55.075Z DEBUG modem << OK
2021-06-02T03:04:55.092Z DEBUG modem >> AT%XCBAND
2021-06-02T03:04:55.108Z DEBUG modem << %XCBAND: 0
2021-06-02T03:04:55.109Z DEBUG modem << OK
2021-06-02T03:04:55.125Z DEBUG modem >> AT+CGDCONT?
2021-06-02T03:04:55.141Z DEBUG modem << +CGDCONT: 0,"IP","nbiot","9.75.31.91",0,0
2021-06-02T03:04:55.143Z DEBUG modem << OK
2021-06-02T03:04:55.159Z DEBUG modem >> AT+CGACT?
2021-06-02T03:04:55.175Z DEBUG modem << +CGACT: 0,1
2021-06-02T03:04:55.178Z DEBUG modem << OK
2021-06-02T03:04:55.475Z DEBUG modem << +CEREG: 1,"61E6","090A8C16",9,,,"11100000","11100000"
2021-06-02T03:04:55.510Z DEBUG modem >> AT+COPS=3,2
2021-06-02T03:04:55.525Z DEBUG modem << OK
2021-06-02T03:04:55.541Z DEBUG modem >> AT+COPS?
2021-06-02T03:04:55.558Z DEBUG modem << +COPS: 0,2,"45204",9
2021-06-02T03:04:55.560Z DEBUG modem << OK
2021-06-02T03:04:55.577Z DEBUG modem >> AT%XCBAND
2021-06-02T03:04:55.598Z DEBUG modem << %XCBAND: 3
2021-06-02T03:04:55.599Z DEBUG modem << OK
2021-06-02T03:04:55.604Z DEBUG modem >> AT+CGDCONT?
2021-06-02T03:04:55.648Z DEBUG modem << +CGDCONT: 0,"IP","nbiot","9.75.31.91",0,0
2021-06-02T03:04:55.650Z DEBUG modem << OK
2021-06-02T03:04:55.656Z DEBUG modem >> AT+CGACT?
2021-06-02T03:04:55.674Z DEBUG modem << +CGACT: 0,1
2021-06-02T03:04:55.675Z DEBUG modem << OK
2021-06-02T03:04:56.050Z DEBUG modem << +CSCON: 1
2021-06-02T03:05:28.450Z DEBUG modem << +CSCON: 0
2021-06-02T03:06:55.089Z DEBUG modem << +CSCON: 1
2021-06-02T03:07:26.905Z DEBUG modem << +CSCON: 0
2021-06-02T03:08:54.202Z DEBUG modem << +CEREG: 1,"FFFE","FFFFFFFF",9,,,"11100000","11100000"
2021-06-02T03:08:54.238Z DEBUG modem >> AT+COPS=3,2
2021-06-02T03:08:54.245Z DEBUG modem << OK
2021-06-02T03:08:54.259Z DEBUG modem >> AT+COPS?
2021-06-02T03:08:54.266Z DEBUG modem << +COPS: 0
2021-06-02T03:08:54.267Z DEBUG modem << OK
2021-06-02T03:08:54.275Z DEBUG modem >> AT%XCBAND
2021-06-02T03:08:54.293Z DEBUG modem << %XCBAND: 0
2021-06-02T03:08:54.295Z DEBUG modem << OK
2021-06-02T03:08:54.299Z DEBUG modem >> AT+CGDCONT?
2021-06-02T03:08:54.326Z DEBUG modem << +CGDCONT: 0,"IP","nbiot","9.75.31.91",0,0
2021-06-02T03:08:54.327Z DEBUG modem << OK
2021-06-02T03:08:54.342Z DEBUG modem >> AT+CGACT?
2021-06-02T03:08:54.360Z DEBUG modem << +CGACT: 0,1
2021-06-02T03:08:54.361Z DEBUG modem << OK
2021-06-02T03:08:54.835Z DEBUG modem << +CEREG: 1,"61E6","090A8C16",9,,,"11100000","11100000"
2021-06-02T03:08:54.873Z DEBUG modem >> AT+COPS=3,2
2021-06-02T03:08:54.893Z DEBUG modem << OK
2021-06-02T03:08:54.906Z DEBUG modem >> AT+COPS?
2021-06-02T03:08:54.926Z DEBUG modem << +COPS: 0,2,"45204",9
2021-06-02T03:08:54.928Z DEBUG modem << OK
2021-06-02T03:08:54.943Z DEBUG modem >> AT%XCBAND
2021-06-02T03:08:54.960Z DEBUG modem << %XCBAND: 3
2021-06-02T03:08:54.962Z DEBUG modem << OK
2021-06-02T03:08:54.979Z DEBUG modem >> AT+CGDCONT?
2021-06-02T03:08:54.995Z DEBUG modem << +CGDCONT: 0,"IP","nbiot","9.75.31.91",0,0
2021-06-02T03:08:54.998Z DEBUG modem << OK
2021-06-02T03:08:55.020Z DEBUG modem >> AT+CGACT?
2021-06-02T03:08:55.038Z DEBUG modem << +CGACT: 0,1
2021-06-02T03:08:55.041Z DEBUG modem << OK
2021-06-02T03:08:56.058Z DEBUG modem << +CSCON: 1
2021-06-02T03:09:27.572Z DEBUG modem << +CSCON: 0
2021-06-02T03:10:54.206Z DEBUG modem << +CEREG: 1,"FFFE","FFFFFFFF",9,,,"11100000","11100000"
2021-06-02T03:10:54.212Z DEBUG modem >> AT+COPS=3,2
2021-06-02T03:10:54.229Z DEBUG modem << OK
2021-06-02T03:10:54.232Z DEBUG modem >> AT+COPS?
2021-06-02T03:10:54.239Z DEBUG modem << +COPS: 0
2021-06-02T03:10:54.240Z DEBUG modem << OK
2021-06-02T03:10:54.258Z DEBUG modem >> AT%XCBAND
2021-06-02T03:10:54.274Z DEBUG modem << %XCBAND: 0
2021-06-02T03:10:54.276Z DEBUG modem << OK
2021-06-02T03:10:54.281Z DEBUG modem >> AT+CGDCONT?
2021-06-02T03:10:54.299Z DEBUG modem << +CGDCONT: 0,"IP","nbiot","9.75.31.91",0,0
2021-06-02T03:10:54.301Z DEBUG modem << OK
2021-06-02T03:10:54.312Z DEBUG modem >> AT+CGACT?
2021-06-02T03:10:54.328Z DEBUG modem << +CGACT: 0,1
2021-06-02T03:10:54.329Z DEBUG modem << OK
2021-06-02T03:10:54.513Z DEBUG modem << +CEREG: 1,"61E6","090A8C16",9,,,"11100000","11100000"
2021-06-02T03:10:54.553Z DEBUG modem >> AT+COPS=3,2
2021-06-02T03:10:54.559Z DEBUG modem << OK
2021-06-02T03:10:54.561Z DEBUG modem >> AT+COPS?
2021-06-02T03:10:54.578Z DEBUG modem << +COPS: 0,2,"45204",9
2021-06-02T03:10:54.579Z DEBUG modem << OK
2021-06-02T03:10:54.595Z DEBUG modem >> AT%XCBAND
2021-06-02T03:10:54.602Z DEBUG modem << %XCBAND: 3
2021-06-02T03:10:54.605Z DEBUG modem << OK
2021-06-02T03:10:54.625Z DEBUG modem >> AT+CGDCONT?
2021-06-02T03:10:54.644Z DEBUG modem << +CGDCONT: 0,"IP","nbiot","9.75.31.91",0,0
2021-06-02T03:10:54.646Z DEBUG modem << OK
2021-06-02T03:10:54.661Z DEBUG modem >> AT+CGACT?
2021-06-02T03:10:54.679Z DEBUG modem << +CGACT: 0,1
2021-06-02T03:10:54.680Z DEBUG modem << OK
2021-06-02T03:10:55.088Z DEBUG modem << +CSCON: 1
2021-06-02T03:11:26.993Z DEBUG modem << +CSCON: 0
2021-06-02T03:11:53.671Z DEBUG modem << [00:29:10.996,765] [0m<inf> mqtt_simple: Publishing: Hello from nRF91 MQTT Simple Sample[0m
2021-06-02T03:11:53.674Z DEBUG modem << [00:29:10.996,795] [0m<inf> mqtt_simple: to topic: my/publish/topic len: 16[0m
2021-06-02T03:11:54.942Z DEBUG modem << +CEREG: 1,"61E6","090A8C17",9,,,"11100000","11100000"
2021-06-02T03:11:54.947Z DEBUG modem >> AT+COPS=3,2
2021-06-02T03:11:54.964Z DEBUG modem << OK
2021-06-02T03:11:54.968Z DEBUG modem >> AT+COPS?
2021-06-02T03:11:54.976Z DEBUG modem << +COPS: 0,2,"45204",9
2021-06-02T03:11:54.977Z DEBUG modem << OK
2021-06-02T03:11:54.996Z DEBUG modem >> AT%XCBAND
2021-06-02T03:11:55.012Z DEBUG modem << %XCBAND: 3
2021-06-02T03:11:55.013Z DEBUG modem << OK
2021-06-02T03:11:55.032Z DEBUG modem >> AT+CGDCONT?
2021-06-02T03:11:55.063Z DEBUG modem << +CGDCONT: 0,"IP","nbiot","9.75.31.91",0,0
2021-06-02T03:11:55.065Z DEBUG modem << OK
2021-06-02T03:11:55.086Z DEBUG modem >> AT+CGACT?
2021-06-02T03:11:55.114Z DEBUG modem << +CGACT: 0,1
2021-06-02T03:11:55.116Z DEBUG modem << OK
2021-06-02T03:11:55.285Z DEBUG modem << +CSCON: 1
2021-06-02T03:11:56.019Z DEBUG modem << +CEREG: 2,"61E6","090A8C17",9,0,0,"11100000","11100000"
2021-06-02T03:11:56.241Z DEBUG modem << +CSCON: 0
2021-06-02T03:11:56.827Z DEBUG modem << [00:29:14.141,479] [0m<inf> mqtt_simple: MQTT client disconnected: -57[0m
2021-06-02T03:11:56.828Z DEBUG modem << [00:29:14.141,510] [1;31m<err> mqtt_simple: mqtt_input: -57[0m
2021-06-02T03:11:56.832Z DEBUG modem << [00:29:14.141,510] [0m<inf> mqtt_simple: Disconnecting MQTT client...[0m
2021-06-02T03:11:56.849Z DEBUG modem << [00:29:14.141,571] [1;31m<err> mqtt_simple: Could not disconnect MQTT client: -57[0m
2021-06-02T03:11:56.850Z DEBUG modem << [00:29:14.141,571] [0m<inf> mqtt_simple: Reconnecting in 60 seconds...[0m
2021-06-02T03:11:57.167Z DEBUG modem << +CSCON: 1
2021-06-02T03:11:57.660Z DEBUG modem << +CEREG: 1,"61E6","090A8C17",9,,,"11100000","11100000"
2021-06-02T03:11:57.683Z DEBUG modem >> AT+COPS=3,2
2021-06-02T03:11:57.690Z DEBUG modem << OK
2021-06-02T03:11:57.693Z DEBUG modem >> AT+COPS?
2021-06-02T03:11:57.714Z DEBUG modem << +COPS: 0,2,"45204",9
2021-06-02T03:11:57.717Z DEBUG modem << OK
2021-06-02T03:11:57.744Z DEBUG modem >> AT%XCBAND
2021-06-02T03:11:57.768Z DEBUG modem << %XCBAND: 3
2021-06-02T03:11:57.771Z DEBUG modem << OK
2021-06-02T03:11:57.797Z DEBUG modem >> AT+CGDCONT?
2021-06-02T03:11:57.806Z DEBUG modem << +CGDCONT: 0,"IP","nbiot","9.45.186.255",0,0
2021-06-02T03:11:57.808Z DEBUG modem << OK
2021-06-02T03:11:57.833Z DEBUG modem >> AT+CGACT?
2021-06-02T03:11:57.840Z DEBUG modem << +CGACT: 0,1
2021-06-02T03:11:57.842Z DEBUG modem << OK
2021-06-02T03:12:28.043Z DEBUG modem << +CSCON: 0

[JONATHAN LL]

Hi,

Are you outside or inside when testing? 

In the top right corner of LTE Link Monitor you can see the connection info, should look like this


The CREG info loks fine.

The MQTT_simple does not have GPS by default, could you share your project? If you dont want to share your code public we can make the ticket private.



What sim card are you using and does other examples work, like the AssetTracker V2 or V1? 

Regards,
Jonathan

[Nahan_Trogn]

Hi,

I use Local Provider sim card (Viettel), AssetTracker V1 work fine well.

This is a disconnection from my newest project:

I see that signal strength is about 1-2 collumns

Is that cause from inside my code?

mqtt_simple.rar

My log file:

2021-06-02T16_16_49.365Z-log.txt

[JONATHAN LL]

It could be the broker that you use, is this issue consistent?
And do you have the same issue if you use the mqtt_simple example without GPS modifications?

Regards,
Jonathan

[Nahan_Trogn]

This is my log file with original mqtt_simple. In this log file, there are 2 case with different mqtt alive time: Case 1 alivetime = 600s; case 2 alivetime = 120s.

And the result:

_Case1: Client easy to disconnect, It requires RRC must "Connect" more time to contact with broker

_case 2: More stable, when alivetime time's up, RRC only need 1 "Connect" attempt to contact with Broker

Does alive time affect MQTT stability?

If I must use short alivetime (case2), then PSM is useless, and I find dificult when try to integrate GPS with mqtt_simple

Fullscreen 2021-06-03T13_04_30.505Z-log.txt Download

2021-06-03T13:04:31.587Z INFO Application data folder: C:\Users\Admin\AppData\Roaming\nrfconnect\pc-nrfconnect-linkmonitor
2021-06-03T13:04:31.714Z DEBUG App pc-nrfconnect-linkmonitor v1.1.10 official
2021-06-03T13:04:31.714Z DEBUG App path: C:\Users\Admin\.nrfconnect-apps\node_modules\pc-nrfconnect-linkmonitor
2021-06-03T13:04:31.715Z DEBUG nRFConnect 3.6.1 is supported by the app (^3.6.0)
2021-06-03T13:04:31.715Z DEBUG nRFConnect path: C:\Users\Admin\AppData\Local\Programs\nrfconnect\resources\app.asar
2021-06-03T13:04:31.715Z DEBUG HomeDir: C:\Users\Admin
2021-06-03T13:04:31.716Z DEBUG TmpDir: C:\Users\Admin\AppData\Local\Temp
//Part 1______XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX____________________________________________________________
2021-06-03T13:04:50.460Z INFO Modem port is opened
2021-06-03T13:05:00.284Z DEBUG modem << +CEREG: 2,"61E7","0076EB16",9,0,0,"11100000","11100000"
2021-06-03T13:05:01.292Z DEBUG modem << [00:00:16.803,009] [0m<inf> mqtt_simple: LTE cell changed: Cell ID: 7793430, Tracking area: 25063[0m
2021-06-03T13:05:16.789Z DEBUG modem << +CEREG: 2,"FFFE","FFFFFFFF",9,0,0,"11100000","11100000"
2021-06-03T13:05:17.797Z DEBUG modem << [00:00:33.308,593] [0m<inf> mqtt_simple: LTE cell changed: Cell ID: -1, Tracking area: 65534[0m
2021-06-03T13:05:18.204Z DEBUG modem << +CEREG: 2,"61E7","0076EB17",9,0,0,"11100000","11100000"
2021-06-03T13:05:18.598Z DEBUG modem << +CSCON: 1
2021-06-03T13:05:19.218Z DEBUG modem << [00:00:34.723,449] [0m<inf> mqtt_simple: LTE cell changed: Cell ID: 7793431, Tracking area: 25063[0m
2021-06-03T13:05:19.221Z DEBUG modem << [00:00:35.119,110] [0m<inf> mqtt_simple: RRC mode: Connected[0m
2021-06-03T13:05:19.967Z DEBUG modem << +CEREG: 1,"61E7","0076EB17",9,,,"11100000","11100000"
2021-06-03T13:05:20.998Z DEBUG modem << [00:00:36.488,159] [0m<inf> mqtt_simple: LTE Link Connected![0m
2021-06-03T13:05:21.000Z DEBUG modem << [00:00:36.488,250] [0m<inf> mqtt_simple: Network registration status: Connected - home network[0m
2021-06-03T13:05:21.003Z DEBUG modem << [00:00:36.488,250] [0m<inf> mqtt_simple: PSM parameter update: TAU: -1, Active time: -1[0m
2021-06-03T13:05:21.005Z DEBUG modem << [00:00:36.802,703] [0m<inf> mqtt_simple: IPv4 Address found 137.135.83.217[0m
2021-06-03T13:05:22.398Z DEBUG modem << [00:00:37.898,803] [0m<inf> mqtt_simple: MQTT client connected[0m
2021-06-03T13:05:22.400Z DEBUG modem << [00:00:37.898,803] [0m<inf> mqtt_simple: Subscribing to: my/subscribe/topic len 18[0m
2021-06-03T13:05:22.402Z DEBUG modem << [00:00:38.468,811] [0m<inf> mqtt_simple: SUBACK packet id: 1234[0m
2021-06-03T13:05:23.758Z DEBUG modem << [00:00:39.247,833] [0m<inf> mqtt_simple: MQTT PUBLISH result=0 len=5[0m
2021-06-03T13:05:23.762Z DEBUG modem << [00:00:39.247,924] [0m<inf> mqtt_simple: Received: 45664[0m
2021-06-03T13:05:23.764Z DEBUG modem << [00:00:39.293,457] [0m<inf> mqtt_simple: Publishing: 45664[0m
2021-06-03T13:05:23.766Z DEBUG modem << [00:00:39.293,457] [0m<inf> mqtt_simple: to topic: my/publish/topic len: 16[0m
2021-06-03T13:05:23.768Z DEBUG modem << [00:00:39.781,829] [0m<inf> mqtt_simple: PUBACK packet id: 38255[0m
2021-06-03T13:05:53.656Z DEBUG modem << +CSCON: 0
2021-06-03T13:05:54.661Z DEBUG modem << [00:01:10.178,710] [0m<inf> mqtt_simple: RRC mode: Idle[0m
2021-06-03T13:15:23.058Z DEBUG modem << +CSCON: 1
2021-06-03T13:15:24.064Z DEBUG modem << [00:10:39.604,644] [0m<inf> mqtt_simple: RRC mode: Connected[0m
2021-06-03T13:16:41.508Z DEBUG modem << +CSCON: 0
2021-06-03T13:16:42.513Z DEBUG modem << [00:11:58.059,173] [0m<inf> mqtt_simple: RRC mode: Idle[0m
2021-06-03T13:16:44.975Z DEBUG modem << +CSCON: 1
2021-06-03T13:16:45.982Z DEBUG modem << [00:12:01.526,672] [0m<inf> mqtt_simple: RRC mode: Connected[0m
2021-06-03T13:17:15.560Z DEBUG modem << +CSCON: 0
2021-06-03T13:17:16.565Z DEBUG modem << [00:12:32.112,030] [0m<inf> mqtt_simple: RRC mode: Idle[0m
2021-06-03T13:17:54.735Z DEBUG modem << +CSCON: 1
2021-06-03T13:17:55.741Z DEBUG modem << [00:13:11.288,421] [0m<inf> mqtt_simple: RRC mode: Connected[0m
2021-06-03T13:18:25.347Z DEBUG modem << +CSCON: 0
2021-06-03T13:18:26.352Z DEBUG modem << [00:13:41.901,794] [0m<inf> mqtt_simple: RRC mode: Idle[0m
2021-06-03T13:20:07.534Z DEBUG modem << +CSCON: 1
2021-06-03T13:20:08.539Z DEBUG modem << [00:15:24.092,742] [0m<inf> mqtt_simple: RRC mode: Connected[0m
2021-06-03T13:20:38.145Z DEBUG modem << +CSCON: 0
2021-06-03T13:20:39.150Z DEBUG modem << [00:15:54.705,139] [0m<inf> mqtt_simple: RRC mode: Idle[0m
2021-06-03T13:22:16.490Z DEBUG modem << +CSCON: 1
2021-06-03T13:22:17.496Z DEBUG modem << [00:17:33.055,084] [0m<inf> mqtt_simple: RRC mode: Connected[0m
2021-06-03T13:22:47.072Z DEBUG modem << +CSCON: 0
2021-06-03T13:22:48.078Z DEBUG modem << [00:18:03.638,427] [0m<inf> mqtt_simple: RRC mode: Idle[0m
2021-06-03T13:24:25.457Z DEBUG modem << +CSCON: 1
2021-06-03T13:24:26.497Z DEBUG modem << [00:19:42.028,320] [0m<inf> mqtt_simple: RRC mode: Connected[0m
2021-06-03T13:24:26.499Z DEBUG modem << [00:19:42.073,516] [0m<inf> mqtt_simple: MQTT client disconnected: -57[0m
2021-06-03T13:24:26.503Z DEBUG modem << [00:19:42.073,516] [1;31m<err> mqtt_simple: mqtt_input: -57[0m
2021-06-03T13:24:26.505Z DEBUG modem << [00:19:42.073,516] [0m<inf> mqtt_simple: Disconnecting MQTT client...[0m
2021-06-03T13:24:26.507Z DEBUG modem << [00:19:42.073,547] [1;31m<err> mqtt_simple: Could not disconnect MQTT client: -57[0m
2021-06-03T13:24:26.509Z DEBUG modem << [00:19:42.073,547] [0m<inf> mqtt_simple: Reconnecting in 60 seconds...[0m
2021-06-03T13:24:56.089Z DEBUG modem << +CSCON: 0
2021-06-03T13:24:57.094Z DEBUG modem << [00:20:12.660,614] [0m<inf> mqtt_simple: RRC mode: Idle[0m
2021-06-03T13:25:25.926Z DEBUG modem << +CSCON: 1
2021-06-03T13:25:26.932Z DEBUG modem << [00:20:42.499,847] [0m<inf> mqtt_simple: RRC mode: Connected[0m
2021-06-03T13:25:28.136Z DEBUG modem << [00:20:43.689,392] [0m<inf> mqtt_simple: MQTT client connected[0m
2021-06-03T13:25:28.138Z DEBUG modem << [00:20:43.689,392] [0m<inf> mqtt_simple: Subscribing to: my/subscribe/topic len 18[0m
2021-06-03T13:25:28.140Z DEBUG modem << [00:20:44.324,401] [0m<inf> mqtt_simple: SUBACK packet id: 1234[0m
2021-06-03T13:25:29.837Z DEBUG modem << [00:20:45.379,425] [0m<inf> mqtt_simple: MQTT PUBLISH result=0 len=5[0m
2021-06-03T13:25:29.839Z DEBUG modem << [00:20:45.379,516] [0m<inf> mqtt_simple: Received: 45664[0m
2021-06-03T13:25:29.841Z DEBUG modem << [00:20:45.425,048] [0m<inf> mqtt_simple: Publishing: 45664[0m
2021-06-03T13:25:29.844Z DEBUG modem << [00:20:45.425,048] [0m<inf> mqtt_simple: to topic: my/publish/topic len: 16[0m
2021-06-03T13:25:29.848Z DEBUG modem << [00:20:45.929,443] [0m<inf> mqtt_simple: PUBACK packet id: 41025[0m
2021-06-03T13:26:00.403Z DEBUG modem << +CSCON: 0
2021-06-03T13:26:01.409Z DEBUG modem << [00:21:16.978,332] [0m<inf> mqtt_simple: RRC mode: Idle[0m
2021-06-03T13:29:29.442Z DEBUG modem << +CSCON: 1
2021-06-03T13:29:30.479Z DEBUG modem << [00:24:46.026,062] [0m<inf> mqtt_simple: RRC mode: Connected[0m
2021-06-03T13:29:30.481Z DEBUG modem << [00:24:46.190,704] [0m<inf> mqtt_simple: MQTT PUBLISH result=0 len=0[0m
2021-06-03T13:29:30.484Z DEBUG modem << [00:24:46.190,704] [0m<inf> mqtt_simple: Received: [0m
2021-06-03T13:29:30.487Z DEBUG modem << [00:24:46.236,236] [0m<inf> mqtt_simple: Publishing: [0m
2021-06-03T13:29:30.489Z DEBUG modem << [00:24:46.236,267] [0m<inf> mqtt_simple: to topic: my/publish/topic len: 16[0m
2021-06-03T13:29:30.491Z DEBUG modem << [00:24:46.858,764] [0m<inf> mqtt_simple: PUBACK packet id: 36428[0m
2021-06-03T13:29:33.928Z DEBUG modem << [00:24:49.481,628] [0m<inf> mqtt_simple: MQTT PUBLISH result=0 len=2[0m
2021-06-03T13:29:33.932Z DEBUG modem << [00:24:49.481,750] [0m<inf> mqtt_simple: Received: gh[0m
2021-06-03T13:29:33.939Z DEBUG modem << [00:24:49.527,282] [0m<inf> mqtt_simple: Publishing: gh[0m
2021-06-03T13:29:33.945Z DEBUG modem << [00:24:49.527,282] [0m<inf> mqtt_simple: to topic: my/publish/topic len: 16[0m
2021-06-03T13:29:33.948Z DEBUG modem << [00:24:49.901,672] [0m<inf> mqtt_simple: PUBACK packet id: 26516[0m
2021-06-03T13:30:04.374Z DEBUG modem << +CSCON: 0
2021-06-03T13:30:05.379Z DEBUG modem << [00:25:20.959,533] [0m<inf> mqtt_simple: RRC mode: Idle[0m
2021-06-03T13:30:58.471Z INFO Modem port is opened
2021-06-03T13:30:59.535Z DEBUG modem << +CEREG: 2,"61E7","09026616",9,0,0,"11100000","11100000"
2021-06-03T13:31:00.544Z DEBUG modem << [00:00:03.671,508] [0m<inf> mqtt_simple: LTE cell changed: Cell ID: 151152150, Tracking area: 25063[0m
2021-06-03T13:31:01.630Z DEBUG modem << +CSCON: 1
2021-06-03T13:31:02.636Z DEBUG modem << [00:00:05.766,265] [0m<inf> mqtt_simple: RRC mode: Connected[0m
2021-06-03T13:31:05.241Z DEBUG modem << +CEREG: 1,"61E7","09026616",9,,,"11100000","11100000"
2021-06-03T13:31:06.271Z DEBUG modem << [00:00:09.378,326] [0m<inf> mqtt_simple: LTE Link Connected![0m
2021-06-03T13:31:06.274Z DEBUG modem << [00:00:09.378,417] [0m<inf> mqtt_simple: Network registration status: Connected - home network[0m
2021-06-03T13:31:06.278Z DEBUG modem << [00:00:09.378,448] [0m<inf> mqtt_simple: PSM parameter update: TAU: -1, Active time: -1[0m
2021-06-03T13:31:06.280Z DEBUG modem << [00:00:09.920,898] [0m<inf> mqtt_simple: IPv4 Address found 137.135.83.217[0m
2021-06-03T13:31:08.096Z DEBUG modem << [00:00:11.212,951] [0m<inf> mqtt_simple: MQTT client connected[0m
2021-06-03T13:31:08.097Z DEBUG modem << [00:00:11.212,982] [0m<inf> mqtt_simple: Subscribing to: my/subscribe/topic len 18[0m
2021-06-03T13:31:08.099Z DEBUG modem << [00:00:11.966,949] [0m<inf> mqtt_simple: SUBACK packet id: 1234[0m
2021-06-03T13:31:11.700Z DEBUG modem << [00:00:14.806,060] [0m<inf> mqtt_simple: MQTT PUBLISH result=0 len=5[0m
2021-06-03T13:31:11.702Z DEBUG modem << [00:00:14.806,152] [0m<inf> mqtt_simple: Received: 45664[0m
2021-06-03T13:31:11.703Z DEBUG modem << [00:00:14.851,623] [0m<inf> mqtt_simple: Publishing: 45664[0m
2021-06-03T13:31:11.705Z DEBUG modem << [00:00:14.851,623] [0m<inf> mqtt_simple: to topic: my/publish/topic len: 16[0m
2021-06-03T13:31:11.706Z DEBUG modem << [00:00:15.410,095] [0m<inf> mqtt_simple: PUBACK packet id: 30408[0m
2021-06-03T13:31:41.748Z DEBUG modem << +CSCON: 0
2021-06-03T13:31:42.753Z DEBUG modem << [00:00:45.885,955] [0m<inf> mqtt_simple: RRC mode: Idle[0m
2021-06-03T13:31:42.813Z DEBUG modem << +CEREG: 1,"61E7","0076EB17",9,,,"11100000","11100000"
2021-06-03T13:31:43.822Z DEBUG modem << [00:00:46.951,843] [0m<inf> mqtt_simple: LTE cell changed: Cell ID: 7793431, Tracking area: 25063[0m
2021-06-03T13:32:03.039Z DEBUG modem << +CSCON: 1
2021-06-03T13:32:03.919Z DEBUG modem << [00:01:07.178,894] [0m<inf> mqtt_simple: RRC mode: Connected[0m
2021-06-03T13:32:03.920Z DEBUG modem << [00:01:07.343,475] [0m<inf> mqtt_simple: MQTT PUBLISH result=0 len=3[0m
2021-06-03T13:32:03.923Z DEBUG modem << [00:01:07.343,566] [0m<inf> mqtt_simple: Received: gha[0m
2021-06-03T13:32:03.924Z DEBUG modem << [00:01:07.389,099] [0m<inf> mqtt_simple: Publishing: gha[0m
2021-06-03T13:32:03.925Z DEBUG modem << [00:01:07.389,099] [0m<inf> mqtt_simple: to topic: my/publish/topic len: 16[0m
2021-06-03T13:32:03.926Z DEBUG modem << [00:01:08.008,453] [0m<inf> mqtt_simple: MQTT client disconnected: -57[0m
2021-06-03T13:32:03.928Z DEBUG modem << [00:01:08.008,483] [1;31m<err> mqtt_simple: mqtt_input: -57[0m
2021-06-03T13:32:03.929Z DEBUG modem << [00:01:08.008,483] [0m<inf> mqtt_simple: Disconnecting MQTT client...[0m
2021-06-03T13:32:03.947Z DEBUG modem << [00:01:08.008,483] [1;31m<err> mqtt_simple: Could not disconnect MQTT client: -57[0m
2021-06-03T13:32:03.949Z DEBUG modem << [00:01:08.008,514] [0m<inf> mqtt_simple: Reconnecting in 60 seconds...[0m
2021-06-03T13:32:04.098Z DEBUG modem << +CEREG: 2,"61E7","0076EB17",9,0,0,"11100000","11100000"
2021-06-03T13:32:04.324Z DEBUG modem << +CSCON: 0
2021-06-03T13:32:04.961Z DEBUG modem << +CSCON: 1
2021-06-03T13:32:05.336Z DEBUG modem << [00:01:08.464,416] [0m<inf> mqtt_simple: RRC mode: Idle[0m
2021-06-03T13:32:05.337Z DEBUG modem << [00:01:09.099,792] [0m<inf> mqtt_simple: RRC mode: Connected[0m
2021-06-03T13:32:05.539Z DEBUG modem << +CEREG: 1,"61E7","0076EB17",9,,,"11100000","11100000"
2021-06-03T13:32:06.548Z DEBUG modem << [00:01:09.678,741] [0m<inf> mqtt_simple: Network registration status: Connected - home network[0m
2021-06-03T13:32:36.009Z DEBUG modem << +CSCON: 0
2021-06-03T13:32:37.015Z DEBUG modem << [00:01:40.150,207] [0m<inf> mqtt_simple: RRC mode: Idle[0m
2021-06-03T13:33:04.478Z DEBUG modem << +CSCON: 1
2021-06-03T13:33:05.484Z DEBUG modem << [00:02:08.620,361] [0m<inf> mqtt_simple: RRC mode: Connected[0m
2021-06-03T13:33:06.716Z DEBUG modem << [00:02:09.838,928] [0m<inf> mqtt_simple: MQTT client connected[0m
2021-06-03T13:33:06.718Z DEBUG modem << [00:02:09.838,958] [0m<inf> mqtt_simple: Subscribing to: my/subscribe/topic len 18[0m
2021-06-03T13:33:06.719Z DEBUG modem << [00:02:10.444,946] [0m<inf> mqtt_simple: SUBACK packet id: 1234[0m
2021-06-03T13:33:08.807Z DEBUG modem << [00:02:11.917,968] [0m<inf> mqtt_simple: MQTT PUBLISH result=0 len=5[0m
2021-06-03T13:33:08.808Z DEBUG modem << [00:02:11.918,090] [0m<inf> mqtt_simple: Received: 45664[0m
2021-06-03T13:33:08.810Z DEBUG modem << [00:02:11.963,562] [0m<inf> mqtt_simple: Publishing: 45664[0m
2021-06-03T13:33:08.812Z DEBUG modem << [00:02:11.963,592] [0m<inf> mqtt_simple: to topic: my/publish/topic len: 16[0m
2021-06-03T13:33:08.813Z DEBUG modem << [00:02:12.459,991] [0m<inf> mqtt_simple: PUBACK packet id: 63672[0m
2021-06-03T13:33:39.275Z DEBUG modem << +CSCON: 0
2021-06-03T13:33:40.281Z DEBUG modem << [00:02:43.418,884] [0m<inf> mqtt_simple: RRC mode: Idle[0m
2021-06-03T13:34:43.036Z DEBUG modem << +CSCON: 1
2021-06-03T13:34:43.231Z DEBUG modem << +CEREG: 1,"61E7","0076EB17",9,,,"00000100","00011100"
2021-06-03T13:34:43.634Z DEBUG modem << +CSCON: 0
2021-06-03T13:34:44.055Z DEBUG modem << [00:03:47.182,891] [0m<inf> mqtt_simple: RRC mode: Connected[0m
2021-06-03T13:34:44.057Z DEBUG modem << [00:03:47.378,753] [0m<inf> mqtt_simple: PSM parameter update: TAU: 16800, Active time: 8[0m
2021-06-03T13:34:44.058Z DEBUG modem << [00:03:47.780,487] [0m<inf> mqtt_simple: RRC mode: Idle[0m
2021-06-03T13:43:09.041Z DEBUG modem << +CEREG: 1,"61E7","09026616",9,,,"00000100","00011100"
2021-06-03T13:43:10.050Z DEBUG modem << [00:12:13.209,503] [0m<inf> mqtt_simple: LTE cell changed: Cell ID: 151152150, Tracking area: 25063[0m
2021-06-03T13:43:11.552Z DEBUG modem << +CSCON: 1
2021-06-03T13:43:12.573Z DEBUG modem << [00:12:15.720,947] [0m<inf> mqtt_simple: RRC mode: Connected[0m
2021-06-03T13:43:12.575Z DEBUG modem << [00:12:16.534,576] [0m<inf> mqtt_simple: MQTT client disconnected: -57[0m
2021-06-03T13:43:12.577Z DEBUG modem << [00:12:16.534,576] [1;31m<err> mqtt_simple: mqtt_input: -57[0m
2021-06-03T13:43:12.595Z DEBUG modem << [00:12:16.534,576] [0m<inf> mqtt_simple: Disconnecting MQTT client...[0m
2021-06-03T13:43:12.598Z DEBUG modem << [00:12:16.534,606] [1;31m<err> mqtt_simple: Could not disconnect MQTT client: -57[0m
2021-06-03T13:43:12.600Z DEBUG modem << [00:12:16.534,606] [0m<inf> mqtt_simple: Reconnecting in 60 seconds...[0m
2021-06-03T13:43:12.622Z DEBUG modem << +CEREG: 2,"61E7","09026616",9,0,0,"11100000","11100000"
2021-06-03T13:43:12.899Z DEBUG modem << +CSCON: 0
2021-06-03T13:43:13.904Z DEBUG modem << [00:12:17.067,474] [0m<inf> mqtt_simple: RRC mode: Idle[0m
2021-06-03T13:43:15.377Z DEBUG modem << +CSCON: 1
2021-06-03T13:43:16.140Z DEBUG modem << +CEREG: 1,"61E7","09026616",9,,,"00000100","00011101"
2021-06-03T13:43:16.401Z DEBUG modem << [00:12:19.545,959] [0m<inf> mqtt_simple: RRC mode: Connected[0m
2021-06-03T13:43:16.402Z DEBUG modem << [00:12:20.309,844] [0m<inf> mqtt_simple: Network registration status: Connected - home network[0m
2021-06-03T13:43:16.404Z DEBUG modem << [00:12:20.309,844] [0m<inf> mqtt_simple: PSM parameter update: TAU: 17400, Active time: 8[0m
2021-06-03T13:43:46.752Z DEBUG modem << +CSCON: 0
2021-06-03T13:43:47.757Z DEBUG modem << [00:12:50.922,332] [0m<inf> mqtt_simple: RRC mode: Idle[0m
2021-06-03T13:44:12.960Z DEBUG modem << +CEREG: 1,"61E7","0076EB17",9,,,"00000100","00011101"
2021-06-03T13:44:13.586Z DEBUG modem << +CSCON: 1
2021-06-03T13:44:13.975Z DEBUG modem << [00:13:17.131,103] [0m<inf> mqtt_simple: LTE cell changed: Cell ID: 7793431, Tracking area: 25063[0m
2021-06-03T13:44:13.976Z DEBUG modem << [00:13:17.757,476] [0m<inf> mqtt_simple: RRC mode: Connected[0m
2021-06-03T13:44:14.198Z DEBUG modem << +CEREG: 2,"61E7","0076EB17",9,0,0,"11100000","11100000"
2021-06-03T13:44:14.410Z DEBUG modem << +CSCON: 0
2021-06-03T13:44:15.052Z DEBUG modem << [00:13:18.205,200] [1;31m<err> mqtt_simple: mqtt_connect -60[0m
2021-06-03T13:44:15.055Z DEBUG modem << [00:13:18.205,230] [0m<inf> mqtt_simple: Reconnecting in 60 seconds...[0m
2021-06-03T13:44:15.057Z DEBUG modem << [00:13:18.581,024] [0m<inf> mqtt_simple: RRC mode: Idle[0m
2021-06-03T13:44:15.441Z DEBUG modem << +CEREG: 2,"61E7","09026616",9,0,0,"11100000","11100000"
2021-06-03T13:44:15.837Z DEBUG modem << +CSCON: 1
2021-06-03T13:44:16.405Z DEBUG modem << +CEREG: 1,"61E7","09026616",9,,,"00000100","00011100"
2021-06-03T13:44:16.474Z DEBUG modem << [00:13:19.612,548] [0m<inf> mqtt_simple: LTE cell changed: Cell ID: 151152150, Tracking area: 25063[0m
2021-06-03T13:44:16.475Z DEBUG modem << [00:13:20.007,446] [0m<inf> mqtt_simple: RRC mode: Connected[0m
2021-06-03T13:44:16.477Z DEBUG modem << [00:13:20.576,385] [0m<inf> mqtt_simple: Network registration status: Connected - home network[0m
2021-06-03T13:44:16.478Z DEBUG modem << [00:13:20.576,416] [0m<inf> mqtt_simple: PSM parameter update: TAU: 16800, Active time: 8[0m
2021-06-03T13:44:46.789Z DEBUG modem << +CSCON: 0
2021-06-03T13:44:47.794Z DEBUG modem << [00:13:50.961,853] [0m<inf> mqtt_simple: RRC mode: Idle[0m
2021-06-03T13:44:48.719Z DEBUG modem << +CEREG: 1,"61E7","0076EB17",9,,,"00000100","00011100"
2021-06-03T13:44:49.728Z DEBUG modem << [00:13:52.891,784] [0m<inf> mqtt_simple: LTE cell changed: Cell ID: 7793431, Tracking area: 25063[0m
2021-06-03T13:45:14.719Z DEBUG modem << +CEREG: 1,"61E7","09026616",9,,,"00000100","00011100"
2021-06-03T13:45:15.696Z DEBUG modem << +CSCON: 1
2021-06-03T13:45:15.734Z DEBUG modem << [00:14:18.892,639] [0m<inf> mqtt_simple: LTE cell changed: Cell ID: 151152150, Tracking area: 25063[0m
2021-06-03T13:45:15.735Z DEBUG modem << [00:14:19.870,025] [0m<inf> mqtt_simple: RRC mode: Connected[0m
2021-06-03T13:45:18.135Z DEBUG modem << [00:14:21.289,581] [0m<inf> mqtt_simple: MQTT client connected[0m
2021-06-03T13:45:18.137Z DEBUG modem << [00:14:21.289,581] [0m<inf> mqtt_simple: Subscribing to: my/subscribe/topic len 18[0m
2021-06-03T13:45:18.139Z DEBUG modem << [00:14:21.954,620] [0m<inf> mqtt_simple: SUBACK packet id: 1234[0m
2021-06-03T13:45:19.286Z DEBUG modem << [00:14:22.428,558] [0m<inf> mqtt_simple: MQTT PUBLISH result=0 len=5[0m
2021-06-03T13:45:19.287Z DEBUG modem << [00:14:22.428,649] [0m<inf> mqtt_simple: Received: 45664[0m
2021-06-03T13:45:19.289Z DEBUG modem << [00:14:22.474,151] [0m<inf> mqtt_simple: Publishing: 45664[0m
2021-06-03T13:45:19.290Z DEBUG modem << [00:14:22.474,151] [0m<inf> mqtt_simple: to topic: my/publish/topic len: 16[0m
2021-06-03T13:45:19.291Z DEBUG modem << [00:14:23.074,645] [0m<inf> mqtt_simple: PUBACK packet id: 33996[0m
2021-06-03T13:45:49.957Z DEBUG modem << +CSCON: 0
2021-06-03T13:45:50.558Z DEBUG modem << +CEREG: 1,"61E7","0076EB17",9,,,"00000100","00011100"
2021-06-03T13:45:50.972Z DEBUG modem << [00:14:54.132,537] [0m<inf> mqtt_simple: RRC mode: Idle[0m
2021-06-03T13:45:50.973Z DEBUG modem << [00:14:54.733,398] [0m<inf> mqtt_simple: LTE cell changed: Cell ID: 7793431, Tracking area: 25063[0m
2021-06-03T13:51:07.771Z DEBUG modem >> AT+CPSMS=0
2021-06-03T13:51:07.810Z DEBUG modem << OK
2021-06-03T13:51:08.939Z DEBUG modem << +CSCON: 1
2021-06-03T13:51:09.134Z DEBUG modem << +CEREG: 1,"61E7","0076EB17",9,,,"11100000","11100000"
2021-06-03T13:51:09.536Z DEBUG modem << +CSCON: 0
2021-06-03T13:51:09.958Z DEBUG modem << [00:20:13.128,112] [0m<inf> mqtt_simple: RRC mode: Connected[0m
2021-06-03T13:51:09.960Z DEBUG modem << [00:20:13.323,974] [0m<inf> mqtt_simple: PSM parameter update: TAU: -1, Active time: -1[0m
2021-06-03T13:51:09.961Z DEBUG modem << [00:20:13.725,708] [0m<inf> mqtt_simple: RRC mode: Idle[0m
//Part2______________________________________XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX_____________________________________________________________________________
2021-06-03T13:59:00.445Z INFO Modem port is opened
2021-06-03T13:59:01.584Z DEBUG modem << +CEREG: 2,"61E7","09026616",9,0,0,"11100000","11100000"
2021-06-03T13:59:02.593Z DEBUG modem << [00:00:06.765,502] [0m<inf> mqtt_simple: LTE cell changed: Cell ID: 151152150, Tracking area: 25063[0m
2021-06-03T13:59:39.826Z DEBUG modem << +CEREG: 2,"FFFE","FFFFFFFF",9,0,0,"11100000","11100000"
2021-06-03T13:59:40.835Z DEBUG modem << [00:00:45.009,704] [0m<inf> mqtt_simple: LTE cell changed: Cell ID: -1, Tracking area: 65534[0m
2021-06-03T13:59:42.142Z DEBUG modem << +CEREG: 2,"61E7","0076EB17",9,0,0,"11100000","11100000"
2021-06-03T13:59:43.151Z DEBUG modem << [00:00:47.325,714] [0m<inf> mqtt_simple: LTE cell changed: Cell ID: 7793431, Tracking area: 25063[0m
2021-06-03T13:59:50.218Z DEBUG modem << +CSCON: 1
2021-06-03T13:59:51.224Z DEBUG modem << [00:00:55.402,374] [0m<inf> mqtt_simple: RRC mode: Connected[0m
2021-06-03T13:59:51.556Z DEBUG modem << +CEREG: 1,"61E7","0076EB17",9,,,"11100000","11100000"
2021-06-03T13:59:52.580Z DEBUG modem << [00:00:56.740,447] [0m<inf> mqtt_simple: LTE Link Connected![0m
2021-06-03T13:59:52.581Z DEBUG modem << [00:00:56.740,509] [0m<inf> mqtt_simple: Network registration status: Connected - home network[0m
2021-06-03T13:59:52.583Z DEBUG modem << [00:00:56.740,539] [0m<inf> mqtt_simple: PSM parameter update: TAU: -1, Active time: -1[0m
2021-06-03T13:59:53.625Z DEBUG modem << [00:00:57.802,001] [0m<inf> mqtt_simple: IPv4 Address found 137.135.83.217[0m
2021-06-03T13:59:54.781Z DEBUG modem << [00:00:58.945,098] [0m<inf> mqtt_simple: MQTT client connected[0m
2021-06-03T13:59:54.782Z DEBUG modem << [00:00:58.945,129] [0m<inf> mqtt_simple: Subscribing to: my/subscribe/topic len 18[0m
2021-06-03T13:59:54.784Z DEBUG modem << [00:00:59.488,128] [0m<inf> mqtt_simple: SUBACK packet id: 1234[0m
2021-06-03T13:59:56.012Z DEBUG modem << [00:01:00.165,130] [0m<inf> mqtt_simple: MQTT PUBLISH result=0 len=5[0m
2021-06-03T13:59:56.013Z DEBUG modem << [00:01:00.165,222] [0m<inf> mqtt_simple: Received: 45664[0m
2021-06-03T13:59:56.014Z DEBUG modem << [00:01:00.210,693] [0m<inf> mqtt_simple: Publishing: 45664[0m
2021-06-03T13:59:56.016Z DEBUG modem << [00:01:00.210,693] [0m<inf> mqtt_simple: to topic: my/publish/topic len: 16[0m
2021-06-03T13:59:56.017Z DEBUG modem << [00:01:00.685,150] [0m<inf> mqtt_simple: PUBACK packet id: 17972[0m
2021-06-03T14:00:26.232Z DEBUG modem << +CSCON: 0
2021-06-03T14:00:27.237Z DEBUG modem << [00:01:31.417,999] [0m<inf> mqtt_simple: RRC mode: Idle[0m
2021-06-03T14:01:55.337Z DEBUG modem << +CSCON: 1
2021-06-03T14:01:56.342Z DEBUG modem << [00:03:00.525,634] [0m<inf> mqtt_simple: RRC mode: Connected[0m
2021-06-03T14:02:26.821Z DEBUG modem << +CSCON: 0
2021-06-03T14:02:27.827Z DEBUG modem << [00:03:32.012,054] [0m<inf> mqtt_simple: RRC mode: Idle[0m
2021-06-03T14:03:55.324Z DEBUG modem << +CSCON: 1
2021-06-03T14:03:56.330Z DEBUG modem << [00:05:00.518,737] [0m<inf> mqtt_simple: RRC mode: Connected[0m
2021-06-03T14:04:26.829Z DEBUG modem << +CSCON: 0
2021-06-03T14:04:27.834Z DEBUG modem << [00:05:32.025,115] [0m<inf> mqtt_simple: RRC mode: Idle[0m
2021-06-03T14:05:55.323Z DEBUG modem << +CSCON: 1
2021-06-03T14:05:56.328Z DEBUG modem << [00:07:00.521,759] [0m<inf> mqtt_simple: RRC mode: Connected[0m
2021-06-03T14:06:26.827Z DEBUG modem << +CSCON: 0
2021-06-03T14:06:27.832Z DEBUG modem << [00:07:32.028,167] [0m<inf> mqtt_simple: RRC mode: Idle[0m
2021-06-03T14:07:55.320Z DEBUG modem << +CSCON: 1
2021-06-03T14:07:56.326Z DEBUG modem << [00:09:00.524,841] [0m<inf> mqtt_simple: RRC mode: Connected[0m
2021-06-03T14:08:26.866Z DEBUG modem << +CSCON: 0
2021-06-03T14:08:27.820Z DEBUG modem << [00:09:32.021,209] [0m<inf> mqtt_simple: RRC mode: Idle[0m
2021-06-03T14:09:22.999Z DEBUG modem << +CSCON: 1
2021-06-03T14:09:24.035Z DEBUG modem << [00:10:28.207,061] [0m<inf> mqtt_simple: RRC mode: Connected[0m
2021-06-03T14:09:24.038Z DEBUG modem << [00:10:28.371,643] [0m<inf> mqtt_simple: MQTT PUBLISH result=0 len=3[0m
2021-06-03T14:09:24.050Z DEBUG modem << [00:10:28.371,734] [0m<inf> mqtt_simple: Received: gha[0m
2021-06-03T14:09:24.052Z DEBUG modem << [00:10:28.417,266] [0m<inf> mqtt_simple: Publishing: gha[0m
2021-06-03T14:09:24.054Z DEBUG modem << [00:10:28.417,297] [0m<inf> mqtt_simple: to topic: my/publish/topic len: 16[0m
2021-06-03T14:09:24.056Z DEBUG modem << [00:10:28.879,577] [0m<inf> mqtt_simple: PUBACK packet id: 56699[0m
2021-06-03T14:09:55.115Z DEBUG modem << +CSCON: 0
2021-06-03T14:09:56.121Z DEBUG modem << [00:11:00.325,439] [0m<inf> mqtt_simple: RRC mode: Idle[0m
2021-06-03T14:11:23.646Z DEBUG modem << +CSCON: 1
2021-06-03T14:11:24.652Z DEBUG modem << [00:12:28.860,107] [0m<inf> mqtt_simple: RRC mode: Connected[0m
2021-06-03T14:11:55.131Z DEBUG modem << +CSCON: 0
2021-06-03T14:11:56.136Z DEBUG modem << [00:13:00.346,527] [0m<inf> mqtt_simple: RRC mode: Idle[0m
2021-06-03T14:13:23.634Z DEBUG modem << +CSCON: 1
2021-06-03T14:13:24.640Z DEBUG modem << [00:14:28.853,179] [0m<inf> mqtt_simple: RRC mode: Connected[0m
2021-06-03T14:13:55.139Z DEBUG modem << +CSCON: 0
2021-06-03T14:13:56.144Z DEBUG modem << [00:15:00.359,588] [0m<inf> mqtt_simple: RRC mode: Idle[0m
2021-06-03T14:14:22.513Z DEBUG modem << +CSCON: 1
2021-06-03T14:14:22.708Z DEBUG modem << +CEREG: 1,"61E7","0076EB17",9,,,"00000100","00011011"
2021-06-03T14:14:23.111Z DEBUG modem << +CSCON: 0
2021-06-03T14:14:23.533Z DEBUG modem << [00:15:27.734,680] [0m<inf> mqtt_simple: RRC mode: Connected[0m
2021-06-03T14:14:23.535Z DEBUG modem << [00:15:27.930,541] [0m<inf> mqtt_simple: PSM parameter update: TAU: 16200, Active time: 8[0m
2021-06-03T14:14:23.536Z DEBUG modem << [00:15:28.332,305] [0m<inf> mqtt_simple: RRC mode: Idle[0m
2021-06-03T14:15:24.281Z DEBUG modem << +CSCON: 1
2021-06-03T14:15:25.288Z DEBUG modem << [00:16:29.506,225] [0m<inf> mqtt_simple: RRC mode: Connected[0m
2021-06-03T14:15:55.797Z DEBUG modem << +CSCON: 0
2021-06-03T14:15:56.802Z DEBUG modem << [00:17:01.022,674] [0m<inf> mqtt_simple: RRC mode: Idle[0m
2021-06-03T14:17:24.269Z DEBUG modem << +CSCON: 1
2021-06-03T14:17:25.275Z DEBUG modem << [00:18:29.499,328] [0m<inf> mqtt_simple: RRC mode: Connected[0m
2021-06-03T14:17:55.775Z DEBUG modem << +CSCON: 0
2021-06-03T14:17:56.780Z DEBUG modem << [00:19:01.005,737] [0m<inf> mqtt_simple: RRC mode: Idle[0m
2021-06-03T14:19:24.267Z DEBUG modem << +CSCON: 1
2021-06-03T14:19:25.314Z DEBUG modem << [00:20:29.502,410] [0m<inf> mqtt_simple: RRC mode: Connected[0m
2021-06-03T14:19:25.316Z DEBUG modem << [00:20:30.472,900] [0m<inf> mqtt_simple: MQTT PUBLISH result=0 len=3[0m
2021-06-03T14:19:25.317Z DEBUG modem << [00:20:30.472,991] [0m<inf> mqtt_simple: Received: gha[0m
2021-06-03T14:19:25.318Z DEBUG modem << [00:20:30.518,554] [0m<inf> mqtt_simple: Publishing: gha[0m
2021-06-03T14:19:25.319Z DEBUG modem << [00:20:30.518,554] [0m<inf> mqtt_simple: to topic: my/publish/topic len: 16[0m
2021-06-03T14:19:28.506Z DEBUG modem << [00:20:32.734,985] [0m<inf> mqtt_simple: PUBACK packet id: 18555[0m
2021-06-03T14:19:58.484Z DEBUG modem << +CSCON: 0
2021-06-03T14:19:59.489Z DEBUG modem << [00:21:03.720,886] [0m<inf> mqtt_simple: RRC mode: Idle[0m
2021-06-03T14:20:45.409Z INFO Modem port is closed

[JONATHAN LL]

Hi,

Sorry for the slow response. 

So NBIOT might be the issue here, due to the low bandwidth of NBIOT it is not really ideal conditions for a TCP connection. As for the keepalive timer and disconnection issues there is a higher chance of disconnects when increasing the keep alive. This is not a issue on a LTE-M network. Are you able to test you software on a LTE-M network. 

Regards,
Jonathan

[Nahan_Trogn]

Hi,

My provider doesn't support LTE-M. Thank you for all your assistance.