2021-03-28T10:05:22.162Z INFO Application data folder: C:\Users\tgerg\AppData\Roaming\nrfconnect\pc-nrfconnect-linkmonitor
2021-03-28T10:05:22.222Z DEBUG App pc-nrfconnect-linkmonitor v1.1.10 official
2021-03-28T10:05:22.222Z DEBUG App path: C:\Users\tgerg\.nrfconnect-apps\node_modules\pc-nrfconnect-linkmonitor
2021-03-28T10:05:22.224Z DEBUG nRFConnect 3.6.1 is supported by the app (^3.6.0)
2021-03-28T10:05:22.224Z DEBUG nRFConnect path: C:\Users\tgerg\AppData\Local\Programs\nrfconnect\resources\app.asar
2021-03-28T10:05:22.225Z DEBUG HomeDir: C:\Users\tgerg
2021-03-28T10:05:22.225Z DEBUG TmpDir: C:\Users\tgerg\AppData\Local\Temp
2021-03-28T10:05:25.771Z INFO Modem port is opened
2021-03-28T10:05:25.781Z DEBUG modem >> AT+CFUN?
2021-03-28T10:05:25.859Z DEBUG modem << +CFUN: 1
2021-03-28T10:05:25.863Z DEBUG modem << OK
2021-03-28T10:05:25.888Z DEBUG modem >> AT+CGSN=1
2021-03-28T10:05:25.910Z DEBUG modem << +CGSN: "352656100373862"
2021-03-28T10:05:25.913Z DEBUG modem << OK
2021-03-28T10:05:25.917Z DEBUG modem >> AT+CGMI
2021-03-28T10:05:25.935Z DEBUG modem << Nordic Semiconductor ASA
2021-03-28T10:05:25.937Z DEBUG modem << OK
2021-03-28T10:05:25.946Z DEBUG modem >> AT+CGMM
2021-03-28T10:05:25.965Z DEBUG modem << nRF9160-SICA
2021-03-28T10:05:25.967Z DEBUG modem << OK
2021-03-28T10:05:25.970Z DEBUG modem >> AT+CGMR
2021-03-28T10:05:25.987Z DEBUG modem << mfw_nrf9160_1.2.0
2021-03-28T10:05:25.998Z DEBUG modem << OK
2021-03-28T10:05:26.001Z INFO Nordic Semiconductor ASA nRF9160-SICA [mfw_nrf9160_1.2.0] SerNr: 352656100373862
2021-03-28T10:05:26.003Z DEBUG modem >> AT+CEMODE?
2021-03-28T10:05:26.022Z DEBUG modem << +CEMODE: 0
2021-03-28T10:05:26.032Z DEBUG modem << 
2021-03-28T10:05:26.035Z DEBUG modem << OK
2021-03-28T10:05:26.054Z DEBUG modem >> AT%XCBAND=?
2021-03-28T10:05:26.081Z DEBUG modem << %XCBAND: (1,2,3,4,5,8,12,13,17,19,20,25,26,28,66)
2021-03-28T10:05:26.084Z DEBUG modem << OK
2021-03-28T10:05:26.103Z DEBUG modem >> AT+CMEE?
2021-03-28T10:05:26.122Z DEBUG modem << +CMEE: 0
2021-03-28T10:05:26.131Z DEBUG modem << OK
2021-03-28T10:05:26.139Z DEBUG modem >> AT+CMEE=1
2021-03-28T10:05:26.165Z DEBUG modem << OK
2021-03-28T10:05:26.171Z DEBUG modem >> AT+CNEC?
2021-03-28T10:05:26.194Z DEBUG modem << +CNEC: 0
2021-03-28T10:05:26.196Z DEBUG modem << OK
2021-03-28T10:05:26.200Z DEBUG modem >> AT+CNEC=24
2021-03-28T10:05:26.215Z DEBUG modem << OK
2021-03-28T10:05:26.219Z DEBUG modem >> AT+CGEREP?
2021-03-28T10:05:26.245Z DEBUG modem << +CGEREP: 0,0
2021-03-28T10:05:26.248Z DEBUG modem << OK
2021-03-28T10:05:26.252Z DEBUG modem >> AT+CGDCONT?
2021-03-28T10:05:26.274Z DEBUG modem << +CGDCONT: 0,"IP","arkessalp.com","10.72.0.10",0,0
2021-03-28T10:05:26.279Z DEBUG modem << +CGDCONT: 1,"IP","arkessalp.com","",0,0
2021-03-28T10:05:26.280Z DEBUG modem << OK
2021-03-28T10:05:26.302Z DEBUG modem >> AT+CGACT?
2021-03-28T10:05:26.321Z DEBUG modem << +CGACT: 0,1
2021-03-28T10:05:26.334Z DEBUG modem << +CGACT: 1,0
2021-03-28T10:05:26.346Z DEBUG modem << OK
2021-03-28T10:05:26.371Z DEBUG modem >> AT+CGEREP=1
2021-03-28T10:05:26.398Z DEBUG modem << OK
2021-03-28T10:05:26.403Z DEBUG modem >> AT+CIND=1,1,1
2021-03-28T10:05:26.419Z DEBUG modem << OK
2021-03-28T10:05:26.423Z DEBUG modem >> AT+CEREG=5
2021-03-28T10:05:26.449Z DEBUG modem << OK
2021-03-28T10:05:26.454Z DEBUG modem >> AT+CEREG?
2021-03-28T10:05:26.484Z DEBUG modem << +CEREG: 5,5,"2332","0250601F",9,,,"00011110","00000111"
2021-03-28T10:05:26.487Z DEBUG modem << OK
2021-03-28T10:05:26.504Z DEBUG modem >> AT+COPS=3,2
2021-03-28T10:05:26.530Z DEBUG modem << OK
2021-03-28T10:05:26.535Z DEBUG modem >> AT+COPS?
2021-03-28T10:05:26.553Z DEBUG modem << +COPS: 1,2,"27201",9
2021-03-28T10:05:26.555Z DEBUG modem << OK
2021-03-28T10:05:26.588Z DEBUG modem >> AT%XCBAND
2021-03-28T10:05:26.611Z DEBUG modem << %XCBAND: 20
2021-03-28T10:05:26.614Z DEBUG modem << OK
2021-03-28T10:05:26.619Z DEBUG modem >> AT+CGDCONT?
2021-03-28T10:05:26.644Z DEBUG modem << +CGDCONT: 0,"IP","arkessalp.com","10.72.0.10",0,0
2021-03-28T10:05:26.646Z DEBUG modem << +CGDCONT: 1,"IP","arkessalp.com","",0,0
2021-03-28T10:05:26.648Z DEBUG modem << OK
2021-03-28T10:05:26.656Z DEBUG modem >> AT+CGACT?
2021-03-28T10:05:26.682Z DEBUG modem << +CGACT: 0,1
2021-03-28T10:05:26.685Z DEBUG modem << +CGACT: 1,0
2021-03-28T10:05:26.687Z DEBUG modem << OK
2021-03-28T10:05:26.702Z DEBUG modem >> AT%CESQ=1
2021-03-28T10:05:26.718Z DEBUG modem << OK
2021-03-28T10:05:26.721Z DEBUG modem >> AT+CESQ
2021-03-28T10:05:26.737Z DEBUG modem << +CESQ: 99,99,255,255,255,255
2021-03-28T10:05:26.747Z DEBUG modem << OK
2021-03-28T10:05:26.754Z DEBUG modem >> AT%XSIM=1
2021-03-28T10:05:26.777Z DEBUG modem << OK
2021-03-28T10:05:26.780Z DEBUG modem >> AT%XSIM?
2021-03-28T10:05:26.798Z DEBUG modem << %XSIM: 1
2021-03-28T10:05:26.800Z DEBUG modem << OK
2021-03-28T10:05:26.815Z DEBUG modem >> AT+CPIN?
2021-03-28T10:05:26.838Z DEBUG modem << +CPIN: READY
2021-03-28T10:05:26.843Z DEBUG modem << OK
2021-03-28T10:05:26.854Z DEBUG modem >> AT+CPINR="SIM PIN"
2021-03-28T10:05:26.880Z DEBUG modem << +CME ERROR: 0
2021-03-28T10:05:56.049Z DEBUG modem << D: (0x2001a5e0): [0x200203e0]: Transport writing 2 bytes.
2021-03-28T10:05:56.076Z DEBUG modem << D: (0x2001a5e0): [0x200203e0]: Transport write complete.
2021-03-28T10:05:56.411Z DEBUG modem << %CESQ: 75,3,24,3
2021-03-28T10:05:57.199Z DEBUG modem << +CSCON: 1
2021-03-28T10:05:57.211Z DEBUG modem << D: +CSCON notification
2021-03-28T10:05:57.396Z DEBUG modem << D: (0x2001a5e0): state:0x00000006
2021-03-28T10:05:57.409Z DEBUG modem << D: (0x2001a5e0): >> cur:0x2001c0e0, end:0x2001c0e2
2021-03-28T10:05:57.412Z DEBUG modem << D: (0x2001a5e0): << val:d0
2021-03-28T10:05:57.414Z DEBUG modem << D: (0x2001a5e0): length:0x00000000
2021-03-28T10:05:57.419Z DEBUG modem << D: (0x2001a5e0): [CID 0x200203e0]: Received MQTT_PKT_TYPE_PINGRSP!
2021-03-28T10:05:57.437Z DEBUG modem << [mqtt_evt_handler:250] default: 9
2021-03-28T10:06:08.592Z DEBUG modem << +CSCON: 0
2021-03-28T10:06:08.597Z DEBUG modem << D: +CSCON notification
2021-03-28T10:06:14.070Z DEBUG modem << %CESQ: 75,3,29,4
2021-03-28T10:07:08.553Z DEBUG modem << %CESQ: 255,0,255,0
2021-03-28T10:25:57.382Z DEBUG modem << D: (0x2001a5e0): [0x200203e0]: Transport writing 2 bytes.
2021-03-28T10:25:57.413Z DEBUG modem << D: (0x2001a5e0): [0x200203e0]: Transport write complete.
2021-03-28T10:25:57.832Z DEBUG modem << %CESQ: 77,3,24,3
2021-03-28T10:25:58.461Z DEBUG modem << +CSCON: 1
2021-03-28T10:25:58.486Z DEBUG modem << D: +CSCON notification
2021-03-28T10:25:58.770Z DEBUG modem << D: (0x2001a5e0): state:0x00000006
2021-03-28T10:25:58.793Z DEBUG modem << D: (0x2001a5e0): >> cur:0x2001c0e0, end:0x2001c0e2
2021-03-28T10:25:58.799Z DEBUG modem << D: (0x2001a5e0): << val:32
2021-03-28T10:25:58.803Z DEBUG modem << D: (0x2001a5e0): >> cur:0x2001c0e0, end:0x2001c0e3
2021-03-28T10:25:58.804Z DEBUG modem << D: (0x2001a5e0): << val:32
2021-03-28T10:25:58.815Z DEBUG modem << D: (0x2001a5e0): length:0x00000198
2021-03-28T10:25:58.817Z DEBUG modem << D: (0x2001a5e0): [CID 0x200203e0]: Received MQTT_PKT_TYPE_PUBLISH
2021-03-28T10:25:58.819Z DEBUG modem << D: (0x2001a5e0): >> cur:0x2001c0e3, end:0x2001c10e
2021-03-28T10:25:58.821Z DEBUG modem << D: (0x2001a5e0): >> cur:0x2001c0e3, end:0x2001c10e
2021-03-28T10:25:58.830Z DEBUG modem << D: (0x2001a5e0): << val:0027
2021-03-28T10:25:58.832Z DEBUG modem << D: (0x2001a5e0): << str_size:00000029
2021-03-28T10:25:58.834Z DEBUG modem << D: (0x2001a5e0): >> cur:0x2001c10c, end:0x2001c10e
2021-03-28T10:25:58.836Z DEBUG modem << D: (0x2001a5e0): << val:0001
2021-03-28T10:25:58.852Z DEBUG modem << D: (0x2001a5e0): PUB QoS:01, message len 0000016d, topic len 00000027
2021-03-28T10:25:58.856Z DEBUG modem << D: Received topic: $aws/things/DL40_thing/jobs/notify-next
2021-03-28T10:25:58.858Z DEBUG modem << D: Checking for an available job
2021-03-28T10:25:58.878Z DEBUG modem << D: Job doc: {"timestamp":1616926406,"execution":{"jobId":"logger_fota_dg_10","status":"QUEUED","queuedAt":1616926406,"lastUpdatedAt":1616926406,"versionNumber":1,"executionNumber":1,"jobDocument":{"operation":"app_fw_update","fwversion":"v2.0.0","size":183143,"location":{"protocol":"http:","host":"logger-firmware-bucket.s3.eu-west-1.amazonaws.com","path":"app_update.bin"}}}}
2021-03-28T10:25:58.882Z DEBUG modem << D: Job ID: logger_fota_dg_10
2021-03-28T10:25:58.894Z DEBUG modem << D: hostname: logger-firmware-bucket.s3.eu-west-1.amazonaws.com
2021-03-28T10:25:58.899Z DEBUG modem << D: file_path app_update.bin
2021-03-28T10:25:58.901Z DEBUG modem << D: execution_version_number: 1 
2021-03-28T10:25:58.903Z DEBUG modem << D: (0x2001a5e0): [CID 0x200203e0]:[State 0x06]: >> message id 0x0843 topic count 0x0001
2021-03-28T10:25:58.915Z DEBUG modem << D: (0x2001a5e0): >> val:0843 cur:0x2001c165, end:0x2001c1e0
2021-03-28T10:25:58.918Z DEBUG modem << D: (0x2001a5e0): >> str_size:00000038 cur:0x2001c167, end:0x2001c1e0
2021-03-28T10:25:58.920Z DEBUG modem << D: (0x2001a5e0): >> val:0036 cur:0x2001c167, end:0x2001c1e0
2021-03-28T10:25:58.931Z DEBUG modem << D: (0x2001a5e0): >> val:01 cur:0x2001c19f, end:0x2001c1e0
2021-03-28T10:25:58.934Z DEBUG modem << D: (0x2001a5e0): << msg type:0x82 length:0x0000003b
2021-03-28T10:25:58.936Z DEBUG modem << D: (0x2001a5e0): >> length:0x0000003b cur:(nil), end:(nil)
2021-03-28T10:25:58.946Z DEBUG modem << D: (0x2001a5e0): Fixed header length = 02
2021-03-28T10:25:58.949Z DEBUG modem << D: (0x2001a5e0): >> val:82 cur:0x2001c163, end:0x2001c1e0
2021-03-28T10:25:58.951Z DEBUG modem << D: (0x2001a5e0): >> length:0x0000003b cur:0x2001c164, end:0x2001c1e0
2021-03-28T10:25:58.961Z DEBUG modem << D: (0x2001a5e0): [0x200203e0]: Transport writing 61 bytes.
2021-03-28T10:25:58.978Z DEBUG modem << D: (0x2001a5e0): [0x200203e0]: Transport write complete.
2021-03-28T10:25:58.983Z DEBUG modem << D: (0x2001a5e0): [CID 0x200203e0]:[State 0x06]: << result 0x00000000
2021-03-28T10:25:58.994Z DEBUG modem << D: Subscribed to FOTA update topic $aws/things/DL40_thing/jobs/logger_fota_dg_10/update/#
2021-03-28T10:25:58.997Z DEBUG modem << D: (0x2001a5e0): [CID 0x200203e0]:[State 0x06]: >> Message id 0x0001
2021-03-28T10:25:59.000Z DEBUG modem << D: (0x2001a5e0): >> val:0001 cur:0x2001c165, end:0x2001c1e0
2021-03-28T10:25:59.013Z DEBUG modem << D: (0x2001a5e0): << msg type:0x40 length:0x00000002
2021-03-28T10:25:59.017Z DEBUG modem << D: (0x2001a5e0): >> length:0x00000002 cur:(nil), end:(nil)
2021-03-28T10:25:59.029Z DEBUG modem << D: (0x2001a5e0): Fixed header length = 02
2021-03-28T10:25:59.034Z DEBUG modem << D: (0x2001a5e0): >> val:40 cur:0x2001c163, end:0x2001c1e0
2021-03-28T10:25:59.046Z DEBUG modem << D: (0x2001a5e0): >> length:0x00000002 cur:0x2001c164, end:0x2001c1e0
2021-03-28T10:25:59.048Z DEBUG modem << D: (0x2001a5e0): [0x200203e0]: Transport writing 4 bytes.
2021-03-28T10:25:59.049Z DEBUG modem << D: (0x2001a5e0): [0x200203e0]: Transport write complete.
2021-03-28T10:25:59.060Z DEBUG modem << D: (0x2001a5e0): [CID 0x200203e0]:[State 0x06]: << result 0x00000000
2021-03-28T10:25:59.754Z DEBUG modem << D: (0x2001a5e0): state:0x00000006
2021-03-28T10:25:59.786Z DEBUG modem << D: (0x2001a5e0): >> cur:0x2001c0e0, end:0x2001c0e2
2021-03-28T10:25:59.797Z DEBUG modem << D: (0x2001a5e0): << val:32
2021-03-28T10:25:59.798Z DEBUG modem << D: (0x2001a5e0): >> cur:0x2001c0e0, end:0x2001c0e3
2021-03-28T10:25:59.799Z DEBUG modem << D: (0x2001a5e0): << val:32
2021-03-28T10:25:59.800Z DEBUG modem << D: (0x2001a5e0): length:0x00000198
2021-03-28T10:25:59.816Z DEBUG modem << D: (0x2001a5e0): [CID 0x200203e0]: Received MQTT_PKT_TYPE_PUBLISH
2021-03-28T10:25:59.820Z DEBUG modem << D: (0x2001a5e0): >> cur:0x2001c0e3, end:0x2001c10e
2021-03-28T10:25:59.822Z DEBUG modem << D: (0x2001a5e0): >> cur:0x2001c0e3, end:0x2001c10e
2021-03-28T10:25:59.823Z DEBUG modem << D: (0x2001a5e0): << val:0027
2021-03-28T10:25:59.838Z DEBUG modem << D: (0x2001a5e0): << str_size:00000029
2021-03-28T10:25:59.840Z DEBUG modem << D: (0x2001a5e0): >> cur:0x2001c10c, end:0x2001c10e
2021-03-28T10:25:59.841Z DEBUG modem << D: (0x2001a5e0): << val:0001
2021-03-28T10:25:59.842Z DEBUG modem << D: (0x2001a5e0): PUB QoS:01, message len 0000016d, topic len 00000027
2021-03-28T10:25:59.843Z DEBUG modem << D: Received topic: $aws/things/DL40_thing/jobs/notify-next
2021-03-28T10:25:59.845Z DEBUG modem << D: FOTA already in progress, message is ignored
2021-03-28T10:25:59.850Z DEBUG modem << D: (0x2001a5e0): [CID 0x200203e0]:[State 0x06]: >> Message id 0x0001
2021-03-28T10:25:59.862Z DEBUG modem << D: (0x2001a5e0): >> val:0001 cur:0x2001c165, end:0x2001c1e0
2021-03-28T10:25:59.865Z DEBUG modem << D: (0x2001a5e0): << msg type:0x40 length:0x00000002
2021-03-28T10:25:59.876Z DEBUG modem << D: (0x2001a5e0): >> length:0x00000002 cur:(nil), end:(nil)
2021-03-28T10:25:59.880Z DEBUG modem << D: (0x2001a5e0): Fixed header length = 02
2021-03-28T10:25:59.881Z DEBUG modem << D: (0x2001a5e0): >> val:40 cur:0x2001c163, end:0x2001c1e0
2021-03-28T10:25:59.895Z DEBUG modem << D: (0x2001a5e0): >> length:0x00000002 cur:0x2001c164, end:0x2001c1e0
2021-03-28T10:25:59.898Z DEBUG modem << D: (0x2001a5e0): [0x200203e0]: Transport writing 4 bytes.
2021-03-28T10:25:59.901Z DEBUG modem << D: (0x2001a5e0): [0x200203e0]: Transport write complete.
2021-03-28T10:25:59.913Z DEBUG modem << D: (0x2001a5e0): [CID 0x200203e0]:[State 0x06]: << result 0x00000000
2021-03-28T10:25:59.998Z DEBUG modem << D: (0x2001a5e0): state:0x00000006
2021-03-28T10:26:00.038Z DEBUG modem << D: (0x2001a5e0): >> cur:0x2001c0e0, end:0x2001c0e2
2021-03-28T10:26:00.040Z DEBUG modem << D: (0x2001a5e0): << val:32
2021-03-28T10:26:00.043Z DEBUG modem << D: (0x2001a5e0): >> cur:0x2001c0e0, end:0x2001c0e3
2021-03-28T10:26:00.044Z DEBUG modem << D: (0x2001a5e0): << val:32
2021-03-28T10:26:00.045Z DEBUG modem << D: (0x2001a5e0): length:0x00000198
2021-03-28T10:26:00.048Z DEBUG modem << D: (0x2001a5e0): [CID 0x200203e0]: Received MQTT_PKT_TYPE_PUBLISH
2021-03-28T10:26:00.050Z DEBUG modem << D: (0x2001a5e0): >> cur:0x2001c0e3, end:0x2001c10e
2021-03-28T10:26:00.052Z DEBUG modem << D: (0x2001a5e0): >> cur:0x2001c0e3, end:0x2001c10e
2021-03-28T10:26:00.054Z DEBUG modem << D: (0x2001a5e0): << val:0027
2021-03-28T10:26:00.071Z DEBUG modem << D: (0x2001a5e0): << str_size:00000029
2021-03-28T10:26:00.073Z DEBUG modem << D: (0x2001a5e0): >> cur:0x2001c10c, end:0x2001c10e
2021-03-28T10:26:00.076Z DEBUG modem << D: (0x2001a5e0): << val:0001
2021-03-28T10:26:00.079Z DEBUG modem << D: (0x2001a5e0): PUB QoS:01, message len 0000016d, topic len 00000027
2021-03-28T10:26:00.080Z DEBUG modem << D: Received topic: $aws/things/DL40_thing/jobs/notify-next
2021-03-28T10:26:00.099Z DEBUG modem << D: FOTA already in progress, message is ignored
2021-03-28T10:26:00.112Z DEBUG modem << D: (0x2001a5e0): [CID 0x200203e0]:[State 0x06]: >> Message id 0x0001
2021-03-28T10:26:00.114Z DEBUG modem << D: (0x2001a5e0): >> val:0001 cur:0x2001c165, end:0x2001c1e0
2021-03-28T10:26:00.129Z DEBUG modem << D: (0x2001a5e0): << msg type:0x40 length:0x00000002
2021-03-28T10:26:00.131Z DEBUG modem << D: (0x2001a5e0): >> length:0x00000002 cur:(nil), end:(nil)
2021-03-28T10:26:00.133Z DEBUG modem << D: (0x2001a5e0): Fixed header length = 02
2021-03-28T10:26:00.147Z DEBUG modem << D: (0x2001a5e0): >> val:40 cur:0x2001c163, end:0x2001c1e0
2021-03-28T10:26:00.149Z DEBUG modem << D: (0x2001a5e0): >> length:0x00000002 cur:0x2001c164, end:0x2001c1e0
2021-03-28T10:26:00.151Z DEBUG modem << D: (0x2001a5e0): [0x200203e0]: Transport writing 4 bytes.
2021-03-28T10:26:00.164Z DEBUG modem << D: (0x2001a5e0): [0x200203e0]: Transport write complete.
2021-03-28T10:26:00.166Z DEBUG modem << D: (0x2001a5e0): [CID 0x200203e0]:[State 0x06]: << result 0x00000000
2021-03-28T10:26:00.180Z DEBUG modem << D: (0x2001a5e0): state:0x00000006
2021-03-28T10:26:00.182Z DEBUG modem << D: (0x2001a5e0): >> cur:0x2001c0e0, end:0x2001c0e2
2021-03-28T10:26:00.183Z DEBUG modem << D: (0x2001a5e0): << val:32
2021-03-28T10:26:00.186Z DEBUG modem << D: (0x2001a5e0): >> cur:0x2001c0e0, end:0x2001c0e3
2021-03-28T10:26:00.199Z DEBUG modem << D: (0x2001a5e0): << val:32
2021-03-28T10:26:00.200Z DEBUG modem << D: (0x2001a5e0): length:0x00000198
2021-03-28T10:26:00.202Z DEBUG modem << D: (0x2001a5e0): [CID 0x200203e0]: Received MQTT_PKT_TYPE_PUBLISH
2021-03-28T10:26:00.215Z DEBUG modem << D: (0x2001a5e0): >> cur:0x2001c0e3, end:0x2001c10e
2021-03-28T10:26:00.217Z DEBUG modem << D: (0x2001a5e0): >> cur:0x2001c0e3, end:0x2001c10e
2021-03-28T10:26:00.221Z DEBUG modem << D: (0x2001a5e0): << val:0027
2021-03-28T10:26:00.223Z DEBUG modem << D: (0x2001a5e0): << str_size:00000029
2021-03-28T10:26:00.235Z DEBUG modem << D: (0x2001a5e0): >> cur:0x2001c10c, end:0x2001c10e
2021-03-28T10:26:00.238Z DEBUG modem << D: (0x2001a5e0): << val:0001
2021-03-28T10:26:00.240Z DEBUG modem << D: (0x2001a5e0): PUB QoS:01, message len 0000016d, topic len 00000027
2021-03-28T10:26:00.251Z DEBUG modem << D: Received topic: $aws/things/DL40_thing/jobs/notify-next
2021-03-28T10:26:00.254Z DEBUG modem << D: FOTA already in progress, message is ignored
2021-03-28T10:26:00.256Z DEBUG modem << D: (0x2001a5e0): [CID 0x200203e0]:[State 0x06]: >> Message id 0x0001
2021-03-28T10:26:00.273Z DEBUG modem << D: (0x2001a5e0): >> val:0001 cur:0x2001c165, end:0x2001c1e0
2021-03-28T10:26:00.275Z DEBUG modem << D: (0x2001a5e0): << msg type:0x40 length:0x00000002
2021-03-28T10:26:00.278Z DEBUG modem << D: (0x2001a5e0): >> length:0x00000002 cur:(nil), end:(nil)
2021-03-28T10:26:00.280Z DEBUG modem << D: (0x2001a5e0): Fixed header length = 02
2021-03-28T10:26:00.282Z DEBUG modem << D: (0x2001a5e0): >> val:40 cur:0x2001c163, end:0x2001c1e0
2021-03-28T10:26:00.295Z DEBUG modem << D: (0x2001a5e0): >> length:0x00000002 cur:0x2001c164, end:0x2001c1e0
2021-03-28T10:26:00.296Z DEBUG modem << D: (0x2001a5e0): [0x200203e0]: Transport writing 4 bytes.
2021-03-28T10:26:00.299Z DEBUG modem << D: (0x2001a5e0): [0x200203e0]: Transport write complete.
2021-03-28T10:26:00.313Z DEBUG modem << D: (0x2001a5e0): [CID 0x200203e0]:[State 0x06]: << result 0x00000000
2021-03-28T10:26:00.408Z DEBUG modem << D: (0x2001a5e0): state:0x00000006
2021-03-28T10:26:00.443Z DEBUG modem << D: (0x2001a5e0): >> cur:0x2001c0e0, end:0x2001c0e2
2021-03-28T10:26:00.445Z DEBUG modem << D: (0x2001a5e0): << val:32
2021-03-28T10:26:00.449Z DEBUG modem << D: (0x2001a5e0): >> cur:0x2001c0e0, end:0x2001c0e3
2021-03-28T10:26:00.450Z DEBUG modem << D: (0x2001a5e0): << val:32
2021-03-28T10:26:00.452Z DEBUG modem << D: (0x2001a5e0): length:0x00000198
2021-03-28T10:26:00.472Z DEBUG modem << D: (0x2001a5e0): [CID 0x200203e0]: Received MQTT_PKT_TYPE_PUBLISH
2021-03-28T10:26:00.474Z DEBUG modem << D: (0x2001a5e0): >> cur:0x2001c0e3, end:0x2001c10e
2021-03-28T10:26:00.475Z DEBUG modem << D: (0x2001a5e0): >> cur:0x2001c0e3, end:0x2001c10e
2021-03-28T10:26:00.476Z DEBUG modem << D: (0x2001a5e0): << val:0027
2021-03-28T10:26:00.477Z DEBUG modem << D: (0x2001a5e0): << str_size:00000029
2021-03-28T10:26:00.479Z DEBUG modem << D: (0x2001a5e0): >> cur:0x2001c10c, end:0x2001c10e
2021-03-28T10:26:00.480Z DEBUG modem << D: (0x2001a5e0): << val:0001
2021-03-28T10:26:00.525Z DEBUG modem << D: (0x2001a5e0): PUB QoS:01, message len 0000016d, topic len 00000027
2021-03-28T10:26:00.531Z DEBUG modem << D: Received topic: $aws/things/DL40_thing/jobs/notify-next
2021-03-28T10:26:00.545Z DEBUG modem << D: FOTA already in progress, message is ignored
2021-03-28T10:26:00.549Z DEBUG modem << D: (0x2001a5e0): [CID 0x200203e0]:[State 0x06]: >> Message id 0x0001
2021-03-28T10:26:00.562Z DEBUG modem << D: (0x2001a5e0): >> val:0001 cur:0x2001c165, end:0x2001c1e0
2021-03-28T10:26:00.565Z DEBUG modem << D: (0x2001a5e0): << msg type:0x40 length:0x00000002
2021-03-28T10:26:00.582Z DEBUG modem << D: (0x2001a5e0): >> length:0x00000002 cur:(nil), end:(nil)
2021-03-28T10:26:00.595Z DEBUG modem << D: (0x2001a5e0): Fixed header length = 02
2021-03-28T10:26:00.597Z DEBUG modem << D: (0x2001a5e0): >> val:40 cur:0x2001c163, end:0x2001c1e0
2021-03-28T10:26:00.601Z DEBUG modem << D: (0x2001a5e0): >> length:0x00000002 cur:0x2001c164, end:0x2001c1e0
2021-03-28T10:26:00.613Z DEBUG modem << D: (0x2001a5e0): [0x200203e0]: Transport writing 4 bytes.
2021-03-28T10:26:00.615Z DEBUG modem << D: (0x2001a5e0): [0x200203e0]: Transport write complete.
2021-03-28T10:26:00.628Z DEBUG modem << D: (0x2001a5e0): [CID 0x200203e0]:[State 0x06]: << result 0x00000000
2021-03-28T10:26:00.630Z DEBUG modem << D: (0x2001a5e0): state:0x00000006
2021-03-28T10:26:00.634Z DEBUG modem << D: (0x2001a5e0): >> cur:0x2001c0e0, end:0x2001c0e2
2021-03-28T10:26:00.636Z DEBUG modem << D: (0x2001a5e0): << val:32
2021-03-28T10:26:00.638Z DEBUG modem << D: (0x2001a5e0): >> cur:0x2001c0e0, end:0x2001c0e3
2021-03-28T10:26:00.639Z DEBUG modem << D: (0x2001a5e0): << val:32
2021-03-28T10:26:00.651Z DEBUG modem << D: (0x2001a5e0): length:0x00000198
2021-03-28T10:26:00.655Z DEBUG modem << D: (0x2001a5e0): [CID 0x200203e0]: Received MQTT_PKT_TYPE_PUBLISH
2021-03-28T10:26:00.657Z DEBUG modem << D: (0x2001a5e0): >> cur:0x2001c0e3, end:0x2001c10e
2021-03-28T10:26:00.658Z DEBUG modem << D: (0x2001a5e0): >> cur:0x2001c0e3, end:0x2001c10e
2021-03-28T10:26:00.673Z DEBUG modem << D: (0x2001a5e0): << val:0027
2021-03-28T10:26:00.675Z DEBUG modem << D: (0x2001a5e0): << str_size:00000029
2021-03-28T10:26:00.677Z DEBUG modem << D: (0x2001a5e0): >> cur:0x2001c10c, end:0x2001c10e
2021-03-28T10:26:00.677Z DEBUG modem << D: (0x2001a5e0): << val:0001
2021-03-28T10:26:00.678Z DEBUG modem << D: (0x2001a5e0): PUB QoS:01, message len 0000016d, topic len 00000027
2021-03-28T10:26:00.704Z DEBUG modem << D: Received topic: $aws/things/DL40_thing/jobs/notify-next
2021-03-28T10:26:00.709Z DEBUG modem << D: FOTA already in progress, message is ignored
2021-03-28T10:26:00.718Z DEBUG modem << D: (0x2001a5e0): [CID 0x200203e0]:[State 0x06]: >> Message id 0x0001
2021-03-28T10:26:00.730Z DEBUG modem << D: (0x2001a5e0): >> val:0001 cur:0x2001c165, end:0x2001c1e0
2021-03-28T10:26:00.747Z DEBUG modem << D: (0x2001a5e0): << msg type:0x40 length:0x00000002
2021-03-28T10:26:00.750Z DEBUG modem << D: (0x2001a5e0): >> length:0x00000002 cur:(nil), end:(nil)
2021-03-28T10:26:00.765Z DEBUG modem << D: (0x2001a5e0): Fixed header length = 02
2021-03-28T10:26:00.768Z DEBUG modem << D: (0x2001a5e0): >> val:40 cur:0x2001c163, end:0x2001c1e0
2021-03-28T10:26:00.771Z DEBUG modem << D: (0x2001a5e0): >> length:0x00000002 cur:0x2001c164, end:0x2001c1e0
2021-03-28T10:26:00.783Z DEBUG modem << D: (0x2001a5e0): [0x200203e0]: Transport writing 4 bytes.
2021-03-28T10:26:00.785Z DEBUG modem << D: (0x2001a5e0): [0x200203e0]: Transport write complete.
2021-03-28T10:26:00.786Z DEBUG modem << D: (0x2001a5e0): [CID 0x200203e0]:[State 0x06]: << result 0x00000000
2021-03-28T10:26:00.805Z DEBUG modem << D: (0x2001a5e0): state:0x00000006
2021-03-28T10:26:00.807Z DEBUG modem << D: (0x2001a5e0): >> cur:0x2001c0e0, end:0x2001c0e2
2021-03-28T10:26:00.839Z DEBUG modem << D: (0x2001a5e0): << val:d0
2021-03-28T10:26:00.841Z DEBUG modem << D: (0x2001a5e0): length:0x00000000
2021-03-28T10:26:00.843Z DEBUG modem << D: (0x2001a5e0): [CID 0x200203e0]: Received MQTT_PKT_TYPE_PINGRSP!
2021-03-28T10:26:00.844Z DEBUG modem << [mqtt_evt_handler:250] default: 9
2021-03-28T10:26:00.846Z DEBUG modem << D: (0x2001a5e0): state:0x00000006
2021-03-28T10:26:00.847Z DEBUG modem << D: (0x2001a5e0): >> cur:0x2001c0e0, end:0x2001c0e2
2021-03-28T10:26:00.864Z DEBUG modem << D: (0x2001a5e0): << val:90
2021-03-28T10:26:00.867Z DEBUG modem << D: (0x2001a5e0): length:0x00000003
2021-03-28T10:26:00.883Z DEBUG modem << D: (0x2001a5e0): [CID 0x200203e0]: Received MQTT_PKT_TYPE_SUBACK!
2021-03-28T10:26:00.884Z DEBUG modem << D: (0x2001a5e0): >> cur:0x2001c0e2, end:0x2001c0e5
2021-03-28T10:26:00.889Z DEBUG modem << D: (0x2001a5e0): << val:0843
2021-03-28T10:26:00.902Z DEBUG modem << D: (0x2001a5e0): >> cur:0x2001c0e4, end:0x2001c0e5
2021-03-28T10:26:00.905Z DEBUG modem << D: (0x2001a5e0): << bin len:00000001
2021-03-28T10:26:00.906Z DEBUG modem << D: update_job_execution, state: 1, version_number: 1
2021-03-28T10:26:00.922Z DEBUG modem << D: (0x2001a5e0): [CID 0x200203e0]:[State 0x06]: >> Topic size 0x00000034, Data size 0x00000057
2021-03-28T10:26:00.924Z DEBUG modem << D: (0x2001a5e0): >> str_size:00000036 cur:0x2001c165, end:0x2001c1e0
2021-03-28T10:26:00.926Z DEBUG modem << D: (0x2001a5e0): >> val:0034 cur:0x2001c165, end:0x2001c1e0
2021-03-28T10:26:00.929Z DEBUG modem << D: (0x2001a5e0): >> val:1d78 cur:0x2001c19b, end:0x2001c1e0
2021-03-28T10:26:00.934Z DEBUG modem << D: (0x2001a5e0): << msg type:0x32 length:0x0000008f
2021-03-28T10:26:00.948Z DEBUG modem << D: (0x2001a5e0): >> length:0x0000008f cur:(nil), end:(nil)
2021-03-28T10:26:00.950Z DEBUG modem << D: (0x2001a5e0): Fixed header length = 03
2021-03-28T10:26:00.964Z DEBUG modem << D: (0x2001a5e0): >> val:32 cur:0x2001c162, end:0x2001c1e0
2021-03-28T10:26:00.967Z DEBUG modem << D: (0x2001a5e0): >> length:0x0000008f cur:0x2001c163, end:0x2001c1e0
2021-03-28T10:26:00.968Z DEBUG modem << D: (0x2001a5e0): [0x200203e0]: Transport writing message.
2021-03-28T10:26:00.989Z DEBUG modem << D: (0x2001a5e0): [0x200203e0]: Transport write complete.
2021-03-28T10:26:00.993Z DEBUG modem << D: (0x2001a5e0): [CID 0x200203e0]:[State 0x06]: << result 0x00000000
2021-03-28T10:26:01.603Z DEBUG modem << D: (0x2001a5e0): state:0x00000006
2021-03-28T10:26:01.624Z DEBUG modem << D: (0x2001a5e0): >> cur:0x2001c0e0, end:0x2001c0e2
2021-03-28T10:26:01.626Z DEBUG modem << D: (0x2001a5e0): << val:40
2021-03-28T10:26:01.628Z DEBUG modem << D: (0x2001a5e0): length:0x00000002
2021-03-28T10:26:01.629Z DEBUG modem << D: (0x2001a5e0): [CID 0x200203e0]: Received MQTT_PKT_TYPE_PUBACK!
2021-03-28T10:26:01.631Z DEBUG modem << D: (0x2001a5e0): >> cur:0x2001c0e2, end:0x2001c0e4
2021-03-28T10:26:01.633Z DEBUG modem << D: (0x2001a5e0): << val:1d78
2021-03-28T10:26:01.648Z DEBUG modem << [mqtt_evt_handler:235] PUBACK packet id: 7544
2021-03-28T10:26:01.701Z DEBUG modem << D: (0x2001a5e0): state:0x00000006
2021-03-28T10:26:01.714Z DEBUG modem << D: (0x2001a5e0): >> cur:0x2001c0e0, end:0x2001c0e2
2021-03-28T10:26:01.716Z DEBUG modem << D: (0x2001a5e0): << val:30
2021-03-28T10:26:01.730Z DEBUG modem << D: (0x2001a5e0): length:0x00000068
2021-03-28T10:26:01.733Z DEBUG modem << D: (0x2001a5e0): [CID 0x200203e0]: Received MQTT_PKT_TYPE_PUBLISH
2021-03-28T10:26:01.734Z DEBUG modem << D: (0x2001a5e0): >> cur:0x2001c0e2, end:0x2001c121
2021-03-28T10:26:01.749Z DEBUG modem << D: (0x2001a5e0): >> cur:0x2001c0e2, end:0x2001c121
2021-03-28T10:26:01.766Z DEBUG modem << D: (0x2001a5e0): << val:003d
2021-03-28T10:26:01.767Z DEBUG modem << D: (0x2001a5e0): << str_size:0000003f
2021-03-28T10:26:01.770Z DEBUG modem << D: (0x2001a5e0): PUB QoS:00, message len 00000029, topic len 0000003d
2021-03-28T10:26:01.789Z DEBUG modem << D: Received topic: $aws/things/DL40_thing/jobs/logger_fota_dg_10/update/accepted
2021-03-28T10:26:01.791Z DEBUG modem << D: Start downloading firmware from logger-firmware-bucket.s3.eu-west-1.amazonaws.com/app_update.bin
2021-03-28T10:26:02.475Z DEBUG modem << I: Configuring socket timeout (30 s)
2021-03-28T10:26:02.493Z DEBUG modem << I: Connecting to logger-firmware-bucket.s3.eu-west-1.amazonaws.com
2021-03-28T10:26:03.028Z DEBUG modem << I: Downloading: app_update.bin [0]
2021-03-28T10:26:03.043Z DEBUG modem << AWS_FOTA_EVT_START, job id = logger_fota_dg_10
2021-03-28T10:26:04.251Z DEBUG modem << I: Downloaded 2048/183143 bytes (1%)
2021-03-28T10:26:04.281Z DEBUG modem << I: 2 Sectors of 4096 bytes
2021-03-28T10:26:04.299Z DEBUG modem << I: alloc wra: 0, ed8
2021-03-28T10:26:04.300Z DEBUG modem << I: data wra: 0, a0
2021-03-28T10:26:06.334Z DEBUG modem << I: Downloaded 4096/183143 bytes (2%)
2021-03-28T10:26:08.560Z DEBUG modem << I: Downloaded 6144/183143 bytes (3%)
2021-03-28T10:26:10.134Z DEBUG modem << I: Downloaded 8192/183143 bytes (4%)
2021-03-28T10:26:11.725Z DEBUG modem << I: Downloaded 10240/183143 bytes (5%)
2021-03-28T10:26:13.469Z DEBUG modem << I: Downloaded 12288/183143 bytes (6%)
2021-03-28T10:26:14.907Z DEBUG modem << I: Downloaded 14336/183143 bytes (7%)
2021-03-28T10:26:16.520Z DEBUG modem << I: Downloaded 16384/183143 bytes (8%)
2021-03-28T10:26:18.163Z DEBUG modem << I: Downloaded 18432/183143 bytes (10%)
2021-03-28T10:26:19.735Z DEBUG modem << I: Downloaded 20480/183143 bytes (11%)
2021-03-28T10:26:21.292Z DEBUG modem << I: Downloaded 22528/183143 bytes (12%)
2021-03-28T10:26:22.921Z DEBUG modem << I: Downloaded 24576/183143 bytes (13%)
2021-03-28T10:26:24.545Z DEBUG modem << I: Downloaded 26624/183143 bytes (14%)
2021-03-28T10:26:26.074Z DEBUG modem << I: Downloaded 28672/183143 bytes (15%)
2021-03-28T10:26:27.685Z DEBUG modem << I: Downloaded 30720/183143 bytes (16%)
2021-03-28T10:26:29.735Z DEBUG modem << I: Downloaded 32768/183143 bytes (17%)
2021-03-28T10:26:31.166Z DEBUG modem << I: Downloaded 34816/183143 bytes (19%)
2021-03-28T10:26:32.803Z DEBUG modem << I: Downloaded 36864/183143 bytes (20%)
2021-03-28T10:26:34.456Z DEBUG modem << I: Downloaded 38912/183143 bytes (21%)
2021-03-28T10:26:36.081Z DEBUG modem << I: Downloaded 40960/183143 bytes (22%)
2021-03-28T10:26:37.676Z DEBUG modem << I: Downloaded 43008/183143 bytes (23%)
2021-03-28T10:26:39.617Z DEBUG modem << I: Downloaded 45056/183143 bytes (24%)
2021-03-28T10:26:41.112Z DEBUG modem << I: Downloaded 47104/183143 bytes (25%)
2021-03-28T10:26:42.789Z DEBUG modem << I: Downloaded 49152/183143 bytes (26%)
2021-03-28T10:26:44.380Z DEBUG modem << I: Downloaded 51200/183143 bytes (27%)
2021-03-28T10:26:45.855Z DEBUG modem << I: Downloaded 53248/183143 bytes (29%)
2021-03-28T10:26:47.514Z DEBUG modem << I: Downloaded 55296/183143 bytes (30%)
2021-03-28T10:26:49.382Z DEBUG modem << I: Downloaded 57344/183143 bytes (31%)
2021-03-28T10:26:50.752Z DEBUG modem << I: Downloaded 59392/183143 bytes (32%)
2021-03-28T10:26:52.281Z DEBUG modem << I: Downloaded 61440/183143 bytes (33%)
2021-03-28T10:26:54.001Z DEBUG modem << I: Downloaded 63488/183143 bytes (34%)
2021-03-28T10:26:55.488Z DEBUG modem << I: Downloaded 65536/183143 bytes (35%)
2021-03-28T10:26:57.094Z DEBUG modem << I: Downloaded 67584/183143 bytes (36%)
2021-03-28T10:26:58.789Z DEBUG modem << I: Downloaded 69632/183143 bytes (38%)
2021-03-28T10:27:00.294Z DEBUG modem << I: Downloaded 71680/183143 bytes (39%)
2021-03-28T10:27:02.025Z DEBUG modem << I: Downloaded 73728/183143 bytes (40%)
2021-03-28T10:27:03.565Z DEBUG modem << I: Downloaded 75776/183143 bytes (41%)
2021-03-28T10:27:05.173Z DEBUG modem << I: Downloaded 77824/183143 bytes (42%)
2021-03-28T10:27:06.696Z DEBUG modem << I: Downloaded 79872/183143 bytes (43%)
2021-03-28T10:27:08.275Z DEBUG modem << I: Downloaded 81920/183143 bytes (44%)
2021-03-28T10:27:09.613Z DEBUG modem << I: Downloaded 83968/183143 bytes (45%)
2021-03-28T10:27:11.159Z DEBUG modem << I: Downloaded 86016/183143 bytes (46%)
2021-03-28T10:27:12.853Z DEBUG modem << I: Downloaded 88064/183143 bytes (48%)
2021-03-28T10:27:14.641Z DEBUG modem << I: Downloaded 90112/183143 bytes (49%)
2021-03-28T10:27:16.078Z DEBUG modem << I: Downloaded 92160/183143 bytes (50%)
2021-03-28T10:27:17.602Z DEBUG modem << I: Downloaded 94208/183143 bytes (51%)
2021-03-28T10:27:19.585Z DEBUG modem << I: Downloaded 96256/183143 bytes (52%)
2021-03-28T10:27:21.042Z DEBUG modem << I: Downloaded 98304/183143 bytes (53%)
2021-03-28T10:27:22.409Z DEBUG modem << I: Downloaded 100352/183143 bytes (54%)
2021-03-28T10:27:24.105Z DEBUG modem << I: Downloaded 102400/183143 bytes (55%)
2021-03-28T10:27:25.404Z DEBUG modem << I: Downloaded 104448/183143 bytes (57%)
2021-03-28T10:27:26.949Z DEBUG modem << I: Downloaded 106496/183143 bytes (58%)
2021-03-28T10:27:28.509Z DEBUG modem << I: Downloaded 108544/183143 bytes (59%)
2021-03-28T10:27:30.174Z DEBUG modem << I: Downloaded 110592/183143 bytes (60%)
2021-03-28T10:27:31.761Z DEBUG modem << I: Downloaded 112640/183143 bytes (61%)
2021-03-28T10:27:33.333Z DEBUG modem << I: Downloaded 114688/183143 bytes (62%)
2021-03-28T10:27:35.152Z DEBUG modem << I: Downloaded 116736/183143 bytes (63%)
2021-03-28T10:27:36.853Z DEBUG modem << I: Downloaded 118784/183143 bytes (64%)
2021-03-28T10:27:38.355Z DEBUG modem << I: Downloaded 120832/183143 bytes (65%)
2021-03-28T10:27:40.261Z DEBUG modem << I: Downloaded 122880/183143 bytes (67%)
2021-03-28T10:27:41.709Z DEBUG modem << I: Downloaded 124928/183143 bytes (68%)
2021-03-28T10:27:43.213Z DEBUG modem << I: Downloaded 126976/183143 bytes (69%)
2021-03-28T10:27:44.867Z DEBUG modem << I: Downloaded 129024/183143 bytes (70%)
2021-03-28T10:27:46.388Z DEBUG modem << I: Downloaded 131072/183143 bytes (71%)
2021-03-28T10:27:48.013Z DEBUG modem << I: Downloaded 133120/183143 bytes (72%)
2021-03-28T10:27:49.847Z DEBUG modem << I: Downloaded 135168/183143 bytes (73%)
2021-03-28T10:27:51.236Z DEBUG modem << I: Downloaded 137216/183143 bytes (74%)
2021-03-28T10:27:52.852Z DEBUG modem << I: Downloaded 139264/183143 bytes (76%)
2021-03-28T10:27:54.373Z DEBUG modem << I: Downloaded 141312/183143 bytes (77%)
2021-03-28T10:27:56.027Z DEBUG modem << I: Downloaded 143360/183143 bytes (78%)
2021-03-28T10:27:57.654Z DEBUG modem << I: Downloaded 145408/183143 bytes (79%)
2021-03-28T10:27:59.235Z DEBUG modem << I: Downloaded 147456/183143 bytes (80%)
2021-03-28T10:28:01.092Z DEBUG modem << I: Downloaded 149504/183143 bytes (81%)
2021-03-28T10:28:02.732Z DEBUG modem << I: Downloaded 151552/183143 bytes (82%)
2021-03-28T10:28:04.293Z DEBUG modem << I: Downloaded 153600/183143 bytes (83%)
2021-03-28T10:28:06.004Z DEBUG modem << I: Downloaded 155648/183143 bytes (84%)
2021-03-28T10:28:07.598Z DEBUG modem << I: Downloaded 157696/183143 bytes (86%)
2021-03-28T10:28:09.532Z DEBUG modem << I: Downloaded 159744/183143 bytes (87%)
2021-03-28T10:28:11.112Z DEBUG modem << I: Downloaded 161792/183143 bytes (88%)
2021-03-28T10:28:12.596Z DEBUG modem << I: Downloaded 163840/183143 bytes (89%)
2021-03-28T10:28:14.191Z DEBUG modem << I: Downloaded 165888/183143 bytes (90%)
2021-03-28T10:28:16.075Z DEBUG modem << I: Downloaded 167936/183143 bytes (91%)
2021-03-28T10:28:17.518Z DEBUG modem << I: Downloaded 169984/183143 bytes (92%)
2021-03-28T10:28:19.120Z DEBUG modem << I: Downloaded 172032/183143 bytes (93%)
2021-03-28T10:28:20.679Z DEBUG modem << I: Downloaded 174080/183143 bytes (95%)
2021-03-28T10:28:22.394Z DEBUG modem << I: Downloaded 176128/183143 bytes (96%)
2021-03-28T10:28:23.798Z DEBUG modem << I: Downloaded 178176/183143 bytes (97%)
2021-03-28T10:28:25.442Z DEBUG modem << I: Downloaded 180224/183143 bytes (98%)
2021-03-28T10:28:27.090Z DEBUG modem << I: Downloaded 182272/183143 bytes (99%)
2021-03-28T10:28:28.195Z DEBUG modem << I: Downloaded 183143/183143 bytes (100%)
2021-03-28T10:28:28.227Z DEBUG modem << I: Download complete
2021-03-28T10:28:28.323Z DEBUG modem << I: MCUBoot image upgrade scheduled. Reset device to apply
2021-03-28T10:28:28.345Z DEBUG modem << D: FOTA download completed evt received
2021-03-28T10:28:28.349Z DEBUG modem << AWS_FOTA_EVT_DL_PROGRESS, 100% downloaded
2021-03-28T10:28:28.366Z DEBUG modem << D: update_job_execution, state: 1, version_number: 2
2021-03-28T10:28:28.368Z DEBUG modem << D: (0x200193f0): [CID 0x200203e0]:[State 0x06]: >> Topic size 0x00000034, Data size 0x00000057
2021-03-28T10:28:28.371Z DEBUG modem << D: (0x200193f0): >> str_size:00000036 cur:0x2001c165, end:0x2001c1e0
2021-03-28T10:28:28.396Z DEBUG modem << D: (0x200193f0): >> val:0034 cur:0x2001c165, end:0x2001c1e0
2021-03-28T10:28:28.399Z DEBUG modem << D: (0x200193f0): >> val:d88d cur:0x2001c19b, end:0x2001c1e0
2021-03-28T10:28:28.401Z DEBUG modem << D: (0x200193f0): << msg type:0x32 length:0x0000008f
2021-03-28T10:28:28.402Z DEBUG modem << D: (0x200193f0): >> length:0x0000008f cur:(nil), end:(nil)
2021-03-28T10:28:28.403Z DEBUG modem << D: (0x200193f0): Fixed header length = 03
2021-03-28T10:28:28.434Z DEBUG modem << D: (0x200193f0): >> val:32 cur:0x2001c162, end:0x2001c1e0
2021-03-28T10:28:28.435Z DEBUG modem << D: (0x200193f0): >> length:0x0000008f cur:0x2001c163, end:0x2001c1e0
2021-03-28T10:28:28.437Z DEBUG modem << D: (0x200193f0): [0x200203e0]: Transport writing message.
2021-03-28T10:28:28.444Z DEBUG modem << D: (0x200193f0): [0x200203e0]: Transport write complete.
2021-03-28T10:28:28.464Z DEBUG modem << D: (0x200193f0): [CID 0x200203e0]:[State 0x06]: << result 0x00000000
2021-03-28T10:28:29.175Z DEBUG modem << D: (0x2001a5e0): state:0x00000006
2021-03-28T10:28:29.199Z DEBUG modem << D: (0x2001a5e0): >> cur:0x2001c0e0, end:0x2001c0e2
2021-03-28T10:28:29.202Z DEBUG modem << D: (0x2001a5e0): << val:40
2021-03-28T10:28:29.203Z DEBUG modem << D: (0x2001a5e0): length:0x00000002
2021-03-28T10:28:29.204Z DEBUG modem << D: (0x2001a5e0): [CID 0x200203e0]: Received MQTT_PKT_TYPE_PUBACK!
2021-03-28T10:28:29.206Z DEBUG modem << D: (0x2001a5e0): >> cur:0x2001c0e2, end:0x2001c0e4
2021-03-28T10:28:29.208Z DEBUG modem << D: (0x2001a5e0): << val:d88d
2021-03-28T10:28:29.211Z DEBUG modem << [mqtt_evt_handler:235] PUBACK packet id: 55437
2021-03-28T10:28:29.291Z DEBUG modem << D: (0x2001a5e0): state:0x00000006
2021-03-28T10:28:29.298Z DEBUG modem << D: (0x2001a5e0): >> cur:0x2001c0e0, end:0x2001c0e2
2021-03-28T10:28:29.315Z DEBUG modem << D: (0x2001a5e0): << val:30
2021-03-28T10:28:29.318Z DEBUG modem << D: (0x2001a5e0): length:0x00000068
2021-03-28T10:28:29.341Z DEBUG modem << D: (0x2001a5e0): [CID 0x200203e0]: Received MQTT_PKT_TYPE_PUBLISH
2021-03-28T10:28:29.343Z DEBUG modem << D: (0x2001a5e0): >> cur:0x2001c0e2, end:0x2001c121
2021-03-28T10:28:29.345Z DEBUG modem << D: (0x2001a5e0): >> cur:0x2001c0e2, end:0x2001c121
2021-03-28T10:28:29.346Z DEBUG modem << D: (0x2001a5e0): << val:003d
2021-03-28T10:28:29.348Z DEBUG modem << D: (0x2001a5e0): << str_size:0000003f
2021-03-28T10:28:29.368Z DEBUG modem << D: (0x2001a5e0): PUB QoS:00, message len 00000029, topic len 0000003d
2021-03-28T10:28:29.370Z DEBUG modem << D: Received topic: $aws/things/DL40_thing/jobs/logger_fota_dg_10/update/accepted
2021-03-28T10:28:29.372Z DEBUG modem << D: Firmware download completed
2021-03-28T10:28:29.373Z DEBUG modem << D: update_job_execution, state: 2, version_number: 3
2021-03-28T10:28:29.376Z DEBUG modem << D: (0x2001a5e0): [CID 0x200203e0]:[State 0x06]: >> Topic size 0x00000034, Data size 0x00000055
2021-03-28T10:28:29.378Z DEBUG modem << D: (0x2001a5e0): >> str_size:00000036 cur:0x2001c165, end:0x2001c1e0
2021-03-28T10:28:29.399Z DEBUG modem << D: (0x2001a5e0): >> val:0034 cur:0x2001c165, end:0x2001c1e0
2021-03-28T10:28:29.402Z DEBUG modem << D: (0x2001a5e0): >> val:5a4f cur:0x2001c19b, end:0x2001c1e0
2021-03-28T10:28:29.403Z DEBUG modem << D: (0x2001a5e0): << msg type:0x32 length:0x0000008d
2021-03-28T10:28:29.405Z DEBUG modem << D: (0x2001a5e0): >> length:0x0000008d cur:(nil), end:(nil)
2021-03-28T10:28:29.427Z DEBUG modem << D: (0x2001a5e0): Fixed header length = 03
2021-03-28T10:28:29.430Z DEBUG modem << D: (0x2001a5e0): >> val:32 cur:0x2001c162, end:0x2001c1e0
2021-03-28T10:28:29.432Z DEBUG modem << D: (0x2001a5e0): >> length:0x0000008d cur:0x2001c163, end:0x2001c1e0
2021-03-28T10:28:29.435Z DEBUG modem << D: (0x2001a5e0): [0x200203e0]: Transport writing message.
2021-03-28T10:28:29.460Z DEBUG modem << D: (0x2001a5e0): [0x200203e0]: Transport write complete.
2021-03-28T10:28:29.462Z DEBUG modem << D: (0x2001a5e0): [CID 0x200203e0]:[State 0x06]: << result 0x00000000
2021-03-28T10:28:30.139Z DEBUG modem << D: (0x2001a5e0): state:0x00000006
2021-03-28T10:28:30.162Z DEBUG modem << D: (0x2001a5e0): >> cur:0x2001c0e0, end:0x2001c0e2
2021-03-28T10:28:30.165Z DEBUG modem << D: (0x2001a5e0): << val:40
2021-03-28T10:28:30.168Z DEBUG modem << D: (0x2001a5e0): length:0x00000002
2021-03-28T10:28:30.169Z DEBUG modem << D: (0x2001a5e0): [CID 0x200203e0]: Received MQTT_PKT_TYPE_PUBACK!
2021-03-28T10:28:30.190Z DEBUG modem << D: (0x2001a5e0): >> cur:0x2001c0e2, end:0x2001c0e4
2021-03-28T10:28:30.192Z DEBUG modem << D: (0x2001a5e0): << val:5a4f
2021-03-28T10:28:30.194Z DEBUG modem << [mqtt_evt_handler:235] PUBACK packet id: 23119
2021-03-28T10:28:30.195Z DEBUG modem << D: (0x2001a5e0): state:0x00000006
2021-03-28T10:28:30.196Z DEBUG modem << D: (0x2001a5e0): >> cur:0x2001c0e0, end:0x2001c0e2
2021-03-28T10:28:30.197Z DEBUG modem << D: (0x2001a5e0): << val:30
2021-03-28T10:28:30.198Z DEBUG modem << D: (0x2001a5e0): length:0x00000068
2021-03-28T10:28:30.200Z DEBUG modem << D: (0x2001a5e0): [CID 0x200203e0]: Received MQTT_PKT_TYPE_PUBLISH
2021-03-28T10:28:30.203Z DEBUG modem << D: (0x2001a5e0): >> cur:0x2001c0e2, end:0x2001c121
2021-03-28T10:28:30.205Z DEBUG modem << D: (0x2001a5e0): >> cur:0x2001c0e2, end:0x2001c121
2021-03-28T10:28:30.229Z DEBUG modem << D: (0x2001a5e0): << val:003d
2021-03-28T10:28:30.232Z DEBUG modem << D: (0x2001a5e0): << str_size:0000003f
2021-03-28T10:28:30.234Z DEBUG modem << D: (0x2001a5e0): PUB QoS:00, message len 00000029, topic len 0000003d
2021-03-28T10:28:30.235Z DEBUG modem << D: Received topic: $aws/things/DL40_thing/jobs/logger_fota_dg_10/update/accepted
2021-03-28T10:28:30.236Z DEBUG modem << AWS_FOTA_EVT_DONE, rebooting to apply update
2021-03-28T10:28:30.238Z DEBUG modem << D: Job document updated with SUCCEDED
2021-03-28T10:28:30.241Z DEBUG modem << D: Ready to reboot
2021-03-28T10:28:30.265Z DEBUG modem << D: (0x2001a5e0): [0x200203e0]: Transport writing 2 bytes.
2021-03-28T10:28:30.268Z DEBUG modem << D: (0x2001a5e0): [0x200203e0]: Transport write complete.
2021-03-28T10:28:30.270Z DEBUG modem << D: (0x2001a5e0): Closing socket 1
2021-03-28T10:28:30.278Z DEBUG modem << [mqtt_evt_handler:193] MQTT client disconnected 0
2021-03-28T10:28:30.534Z DEBUG modem << þ*** Booting Zephyr OS build v2.4.99-ncs1  ***
2021-03-28T10:28:30.565Z DEBUG modem << I: Starting bootloader
2021-03-28T10:28:30.568Z DEBUG modem << I: Primary image: magic=good, swap_type=0x2, copy_done=0x1, image_ok=0x1
2021-03-28T10:28:30.571Z DEBUG modem << I: Secondary image: magic=good, swap_type=0x2, copy_done=0x3, image_ok=0x3
2021-03-28T10:28:30.572Z DEBUG modem << I: Boot source: none
2021-03-28T10:28:30.573Z DEBUG modem << I: Swap type: test
2021-03-28T10:28:48.886Z DEBUG modem << I: Bootloader chainload address offset: 0x10000
2021-03-28T10:28:48.909Z DEBUG modem << I: Jumping to the first image slot
2021-03-28T10:28:48.952Z DEBUG modem << *** Booting Zephyr OS build v2.4.99-ncs1  ***
2021-03-28T10:28:48.956Z DEBUG modem << Flash regionsDomainPermissions
2021-03-28T10:28:48.960Z DEBUG modem << 00 02 0x00000 0x18000 Securerwxl
2021-03-28T10:28:48.962Z DEBUG modem << 03 31 0x18000 0x100000 Non-Securerwxl
2021-03-28T10:28:48.963Z DEBUG modem << Non-secure callable region 0 placed in flash region 2 with size 32.
2021-03-28T10:28:49.084Z DEBUG modem << SRAM regionDomainPermissions
2021-03-28T10:28:49.108Z DEBUG modem << 00 07 0x00000 0x10000 Securerwxl
2021-03-28T10:28:49.134Z DEBUG modem << 08 31 0x10000 0x40000 Non-Securerwxl
2021-03-28T10:28:49.144Z DEBUG modem << PeripheralDomainStatus
2021-03-28T10:28:49.162Z DEBUG modem << 00 NRF_P0               Non-SecureOK
2021-03-28T10:28:49.164Z DEBUG modem << 01 NRF_CLOCK            Non-SecureOK
2021-03-28T10:28:49.165Z DEBUG modem << 02 NRF_RTC0             Non-SecureOK
2021-03-28T10:28:49.175Z DEBUG modem << 03 NRF_RTC1             Non-SecureOK
2021-03-28T10:28:49.350Z DEBUG modem << 04 NRF_NVMC             Non-SecureOK
2021-03-28T10:28:49.352Z DEBUG modem << 05 NRF_UARTE1           Non-SecureOK
2021-03-28T10:28:49.361Z DEBUG modem << 06 NRF_UARTE2           SecureSKIP
2021-03-28T10:28:49.383Z DEBUG modem << 07 NRF_TWIM2            Non-SecureOK
2021-03-28T10:28:49.385Z DEBUG modem << 08 NRF_SPIM3            Non-SecureOK
2021-03-28T10:28:49.388Z DEBUG modem << 09 NRF_TIMER0           Non-SecureOK
2021-03-28T10:28:49.390Z DEBUG modem << 10 NRF_TIMER1           Non-SecureOK
2021-03-28T10:28:49.392Z DEBUG modem << 11 NRF_TIMER2           Non-SecureOK
2021-03-28T10:28:49.394Z DEBUG modem << 12 NRF_SAADC            Non-SecureOK
2021-03-28T10:28:49.395Z DEBUG modem << 13 NRF_PWM0             Non-SecureOK
2021-03-28T10:28:49.396Z DEBUG modem << 14 NRF_PWM1             Non-SecureOK
2021-03-28T10:28:49.397Z DEBUG modem << 15 NRF_PWM2             Non-SecureOK
2021-03-28T10:28:49.398Z DEBUG modem << 16 NRF_PWM3             Non-SecureOK
2021-03-28T10:28:49.400Z DEBUG modem << 17 NRF_WDT              Non-SecureOK
2021-03-28T10:28:49.403Z DEBUG modem << 18 NRF_IPC              Non-SecureOK
2021-03-28T10:28:49.405Z DEBUG modem << 19 NRF_VMC              Non-SecureOK
2021-03-28T10:28:49.407Z DEBUG modem << 20 NRF_FPU              Non-SecureOK
2021-03-28T10:28:49.409Z DEBUG modem << 21 NRF_EGU1             Non-SecureOK
2021-03-28T10:28:49.425Z DEBUG modem << 22 NRF_EGU2             Non-SecureOK
2021-03-28T10:28:49.427Z DEBUG modem << 23 NRF_DPPIC            Non-SecureOK
2021-03-28T10:28:49.445Z DEBUG modem << 24 NRF_REGULATORS       Non-SecureOK
2021-03-28T10:28:49.446Z DEBUG modem << 25 NRF_GPIOTE1          Non-SecureOK
2021-03-28T10:28:49.467Z DEBUG modem << SPM: NS image at 0x1c200
2021-03-28T10:28:49.469Z DEBUG modem << SPM: NS MSP at 0x20020488
2021-03-28T10:28:49.471Z DEBUG modem << SPM: NS reset vector at 0x22161
2021-03-28T10:28:49.472Z DEBUG modem << SPM: prepare to jump to Non-Secure image.
2021-03-28T10:28:49.510Z DEBUG modem << *** Booting Zephyr OS build v2.4.99-ncs1  ***
2021-03-28T10:28:49.513Z DEBUG modem << MQTT AWS Jobs FOTA Sample, version: 2.0.1
2021-03-28T10:28:49.516Z DEBUG modem << Initializing modem library
2021-03-28T10:28:49.531Z DEBUG modem << Initialized modem library
2021-03-28T10:28:49.532Z DEBUG modem << W: Already initialized. Nothing to do
2021-03-28T10:28:49.534Z DEBUG modem << LTE Link Connecting ...
2021-03-28T10:28:49.536Z DEBUG modem << D: Preferred system mode (2) is already configured
2021-03-28T10:28:49.561Z DEBUG modem << I: PDP Context: AT+CGDCONT=1,"IP","arkessalp.com"
2021-03-28T10:28:49.563Z DEBUG modem << D: Sending AT command to set system mode: AT%XSYSTEMMODE=0,1,0,0
2021-03-28T10:28:51.028Z DEBUG modem << +CEREG: 2,"2332","0250601F",9,0,0,"11100000","11100000"
2021-03-28T10:28:51.093Z DEBUG modem << D: +CEREG notification: +CEREG: 2,"2332","0250601F",9,0,0,"11100000","11100000"
2021-03-28T10:28:51.290Z DEBUG modem << +CSCON: 1
2021-03-28T10:28:51.292Z DEBUG modem << D: +CSCON notification
2021-03-28T10:28:52.651Z DEBUG modem << +CEREG: 5,"2332","0250601F",9,,,"00011110","00000111"
2021-03-28T10:28:52.698Z DEBUG modem >> AT+COPS=3,2
2021-03-28T10:28:52.722Z DEBUG modem << D: +CEREG notification: +CEREG: 5,"2332","0250601F",9,,,"00011110","00000111"
2021-03-28T10:28:52.725Z DEBUG modem << D: TAU: 4200 sec, active time: 60 sec
2021-03-28T10:28:52.727Z DEBUG modem << LTE Link Connected!
2021-03-28T10:28:52.730Z DEBUG modem << OK
2021-03-28T10:28:52.758Z DEBUG modem >> AT+COPS?
2021-03-28T10:28:52.795Z DEBUG modem << +COPS: 1,2,"27201",9
2021-03-28T10:28:52.805Z DEBUG modem << OK
2021-03-28T10:28:52.839Z DEBUG modem >> AT%XCBAND
2021-03-28T10:28:52.849Z DEBUG modem << %XCBAND: 20
2021-03-28T10:28:52.872Z DEBUG modem << OK
2021-03-28T10:28:52.882Z DEBUG modem >> AT+CGDCONT?
2021-03-28T10:28:52.916Z DEBUG modem << +CGDCONT: 0,"IP","arkessalp.com","10.72.0.10",0,0
2021-03-28T10:28:52.919Z DEBUG modem << +CGDCONT: 1,"IP","arkessalp.com","",0,0
2021-03-28T10:28:52.922Z DEBUG modem << OK
2021-03-28T10:28:52.953Z DEBUG modem >> AT+CGACT?
2021-03-28T10:28:52.978Z DEBUG modem << +CGACT: 0,1
2021-03-28T10:28:52.980Z DEBUG modem << +CGACT: 1,0
2021-03-28T10:28:52.981Z DEBUG modem << OK
2021-03-28T10:28:53.365Z DEBUG modem << IPv4 Address 34.253.25.169
2021-03-28T10:28:53.388Z DEBUG modem << client_id: DL40_thing
2021-03-28T10:28:53.391Z DEBUG modem << D: (0x2001a5e0): Created socket 1
2021-03-28T10:28:59.778Z DEBUG modem << D: (0x2001a5e0): Connect completed
2021-03-28T10:28:59.807Z DEBUG modem << D: (0x2001a5e0): Encoding Protocol Description. Str:MQTT Size:00000004.
2021-03-28T10:28:59.809Z DEBUG modem << D: (0x2001a5e0): >> str_size:00000006 cur:0x2001c165, end:0x2001c1e0
2021-03-28T10:28:59.810Z DEBUG modem << D: (0x2001a5e0): >> val:0004 cur:0x2001c165, end:0x2001c1e0
2021-03-28T10:28:59.812Z DEBUG modem << D: (0x2001a5e0): Encoding Protocol Version 04.
2021-03-28T10:28:59.815Z DEBUG modem << D: (0x2001a5e0): >> val:04 cur:0x2001c16b, end:0x2001c1e0
2021-03-28T10:28:59.817Z DEBUG modem << D: (0x2001a5e0): >> val:00 cur:0x2001c16c, end:0x2001c1e0
2021-03-28T10:28:59.848Z DEBUG modem << D: (0x2001a5e0): Encoding Keep Alive Time 04b0.
2021-03-28T10:28:59.850Z DEBUG modem << D: (0x2001a5e0): >> val:04b0 cur:0x2001c16d, end:0x2001c1e0
2021-03-28T10:28:59.852Z DEBUG modem << D: (0x2001a5e0): Encoding Client Id. Str:DL40_thing Size:0000000a.
2021-03-28T10:28:59.855Z DEBUG modem << D: (0x2001a5e0): >> str_size:0000000c cur:0x2001c16f, end:0x2001c1e0
2021-03-28T10:28:59.857Z DEBUG modem << D: (0x2001a5e0): >> val:000a cur:0x2001c16f, end:0x2001c1e0
2021-03-28T10:28:59.860Z DEBUG modem << D: (0x2001a5e0): << msg type:0x10 length:0x00000016
2021-03-28T10:28:59.862Z DEBUG modem << D: (0x2001a5e0): >> length:0x00000016 cur:(nil), end:(nil)
2021-03-28T10:28:59.864Z DEBUG modem << D: (0x2001a5e0): Fixed header length = 02
2021-03-28T10:28:59.902Z DEBUG modem << D: (0x2001a5e0): >> val:10 cur:0x2001c163, end:0x2001c1e0
2021-03-28T10:28:59.904Z DEBUG modem << D: (0x2001a5e0): >> length:0x00000016 cur:0x2001c164, end:0x2001c1e0
2021-03-28T10:28:59.906Z DEBUG modem << D: (0x2001a5e0): Connect completed
2021-03-28T10:29:00.389Z DEBUG modem << D: (0x2001a5e0): state:0x00000002
2021-03-28T10:29:00.426Z DEBUG modem << D: (0x2001a5e0): >> cur:0x2001c0e0, end:0x2001c0e2
2021-03-28T10:29:00.430Z DEBUG modem << D: (0x2001a5e0): << val:20
2021-03-28T10:29:00.433Z DEBUG modem << D: (0x2001a5e0): length:0x00000002
2021-03-28T10:29:00.435Z DEBUG modem << D: (0x2001a5e0): [CID 0x200203e0]: Received MQTT_PKT_TYPE_CONNACK!
2021-03-28T10:29:00.436Z DEBUG modem << D: (0x2001a5e0): >> cur:0x2001c0e2, end:0x2001c0e4
2021-03-28T10:29:00.437Z DEBUG modem << D: (0x2001a5e0): << val:01
2021-03-28T10:29:00.438Z DEBUG modem << D: (0x2001a5e0): >> cur:0x2001c0e3, end:0x2001c0e4
2021-03-28T10:29:00.438Z DEBUG modem << D: (0x2001a5e0): << val:00
2021-03-28T10:29:00.461Z DEBUG modem << D: (0x2001a5e0): [CID 0x200203e0]: session_present_flag: 1
2021-03-28T10:29:00.463Z DEBUG modem << D: (0x2001a5e0): [CID 0x200203e0]: return_code: 0
2021-03-28T10:29:00.465Z DEBUG modem << D: Previous session valid; skipping FOTA subscriptions
2021-03-28T10:29:00.467Z DEBUG modem << D: Created notify_next_topic $aws/things/DL40_thing/jobs/notify-next
2021-03-28T10:29:00.469Z DEBUG modem << D: Created get_topic $aws/things/DL40_thing/jobs/$next/get/#
2021-03-28T10:29:00.493Z DEBUG modem << D: previously subscribed to notify-next topic
2021-03-28T10:29:00.496Z DEBUG modem << D: (0x2001a5e0): [CID 0x200203e0]:[State 0x06]: >> Topic size 0x00000025, Data size 0x00000013
2021-03-28T10:29:00.497Z DEBUG modem << D: (0x2001a5e0): >> str_size:00000027 cur:0x2001c165, end:0x2001c1e0
2021-03-28T10:29:00.498Z DEBUG modem << D: (0x2001a5e0): >> val:0025 cur:0x2001c165, end:0x2001c1e0
2021-03-28T10:29:00.525Z DEBUG modem << D: (0x2001a5e0): >> val:a2c2 cur:0x2001c18c, end:0x2001c1e0
2021-03-28T10:29:00.527Z DEBUG modem << D: (0x2001a5e0): << msg type:0x32 length:0x0000003c
2021-03-28T10:29:00.529Z DEBUG modem << D: (0x2001a5e0): >> length:0x0000003c cur:(nil), end:(nil)
2021-03-28T10:29:00.531Z DEBUG modem << D: (0x2001a5e0): Fixed header length = 02
2021-03-28T10:29:00.531Z DEBUG modem << D: (0x2001a5e0): >> val:32 cur:0x2001c163, end:0x2001c1e0
2021-03-28T10:29:00.563Z DEBUG modem << D: (0x2001a5e0): >> length:0x0000003c cur:0x2001c164, end:0x2001c1e0
2021-03-28T10:29:00.565Z DEBUG modem << D: (0x2001a5e0): [0x200203e0]: Transport writing message.
2021-03-28T10:29:00.568Z DEBUG modem << D: (0x2001a5e0): [0x200203e0]: Transport write complete.
2021-03-28T10:29:00.597Z DEBUG modem << D: (0x2001a5e0): [CID 0x200203e0]:[State 0x06]: << result 0x00000000
2021-03-28T10:29:00.600Z DEBUG modem << [mqtt_evt_handler:182] MQTT client connected!
2021-03-28T10:29:00.656Z DEBUG modem << D: (0x2001a5e0): [CID 0x200203e0]:[State 0x06]: >> Topic size 0x00000024, Data size 0x0000003a
2021-03-28T10:29:00.691Z DEBUG modem << D: (0x2001a5e0): >> str_size:00000026 cur:0x2001c165, end:0x2001c1e0
2021-03-28T10:29:00.695Z DEBUG modem << D: (0x2001a5e0): >> val:0024 cur:0x2001c165, end:0x2001c1e0
2021-03-28T10:29:00.729Z DEBUG modem << D: (0x2001a5e0): >> val:0c28 cur:0x2001c18b, end:0x2001c1e0
2021-03-28T10:29:00.730Z DEBUG modem << D: (0x2001a5e0): << msg type:0x32 length:0x00000062
2021-03-28T10:29:00.733Z DEBUG modem << D: (0x2001a5e0): >> length:0x00000062 cur:(nil), end:(nil)
2021-03-28T10:29:00.735Z DEBUG modem << D: (0x2001a5e0): Fixed header length = 02
2021-03-28T10:29:00.737Z DEBUG modem << D: (0x2001a5e0): >> val:32 cur:0x2001c163, end:0x2001c1e0
2021-03-28T10:29:00.739Z DEBUG modem << D: (0x2001a5e0): >> length:0x00000062 cur:0x2001c164, end:0x2001c1e0
2021-03-28T10:29:00.772Z DEBUG modem << D: (0x2001a5e0): [0x200203e0]: Transport writing message.
2021-03-28T10:29:00.773Z DEBUG modem << D: (0x2001a5e0): [0x200203e0]: Transport write complete.
2021-03-28T10:29:00.778Z DEBUG modem << D: (0x2001a5e0): [CID 0x200203e0]:[State 0x06]: << result 0x00000000
2021-03-28T10:29:00.780Z DEBUG modem << D: (0x2001a5e0): state:0x00000006
2021-03-28T10:29:00.808Z DEBUG modem << D: (0x2001a5e0): >> cur:0x2001c0e0, end:0x2001c0e2
2021-03-28T10:29:00.811Z DEBUG modem << D: (0x2001a5e0): << val:32
2021-03-28T10:29:00.812Z DEBUG modem << D: (0x2001a5e0): length:0x00000043
2021-03-28T10:29:00.813Z DEBUG modem << D: (0x2001a5e0): [CID 0x200203e0]: Received MQTT_PKT_TYPE_PUBLISH
2021-03-28T10:29:00.814Z DEBUG modem << D: (0x2001a5e0): >> cur:0x2001c0e2, end:0x2001c10d
2021-03-28T10:29:00.839Z DEBUG modem << D: (0x2001a5e0): >> cur:0x2001c0e2, end:0x2001c10d
2021-03-28T10:29:00.842Z DEBUG modem << D: (0x2001a5e0): << val:0027
2021-03-28T10:29:00.844Z DEBUG modem << D: (0x2001a5e0): << str_size:00000029
2021-03-28T10:29:00.845Z DEBUG modem << D: (0x2001a5e0): >> cur:0x2001c10b, end:0x2001c10d
2021-03-28T10:29:00.847Z DEBUG modem << D: (0x2001a5e0): << val:0001
2021-03-28T10:29:00.875Z DEBUG modem << D: (0x2001a5e0): PUB QoS:01, message len 00000018, topic len 00000027
2021-03-28T10:29:00.877Z DEBUG modem << D: Received topic: $aws/things/DL40_thing/jobs/notify-next
2021-03-28T10:29:00.879Z DEBUG modem << D: Checking for an available job
2021-03-28T10:29:00.889Z DEBUG modem << D: Job doc: {"timestamp":1616927311}
2021-03-28T10:29:00.890Z DEBUG modem << D: Got only one field
2021-03-28T10:29:00.919Z DEBUG modem << D: No queued jobs for this device
2021-03-28T10:29:00.921Z DEBUG modem << D: (0x2001a5e0): [CID 0x200203e0]:[State 0x06]: >> Message id 0x0001
2021-03-28T10:29:00.922Z DEBUG modem << D: (0x2001a5e0): >> val:0001 cur:0x2001c165, end:0x2001c1e0
2021-03-28T10:29:00.924Z DEBUG modem << D: (0x2001a5e0): << msg type:0x40 length:0x00000002
2021-03-28T10:29:00.925Z DEBUG modem << D: (0x2001a5e0): >> length:0x00000002 cur:(nil), end:(nil)
2021-03-28T10:29:00.927Z DEBUG modem << D: (0x2001a5e0): Fixed header length = 02
2021-03-28T10:29:00.929Z DEBUG modem << D: (0x2001a5e0): >> val:40 cur:0x2001c163, end:0x2001c1e0
2021-03-28T10:29:00.930Z DEBUG modem << D: (0x2001a5e0): >> length:0x00000002 cur:0x2001c164, end:0x2001c1e0
2021-03-28T10:29:00.963Z DEBUG modem << D: (0x2001a5e0): [0x200203e0]: Transport writing 4 bytes.
2021-03-28T10:29:00.966Z DEBUG modem << D: (0x2001a5e0): [0x200203e0]: Transport write complete.
2021-03-28T10:29:00.968Z DEBUG modem << D: (0x2001a5e0): [CID 0x200203e0]:[State 0x06]: << result 0x00000000
2021-03-28T10:29:00.993Z DEBUG modem << D: (0x2001a5e0): state:0x00000006
2021-03-28T10:29:00.996Z DEBUG modem << D: (0x2001a5e0): >> cur:0x2001c0e0, end:0x2001c0e2
2021-03-28T10:29:00.998Z DEBUG modem << D: (0x2001a5e0): << val:40
2021-03-28T10:29:01.000Z DEBUG modem << D: (0x2001a5e0): length:0x00000002
2021-03-28T10:29:01.034Z DEBUG modem << D: (0x2001a5e0): [CID 0x200203e0]: Received MQTT_PKT_TYPE_PUBACK!
2021-03-28T10:29:01.035Z DEBUG modem << D: (0x2001a5e0): >> cur:0x2001c0e2, end:0x2001c0e4
2021-03-28T10:29:01.038Z DEBUG modem << D: (0x2001a5e0): << val:a2c2
2021-03-28T10:29:01.039Z DEBUG modem << [mqtt_evt_handler:235] PUBACK packet id: 41666
2021-03-28T10:29:01.041Z DEBUG modem << D: (0x2001a5e0): state:0x00000006
2021-03-28T10:29:01.043Z DEBUG modem << D: (0x2001a5e0): >> cur:0x2001c0e0, end:0x2001c0e2
2021-03-28T10:29:01.046Z DEBUG modem << D: (0x2001a5e0): << val:30
2021-03-28T10:29:01.074Z DEBUG modem << D: (0x2001a5e0): length:0x00000059
2021-03-28T10:29:01.076Z DEBUG modem << D: (0x2001a5e0): [CID 0x200203e0]: Received MQTT_PKT_TYPE_PUBLISH
2021-03-28T10:29:01.078Z DEBUG modem << D: (0x2001a5e0): >> cur:0x2001c0e2, end:0x2001c112
2021-03-28T10:29:01.080Z DEBUG modem << D: (0x2001a5e0): >> cur:0x2001c0e2, end:0x2001c112
2021-03-28T10:29:01.083Z DEBUG modem << D: (0x2001a5e0): << val:002e
2021-03-28T10:29:01.109Z DEBUG modem << D: (0x2001a5e0): << str_size:00000030
2021-03-28T10:29:01.113Z DEBUG modem << D: (0x2001a5e0): PUB QoS:00, message len 00000029, topic len 0000002e
2021-03-28T10:29:01.114Z DEBUG modem << D: Received topic: $aws/things/DL40_thing/jobs/$next/get/accepted
2021-03-28T10:29:01.115Z DEBUG modem << D: Checking for an available job
2021-03-28T10:29:01.119Z DEBUG modem << D: Job doc: {"clientToken":"","timestamp":1616927341}
2021-03-28T10:29:01.120Z DEBUG modem << D: Got only one field
2021-03-28T10:29:01.145Z DEBUG modem << D: No queued jobs for this device
2021-03-28T10:29:01.147Z DEBUG modem << D: (0x2001a5e0): state:0x00000006
2021-03-28T10:29:01.149Z DEBUG modem << D: (0x2001a5e0): >> cur:0x2001c0e0, end:0x2001c0e2
2021-03-28T10:29:01.152Z DEBUG modem << D: (0x2001a5e0): << val:40
2021-03-28T10:29:01.154Z DEBUG modem << D: (0x2001a5e0): length:0x00000002
2021-03-28T10:29:01.156Z DEBUG modem << D: (0x2001a5e0): [CID 0x200203e0]: Received MQTT_PKT_TYPE_PUBACK!
2021-03-28T10:29:01.157Z DEBUG modem << D: (0x2001a5e0): >> cur:0x2001c0e2, end:0x2001c0e4
2021-03-28T10:29:01.159Z DEBUG modem << D: (0x2001a5e0): << val:0c28
2021-03-28T10:29:01.160Z DEBUG modem << [mqtt_evt_handler:235] PUBACK packet id: 3112
2021-03-28T10:29:12.520Z DEBUG modem << +CSCON: 0
2021-03-28T10:29:12.550Z DEBUG modem << D: +CSCON notification
2021-03-28T10:49:01.099Z DEBUG modem << D: (0x2001a5e0): [0x200203e0]: Transport writing 2 bytes.
2021-03-28T10:49:01.148Z DEBUG modem << D: (0x2001a5e0): [0x200203e0]: Transport write complete.
2021-03-28T10:49:02.125Z DEBUG modem << +CSCON: 1
2021-03-28T10:49:02.130Z DEBUG modem << D: +CSCON notification
2021-03-28T10:49:02.342Z DEBUG modem << D: (0x2001a5e0): state:0x00000006
2021-03-28T10:49:02.381Z DEBUG modem << D: (0x2001a5e0): >> cur:0x2001c0e0, end:0x2001c0e2
2021-03-28T10:49:02.383Z DEBUG modem << D: (0x2001a5e0): << val:d0
2021-03-28T10:49:02.409Z DEBUG modem << D: (0x2001a5e0): length:0x00000000
2021-03-28T10:49:02.411Z DEBUG modem << D: (0x2001a5e0): [CID 0x200203e0]: Received MQTT_PKT_TYPE_PINGRSP!
2021-03-28T10:49:02.413Z DEBUG modem << [mqtt_evt_handler:250] default: 9
2021-03-28T10:49:12.870Z DEBUG modem << +CSCON: 0
2021-03-28T10:49:12.898Z DEBUG modem << D: +CSCON notification
2021-03-28T11:09:02.335Z DEBUG modem << D: (0x2001a5e0): [0x200203e0]: Transport writing 2 bytes.
2021-03-28T11:09:02.380Z DEBUG modem << D: (0x2001a5e0): [0x200203e0]: Transport write complete.
2021-03-28T11:09:03.385Z DEBUG modem << +CSCON: 1
2021-03-28T11:09:03.389Z DEBUG modem << D: +CSCON notification
2021-03-28T11:09:03.567Z DEBUG modem << D: (0x2001a5e0): state:0x00000006
2021-03-28T11:09:03.611Z DEBUG modem << D: (0x2001a5e0): >> cur:0x2001c0e0, end:0x2001c0e2
2021-03-28T11:09:03.613Z DEBUG modem << D: (0x2001a5e0): << val:d0
2021-03-28T11:09:03.615Z DEBUG modem << D: (0x2001a5e0): length:0x00000000
2021-03-28T11:09:03.616Z DEBUG modem << D: (0x2001a5e0): [CID 0x200203e0]: Received MQTT_PKT_TYPE_PINGRSP!
2021-03-28T11:09:03.618Z DEBUG modem << [mqtt_evt_handler:250] default: 9
2021-03-28T11:09:14.129Z DEBUG modem << +CSCON: 0
2021-03-28T11:09:14.159Z DEBUG modem << D: +CSCON notification
2021-03-28T11:29:03.544Z DEBUG modem << D: (0x2001a5e0): [0x200203e0]: Transport writing 2 bytes.
2021-03-28T11:29:03.597Z DEBUG modem << D: (0x2001a5e0): [0x200203e0]: Transport write complete.
2021-03-28T11:29:04.646Z DEBUG modem << +CSCON: 1
2021-03-28T11:29:04.658Z DEBUG modem << D: +CSCON notification
2021-03-28T11:29:04.843Z DEBUG modem << D: (0x2001a5e0): state:0x00000006
2021-03-28T11:29:04.900Z DEBUG modem << D: (0x2001a5e0): >> cur:0x2001c0e0, end:0x2001c0e2
2021-03-28T11:29:04.902Z DEBUG modem << D: (0x2001a5e0): << val:d0
2021-03-28T11:29:04.909Z DEBUG modem << D: (0x2001a5e0): length:0x00000000
2021-03-28T11:29:04.910Z DEBUG modem << D: (0x2001a5e0): [CID 0x200203e0]: Received MQTT_PKT_TYPE_PINGRSP!
2021-03-28T11:29:04.912Z DEBUG modem << [mqtt_evt_handler:250] default: 9
2021-03-28T11:29:15.003Z DEBUG modem << +CSCON: 0
2021-03-28T11:29:15.043Z DEBUG modem << D: +CSCON notification
2021-03-28T11:49:04.983Z DEBUG modem << D: (0x2001a5e0): [0x200203e0]: Transport writing 2 bytes.
2021-03-28T11:49:05.318Z DEBUG modem << D: (0x2001a5e0): [0x200203e0]: Transport write complete.
2021-03-28T11:49:05.911Z DEBUG modem << +CSCON: 1
2021-03-28T11:49:05.915Z DEBUG modem << D: +CSCON notification
2021-03-28T11:49:06.216Z DEBUG modem << D: (0x2001a5e0): state:0x00000006
2021-03-28T11:49:06.266Z DEBUG modem << D: (0x2001a5e0): >> cur:0x2001c0e0, end:0x2001c0e2
2021-03-28T11:49:06.269Z DEBUG modem << D: (0x2001a5e0): << val:32
2021-03-28T11:49:06.300Z DEBUG modem << D: (0x2001a5e0): >> cur:0x2001c0e0, end:0x2001c0e3
2021-03-28T11:49:06.302Z DEBUG modem << D: (0x2001a5e0): << val:32
2021-03-28T11:49:06.304Z DEBUG modem << D: (0x2001a5e0): length:0x00000198
2021-03-28T11:49:06.310Z DEBUG modem << D: (0x2001a5e0): [CID 0x200203e0]: Received MQTT_PKT_TYPE_PUBLISH
2021-03-28T11:49:06.312Z DEBUG modem << D: (0x2001a5e0): >> cur:0x2001c0e3, end:0x2001c10e
2021-03-28T11:49:06.314Z DEBUG modem << D: (0x2001a5e0): >> cur:0x2001c0e3, end:0x2001c10e
2021-03-28T11:49:06.315Z DEBUG modem << D: (0x2001a5e0): << val:0027
2021-03-28T11:49:06.316Z DEBUG modem << D: (0x2001a5e0): << str_size:00000029
2021-03-28T11:49:06.317Z DEBUG modem << D: (0x2001a5e0): >> cur:0x2001c10c, end:0x2001c10e
2021-03-28T11:49:06.320Z DEBUG modem << D: (0x2001a5e0): << val:0001
2021-03-28T11:49:06.357Z DEBUG modem << D: (0x2001a5e0): PUB QoS:01, message len 0000016d, topic len 00000027
2021-03-28T11:49:06.359Z DEBUG modem << D: Received topic: $aws/things/DL40_thing/jobs/notify-next
2021-03-28T11:49:06.360Z DEBUG modem << D: Checking for an available job
2021-03-28T11:49:06.435Z DEBUG modem << D: Job doc: {"timestamp":1616931099,"execution":{"jobId":"logger_fota_DG_11","status":"QUEUED","queuedAt":1616931098,"lastUpdatedAt":1616931098,"versionNumber":1,"executionNumber":1,"jobDocument":{"operation":"app_fw_update","fwversion":"v2.0.0","size":183143,"location":{"protocol":"http:","host":"logger-firmware-bucket.s3.eu-west-1.amazonaws.com","path":"app_update.bin"}}}}
2021-03-28T11:49:06.437Z DEBUG modem << D: Job ID: logger_fota_DG_11
2021-03-28T11:49:06.439Z DEBUG modem << D: hostname: logger-firmware-bucket.s3.eu-west-1.amazonaws.com
2021-03-28T11:49:06.441Z DEBUG modem << D: file_path app_update.bin
2021-03-28T11:49:06.442Z DEBUG modem << D: execution_version_number: 1 
2021-03-28T11:49:06.443Z DEBUG modem << D: (0x2001a5e0): [CID 0x200203e0]:[State 0x06]: >> message id 0x0843 topic count 0x0001
2021-03-28T11:49:06.444Z DEBUG modem << D: (0x2001a5e0): >> val:0843 cur:0x2001c165, end:0x2001c1e0
2021-03-28T11:49:06.445Z DEBUG modem << D: (0x2001a5e0): >> str_size:00000038 cur:0x2001c167, end:0x2001c1e0
2021-03-28T11:49:06.446Z DEBUG modem << D: (0x2001a5e0): >> val:0036 cur:0x2001c167, end:0x2001c1e0
2021-03-28T11:49:06.447Z DEBUG modem << D: (0x2001a5e0): >> val:01 cur:0x2001c19f, end:0x2001c1e0
2021-03-28T11:49:06.448Z DEBUG modem << D: (0x2001a5e0): << msg type:0x82 length:0x0000003b
2021-03-28T11:49:06.449Z DEBUG modem << D: (0x2001a5e0): >> length:0x0000003b cur:(nil), end:(nil)
2021-03-28T11:49:06.451Z DEBUG modem << D: (0x2001a5e0): Fixed header length = 02
2021-03-28T11:49:06.453Z DEBUG modem << D: (0x2001a5e0): >> val:82 cur:0x2001c163, end:0x2001c1e0
2021-03-28T11:49:06.454Z DEBUG modem << D: (0x2001a5e0): >> length:0x0000003b cur:0x2001c164, end:0x2001c1e0
2021-03-28T11:49:06.459Z DEBUG modem << D: (0x2001a5e0): [0x200203e0]: Transport writing 61 bytes.
2021-03-28T11:49:06.461Z DEBUG modem << D: (0x2001a5e0): [0x200203e0]: Transport write complete.
2021-03-28T11:49:06.463Z DEBUG modem << D: (0x2001a5e0): [CID 0x200203e0]:[State 0x06]: << result 0x00000000
2021-03-28T11:49:06.464Z DEBUG modem << D: Subscribed to FOTA update topic $aws/things/DL40_thing/jobs/logger_fota_DG_11/update/#
2021-03-28T11:49:06.465Z DEBUG modem << D: (0x2001a5e0): [CID 0x200203e0]:[State 0x06]: >> Message id 0x0001
2021-03-28T11:49:06.467Z DEBUG modem << D: (0x2001a5e0): >> val:0001 cur:0x2001c165, end:0x2001c1e0
2021-03-28T11:49:06.469Z DEBUG modem << D: (0x2001a5e0): << msg type:0x40 length:0x00000002
2021-03-28T11:49:06.514Z DEBUG modem << D: (0x2001a5e0): >> length:0x00000002 cur:(nil), end:(nil)
2021-03-28T11:49:06.518Z DEBUG modem << D: (0x2001a5e0): Fixed header length = 02
2021-03-28T11:49:06.523Z DEBUG modem << D: (0x2001a5e0): >> val:40 cur:0x2001c163, end:0x2001c1e0
2021-03-28T11:49:06.526Z DEBUG modem << D: (0x2001a5e0): >> length:0x00000002 cur:0x2001c164, end:0x2001c1e0
2021-03-28T11:49:06.530Z DEBUG modem << D: (0x2001a5e0): [0x200203e0]: Transport writing 4 bytes.
2021-03-28T11:49:06.532Z DEBUG modem << D: (0x2001a5e0): [0x200203e0]: Transport write complete.
2021-03-28T11:49:06.536Z DEBUG modem << D: (0x2001a5e0): [CID 0x200203e0]:[State 0x06]: << result 0x00000000
2021-03-28T11:49:16.387Z DEBUG modem << +CSCON: 0
2021-03-28T11:49:16.423Z DEBUG modem << D: +CSCON notification
2021-03-28T11:50:43.186Z DEBUG modem << +CSCON: 1
2021-03-28T11:50:43.191Z DEBUG modem << D: +CSCON notification
2021-03-28T11:50:43.339Z DEBUG modem << D: (0x2001a5e0): state:0x00000006
2021-03-28T11:50:43.383Z DEBUG modem << D: (0x2001a5e0): [CID 0x200203e0]: Connection closed.
2021-03-28T11:50:43.386Z DEBUG modem << D: (0x2001a5e0): Closing socket 1
2021-03-28T11:50:43.387Z DEBUG modem << [mqtt_evt_handler:193] MQTT client disconnected -128
2021-03-28T11:50:43.390Z DEBUG modem << ERROR: mqtt_input -128
2021-03-28T11:50:43.393Z DEBUG modem << Disconnecting MQTT client...
2021-03-28T11:50:43.395Z DEBUG modem << Could not disconnect MQTT client. Error: -128
2021-03-28T11:50:53.488Z DEBUG modem << +CSCON: 0
2021-03-28T11:50:53.537Z DEBUG modem << D: +CSCON notification
2021-03-28T11:52:10.471Z DEBUG modem >> AT
2021-03-28T11:52:10.506Z DEBUG modem << OK