2021-05-12T12:05:11.478Z INFO Application data folder: C:\Users\adeel\AppData\Roaming\nrfconnect\pc-nrfconnect-linkmonitor
2021-05-12T12:05:11.494Z VERBOSE Could not fetch serial number for serial port at COM3
2021-05-12T12:05:11.494Z VERBOSE Could not fetch serial number for serial port at COM1
2021-05-12T12:05:11.578Z DEBUG App pc-nrfconnect-linkmonitor v1.1.10 official
2021-05-12T12:05:11.579Z DEBUG App path: C:\Users\adeel\.nrfconnect-apps\node_modules\pc-nrfconnect-linkmonitor
2021-05-12T12:05:11.579Z DEBUG nRFConnect 3.6.1 is supported by the app (^3.6.0)
2021-05-12T12:05:11.579Z DEBUG nRFConnect path: C:\Users\adeel\AppData\Local\Programs\nrfconnect\resources\app.asar
2021-05-12T12:05:11.580Z DEBUG HomeDir: C:\Users\adeel
2021-05-12T12:05:11.580Z DEBUG TmpDir: C:\Users\adeel\AppData\Local\Temp
2021-05-12T12:05:30.576Z INFO Modem port is opened
2021-05-12T12:05:30.582Z DEBUG modem >> AT+CFUN?
2021-05-12T12:05:30.608Z DEBUG modem << +CFUN: 1
2021-05-12T12:05:30.611Z DEBUG modem << OK
2021-05-12T12:05:30.628Z DEBUG modem >> AT+CGSN=1
2021-05-12T12:05:30.637Z DEBUG modem << +CGSN: "352656101119801"
2021-05-12T12:05:30.639Z DEBUG modem << OK
2021-05-12T12:05:30.642Z DEBUG modem >> AT+CGMI
2021-05-12T12:05:30.650Z DEBUG modem << Nordic Semiconductor ASA
2021-05-12T12:05:30.652Z DEBUG modem << OK
2021-05-12T12:05:30.659Z DEBUG modem >> AT+CGMM
2021-05-12T12:05:30.666Z DEBUG modem << nRF9160-SICA
2021-05-12T12:05:30.668Z DEBUG modem << OK
2021-05-12T12:05:30.670Z DEBUG modem >> AT+CGMR
2021-05-12T12:05:30.678Z DEBUG modem << mfw_nrf9160_1.2.2-FOTA-TEST
2021-05-12T12:05:30.680Z DEBUG modem << OK
2021-05-12T12:05:30.681Z INFO Nordic Semiconductor ASA nRF9160-SICA [mfw_nrf9160_1.2.2-FOTA-TEST] SerNr: 352656101119801
2021-05-12T12:05:30.683Z DEBUG modem >> AT+CEMODE?
2021-05-12T12:05:30.690Z DEBUG modem << +CEMODE: 0
2021-05-12T12:05:30.692Z DEBUG modem << OK
2021-05-12T12:05:30.699Z DEBUG modem >> AT%XCBAND=?
2021-05-12T12:05:30.711Z DEBUG modem << %XCBAND: (1,2,3,4,5,8,12,13,17,19,20,25,26,28,66)
2021-05-12T12:05:30.715Z DEBUG modem << OK
2021-05-12T12:05:30.730Z DEBUG modem >> AT+CMEE?
2021-05-12T12:05:30.737Z DEBUG modem << +CMEE: 0
2021-05-12T12:05:30.739Z DEBUG modem << OK
2021-05-12T12:05:30.742Z DEBUG modem >> AT+CMEE=1
2021-05-12T12:05:30.749Z DEBUG modem << OK
2021-05-12T12:05:30.755Z DEBUG modem >> AT+CNEC?
2021-05-12T12:05:30.763Z DEBUG modem << +CNEC: 0
2021-05-12T12:05:30.764Z DEBUG modem << OK
2021-05-12T12:05:30.768Z DEBUG modem >> AT+CNEC=24
2021-05-12T12:05:30.775Z DEBUG modem << OK
2021-05-12T12:05:30.779Z DEBUG modem >> AT+CGEREP?
2021-05-12T12:05:30.789Z DEBUG modem << +CGEREP: 0,0
2021-05-12T12:05:30.794Z DEBUG modem << OK
2021-05-12T12:05:30.798Z DEBUG modem >> AT+CGDCONT?
2021-05-12T12:05:30.805Z DEBUG modem << OK
2021-05-12T12:05:30.808Z DEBUG modem >> AT+CGACT?
2021-05-12T12:05:30.814Z DEBUG modem << OK
2021-05-12T12:05:30.819Z DEBUG modem >> AT+CGEREP=1
2021-05-12T12:05:30.826Z DEBUG modem << OK
2021-05-12T12:05:30.830Z DEBUG modem >> AT+CIND=1,1,1
2021-05-12T12:05:30.836Z DEBUG modem << OK
2021-05-12T12:05:30.841Z DEBUG modem >> AT+CEREG=5
2021-05-12T12:05:30.855Z DEBUG modem << OK
2021-05-12T12:05:30.859Z DEBUG modem >> AT+CEREG?
2021-05-12T12:05:30.872Z DEBUG modem << +CEREG: 5,4,"FFFE","FFFFFFFF",9,0,0,"00000000","00000000"
2021-05-12T12:05:30.873Z DEBUG modem << OK
2021-05-12T12:05:30.879Z DEBUG modem >> AT%CESQ=1
2021-05-12T12:05:30.887Z DEBUG modem << OK
2021-05-12T12:05:30.891Z DEBUG modem >> AT+CESQ
2021-05-12T12:05:30.900Z DEBUG modem << +CESQ: 99,99,255,255,255,255
2021-05-12T12:05:30.902Z DEBUG modem << OK
2021-05-12T12:05:30.907Z DEBUG modem >> AT%XSIM=1
2021-05-12T12:05:30.913Z DEBUG modem << OK
2021-05-12T12:05:30.919Z DEBUG modem >> AT%XSIM?
2021-05-12T12:05:30.926Z DEBUG modem << %XSIM: 0
2021-05-12T12:05:30.927Z DEBUG modem << OK
2021-05-12T12:05:33.879Z DEBUG modem << meter_modem_data_init()...
2021-05-12T12:05:33.880Z DEBUG modem << LTE connection established!
2021-05-12T12:05:33.911Z DEBUG modem << E: Link data not obtained: 20 -22
2021-05-12T12:05:33.913Z DEBUG modem << E: Network data not obtained: -22
2021-05-12T12:05:33.914Z DEBUG modem << Modem Infos:
2021-05-12T12:05:33.915Z DEBUG modem <<          IP: 10.205.85.10
2021-05-12T12:05:33.915Z DEBUG modem <<     ue_mode: 0
2021-05-12T12:05:33.916Z DEBUG modem <<    lte_mode: 0
2021-05-12T12:05:33.916Z DEBUG modem <<    current_band: 8
2021-05-12T12:05:33.917Z DEBUG modem <<    area_code: 52015
2021-05-12T12:05:33.918Z DEBUG modem <<    operator: 26201
2021-05-12T12:05:33.918Z DEBUG modem <<    dev imei: 352656101119801
2021-05-12T12:05:33.918Z DEBUG modem <<    modem_firmware: 
2021-05-12T12:05:34.430Z DEBUG modem << DATE_TIME: got time from NTP.
2021-05-12T12:05:34.432Z DEBUG modem << Initialize cJSON...done!
2021-05-12T12:05:34.434Z DEBUG modem <<   Using cJSON ver. 1.7.12
2021-05-12T12:05:44.440Z DEBUG modem << ----------------------------------------------------------------
2021-05-12T12:05:44.441Z DEBUG modem << work_handler_collect_data started!
2021-05-12T12:05:44.948Z DEBUG modem << Getting latest time...done!
2021-05-12T12:05:44.949Z DEBUG modem << Reading out bosch sensor...done!
2021-05-12T12:05:44.959Z DEBUG modem << Reading out modem info...done!
2021-05-12T12:05:46.992Z DEBUG modem << Reading out Teros12 sensor...done!
2021-05-12T12:05:46.993Z DEBUG modem << ++++++++++++++++++++++++++++++++++++++++++++
2021-05-12T12:05:46.997Z DEBUG modem << Measured data:
2021-05-12T12:05:46.999Z DEBUG modem <<        Timer: 2021-05-12 12:05:44
2021-05-12T12:05:47.000Z DEBUG modem <<  #msg queued: 0
2021-05-12T12:05:47.002Z DEBUG modem <<  Temperature: 27.000 degC
2021-05-12T12:05:47.003Z DEBUG modem <<     Pressure: 94.785 
2021-05-12T12:05:47.004Z DEBUG modem <<     Humidity: 28.921 
2021-05-12T12:05:47.005Z DEBUG modem <<     SoilTemp: 0.000 degC
2021-05-12T12:05:47.006Z DEBUG modem <<    VolWaterC: 0.000
2021-05-12T12:05:47.006Z DEBUG modem <<   Modem bat.: 4.394 V
2021-05-12T12:05:47.007Z DEBUG modem <<   Current band: 8
2021-05-12T12:05:47.008Z DEBUG modem <<         RSRP: -141
2021-05-12T12:05:47.008Z DEBUG modem << ++++++++++++++++++++++++++++++++++++++++++++
2021-05-12T12:05:49.442Z DEBUG modem << ----------------------------------------------------------------
2021-05-12T12:05:49.444Z DEBUG modem << work_handler_send_data started!
2021-05-12T12:05:49.445Z DEBUG modem << Length of message queue : 1
2021-05-12T12:05:49.944Z DEBUG modem << mqtt_meter_init running...
2021-05-12T12:05:51.632Z DEBUG modem << IPv4 Address 13.80.240.194
2021-05-12T12:05:51.633Z DEBUG modem << MQTT Meter client id: Meter
2021-05-12T12:05:51.730Z DEBUG modem << Using device imei: 352656101119801
2021-05-12T12:05:51.731Z DEBUG modem << mqtt_meter_init finished!
2021-05-12T12:05:51.735Z DEBUG modem << Starting to send 1 messages:
2021-05-12T12:05:51.737Z DEBUG modem << Need to send 1 more messages:
2021-05-12T12:05:51.832Z DEBUG modem << [mqtt_evt_handler:179] MQTT client connected!
2021-05-12T12:05:51.837Z DEBUG modem << Subscribing to: sensorstest1/nrf352656101119801/command
2021-05-12T12:05:51.922Z DEBUG modem << [mqtt_evt_handler:287] SUBACK packet id: 1234
2021-05-12T12:05:52.027Z DEBUG modem << [mqtt_evt_handler:276] PUBACK packet id: 40847
2021-05-12T12:05:52.138Z DEBUG modem << [mqtt_evt_handler:276] PUBACK packet id: 20109
2021-05-12T12:05:52.250Z DEBUG modem << [mqtt_evt_handler:276] PUBACK packet id: 4352
2021-05-12T12:05:52.370Z DEBUG modem << [mqtt_evt_handler:276] PUBACK packet id: 58206
2021-05-12T12:05:52.482Z DEBUG modem << [mqtt_evt_handler:276] PUBACK packet id: 51472
2021-05-12T12:05:52.593Z DEBUG modem << [mqtt_evt_handler:276] PUBACK packet id: 60475
2021-05-12T12:05:52.697Z DEBUG modem << [mqtt_evt_handler:276] PUBACK packet id: 41695
2021-05-12T12:05:52.852Z DEBUG modem << [mqtt_evt_handler:197] MQTT client disconnected 0
2021-05-12T12:05:52.857Z DEBUG modem << All messages have been send!
2021-05-12T12:06:44.437Z DEBUG modem << ----------------------------------------------------------------
2021-05-12T12:06:44.439Z DEBUG modem << work_handler_collect_data started!
2021-05-12T12:06:44.946Z DEBUG modem << Getting latest time...done!
2021-05-12T12:06:44.946Z DEBUG modem << Reading out bosch sensor...done!
2021-05-12T12:06:44.957Z DEBUG modem << Reading out modem info...done!
2021-05-12T12:06:46.989Z DEBUG modem << Reading out Teros12 sensor...done!
2021-05-12T12:06:46.990Z DEBUG modem << ++++++++++++++++++++++++++++++++++++++++++++
2021-05-12T12:06:46.995Z DEBUG modem << Measured data:
2021-05-12T12:06:46.996Z DEBUG modem <<        Timer: 2021-05-12 12:06:44
2021-05-12T12:06:46.998Z DEBUG modem <<  #msg queued: 0
2021-05-12T12:06:46.999Z DEBUG modem <<  Temperature: 27.020 degC
2021-05-12T12:06:46.999Z DEBUG modem <<     Pressure: 94.783 
2021-05-12T12:06:47.000Z DEBUG modem <<     Humidity: 29.003 
2021-05-12T12:06:47.000Z DEBUG modem <<     SoilTemp: 0.000 degC
2021-05-12T12:06:47.001Z DEBUG modem <<    VolWaterC: 0.000
2021-05-12T12:06:47.001Z DEBUG modem <<   Modem bat.: 4.394 V
2021-05-12T12:06:47.001Z DEBUG modem <<   Current band: 8
2021-05-12T12:06:47.002Z DEBUG modem <<         RSRP: -100
2021-05-12T12:06:47.002Z DEBUG modem << ++++++++++++++++++++++++++++++++++++++++++++
2021-05-12T12:07:44.435Z DEBUG modem << ----------------------------------------------------------------
2021-05-12T12:07:44.436Z DEBUG modem << work_handler_collect_data started!
2021-05-12T12:07:44.944Z DEBUG modem << Getting latest time...done!
2021-05-12T12:07:44.945Z DEBUG modem << Reading out bosch sensor...done!
2021-05-12T12:07:44.955Z DEBUG modem << Reading out modem info...done!
2021-05-12T12:07:46.987Z DEBUG modem << Reading out Teros12 sensor...done!
2021-05-12T12:07:46.988Z DEBUG modem << ++++++++++++++++++++++++++++++++++++++++++++
2021-05-12T12:07:46.994Z DEBUG modem << Measured data:
2021-05-12T12:07:46.995Z DEBUG modem <<        Timer: 2021-05-12 12:07:44
2021-05-12T12:07:46.996Z DEBUG modem <<  #msg queued: 1
2021-05-12T12:07:46.997Z DEBUG modem <<  Temperature: 26.640 degC
2021-05-12T12:07:46.997Z DEBUG modem <<     Pressure: 94.783 
2021-05-12T12:07:46.998Z DEBUG modem <<     Humidity: 29.384 
2021-05-12T12:07:46.998Z DEBUG modem <<     SoilTemp: 0.000 degC
2021-05-12T12:07:46.999Z DEBUG modem <<    VolWaterC: 0.000
2021-05-12T12:07:47.000Z DEBUG modem <<   Modem bat.: 4.394 V
2021-05-12T12:07:47.001Z DEBUG modem <<   Current band: 8
2021-05-12T12:07:47.001Z DEBUG modem <<         RSRP: -100
2021-05-12T12:07:47.002Z DEBUG modem << ++++++++++++++++++++++++++++++++++++++++++++
2021-05-12T12:07:49.438Z DEBUG modem << ----------------------------------------------------------------
2021-05-12T12:07:49.439Z DEBUG modem << work_handler_send_data started!
2021-05-12T12:07:49.445Z DEBUG modem << Length of message queue : 2
2021-05-12T12:07:49.940Z DEBUG modem << mqtt_meter_init running...
2021-05-12T12:07:49.954Z DEBUG modem << IPv4 Address 13.80.240.194
2021-05-12T12:07:49.955Z DEBUG modem << MQTT Meter client id: Meter
2021-05-12T12:07:52.409Z DEBUG modem << Using device imei: 352656101119801
2021-05-12T12:07:52.411Z DEBUG modem << mqtt_meter_init finished!
2021-05-12T12:07:52.417Z DEBUG modem << Starting to send 2 messages:
2021-05-12T12:07:52.418Z DEBUG modem << Need to send 2 more messages:
2021-05-12T12:07:52.511Z DEBUG modem << [mqtt_evt_handler:179] MQTT client connected!
2021-05-12T12:07:52.512Z DEBUG modem << Subscribing to: sensorstest1/nrf352656101119801/command
2021-05-12T12:07:52.634Z DEBUG modem << MQTT PUBLISH result=0 len=4
2021-05-12T12:07:52.635Z DEBUG modem << Received MQTT Buf: 
2021-05-12T12:07:52.635Z DEBUG modem << FOTA
2021-05-12T12:07:52.636Z DEBUG modem << Payload buf is : FOTA
2021-05-12T12:07:52.736Z DEBUG modem << [mqtt_evt_handler:287] SUBACK packet id: 1234
2021-05-12T12:07:52.841Z DEBUG modem << [mqtt_evt_handler:276] PUBACK packet id: 16585
2021-05-12T12:07:52.946Z DEBUG modem << [mqtt_evt_handler:276] PUBACK packet id: 3144
2021-05-12T12:07:53.066Z DEBUG modem << [mqtt_evt_handler:276] PUBACK packet id: 51224
2021-05-12T12:07:53.169Z DEBUG modem << [mqtt_evt_handler:276] PUBACK packet id: 56557
2021-05-12T12:07:53.281Z DEBUG modem << [mqtt_evt_handler:276] PUBACK packet id: 11750
2021-05-12T12:07:53.394Z DEBUG modem << [mqtt_evt_handler:276] PUBACK packet id: 59118
2021-05-12T12:07:53.548Z DEBUG modem << Need to send 1 more messages:
2021-05-12T12:07:53.557Z DEBUG modem << [mqtt_evt_handler:276] PUBACK packet id: 48968
2021-05-12T12:07:53.610Z DEBUG modem << [mqtt_evt_handler:276] PUBACK packet id: 29619
2021-05-12T12:07:53.721Z DEBUG modem << [mqtt_evt_handler:276] PUBACK packet id: 18647
2021-05-12T12:07:53.826Z DEBUG modem << [mqtt_evt_handler:276] PUBACK packet id: 44297
2021-05-12T12:07:53.936Z DEBUG modem << [mqtt_evt_handler:276] PUBACK packet id: 56929
2021-05-12T12:07:54.048Z DEBUG modem << [mqtt_evt_handler:276] PUBACK packet id: 63531
2021-05-12T12:07:54.161Z DEBUG modem << [mqtt_evt_handler:276] PUBACK packet id: 22827
2021-05-12T12:07:54.266Z DEBUG modem << [mqtt_evt_handler:276] PUBACK packet id: 43565
2021-05-12T12:07:54.386Z DEBUG modem << [mqtt_evt_handler:276] PUBACK packet id: 43866
2021-05-12T12:07:54.540Z DEBUG modem << [mqtt_evt_handler:197] MQTT client disconnected 0
2021-05-12T12:07:54.542Z DEBUG modem << All messages have been send!
2021-05-12T12:08:02.643Z DEBUG modem << ----------------------------------------------------------------
2021-05-12T12:08:02.644Z DEBUG modem << work_handler_fota started!
2021-05-12T12:08:03.147Z DEBUG modem << Initializing MQTT AWS broker via mqtt_aws_init()...
2021-05-12T12:08:05.267Z DEBUG modem << IPv4 Address 18.159.13.101
2021-05-12T12:08:05.268Z DEBUG modem << MQTT AWS client id: nrf-testing1
2021-05-12T12:08:11.033Z DEBUG modem << Initializing aws_fota_init()...
2021-05-12T12:08:11.274Z DEBUG modem << [mqtt_evt_handler:179] MQTT client connected!
2021-05-12T12:08:11.686Z DEBUG modem << [mqtt_evt_handler:276] PUBACK packet id: 55247
2021-05-12T12:08:12.034Z DEBUG modem << [mqtt_evt_handler:276] PUBACK packet id: 59550
2021-05-12T12:09:12.291Z DEBUG modem << [mqtt_evt_handler:276] PUBACK packet id: 16638
2021-05-12T12:09:14.471Z DEBUG modem << I: Configuring socket timeout (30 s)
2021-05-12T12:09:14.477Z DEBUG modem << I: Connecting to nrfbuc.s3.eu-central-1.amazonaws.com
2021-05-12T12:09:14.796Z DEBUG modem << I: Downloading: app_update.bin [0]
2021-05-12T12:09:14.803Z DEBUG modem << AWS_FOTA_EVT_START, job id = 3
2021-05-12T12:09:14.805Z DEBUG modem << [mqtt_evt_handler:276] PUBACK packet id: 22132
2021-05-12T12:09:14.807Z DEBUG modem << E: Job document update was rejected
2021-05-12T12:09:14.808Z DEBUG modem << E: Job document update was rejected
2021-05-12T12:09:14.846Z DEBUG modem << [mqtt_evt_handler:197] MQTT client disconnected 0
2021-05-12T12:09:14.848Z DEBUG modem << Disconnecting MQTT client again again...
2021-05-12T12:09:14.849Z DEBUG modem << E: Error -5 when getting the payload
2021-05-12T12:09:14.849Z DEBUG modem << aws_fota_mqtt_evt_handler: Failed! -5
2021-05-12T12:09:14.851Z DEBUG modem << [mqtt_evt_handler:210] MQTT PUBLISH result=0 len=177
2021-05-12T12:09:14.852Z DEBUG modem << mqtt_read_publish_payload: Failed! -5
2021-05-12T12:09:14.852Z DEBUG modem << Disconnecting MQTT client...
2021-05-12T12:09:14.852Z DEBUG modem << Could not disconnect: -128
2021-05-12T12:09:14.853Z DEBUG modem << Received AWS Buf: {"state":{"reported":{"nrfcloud__dfu_v1__app_v":"v1.0.0"}}}
2021-05-12T12:09:14.854Z DEBUG modem << ERROR: mqtt_live -128
2021-05-12T12:09:14.855Z DEBUG modem << mqtt_aws_run failed!
2021-05-12T12:09:14.856Z DEBUG modem << ----------------------------------------------------------------
2021-05-12T12:09:14.857Z DEBUG modem << work_handler_collect_data started!
2021-05-12T12:09:15.348Z DEBUG modem << Getting latest time...done!
2021-05-12T12:09:15.359Z DEBUG modem << Reading out bosch sensor...done!
2021-05-12T12:09:15.360Z DEBUG modem << Reading out modem info...done!
2021-05-12T12:09:16.153Z DEBUG modem << Reading out Teros12 sensor...I: Downloaded 2048/234807 bytes (0%)
2021-05-12T12:09:16.162Z DEBUG modem << I: 2 Sectors of 4096 bytes
2021-05-12T12:09:16.163Z DEBUG modem << I: alloc wra: 0, ff0
2021-05-12T12:09:16.165Z DEBUG modem << I: data wra: 0, 0
2021-05-12T12:09:17.390Z DEBUG modem << done!
2021-05-12T12:09:17.391Z DEBUG modem << ++++++++++++++++++++++++++++++++++++++++++++
2021-05-12T12:09:17.398Z DEBUG modem << Measured data:
2021-05-12T12:09:17.399Z DEBUG modem <<        Timer: 2021-05-12 12:09:14
2021-05-12T12:09:17.400Z DEBUG modem <<  #msg queued: 0
2021-05-12T12:09:17.400Z DEBUG modem <<  Temperature: 26.230 degC
2021-05-12T12:09:17.401Z DEBUG modem <<     Pressure: 94.785 
2021-05-12T12:09:17.401Z DEBUG modem <<     Humidity: 30.000 
2021-05-12T12:09:17.401Z DEBUG modem <<     SoilTemp: 0.000 degC
2021-05-12T12:09:17.402Z DEBUG modem <<    VolWaterC: 0.000
2021-05-12T12:09:17.402Z DEBUG modem <<   Modem bat.: 4.394 V
2021-05-12T12:09:17.402Z DEBUG modem <<   Current band: 8
2021-05-12T12:09:17.403Z DEBUG modem <<         RSRP: -99
2021-05-12T12:09:17.403Z DEBUG modem << ++++++++++++++++++++++++++++++++++++++++++++
2021-05-12T12:09:20.008Z DEBUG modem << I: Downloaded 4096/234807 bytes (1%)