Skip to content

Instantly share code, notes, and snippets.

@slimcdk

slimcdk/1638261346.toit-sim-4786.hardware_id-4c2a0869-49c3-4fe7-834e-ce3a6a9e2b7c.firmware-v1.6.0-pre.32+621224922.log

Created November 30, 2021 08:40
Show Gist options
  • Star 0 You must be signed in to star a gist
  • Fork 0 You must be signed in to fork a gist
  • Save slimcdk/b2fb3cf9612be8b6de35e9cd2a0fb7e1 to your computer and use it in GitHub Desktop.
Save slimcdk/b2fb3cf9612be8b6de35e9cd2a0fb7e1 to your computer and use it in GitHub Desktop.
Download ZIP
Raw
1638261346.toit-sim-4786.hardware_id-4c2a0869-49c3-4fe7-834e-ce3a6a9e2b7c.firmware-v1.6.0-pre.32+621224922.log
$ toit serial reinstall --firmware v1.6.0-pre.32+621224922 -p model.cellular.enabled=1 -p model.cellular.tx=5 -p model.cellular.rx=23 -p model.cellular.rts=19 -p model.cellular.cts=18 -p model.cellular.pwr=27 -p model.cellular.monarch=1 -p cellular.apn=nbiot.tdc.dk --port=/dev/ttyUSB0 --debug
Detecting chip type. This can take up to 10s.
Reinstalling hardware with firmware: v1.6.0-pre.32+621224922 (using model: esp32-4mb) on port /dev/ttyUSB0
Running "/home/christian/.cache/toit/tools/esptool_linux_v3.0 --chip esp32 --port /dev/ttyUSB0 --baud 921600 --before default_reset --after hard_reset write_flash -z --flash_mode dio --flash_freq 40m --flash_size detect 0xd000 /tmp/065380270/otadata435014453 0x10000 /tmp/065380270/ota_0823418640"
esptool.py v3.0
Serial port /dev/ttyUSB0
Connecting......
Chip is ESP32-D0WDQ6 (revision 1)
Features: WiFi, BT, Dual Core, 240MHz, VRef calibration in efuse, Coding Scheme None
Crystal is 40MHz
MAC: f0:08:d1:ce:c3:b0
Uploading stub...
Running stub...
Stub running...
Changing baud rate to 921600
Changed.
Configuring flash size...
Auto-detected Flash size: 8MB
Compressed 8192 bytes to 31...
Writing at 0x0000d000... (100 %)
Wrote 8192 bytes (31 compressed) at 0x0000d000 in 0.0 seconds (effective 23485.8 kbit/s)...
Hash of data verified.
Compressed 1327712 bytes to 804868...
Writing at 0x00010000... (2 %)
Writing at 0x00014000... (4 %)
Writing at 0x00018000... (6 %)
Writing at 0x0001c000... (8 %)
Writing at 0x00020000... (10 %)
Writing at 0x00024000... (12 %)
Writing at 0x00028000... (14 %)
Writing at 0x0002c000... (16 %)
Writing at 0x00030000... (18 %)
Writing at 0x00034000... (20 %)
Writing at 0x00038000... (22 %)
Writing at 0x0003c000... (24 %)
Writing at 0x00040000... (26 %)
Writing at 0x00044000... (28 %)
Writing at 0x00048000... (30 %)
Writing at 0x0004c000... (32 %)
Writing at 0x00050000... (34 %)
Writing at 0x00054000... (36 %)
Writing at 0x00058000... (38 %)
Writing at 0x0005c000... (40 %)
Writing at 0x00060000... (42 %)
Writing at 0x00064000... (44 %)
Writing at 0x00068000... (46 %)
Writing at 0x0006c000... (48 %)
Writing at 0x00070000... (50 %)
Writing at 0x00074000... (52 %)
Writing at 0x00078000... (54 %)
Writing at 0x0007c000... (56 %)
Writing at 0x00080000... (58 %)
Writing at 0x00084000... (60 %)
Writing at 0x00088000... (62 %)
Writing at 0x0008c000... (64 %)
Writing at 0x00090000... (66 %)
Writing at 0x00094000... (68 %)
Writing at 0x00098000... (70 %)
Writing at 0x0009c000... (72 %)
Writing at 0x000a0000... (74 %)
Writing at 0x000a4000... (76 %)
Writing at 0x000a8000... (78 %)
Writing at 0x000ac000... (80 %)
Writing at 0x000b0000... (82 %)
Writing at 0x000b4000... (84 %)
Writing at 0x000b8000... (86 %)
Writing at 0x000bc000... (88 %)
Writing at 0x000c0000... (90 %)
Writing at 0x000c4000... (92 %)
Writing at 0x000c8000... (94 %)
Writing at 0x000cc000... (96 %)
Writing at 0x000d0000... (98 %)
Writing at 0x000d4000... (100 %)
Wrote 1327712 bytes (804868 compressed) at 0x00010000 in 13.1 seconds (effective 811.7 kbit/s)...
Hash of data verified.
Leaving...
Hard resetting via RTS pin...
Hardware successfully reinstalled with the Toit firmware.
The hardware has been reinstalled with the following configurations:
+------------------------+----------------+
| SETTING | VALUE |
+------------------------+----------------+
| context | remote |
| model.cellular.pwr | 27 |
| cellular.apn | nbiot.tdc.dk |
| model.cellular.tx | 5 |
| model | esp32-4mb |
| broker.cn | device.toit.io |
| model.cellular.rts | 19 |
| model.cellular.cts | 18 |
| model.cellular.enabled | 1 |
| model.cellular.rx | 23 |
| broker.host | device.toit.io |
| broker.port | 9426 |
| model.cellular.monarch | 1 |
+------------------------+----------------+
$ toit serial info --output table
+-------------------------------------------------------------------------+-----------+--------------------------------------+
| NAME | MODEL | HARDWARE ID |
+-------------------------------------------------------------------------+-----------+--------------------------------------+
| 4c2a0869-49c3-4fe7-834e-ce3a6a9e2b7c.744.2021-09-26.device.toitware.com | esp32-4mb | 4c2a0869-49c3-4fe7-834e-ce3a6a9e2b7c |
+-------------------------------------------------------------------------+-----------+--------------------------------------+
$ toit serial monitor
ets Jun 8 2016 00:22:57
rst:0x1 (POWERON_RESET),boot:0x13 (SPI_FAST_FLASH_BOOT)
configsip: 0, SPIWP:0xee
clk_drv:0x00,q_drv:0x00,d_drv:0x00,cs0_drv:0x00,hd_drv:0x00,wp_drv:0x00
mode:DIO, clock div:2
load:0x3fff0030,len:188
ho 0 tail 12 room 4
load:0x40078000,len:12180
load:0x40080400,len:2944
entry 0x400805c8
clearing RTC memory: RTC memory is in inconsistent state
[flash reg] address 0x3f46c000, size 0x00134000
[IDENTITY] name=4c2a0869-49c3-4fe7-834e-ce3a6a9e2b7c.744.2021-09-26.device.toitware.com,model=esp32-4mb
(0.015791) [toit] INFO: booting toit {sdk: v1.6.0-pre.32+621224922, model: esp32-4mb}
(0.021976) [toit.scheduler] INFO: set initial run_level {run_level: 4}
(0.535474) [toit.state] INFO: starting new session {session_id: 1, session_time: 0.542343}
[gc @ 0x3ffcf9f0* | soft limit reached (20480 >= 18432)]
[gc @ 0x3ffcf9f0* | heap: 20kb -> 8kb | external: 0kb -> 0kb | used: 94kb | free: 9kb->149kb (largest 108kb) 3.173ms]
(0.637875) [toit.kernel] INFO: using model parameters {cellular.cts: 18, cellular.enabled: 1, cellular.monarch: 1, cellular.pwr: 27, cellular.rts: 19, cellular.rx: 23, cellular.tx: 5}
(0.748089) [toit.console_sync] INFO: loaded last console sync {time: null}
(0.760424) [toit.scheduler] INFO: running job {job: console_sync}
(0.763220) [toit.scheduler] INFO: running job {job: flush_metrics}
(0.765279) [toit.scheduler] INFO: running job {job: poll_metric_flash_usage}
[gc @ 0x3ffcf9f0* | soft limit reached (24576 >= 24576)]
[gc @ 0x3ffcf9f0* | byte array allocation failed, length = 6 (heap)]
[gc @ 0x3ffcf9f0* | heap: 24kb -> 16kb | external: 0kb -> 0kb | used: 102kb | free: 12kb->140kb (largest 108kb) 5.197ms]
(0.770299) [toit.scheduler] INFO: running job {job: poll_metric_memory_profile}
(0.801860) [toit.console] INFO: waiting for console connection to be established
(0.806367) [toit.network] DEBUG: connecting {network: Cellular (nbiot.tdc.dk)}
(0.814064) [toit.metrics] INFO: persisting metrics
(0.817152) [toit.metrics] INFO: flushing metrics
[gc @ 0x3ffcf9f0* | soft limit reached (36892 >= 36864)]
[gc @ 0x3ffcf9f0* | byte array allocation failed, length = 511 (heap)]
[gc @ 0x3ffcf9f0* | heap: 36kb -> 20kb | external: 0kb -> 0kb | used: 127kb | free: 12kb->116kb (largest 104kb) 5.679ms]
(1.092383) [toit] INFO: -> AT
(1.106060) [toit] INFO: <- OK
(1.210648) [toit] INFO: -> ATE0
(1.220620) [toit] INFO: <- OK
(1.243132) [toit] INFO: -> AT+CMEE=2
(1.249049) [toit] INFO: <- OK
[gc @ 0x3ffcf9f0* | soft limit reached (45084 >= 43050)]
[gc @ 0x3ffcf9f0* | byte array allocation failed, length = 511 (heap)]
[gc @ 0x3ffcf9f0* | heap: 44kb -> 24kb | external: 0kb -> 0kb | used: 149kb | free: 6kb->94kb (largest 88kb) 6.351ms]
(1.271474) [toit] INFO: -> AT+CGMM
(1.277391) [toit] INFO: <- FiPy
(1.280054) [toit] INFO: <- OK
(1.302139) [toit] INFO: -> AT+CGMR
(1.324171) [toit] INFO: <- UE5.4.0.2
(1.326783) [toit] INFO: <- OK
(1.348932) [toit] INFO: -> AT+SQNCCID?
(1.355132) [toit] INFO: <- +SQNCCID: "89882390000069114786",""
(1.357762) [toit] INFO: <- OK
(1.360474) [toit.cellular] DEBUG: initialized {model: FiPy, version: UE5.4.0.2, iccid: 89882390000069114786}
(1.380235) [toit] INFO: -> AT+CFUN=4
(2.064137) [toit] INFO: <- OK
(2.086740) [toit] INFO: -> AT+CPIN?
(2.092724) [toit] INFO: <- +CPIN: READY
(2.095363) [toit] INFO: <- OK
(2.117874) [toit] INFO: -> AT+CEREG=2
(2.140480) [toit] INFO: <- OK
(2.162976) [toit] INFO: -> AT+CPSMS=0
(2.170457) [toit] INFO: <- OK
(2.192921) [toit] INFO: -> AT+CEDRXS=0
(2.200779) [toit] INFO: <- OK
(2.223302) [toit] INFO: -> AT+SQNIBRCFG=0
(2.236350) [toit] INFO: <- OK
(2.259013) [toit] INFO: -> AT+SQNIPSCFG=1,100
(2.278284) [toit] INFO: <- OK
(2.301062) [toit] INFO: -> AT+SQNBANDSEL=0,"standard","20"
(2.379087) [toit] INFO: <- +SQNBANDSEL: <custom>
(2.381815) [toit] INFO: <- OK
(2.404267) [toit] INFO: -> AT+SQNBANDSEL=1,"standard","20"
(2.411940) [toit] INFO: <- +CME ERROR: operation not supported
(2.434073) [toit] INFO: -> AT+CGDCONT?
(2.440284) [toit] INFO: <- +CGDCONT: 1,"IP","nbiot.tdc.dk",,,,0,0,0,0,0,0,0,,0
(2.443371) [toit] INFO: <- OK
(2.465872) [toit] INFO: -> AT+CFUN=1
(2.543516) [toit] INFO: <- OK
(2.738402) [toit] INFO: <- +CEREG: 2
(2.740768) [toit] INFO: <- [URC] +CEREG [2]
(2.743026) [toit.cellular] DEBUG: attempt modem's automatic connect
(2.750265) [toit.cellular] DEBUG: connecting {operator: null, attempt: 1}
(2.762196) [toit] INFO: -> AT+COPS?
(2.770757) [toit] INFO: <- +COPS: 0
(2.772950) [toit] INFO: <- OK
(2.795329) [toit] INFO: -> AT+CEREG=2
(2.806206) [toit] INFO: <- OK
(2.828539) [toit] INFO: -> AT+CEREG?
(2.834391) [toit] INFO: <- +CEREG: 2,2
(2.837027) [toit] INFO: <- OK
(3.841485) [toit] INFO: -> AT
(3.846613) [toit] INFO: <- OK
(4.850993) [toit] INFO: -> AT
(5.568550) [toit] INFO: <- OK
(6.573347) [toit] INFO: -> AT
(6.578866) [toit] INFO: <- OK
(7.583622) [toit] INFO: -> AT
(7.588646) [toit] INFO: <- OK
(8.593144) [toit] INFO: -> AT
(8.598101) [toit] INFO: <- OK
(9.603018) [toit] INFO: -> AT
(9.607858) [toit] INFO: <- OK
(10.612384) [toit] INFO: -> AT
(10.617097) [toit] INFO: <- OK
(10.762909) [toit.scheduler] INFO: running job {job: persist_metrics}
[gc @ 0x3ffcf9f0* | soft limit reached (53276 >= 49194)]
[gc @ 0x3ffcf9f0* | heap: 52kb -> 24kb | external: 0kb -> 0kb | used: 165kb | free: 10kb->78kb (largest 68kb) 6.553ms]
(10.775799) [toit.metrics] INFO: persisting metrics
(11.095633) [toit.scheduler] INFO: running job {job: poll_metric_memory_profile}
(11.622438) [toit] INFO: -> AT
(11.628479) [toit] INFO: <- OK
(12.633211) [toit] INFO: -> AT
(12.638369) [toit] INFO: <- OK
(13.642728) [toit] INFO: -> AT
(13.647882) [toit] INFO: <- OK
(14.652238) [toit] INFO: -> AT
(14.657609) [toit] INFO: <- OK
(15.661978) [toit] INFO: -> AT
(15.667199) [toit] INFO: <- OK
(16.671917) [toit] INFO: -> AT
(16.677623) [toit] INFO: <- OK
(17.682362) [toit] INFO: -> AT
(17.819500) [toit] INFO: <- OK
(17.822735) [toit] INFO: <- +CEREG: 5,"1BBC","00EEAE14",7
(17.825089) [toit] INFO: <- [URC] +CEREG [5, 1BBC, 00EEAE14, 7]
(17.828173) [toit.cellular] DEBUG: connected successfully
(17.845315) [toit] INFO: -> AT+COPS?
(17.904496) [toit] INFO: <- +COPS: 0,2,"23801",7
(17.907416) [toit] INFO: <- OK
(17.926055) [toit.network] DEBUG: connected {network: Cellular (nbiot.tdc.dk)}
(17.938302) [toit] INFO: -> AT+CSQ
(17.946125) [toit] INFO: <- +CSQ: 14,99
(17.949358) [toit] INFO: <- OK
(17.953223) [toit.console_conn] INFO: initialized {identity: 4c2a0869-49c3-4fe7-834e-ce3a6a9e2b7c.744.2021-09-26.device.toitware.com}
(17.971830) [toit] INFO: -> AT+SQNDNSLKUP="device.toit.io"
(18.311191) [toit] INFO: <- +SQNDNSLKUP: <custom>
(18.314283) [toit] INFO: <- OK
[gc @ 0x3ffcf9f0* | soft limit reached (53276 >= 49194)]
[gc @ 0x3ffcf9f0* | string allocation failed, length = 13 (heap)]
[gc @ 0x3ffcf9f0* | heap: 52kb -> 24kb | external: 0kb -> 0kb | used: 167kb | free: 7kb->75kb (largest 68kb) 7.386ms]
(18.369727) [toit.console_conn] INFO: connecting to console {host: 35.228.86.200:9426, common_name: device.toit.io, protocol: tls}
(18.395282) [toit] INFO: -> AT+SQNSCFG=1,1,300,0,80,50
(18.408976) [toit] INFO: <- OK
(18.432105) [toit] INFO: -> AT+SQNSD=1,0,9426,"35.228.86.200",0,0,1
(18.852616) [toit] INFO: <- OK
(18.880907) [toit] INFO: -> AT+SQNSSENDEXT=1,243
(18.887640) [toit] INFO: <- >
(18.889480) [toit] INFO: -> <243 bytes>
(18.894701) [toit] INFO: <-
(18.896817) [toit] INFO: <- OK
(19.183828) [toit] INFO: <- +SQNSRING: 1
(19.186039) [toit] INFO: <- [URC] +SQNSRING [1]
(19.206709) [toit] INFO: -> AT+SQNSI=1
(19.212902) [toit] INFO: <- +SQNSI: 1,243,846,846,0
(19.215736) [toit] INFO: <- OK
(19.238268) [toit] INFO: -> AT+SQNSRECV=1,1500
(19.245367) [toit] INFO: <- +SQNSRECV: <custom>
(19.255258) [toit] INFO: <- OK
(20.828729) [toit.scheduler] INFO: running job {job: persist_metrics}
(20.833792) [toit] INFO: -> AT+CSQ
(20.859403) [toit] INFO: <- +CSQ: 14,99
(20.862138) [toit] INFO: <- OK
(20.865039) [toit.metrics] INFO: persisting metrics
(21.124565) [toit.scheduler] INFO: running job {job: poll_metric_memory_profile}
[gc @ 0x3ffcf9f0* | soft limit reached (53276 >= 49194)]
[gc @ 0x3ffcf9f0* | heap: 52kb -> 28kb | external: 0kb -> 0kb | used: 193kb | free: 6kb->50kb (largest 44kb) 7.129ms]
(22.124392) [toit] INFO: -> AT+SQNSSENDEXT=1,854
(22.131336) [toit] INFO: <- >
(22.133036) [toit] INFO: -> <854 bytes>
(22.145441) [toit] INFO: <-
(22.147801) [toit] INFO: <- OK
(22.170201) [toit] INFO: -> AT+SQNSI=1
(22.176813) [toit] INFO: <- +SQNSI: 1,1097,846,0,854
(22.179717) [toit] INFO: <- OK
(22.392592) [toit] INFO: <- +SQNSRING: 1
(22.394940) [toit] INFO: <- [URC] +SQNSRING [1]
(22.397369) [toit] INFO: <- +SQNSRING: 1
(22.399362) [toit] INFO: <- [URC] +SQNSRING [1]
(22.419879) [toit] INFO: -> AT+SQNSI=1
(22.426968) [toit] INFO: <- +SQNSI: 1,1097,1645,799,0
(22.429806) [toit] INFO: <- OK
(22.452462) [toit] INFO: -> AT+SQNSRECV=1,1500
(22.458718) [toit] INFO: <- +SQNSRECV: <custom>
(22.468214) [toit] INFO: <- OK
(22.496881) [toit.console_conn] INFO: established
(22.502079) [toit] INFO: -> AT+SQNSSENDEXT=1,31
(22.509201) [toit] INFO: <- >
(22.511053) [toit] INFO: -> <31 bytes>
(22.512931) [toit] INFO: <-
(22.514846) [toit] INFO: <- OK
(22.518756) [toit.console] INFO: connected, starting main loop
(22.538163) [toit] INFO: -> AT+SQNSSENDEXT=1,52
(22.544466) [toit] INFO: <- >
(22.546102) [toit] INFO: -> <52 bytes>
(22.547936) [toit] INFO: <-
(22.550162) [toit] INFO: <- OK
(22.572464) [toit] INFO: -> AT+SQNSI=1
(22.578923) [toit] INFO: <- +SQNSI: 1,1180,1645,0,83
(22.581959) [toit] INFO: <- OK
(22.604531) [toit] INFO: -> AT+SQNSSENDEXT=1,68
[gc @ 0x3ffcf9f0* | soft limit reached (57372 >= 55338)]
[gc @ 0x3ffcf9f0* | byte array allocation failed, length = 6 (heap)]
[gc @ 0x3ffcf9f0* | heap: 56kb -> 32kb | external: 0kb -> 0kb | used: 197kb | free: 21kb->45kb (largest 24kb) 7.660ms]
(22.610331) [toit] INFO: <- >
(22.625482) [toit] INFO: -> <68 bytes>
(22.629603) [toit] INFO: <-
(22.632050) [toit] INFO: <- OK
(22.969744) [toit] INFO: <- +SQNSRING: 1
(22.971983) [toit] INFO: <- [URC] +SQNSRING [1]
(22.992851) [toit] INFO: -> AT+SQNSI=1
(22.999123) [toit] INFO: <- +SQNSI: 1,1248,1734,89,0
(23.001979) [toit] INFO: <- OK
(23.024447) [toit] INFO: -> AT+SQNSRECV=1,1500
(23.030471) [toit] INFO: <- +SQNSRECV: <custom>
(23.033415) [toit] INFO: <- OK
(23.043497) [toit.time] INFO: adjusting time by 455072h36m10.271256639s, accuracy: null -> 512471000 ns
(23.050302) [toit.console] INFO: received action {action: new epoch}
(23.052633) [toit.console] INFO: new epoch 7ee65019-bb47-4657-9b64-cd18d5fa640f
(23.075762) [toit] INFO: -> AT+SQNSSENDEXT=1,52
(23.082739) [toit] INFO: <- >
(23.084751) [toit] INFO: -> <52 bytes>
(23.086602) [toit] INFO: <-
(23.088539) [toit] INFO: <- OK
(23.112053) [toit] INFO: -> AT+SQNSI=1
(23.118699) [toit] INFO: <- +SQNSI: 1,1300,1734,0,52
(23.121760) [toit] INFO: <- OK
(23.144352) [toit] INFO: -> AT+SQNSSENDEXT=1,86
(23.150122) [toit] INFO: <- >
(23.151650) [toit] INFO: -> <86 bytes>
(23.153406) [toit] INFO: <-
(23.155273) [toit] INFO: <- OK
(23.415861) [toit] INFO: <- +SQNSRING: 1
(23.418164) [toit] INFO: <- [URC] +SQNSRING [1]
(23.438932) [toit] INFO: -> AT+SQNSI=1
(23.445450) [toit] INFO: <- +SQNSI: 1,1386,1788,54,0
(23.448346) [toit] INFO: <- OK
(23.470682) [toit] INFO: -> AT+SQNSRECV=1,1500
(23.477105) [toit] INFO: <- +SQNSRECV: <custom>
(23.480067) [toit] INFO: <- OK
(23.502573) [toit] INFO: -> AT+SQNSSENDEXT=1,52
(23.510153) [toit] INFO: <- >
(23.511956) [toit] INFO: -> <52 bytes>
(23.514013) [toit] INFO: <-
(23.515964) [toit] INFO: <- OK
(23.538115) [toit] INFO: -> AT+SQNSI=1
(23.545307) [toit] INFO: <- +SQNSI: 1,1438,1788,0,52
(23.548316) [toit] INFO: <- OK
(23.570810) [toit] INFO: -> AT+SQNSSENDEXT=1,58
(23.576485) [toit] INFO: <- >
(23.579128) [toit] INFO: -> <58 bytes>
(23.580907) [toit] INFO: <-
(23.582770) [toit] INFO: <- OK
(23.821497) [toit] INFO: <- +SQNSRING: 1
(23.823955) [toit] INFO: <- [URC] +SQNSRING [1]
(23.844376) [toit] INFO: -> AT+SQNSI=1
(23.850497) [toit] INFO: <- +SQNSI: 1,1496,1860,72,0
(23.853334) [toit] INFO: <- OK
(23.875694) [toit] INFO: -> AT+SQNSRECV=1,1500
(23.882186) [toit] INFO: <- +SQNSRECV: <custom>
(23.885085) [toit] INFO: <- OK
(23.894906) [toit.time] INFO: adjusting time by -66.044715ms, accuracy: 512471000 -> 403940000 ns
(23.902308) [toit.console] INFO: received action {action: report config}
(23.904242) [toit.console] INFO: reporting config
(23.914237) [toit] INFO: -> AT+SQNSSENDEXT=1,52
(23.920759) [toit] INFO: <- >
(23.922422) [toit] INFO: -> <52 bytes>
(23.924271) [toit] INFO: <-
(23.926202) [toit] INFO: <- OK
(23.948466) [toit] INFO: -> AT+SQNSI=1
(23.955471) [toit] INFO: <- +SQNSI: 1,1548,1860,0,52
(23.958505) [toit] INFO: <- OK
(23.981026) [toit] INFO: -> AT+SQNSSENDEXT=1,235
(23.986648) [toit] INFO: <- >
(23.988206) [toit] INFO: -> <235 bytes>
[gc @ 0x3ffcf9f0* | soft limit reached (65564 >= 61482)]
[gc @ 0x3ffcf9f0* | string allocation failed, length = 29 (heap)]
[gc @ 0x3ffcf9f0* | heap: 64kb -> 32kb | external: 0kb -> 0kb | used: 213kb | free: 21kb->29kb (largest 8kb) 8.472ms]
(23.991270) [toit] INFO: <-
(24.008514) [toit] INFO: <- OK
(24.305475) [toit] INFO: <- +SQNSRING: 1
(24.307659) [toit] INFO: <- [URC] +SQNSRING [1]
(24.328335) [toit] INFO: -> AT+SQNSI=1
(24.335309) [toit] INFO: <- +SQNSI: 1,1783,1914,54,0
(24.338126) [toit] INFO: <- OK
(24.360521) [toit] INFO: -> AT+SQNSRECV=1,1500
(24.366693) [toit] INFO: <- +SQNSRECV: <custom>
(24.369605) [toit] INFO: <- OK
(24.392341) [toit] INFO: -> AT+SQNSSENDEXT=1,52
(24.398225) [toit] INFO: <- >
(24.400028) [toit] INFO: -> <52 bytes>
(24.401859) [toit] INFO: <-
(24.403746) [toit] INFO: <- OK
(24.425862) [toit] INFO: -> AT+SQNSI=1
(24.431990) [toit] INFO: <- +SQNSI: 1,1835,1914,0,52
(24.434959) [toit] INFO: <- OK
(24.457861) [toit] INFO: -> AT+SQNSSENDEXT=1,58
(24.463488) [toit] INFO: <- >
(24.465033) [toit] INFO: -> <58 bytes>
(24.466784) [toit] INFO: <-
(24.468645) [toit] INFO: <- OK
(24.715634) [toit] INFO: <- +SQNSRING: 1
(24.718272) [toit] INFO: <- [URC] +SQNSRING [1]
(24.738751) [toit] INFO: -> AT+SQNSI=1
(24.745212) [toit] INFO: <- +SQNSI: 1,1893,2187,273,0
(24.748095) [toit] INFO: <- OK
(24.770505) [toit] INFO: -> AT+SQNSRECV=1,1500
(24.776658) [toit] INFO: <- +SQNSRECV: <custom>
(24.780029) [toit] INFO: <- OK
(24.793330) [toit.console] INFO: received action {action: config update}
(24.814105) [toit.kernel] INFO: using model parameters {cellular.rts: 19, cellular.rx: 23, cellular.tx: 5, cellular.cts: 18, cellular.enabled: 1, cellular.monarch: 1, cellular.pwr: 27}
(24.822723) [toit.console] INFO: config updated: 3217218
(24.833981) [toit] INFO: -> AT+SQNSSENDEXT=1,52
(24.841772) [toit] INFO: <- >
(24.843628) [toit] INFO: -> <52 bytes>
(24.845504) [toit] INFO: <-
(24.847437) [toit] INFO: <- OK
(24.869983) [toit] INFO: -> AT+SQNSI=1
(24.876331) [toit] INFO: <- +SQNSI: 1,1945,2187,0,52
(24.879358) [toit] INFO: <- OK
(24.901949) [toit] INFO: -> AT+SQNSSENDEXT=1,60
(24.908947) [toit] INFO: <- >
(24.910493) [toit] INFO: -> <60 bytes>
(24.912446) [toit] INFO: <-
(24.914385) [toit] INFO: <- OK
(25.269117) [toit] INFO: <- +SQNSRING: 1
(25.271400) [toit] INFO: <- [URC] +SQNSRING [1]
(25.292123) [toit] INFO: -> AT+SQNSI=1
(25.298336) [toit] INFO: <- +SQNSI: 1,2005,2259,72,0
(25.301141) [toit] INFO: <- OK
(25.323536) [toit] INFO: -> AT+SQNSRECV=1,1500
(25.329871) [toit] INFO: <- +SQNSRECV: <custom>
(25.332795) [toit] INFO: <- OK
(25.342266) [toit.console] INFO: synchronized with console
[gc @ 0x3ffcf9f0* | soft limit reached (61698 >= 61482)]
[gc @ 0x3ffcf9f0* | heap: 60kb -> 32kb | external: 0kb -> 0kb | used: 205kb | free: 21kb->37kb (largest 8kb) 7.980ms]
(25.377488) [toit] INFO: -> AT+SQNSI=1
(25.385283) [toit] INFO: <- +SQNSI: 1,2005,2259,0,0
(25.392114) [toit] INFO: <- OK
(25.414445) [toit] INFO: -> AT+SQNSSENDEXT=1,54
(25.420587) [toit] INFO: <- >
(25.422151) [toit] INFO: -> <54 bytes>
(25.424121) [toit] INFO: <-
(25.426501) [toit] INFO: <- OK
(25.449076) [toit] INFO: -> AT+SQNSSENDEXT=1,83
(25.455765) [toit] INFO: <- >
(25.457384) [toit] INFO: -> <83 bytes>
(25.459411) [toit] INFO: <-
(25.461314) [toit] INFO: <- OK
(25.483633) [toit] INFO: -> AT+SQNSSENDEXT=1,55
(25.492125) [toit] INFO: <- >
(25.494010) [toit] INFO: -> <55 bytes>
(25.495835) [toit] INFO: <-
(25.497986) [toit] INFO: <- OK
(26.397095) [toit] INFO: -> AT
(26.402464) [toit] INFO: <- OK
(26.417515) [toit] INFO: <- +SQNSRING: 1
(26.419778) [toit] INFO: <- [URC] +SQNSRING [1]
(26.440242) [toit] INFO: -> AT+SQNSI=1
(26.446463) [toit] INFO: <- +SQNSI: 1,2197,2341,82,0
(26.449333) [toit] INFO: <- OK
(26.471785) [toit] INFO: -> AT+SQNSRECV=1,1500
(26.477671) [toit] INFO: <- +SQNSRECV: <custom>
(26.480589) [toit] INFO: <- OK
(26.503660) [toit] INFO: -> AT+SQNSI=1
(26.518900) [toit] INFO: <- +SQNSI: 1,2197,2341,0,0
(26.521706) [toit] INFO: <- OK
[gc @ 0x3ffcf9f0* | soft limit reached (65564 >= 61482)]
[gc @ 0x3ffcf9f0* | heap: 64kb -> 36kb | external: 0kb -> 0kb | used: 214kb | free: 21kb->29kb (largest 8kb) 8.134ms]
(26.545354) [toit] INFO: -> AT+SQNSSENDEXT=1,55
(26.562843) [toit] INFO: <- >
(26.564630) [toit] INFO: -> <55 bytes>
(26.566441) [toit] INFO: <-
(26.568338) [toit] INFO: <- OK
(26.591883) [toit] INFO: -> AT+SQNSSENDEXT=1,36
(26.597692) [toit] INFO: <- >
(26.599301) [toit] INFO: -> <36 bytes>
(26.601052) [toit] INFO: <-
(26.602931) [toit] INFO: <- OK
(26.625254) [toit] INFO: -> AT+SQNSSENDEXT=1,56
(26.631038) [toit] INFO: <- >
(26.632678) [toit] INFO: -> <56 bytes>
(26.634468) [toit] INFO: <-
(26.636352) [toit] INFO: <- OK
(27.526123) [toit] INFO: -> AT
(27.530946) [toit] INFO: <- OK
(28.535486) [toit] INFO: -> AT
(28.540060) [toit] INFO: <- OK
(29.544467) [toit] INFO: -> AT
(29.549066) [toit] INFO: <- OK
(30.553724) [toit] INFO: -> AT
(30.558464) [toit] INFO: <- OK
(30.925051) [toit.scheduler] INFO: running job {job: persist_metrics}
(30.929527) [toit] INFO: -> AT+CSQ
(30.935930) [toit] INFO: <- +CSQ: 14,99
(30.939101) [toit] INFO: <- OK
(30.942247) [toit.metrics] INFO: persisting metrics
(31.180423) [toit.scheduler] INFO: running job {job: poll_metric_memory_profile}
(31.563429) [toit] INFO: -> AT
(31.569077) [toit] INFO: <- OK
(32.574097) [toit] INFO: -> AT
(32.578794) [toit] INFO: <- OK
(33.583263) [toit] INFO: -> AT
(33.587901) [toit] INFO: <- OK
(34.592456) [toit] INFO: -> AT
(34.597073) [toit] INFO: <- OK
(35.223306) [toit] INFO: <- +CEREG: 5,"1BBC","0059C514",7
(35.226146) [toit] INFO: <- *ignored* [URC] +CEREG [5, 1BBC, 0059C514, 7]
(35.601680) [toit] INFO: -> AT
(35.606590) [toit] INFO: <- OK
(36.610896) [toit] INFO: -> AT
(36.615668) [toit] INFO: <- OK
[gc @ 0x3ffcf9f0* | soft limit reached (69660 >= 67626)]
[gc @ 0x3ffcf9f0* | byte array allocation failed, length = 64 (heap)]
[gc @ 0x3ffcf9f0* | heap: 68kb -> 32kb | external: 0kb -> 0kb | used: 222kb | free: 21kb->21kb (largest 3544bytes) 7.922ms]
(37.632399) [toit] INFO: -> AT
(37.637039) [toit] INFO: <- OK
(38.641599) [toit] INFO: -> AT
(38.646449) [toit] INFO: <- OK
(39.650882) [toit] INFO: -> AT
(39.655500) [toit] INFO: <- OK
(40.659910) [toit] INFO: -> AT
(40.664533) [toit] INFO: <- OK
(40.995420) [toit.scheduler] INFO: running job {job: persist_metrics}
(41.000651) [toit] INFO: -> AT+CSQ
(41.006712) [toit] INFO: <- +CSQ: 17,99
(41.009605) [toit] INFO: <- OK
(41.012717) [toit.metrics] INFO: persisting metrics
(41.229374) [toit.scheduler] INFO: running job {job: poll_metric_memory_profile}
(41.669613) [toit] INFO: -> AT
(41.675287) [toit] INFO: <- OK
(42.679983) [toit] INFO: -> AT
(42.684728) [toit] INFO: <- OK
(43.696477) [toit] INFO: -> AT
(43.701750) [toit] INFO: <- OK
(44.706212) [toit] INFO: -> AT
(44.710858) [toit] INFO: <- OK
(45.715261) [toit] INFO: -> AT
(45.719909) [toit] INFO: <- OK
(46.724620) [toit] INFO: -> AT
(46.729729) [toit] INFO: <- OK
(47.734652) [toit] INFO: -> AT
(47.739239) [toit] INFO: <- OK
(48.743706) [toit] INFO: -> AT
(48.748279) [toit] INFO: <- OK
(49.752704) [toit] INFO: -> AT
(49.757237) [toit] INFO: <- OK
(50.761925) [toit] INFO: -> AT
(50.766531) [toit] INFO: <- OK
(51.065750) [toit.scheduler] INFO: running job {job: persist_metrics}
[gc @ 0x3ffcf9f0* | soft limit reached (65564 >= 61482)]
[gc @ 0x3ffcf9f0* | heap: 64kb -> 32kb | external: 0kb -> 0kb | used: 214kb | free: 21kb->29kb (largest 8kb) 7.916ms]
(51.079407) [toit] INFO: -> AT+CSQ
(51.085749) [toit] INFO: <- +CSQ: 17,99
(51.096228) [toit] INFO: <- OK
(51.099388) [toit.metrics] INFO: persisting metrics
(51.278301) [toit.scheduler] INFO: running job {job: poll_metric_memory_profile}
(51.771186) [toit] INFO: -> AT
(51.776403) [toit] INFO: <- OK
(52.781130) [toit] INFO: -> AT
(52.785788) [toit] INFO: <- OK
(53.790355) [toit] INFO: -> AT
(53.795128) [toit] INFO: <- OK
(54.799715) [toit] INFO: -> AT
(54.804411) [toit] INFO: <- OK
(55.808866) [toit] INFO: -> AT
(55.813526) [toit] INFO: <- OK
(56.818554) [toit] INFO: -> AT
(56.823557) [toit] INFO: <- OK
(57.828581) [toit] INFO: -> AT
(57.833313) [toit] INFO: <- OK
(58.838356) [toit] INFO: -> AT
(58.843141) [toit] INFO: <- OK
(59.848307) [toit] INFO: -> AT
(59.853172) [toit] INFO: <- OK
(60.858589) [toit] INFO: -> AT
(60.863333) [toit] INFO: <- OK
(61.147118) [toit.scheduler] INFO: running job {job: persist_metrics}
(61.151684) [toit] INFO: -> AT+CSQ
(61.157645) [toit] INFO: <- +CSQ: 17,99
(61.160744) [toit] INFO: <- OK
(61.163852) [toit.metrics] INFO: persisting metrics
(61.266008) [toit.scheduler] INFO: running job {job: poll_metric_flash_usage}
[gc @ 0x3ffcf9f0* | soft limit reached (65564 >= 61482)]
[gc @ 0x3ffcf9f0* | heap: 64kb -> 32kb | external: 0kb -> 0kb | used: 214kb | free: 21kb->29kb (largest 8kb) 7.952ms]
(61.328268) [toit.scheduler] INFO: running job {job: poll_metric_memory_profile}
(61.868811) [toit] INFO: -> AT
(61.874078) [toit] INFO: <- OK
(62.879026) [toit] INFO: -> AT
(62.883770) [toit] INFO: <- OK
(63.896252) [toit] INFO: -> AT
(63.901009) [toit] INFO: <- OK
(64.905298) [toit] INFO: -> AT
(64.910034) [toit] INFO: <- OK
(65.914577) [toit] INFO: -> AT
(65.919173) [toit] INFO: <- OK
(66.923667) [toit] INFO: -> AT
(66.928804) [toit] INFO: <- OK
(67.933239) [toit] INFO: -> AT
(67.938078) [toit] INFO: <- OK
(68.942602) [toit] INFO: -> AT
(68.947315) [toit] INFO: <- OK
(69.951707) [toit] INFO: -> AT
(69.956444) [toit] INFO: <- OK
(70.960855) [toit] INFO: -> AT
(70.965445) [toit] INFO: <- OK
(71.210699) [toit.scheduler] INFO: running job {job: persist_metrics}
(71.215406) [toit] INFO: -> AT+CSQ
(71.221511) [toit] INFO: <- +CSQ: 16,99
(71.224390) [toit] INFO: <- OK
(71.227441) [toit.metrics] INFO: persisting metrics
(71.378044) [toit.scheduler] INFO: running job {job: poll_metric_memory_profile}
[gc @ 0x3ffcf9f0* | soft limit reached (65564 >= 61482)]
[gc @ 0x3ffcf9f0* | heap: 64kb -> 36kb | external: 0kb -> 0kb | used: 214kb | free: 21kb->29kb (largest 8kb) 15.938ms]
(71.970547) [toit] INFO: -> AT
(71.976182) [toit] INFO: <- OK
(72.980890) [toit] INFO: -> AT
(72.985658) [toit] INFO: <- OK
(73.998770) [toit] INFO: -> AT
(74.003473) [toit] INFO: <- OK
(75.008364) [toit] INFO: -> AT
(75.013147) [toit] INFO: <- OK
(76.018340) [toit] INFO: -> AT
(76.023027) [toit] INFO: <- OK
(77.028937) [toit] INFO: -> AT
(77.034145) [toit] INFO: <- OK
(78.038666) [toit] INFO: -> AT
(78.043365) [toit] INFO: <- OK
(79.048501) [toit] INFO: -> AT
(79.053102) [toit] INFO: <- OK
(80.058751) [toit] INFO: -> AT
(80.063387) [toit] INFO: <- OK
(81.068521) [toit] INFO: -> AT
(81.073116) [toit] INFO: <- OK
(81.288237) [toit.scheduler] INFO: running job {job: persist_metrics}
(81.292922) [toit] INFO: -> AT+CSQ
(81.299340) [toit] INFO: <- +CSQ: 17,99
(81.302227) [toit] INFO: <- OK
(81.305328) [toit.metrics] INFO: persisting metrics
(81.446767) [toit.scheduler] INFO: running job {job: poll_metric_memory_profile}
(82.079053) [toit] INFO: -> AT
(82.084692) [toit] INFO: <- OK
(83.089572) [toit] INFO: -> AT
(83.094947) [toit] INFO: <- OK
(84.099608) [toit] INFO: -> AT
(84.104787) [toit] INFO: <- OK
(85.109085) [toit] INFO: -> AT
(85.113810) [toit] INFO: <- OK
(86.118338) [toit] INFO: -> AT
(86.123160) [toit] INFO: <- OK
(87.128533) [toit] INFO: -> AT
[gc @ 0x3ffcf9f0* | soft limit reached (69660 >= 67626)]
[gc @ 0x3ffcf9f0* | string allocation failed, length = 30 (heap)]
[gc @ 0x3ffcf9f0* | heap: 68kb -> 32kb | external: 0kb -> 0kb | used: 222kb | free: 21kb->21kb (largest 3544bytes) 7.983ms]
(87.133567) [toit] INFO: <- OK
(88.151646) [toit] INFO: -> AT
(88.156718) [toit] INFO: <- OK
(89.156053) [toit] INFO: <- +SQNSRING: 1
(89.161296) [toit] INFO: <- [URC] +SQNSRING [1]
(89.181835) [toit] INFO: -> AT+SQNSI=1
(89.188397) [toit] INFO: <- +SQNSI: 1,2344,2388,47,0
(89.191254) [toit] INFO: <- OK
(89.213652) [toit] INFO: -> AT+SQNSRECV=1,1500
(89.219995) [toit] INFO: <- +SQNSRECV: <custom>
(89.223108) [toit] INFO: <- OK
(89.245637) [toit] INFO: -> AT+SQNSI=1
(89.252591) [toit] INFO: <- +SQNSI: 1,2344,2388,0,0
(89.255701) [toit] INFO: <- OK
(90.260107) [toit] INFO: -> AT
(90.264775) [toit] INFO: <- OK
(91.269218) [toit] INFO: -> AT
(91.275065) [toit] INFO: <- OK
(91.369723) [toit.scheduler] INFO: running job {job: persist_metrics}
(91.374503) [toit] INFO: -> AT+CSQ
(91.380563) [toit] INFO: <- +CSQ: 15,99
(91.383435) [toit] INFO: <- OK
(91.386489) [toit.metrics] INFO: persisting metrics
(91.500628) [toit.scheduler] INFO: running job {job: poll_metric_memory_profile}
(92.280152) [toit] INFO: -> AT
(92.285822) [toit] INFO: <- OK
(93.290519) [toit] INFO: -> AT
(93.295329) [toit] INFO: <- OK
(94.301127) [toit] INFO: -> AT
(94.305977) [toit] INFO: <- OK
(95.310541) [toit] INFO: -> AT
(95.315263) [toit] INFO: <- OK
(96.319668) [toit] INFO: -> AT
(96.324353) [toit] INFO: <- OK
(97.329114) [toit] INFO: -> AT
(97.334202) [toit] INFO: <- OK
(98.339124) [toit] INFO: -> AT
(98.343710) [toit] INFO: <- OK
(99.348486) [toit] INFO: -> AT
(99.353075) [toit] INFO: <- OK
(100.358478) [toit] INFO: -> AT
(100.363077) [toit] INFO: <- OK
[gc @ 0x3ffcf9f0* | soft limit reached (65564 >= 61482)]
[gc @ 0x3ffcf9f0* | byte array allocation failed, length = 606 (heap)]
[gc @ 0x3ffcf9f0* | heap: 64kb -> 32kb | external: 0kb -> 0kb | used: 214kb | free: 21kb->29kb (largest 4kb) 7.873ms]
(101.380139) [toit] INFO: -> AT
(101.384848) [toit] INFO: <- OK
(101.449092) [toit.scheduler] INFO: running job {job: persist_metrics}
(101.453596) [toit] INFO: -> AT+CSQ
(101.459821) [toit] INFO: <- +CSQ: 16,99
(101.462701) [toit] INFO: <- OK
(101.465786) [toit.metrics] INFO: persisting metrics
(101.553222) [toit.scheduler] INFO: running job {job: poll_metric_memory_profile}
(102.389845) [toit] INFO: -> AT
(102.395490) [toit] INFO: <- OK
(103.400375) [toit] INFO: -> AT
(103.405123) [toit] INFO: <- OK
(104.409446) [toit] INFO: -> AT
(104.414160) [toit] INFO: <- OK
(105.418875) [toit] INFO: -> AT
(105.423426) [toit] INFO: <- OK
(106.428485) [toit] INFO: -> AT
(106.433143) [toit] INFO: <- OK
(107.438635) [toit] INFO: -> AT
(107.443769) [toit] INFO: <- OK
(108.448455) [toit] INFO: -> AT
(108.453194) [toit] INFO: <- OK
(109.458356) [toit] INFO: -> AT
(109.467173) [toit] INFO: <- OK
(110.471413) [toit] INFO: -> AT
(110.476197) [toit] INFO: <- OK
(111.480715) [toit] INFO: -> AT
(111.485397) [toit] INFO: <- OK
(111.529148) [toit.scheduler] INFO: running job {job: persist_metrics}
(111.533727) [toit] INFO: -> AT+CSQ
(111.539650) [toit] INFO: <- +CSQ: 15,99
[gc @ 0x3ffcf9f0* | soft limit reached (65564 >= 61482)]
[gc @ 0x3ffcf9f0* | heap: 64kb -> 36kb | external: 0kb -> 0kb | used: 214kb | free: 21kb->29kb (largest 8kb) 8.070ms]
(111.552110) [toit] INFO: <- OK
(111.555475) [toit.metrics] INFO: persisting metrics
(111.607467) [toit.scheduler] INFO: running job {job: poll_metric_memory_profile}
(112.490746) [toit] INFO: -> AT
(112.496440) [toit] INFO: <- OK
(113.509725) [toit] INFO: -> AT
(113.514395) [toit] INFO: <- OK
(114.518807) [toit] INFO: -> AT
(114.523485) [toit] INFO: <- OK
(115.528342) [toit] INFO: -> AT
(115.533382) [toit] INFO: <- OK
(116.538358) [toit] INFO: -> AT
(116.543168) [toit] INFO: <- OK
(117.548716) [toit] INFO: -> AT
(117.553794) [toit] INFO: <- OK
(118.558490) [toit] INFO: -> AT
(118.563065) [toit] INFO: <- OK
(119.568494) [toit] INFO: -> AT
(119.573080) [toit] INFO: <- OK
(120.578482) [toit] INFO: -> AT
(120.583096) [toit] INFO: <- OK
(121.543898) [toit.scheduler] INFO: running job {job: poll_metric_flash_usage}
(121.588478) [toit] INFO: -> AT
(121.593845) [toit] INFO: <- OK
(121.619617) [toit.scheduler] INFO: running job {job: persist_metrics}
(121.624057) [toit] INFO: -> AT+CSQ
(121.629684) [toit] INFO: <- +CSQ: 17,99
(121.632602) [toit] INFO: <- OK
(121.635692) [toit.metrics] INFO: persisting metrics
(121.660929) [toit.scheduler] INFO: running job {job: poll_metric_memory_profile}
[gc @ 0x3ffcf9f0* | soft limit reached (69660 >= 67626)]
[gc @ 0x3ffcf9f0* | heap: 68kb -> 32kb | external: 0kb -> 0kb | used: 222kb | free: 21kb->21kb (largest 3544bytes) 8.045ms]
(122.599063) [toit] INFO: -> AT
(122.604744) [toit] INFO: <- OK
(123.609758) [toit] INFO: -> AT
(123.614672) [toit] INFO: <- OK
(124.618990) [toit] INFO: -> AT
(124.623533) [toit] INFO: <- OK
(125.628509) [toit] INFO: -> AT
(125.633245) [toit] INFO: <- OK
(126.638750) [toit] INFO: -> AT
(126.643509) [toit] INFO: <- OK
(127.648430) [toit] INFO: -> AT
(127.653704) [toit] INFO: <- OK
(128.658356) [toit] INFO: -> AT
(128.663247) [toit] INFO: <- OK
(129.668611) [toit] INFO: -> AT
(129.673648) [toit] INFO: <- OK
(130.678330) [toit] INFO: -> AT
(130.683022) [toit] INFO: <- OK
(131.688502) [toit] INFO: -> AT
(131.693477) [toit] INFO: <- OK
(131.707155) [toit.scheduler] INFO: running job {job: persist_metrics}
(131.716069) [toit] INFO: -> AT+CSQ
(131.722191) [toit] INFO: <- +CSQ: 19,99
(131.724847) [toit] INFO: <- OK
(131.727978) [toit.metrics] INFO: persisting metrics
(131.746523) [toit.scheduler] INFO: running job {job: poll_metric_memory_profile}
(132.708554) [toit] INFO: -> AT
(132.714037) [toit] INFO: <- OK
(133.718780) [toit] INFO: -> AT
(133.723401) [toit] INFO: <- OK
(134.728486) [toit] INFO: -> AT
[gc @ 0x3ffcf9f0* | soft limit reached (65564 >= 61482)]
[gc @ 0x3ffcf9f0* | heap: 64kb -> 32kb | external: 0kb -> 0kb | used: 214kb | free: 21kb->29kb (largest 8kb) 7.960ms]
(134.733164) [toit] INFO: <- OK
(135.748800) [toit] INFO: -> AT
(135.753430) [toit] INFO: <- OK
(136.759652) [toit] INFO: -> AT
(136.764331) [toit] INFO: <- OK
(137.768802) [toit] INFO: -> AT
(137.773361) [toit] INFO: <- OK
(138.778509) [toit] INFO: -> AT
(138.783280) [toit] INFO: <- OK
(139.788514) [toit] INFO: -> AT
(139.793183) [toit] INFO: <- OK
(140.798505) [toit] INFO: -> AT
(140.803342) [toit] INFO: <- OK
(141.796674) [toit.scheduler] INFO: running job {job: persist_metrics}
(141.801372) [toit] INFO: -> AT+CSQ
(141.807792) [toit.scheduler] INFO: running job {job: poll_metric_memory_profile}
(141.812171) [toit] INFO: <- +CSQ: 18,99
(141.814473) [toit] INFO: <- OK
(141.821769) [toit.metrics] INFO: persisting metrics
(141.837743) [toit] INFO: -> AT
(141.844119) [toit] INFO: <- OK
(142.849225) [toit] INFO: -> AT
(142.854113) [toit] INFO: <- OK
(143.858683) [toit] INFO: -> AT
(143.863796) [toit] INFO: <- OK
(144.868367) [toit] INFO: -> AT
(144.873252) [toit] INFO: <- OK
(145.878354) [toit] INFO: -> AT
(145.883164) [toit] INFO: <- OK
(146.888692) [toit] INFO: -> AT
(146.894074) [toit] INFO: <- OK
(147.898811) [toit] INFO: -> AT
(147.903471) [toit] INFO: <- OK
(148.908650) [toit] INFO: -> AT
(148.913257) [toit] INFO: <- OK
(149.917713) [toit] INFO: -> AT
[gc @ 0x3ffcf9f0* | soft limit reached (65564 >= 61482)]
[gc @ 0x3ffcf9f0* | string allocation failed, length = 31 (heap)]
[gc @ 0x3ffcf9f0* | heap: 64kb -> 32kb | external: 0kb -> 0kb | used: 214kb | free: 21kb->29kb (largest 8kb) 7.956ms]
(149.922417) [toit] INFO: <- OK
(150.940345) [toit] INFO: -> AT
(150.945267) [toit] INFO: <- OK
(151.874841) [toit.scheduler] INFO: running job {job: poll_metric_memory_profile}
(151.887856) [toit.scheduler] INFO: running job {job: persist_metrics}
(151.892601) [toit] INFO: -> AT+CSQ
(151.898748) [toit] INFO: <- +CSQ: 15,99
(151.901704) [toit] INFO: <- OK
(151.904799) [toit.metrics] INFO: persisting metrics
(151.951173) [toit] INFO: -> AT
(151.956687) [toit] INFO: <- OK
(152.961647) [toit] INFO: -> AT
(152.966299) [toit] INFO: <- OK
(153.970761) [toit] INFO: -> AT
(153.975433) [toit] INFO: <- OK
(154.980180) [toit] INFO: -> AT
(154.984801) [toit] INFO: <- OK
(155.989285) [toit] INFO: -> AT
(155.993920) [toit] INFO: <- OK
(156.998874) [toit] INFO: -> AT
(157.004057) [toit] INFO: <- OK
(158.009049) [toit] INFO: -> AT
(158.013845) [toit] INFO: <- OK
(159.018346) [toit] INFO: -> AT
(159.023226) [toit] INFO: <- OK
(159.232642) [toit] INFO: -> AT+SQNSH=1
----
Received a Toit system message but unable to send it to the Toit console for decoding.
Executing the command below will make it human readable:
----
[gc @ 0x3ffcf9f0* | soft limit reached (66188 >= 61482)]
[gc @ 0x3ffcf9f0* | byte array allocation failed, length = 609 (heap)]
[gc @ 0x3ffcf9f0* | heap: 64kb -> 32kb | external: 0kb -> 0kb | used: 200kb | free: 10kb->43kb (largest 8kb) 7.896ms]
toit serial decode WyNVBVVYU1UXdjEuNi4wLXByZS4zMis2MjEyMjQ5MjJTVQllc3AzMi00bWJbJFUjVRAAAAAAAAAAAAAAAAAAAAAAWyNVBFVFU1UJRVhDRVBUSU9OU1USQVRfQ09NTUFORF9USU1FT1VUWyNVAlVTWyNVKFsjVQNVRlUAbAABdVFbI1UDVUZVAWwAAXbFWyNVA1VGVQJsAAF1hVsjVQNVRlUDbAABdM9bI1UDVUZVBGwAAgJ5WyNVA1VGVQVsAAJl6VsjVQNVRlUGbAACYRlbI1UDVUZVB2wAAmE9WyNVA1VGVQhsAAJl/lsjVQNVRlUJbAACAp5bI1UDVUZVCmwAANakWyNVA1VGVQtsAADcv1sjVQNVRlUMbAAA3hZbI1UDVUZVD0klz1sjVQNVRlUQSSUvWyNVA1VGVRFsAADgY1sjVQNVRlUSbAAA3wJbI1UDVUZVE2wAANfqWyNVA1VGVRRsAADZU1sjVQNVRlUVbAABoVJbI1UDVUZVFmwAAkswWyNVA1VGVRdsAAJLYlsjVQNVRlUYSTiOWyNVA1VGVRlJOFZbI1UDVUZVGmwAAaGmWyNVA1VGVRtsAAGh0VsjVQNVRlUcSSVoWyNVA1VGVR1JJX1bI1UDVUZVHkklWlsjVQNVRlUfbAABohVbI1UDVUZVIGwAAZ3pWyNVA1VGVSFJMqlbI1UDVUZVImwAAkxHWyNVA1VGVSNJJWhbI1UDVUZVJEklfVsjVQNVRlUlSSUCWyNVA1VGVSZsAAJMXFsjVQNVRlUnbAACTHNbI1UDVUZVKEkyPlsjVQNVRlUpSTLZ
(159.356112) [toit.console] ERROR: connection closed {error: AT_COMMAND_TIMEOUT}
(159.381329) [toit] INFO: <- OK
(159.387433) [toit.scheduler] INFO: changing run level {run_level: 0}
(159.391222) [toit.scheduler] INFO: going into deep sleep {ms: 4980}
(159.393264) [toit.metrics] INFO: persisting metrics
(159.417015) [toit] INFO: -> AT+CFUN=0
(160.499121) [toit] INFO: <- OK
(160.502567) [toit] INFO: <- +CEREG: 0
(160.504581) [toit] INFO: <- *ignored* [URC] +CEREG [0]
(160.611546) [toit] INFO: entering deep sleep {ms: 4980}
Entering deep sleep for 4980ms
ets Jun 8 2016 00:22:57
rst:0x5 (DEEPSLEEP_RESET),boot:0x13 (SPI_FAST_FLASH_BOOT)
configsip: 0, SPIWP:0xee
clk_drv:0x00,q_drv:0x00,d_drv:0x00,cs0_drv:0x00,hd_drv:0x00,wp_drv:0x00
mode:DIO, clock div:2
load:0x3fff0030,len:188
ho 0 tail 12 room 4
load:0x40078000,len:12180
load:0x40080400,len:2944
entry 0x400805c8
[flash reg] address 0x3f46c000, size 0x00134000
[IDENTITY] name=4c2a0869-49c3-4fe7-834e-ce3a6a9e2b7c.744.2021-09-26.device.toitware.com,model=esp32-4mb
(0.015789) [toit] INFO: booting toit {sdk: v1.6.0-pre.32+621224922, model: esp32-4mb}
(0.021974) [toit.scheduler] INFO: set initial run_level {run_level: 4}
(0.053865) [toit.state] INFO: resumed session {session_id: 1, session_time: 165.074962}
[gc @ 0x3ffcfa58* | soft limit reached (20480 >= 18432)]
[gc @ 0x3ffcfa58* | heap: 20kb -> 8kb | external: 0kb -> 0kb | used: 93kb | free: 9kb->149kb (largest 108kb) 2.907ms]
(0.074984) [toit.kernel] INFO: using model parameters {cellular.rts: 19, cellular.rx: 23, cellular.tx: 5, cellular.cts: 18, cellular.enabled: 1, cellular.monarch: 1, cellular.pwr: 27}
(0.111477) [toit.console_sync] INFO: loaded last console sync {time: 158.727028}
[gc @ 0x3ffcfa58* | soft limit reached (24576 >= 24576)]
[gc @ 0x3ffcfa58* | byte array allocation failed, length = 511 (heap)]
[gc @ 0x3ffcfa58* | heap: 24kb -> 16kb | external: 0kb -> 0kb | used: 102kb | free: 8kb->140kb (largest 108kb) 4.843ms]
(0.142013) [toit.scheduler] INFO: running job {job: console_sync}
(0.145212) [toit.scheduler] INFO: running job {job: poll_metric_flash_usage}
(0.147599) [toit.scheduler] INFO: running job {job: poll_metric_memory_profile}
(0.157412) [toit.console] INFO: waiting for console connection to be established
(0.161836) [toit.network] DEBUG: connecting {network: Cellular (nbiot.tdc.dk)}
[gc @ 0x3ffcfa58* | soft limit reached (36892 >= 36864)]
[gc @ 0x3ffcfa58* | byte array allocation failed, length = 511 (heap)]
[gc @ 0x3ffcfa58* | heap: 36kb -> 20kb | external: 0kb -> 0kb | used: 126kb | free: 12kb->116kb (largest 104kb) 5.554ms]
(0.220758) [toit] INFO: -> AT
[gc @ 0x3ffcfa58* | soft limit reached (45084 >= 43050)]
[gc @ 0x3ffcfa58* | heap: 44kb -> 24kb | external: 0kb -> 0kb | used: 149kb | free: 6kb->94kb (largest 88kb) 6.393ms]
(0.234382) [toit] INFO: <- OK
(0.339556) [toit] INFO: -> ATE0
(0.345054) [toit] INFO: <- OK
(0.367899) [toit] INFO: -> AT+CMEE=2
(0.373129) [toit] INFO: <- OK
(0.395423) [toit] INFO: -> AT+CGMM
(0.408901) [toit] INFO: <- FiPy
(0.412081) [toit] INFO: <- OK
(0.434272) [toit] INFO: -> AT+CGMR
(0.439161) [toit] INFO: <- UE5.4.0.2
(0.441787) [toit] INFO: <- OK
(0.464011) [toit] INFO: -> AT+SQNCCID?
(0.469517) [toit] INFO: <- +SQNCCID: "",""
(0.472108) [toit] INFO: <- OK
(0.474832) [toit.cellular] DEBUG: initialized {model: FiPy, version: UE5.4.0.2, iccid: }
(0.494516) [toit] INFO: -> AT+CFUN=4
(0.562787) [toit] INFO: <- OK
(0.585141) [toit] INFO: -> AT+CPIN?
(0.590673) [toit] INFO: <- +CME ERROR: SIM not inserted
(0.845598) [toit] INFO: -> AT+CPIN?
(0.851243) [toit] INFO: <- +CME ERROR: SIM not inserted
(1.106088) [toit] INFO: -> AT+CPIN?
(1.112238) [toit] INFO: <- +CPIN: READY
(1.114961) [toit] INFO: <- OK
(1.137354) [toit] INFO: -> AT+CEREG=2
(1.146883) [toit] INFO: <- OK
(1.169623) [toit] INFO: -> AT+CPSMS=0
(1.176886) [toit] INFO: <- OK
(1.199390) [toit] INFO: -> AT+CEDRXS=0
(1.206879) [toit] INFO: <- OK
(1.229419) [toit] INFO: -> AT+SQNIBRCFG=0
(1.240831) [toit] INFO: <- OK
(1.263497) [toit] INFO: -> AT+SQNIPSCFG=1,100
(1.280528) [toit] INFO: <- OK
(1.303295) [toit] INFO: -> AT+SQNBANDSEL=0,"standard","20"
(1.374017) [toit] INFO: <- +SQNBANDSEL: <custom>
(1.376950) [toit] INFO: <- OK
(1.399454) [toit] INFO: -> AT+SQNBANDSEL=1,"standard","20"
(1.406484) [toit] INFO: <- +CME ERROR: operation not supported
(1.428675) [toit] INFO: -> AT+CGDCONT?
(1.434634) [toit] INFO: <- +CGDCONT: 1,"IP","nbiot.tdc.dk",,,,0,0,0,0,0,0,0,,0
(1.437719) [toit] INFO: <- OK
(1.459995) [toit] INFO: -> AT+CFUN=1
(1.533079) [toit] INFO: <- OK
(2.387292) [toit] INFO: <- +CEREG: 2
(2.389673) [toit] INFO: <- [URC] +CEREG [2]
(2.392002) [toit.cellular] DEBUG: attempt connect to known operator
(2.399869) [toit.cellular] DEBUG: connecting {operator: an instance with class-id 352, attempt: 1}
(2.402398) [toit.cellular] DEBUG: connection failed
(2.411069) [toit] INFO: -> AT+CFUN=4
(2.482016) [toit] INFO: <- OK
(2.484708) [toit] INFO: <- +CEREG: 0
[gc @ 0x3ffcfa58* | soft limit reached (53276 >= 49194)]
[gc @ 0x3ffcfa58* | string allocation failed, length = 29 (heap)]
[gc @ 0x3ffcfa58* | heap: 52kb -> 24kb | external: 0kb -> 0kb | used: 165kb | free: 10kb->78kb (largest 68kb) 6.411ms]
(2.500066) [toit] INFO: <- *ignored* [URC] +CEREG [0]
(2.505593) [toit.cellular] DEBUG: failed, detach from network
----
Received a Toit system message but unable to send it to the Toit console for decoding.
Executing the command below will make it human readable:
----
toit serial decode WyNVBVVYU1UXdjEuNi4wLXByZS4zMis2MjEyMjQ5MjJTVQllc3AzMi00bWJbJFUjVRAAAAAAAAAAAAAAAAAAAAAAWyNVBFVFU1UJRVhDRVBUSU9OU1URQ09OTkVDVElPTiBGQUlMRURbI1UCVVNbI1UmWyNVA1VGVQBsAAI9rFsjVQNVRlUBbAACPh1bI1UDVUZVAmwAAjmgWyNVA1VGVQNsAAI5y1sjVQNVRlUEbAACq29bI1UDVUZVBUklaFsjVQNVRlUGSSV9WyNVA1VGVQdJJVpbI1UDVUZVCGwAAqwuWyNVA1VGVQlsAAKsyFsjVQNVRlUKbAACpY5bI1UDVUZVC2wAAp5zWyNVA1VGVQxsAAKtd1sjVQNVRlUNbAACqglbI1UDVUZVDkklaFsjVQNVRlUPSSV9WyNVA1VGVRBJJQJbI1UDVUZVEWwAAqpGWyNVA1VGVRJsAAKp4lsjVQNVRlUTbAABA/hbI1UDVUZVFGwAAaD4WyNVA1VGVRVsAAEUPlsjVQNVRlUWbAABFFdbI1UDVUZVF2wAARPUWyNVA1VGVRhJJWhbI1UDVUZVGUklfVsjVQNVRlUaSSVaWyNVA1VGVRtsAAET7VsjVQNVRlUcbAABFANbI1UDVUZVHUkyqVsjVQNVRlUebAACTEdbI1UDVUZVH0klaFsjVQNVRlUgSSV9WyNVA1VGVSFJJQJbI1UDVUZVImwAAkxcWyNVA1VGVSNsAAJMc1sjVQNVRlUkSTI+WyNVA1VGVSVJMtk=
(2.614269) [toit.network] DEBUG: error connecting {network: Cellular (nbiot.tdc.dk), error: CONNECTION FAILED}
(2.623409) [toit.network] WARN: failed to connect to any network
----
Received a Toit system message but unable to send it to the Toit console for decoding.
Executing the command below will make it human readable:
----
toit serial decode WyNVBVVYU1UXdjEuNi4wLXByZS4zMis2MjEyMjQ5MjJTVQllc3AzMi00bWJbJFUjVRAAAAAAAAAAAAAAAAAAAAAAWyNVBFVFU1UJRVhDRVBUSU9OU1URRkFJTEVEIFRPIENPTk5FQ1RbI1UCVVNbI1UbWyNVA1VGVQBsAAKuIFsjVQNVRlUBbAACrX1bI1UDVUZVAmwAAqoJWyNVA1VGVQNJJWhbI1UDVUZVBEklfVsjVQNVRlUFSSUCWyNVA1VGVQZsAAKqRlsjVQNVRlUHbAACqeJbI1UDVUZVCGwAAQP4WyNVA1VGVQlsAAGg+FsjVQNVRlUKbAABFD5bI1UDVUZVC2wAARRXWyNVA1VGVQxsAAET1FsjVQNVRlUNSSVoWyNVA1VGVQ5JJX1bI1UDVUZVD0klWlsjVQNVRlUQbAABE+1bI1UDVUZVEWwAARQDWyNVA1VGVRJJMqlbI1UDVUZVE2wAAkxHWyNVA1VGVRRJJWhbI1UDVUZVFUklfVsjVQNVRlUWSSUCWyNVA1VGVRdsAAJMXFsjVQNVRlUYbAACTHNbI1UDVUZVGUkyPlsjVQNVRlUaSTLZ
[gc @ 0x3ffcfa58* | soft limit reached (50229 >= 49194)]
[gc @ 0x3ffcfa58* | heap: 48kb -> 24kb | external: 1kb -> 0kb | used: 156kb | free: 9kb->86kb (largest 68kb) 6.480ms]
(2.728132) [toit] INFO: -> AT+CFUN=0
(2.735364) [toit.scheduler] INFO: changing run level {run_level: 0}
(2.739200) [toit.scheduler] INFO: going into deep sleep {ms: 4980}
(2.742339) [toit.metrics] INFO: persisting metrics
(2.889416) [toit] INFO: <- OK
(2.997832) [toit] INFO: entering deep sleep {ms: 4980}
Entering deep sleep for 4980ms
ets Jun 8 2016 00:22:57
rst:0x5 (DEEPSLEEP_RESET),boot:0x13 (SPI_FAST_FLASH_BOOT)
configsip: 0, SPIWP:0xee
clk_drv:0x00,q_drv:0x00,d_drv:0x00,cs0_drv:0x00,hd_drv:0x00,wp_drv:0x00
mode:DIO, clock div:2
load:0x3fff0030,len:188
ho 0 tail 12 room 4
load:0x40078000,len:12180
load:0x40080400,len:2944
entry 0x400805c8
[flash reg] address 0x3f46c000, size 0x00134000
[IDENTITY] name=4c2a0869-49c3-4fe7-834e-ce3a6a9e2b7c.744.2021-09-26.device.toitware.com,model=esp32-4mb
(0.015787) [toit] INFO: booting toit {sdk: v1.6.0-pre.32+621224922, model: esp32-4mb}
(0.021972) [toit.scheduler] INFO: set initial run_level {run_level: 4}
(0.053586) [toit.state] INFO: resumed session {session_id: 1, session_time: 173.124188}
[gc @ 0x3ffcfa58* | soft limit reached (20480 >= 18432)]
[gc @ 0x3ffcfa58* | heap: 20kb -> 8kb | external: 0kb -> 0kb | used: 93kb | free: 9kb->149kb (largest 108kb) 2.768ms]
(0.076466) [toit.kernel] INFO: using model parameters {cellular.rts: 19, cellular.rx: 23, cellular.tx: 5, cellular.cts: 18, cellular.enabled: 1, cellular.monarch: 1, cellular.pwr: 27}
(0.111573) [toit.console_sync] INFO: loaded last console sync {time: 167.730323}
[gc @ 0x3ffcfa58* | soft limit reached (24576 >= 24576)]
[gc @ 0x3ffcfa58* | byte array allocation failed, length = 511 (heap)]
[gc @ 0x3ffcfa58* | heap: 24kb -> 12kb | external: 0kb -> 0kb | used: 102kb | free: 8kb->140kb (largest 108kb) 4.586ms]
(0.146743) [toit.scheduler] INFO: running job {job: console_sync}
(0.149737) [toit.scheduler] INFO: running job {job: poll_metric_flash_usage}
(0.151858) [toit.scheduler] INFO: running job {job: poll_metric_memory_profile}
(0.160744) [toit.console] INFO: waiting for console connection to be established
(0.165162) [toit.network] DEBUG: connecting {network: Cellular (nbiot.tdc.dk)}
[gc @ 0x3ffcfa58* | soft limit reached (32796 >= 30720)]
[gc @ 0x3ffcfa58* | heap: 32kb -> 20kb | external: 0kb -> 0kb | used: 118kb | free: 12kb->124kb (largest 108kb) 5.347ms]
[gc @ 0x3ffcfa58* | soft limit reached (45084 >= 43050)]
[gc @ 0x3ffcfa58* | byte array allocation failed, length = 511 (heap)]
[gc @ 0x3ffcfa58* | heap: 44kb -> 20kb | external: 0kb -> 0kb | used: 142kb | free: 12kb->100kb (largest 88kb) 5.813ms]
(0.239575) [toit] INFO: -> AT
(0.501052) [toit] INFO: -> AT
(0.761646) [toit] INFO: -> AT
(1.022718) [toit] INFO: -> AT
(1.283221) [toit] INFO: -> AT
(1.543980) [toit] INFO: -> AT
(1.804724) [toit] INFO: -> AT
(1.987892) [toit] INFO: <- OK
(2.093853) [toit] INFO: -> ATE0
(2.101737) [toit] INFO: <- OK
(2.124311) [toit] INFO: -> AT+CMEE=2
(2.130095) [toit] INFO: <- OK
(2.152375) [toit] INFO: -> AT+CGMM
(2.157620) [toit] INFO: <- FiPy
(2.160809) [toit] INFO: <- OK
(2.182968) [toit] INFO: -> AT+CGMR
(2.203409) [toit] INFO: <- UE5.4.0.2
(2.206111) [toit] INFO: <- OK
(2.228291) [toit] INFO: -> AT+SQNCCID?
(2.233845) [toit] INFO: <- +SQNCCID: "",""
(2.236448) [toit] INFO: <- OK
(2.239190) [toit.cellular] DEBUG: initialized {model: FiPy, version: UE5.4.0.2, iccid: }
(2.258894) [toit] INFO: -> AT+CFUN=4
(2.815385) [toit] INFO: <- OK
(2.837812) [toit] INFO: -> AT+CPIN?
[gc @ 0x3ffcfa58* | soft limit reached (45084 >= 43050)]
[gc @ 0x3ffcfa58* | string allocation failed, length = 20 (heap)]
[gc @ 0x3ffcfa58* | heap: 44kb -> 24kb | external: 0kb -> 0kb | used: 149kb | free: 10kb->94kb (largest 84kb) 6.244ms]
(2.855274) [toit] INFO: <- +CME ERROR: SIM busy
(3.112443) [toit] INFO: -> AT+CPIN?
(3.118099) [toit] INFO: <- +CPIN: READY
(3.121217) [toit] INFO: <- OK
(3.143635) [toit] INFO: -> AT+CEREG=2
(3.153245) [toit] INFO: <- OK
(3.175732) [toit] INFO: -> AT+CPSMS=0
(3.182961) [toit] INFO: <- OK
(3.206247) [toit] INFO: -> AT+CEDRXS=0
(3.213511) [toit] INFO: <- OK
(3.236010) [toit] INFO: -> AT+SQNIBRCFG=0
(3.256261) [toit] INFO: <- OK
(3.278954) [toit] INFO: -> AT+SQNIPSCFG=1,100
(3.299196) [toit] INFO: <- OK
(3.322256) [toit] INFO: -> AT+SQNBANDSEL=0,"standard","20"
(3.391491) [toit] INFO: <- +SQNBANDSEL: <custom>
(3.394199) [toit] INFO: <- OK
(3.416696) [toit] INFO: -> AT+SQNBANDSEL=1,"standard","20"
(3.423692) [toit] INFO: <- +CME ERROR: operation not supported
(3.445871) [toit] INFO: -> AT+CGDCONT?
(3.452454) [toit] INFO: <- +CGDCONT: 1,"IP","nbiot.tdc.dk",,,,0,0,0,0,0,0,0,,0
(3.455203) [toit] INFO: <- OK
(3.477959) [toit] INFO: -> AT+CFUN=1
(3.776345) [toit] INFO: <- OK
(4.231357) [toit] INFO: <- +CEREG: 2
(4.233603) [toit] INFO: <- [URC] +CEREG [2]
(4.235884) [toit.cellular] DEBUG: attempt connect to known operator
(4.250389) [toit.cellular] DEBUG: connecting {operator: an instance with class-id 352, attempt: 2}
(4.252917) [toit.cellular] DEBUG: connection failed
(4.257147) [toit] INFO: -> AT+CFUN=4
(4.327376) [toit] INFO: <- OK
(4.330546) [toit] INFO: <- +CEREG: 0
(4.332506) [toit] INFO: <- *ignored* [URC] +CEREG [0]
(4.334530) [toit.cellular] DEBUG: failed, detach from network
----
Received a Toit system message but unable to send it to the Toit console for decoding.
Executing the command below will make it human readable:
----
toit serial decode WyNVBVVYU1UXdjEuNi4wLXByZS4zMis2MjEyMjQ5MjJTVQllc3AzMi00bWJbJFUjVRAAAAAAAAAAAAAAAAAAAAAAWyNVBFVFU1UJRVhDRVBUSU9OU1URQ09OTkVDVElPTiBGQUlMRURbI1UCVVNbI1UmWyNVA1VGVQBsAAI9rFsjVQNVRlUBbAACPh1bI1UDVUZVAmwAAjmgWyNVA1VGVQNsAAI5y1sjVQNVRlUEbAACq29bI1UDVUZVBUklaFsjVQNVRlUGSSV9WyNVA1VGVQdJJVpbI1UDVUZVCGwAAqwuWyNVA1VGVQlsAAKsyFsjVQNVRlUKbAACpY5bI1UDVUZVC2wAAp5zWyNVA1VGVQxsAAKtd1sjVQNVRlUNbAACqglbI1UDVUZVDkklaFsjVQNVRlUPSSV9WyNVA1VGVRBJJQJbI1UDVUZVEWwAAqpGWyNVA1VGVRJsAAKp4lsjVQNVRlUTbAABA/hbI1UDVUZVFGwAAaD4WyNVA1VGVRVsAAEUPlsjVQNVRlUWbAABFFdbI1UDVUZVF2wAARPUWyNVA1VGVRhJJWhbI1UDVUZVGUklfVsjVQNVRlUaSSVaWyNVA1VGVRtsAAET7VsjVQNVRlUcbAABFANbI1UDVUZVHUkyqVsjVQNVRlUebAACTEdbI1UDVUZVH0klaFsjVQNVRlUgSSV9WyNVA1VGVSFJJQJbI1UDVUZVImwAAkxcWyNVA1VGVSNsAAJMc1sjVQNVRlUkSTI+WyNVA1VGVSVJMtk=
(4.444393) [toit.network] DEBUG: error connecting {network: Cellular (nbiot.tdc.dk), error: CONNECTION FAILED}
[gc @ 0x3ffcfa58* | soft limit reached (49773 >= 49194)]
[gc @ 0x3ffcfa58* | byte array allocation failed, length = 70 (heap)]
[gc @ 0x3ffcfa58* | heap: 48kb -> 24kb | external: 0kb -> 0kb | used: 157kb | free: 9kb->86kb (largest 76kb) 6.563ms]
(4.473438) [toit.network] WARN: failed to connect to any network
----
Received a Toit system message but unable to send it to the Toit console for decoding.
Executing the command below will make it human readable:
----
toit serial decode WyNVBVVYU1UXdjEuNi4wLXByZS4zMis2MjEyMjQ5MjJTVQllc3AzMi00bWJbJFUjVRAAAAAAAAAAAAAAAAAAAAAAWyNVBFVFU1UJRVhDRVBUSU9OU1URRkFJTEVEIFRPIENPTk5FQ1RbI1UCVVNbI1UbWyNVA1VGVQBsAAKuIFsjVQNVRlUBbAACrX1bI1UDVUZVAmwAAqoJWyNVA1VGVQNJJWhbI1UDVUZVBEklfVsjVQNVRlUFSSUCWyNVA1VGVQZsAAKqRlsjVQNVRlUHbAACqeJbI1UDVUZVCGwAAQP4WyNVA1VGVQlsAAGg+FsjVQNVRlUKbAABFD5bI1UDVUZVC2wAARRXWyNVA1VGVQxsAAET1FsjVQNVRlUNSSVoWyNVA1VGVQ5JJX1bI1UDVUZVD0klWlsjVQNVRlUQbAABE+1bI1UDVUZVEWwAARQDWyNVA1VGVRJJMqlbI1UDVUZVE2wAAkxHWyNVA1VGVRRJJWhbI1UDVUZVFUklfVsjVQNVRlUWSSUCWyNVA1VGVRdsAAJMXFsjVQNVRlUYbAACTHNbI1UDVUZVGUkyPlsjVQNVRlUaSTLZ
(4.579847) [toit] INFO: -> AT+CFUN=0
(4.586823) [toit.scheduler] INFO: changing run level {run_level: 0}
(4.590618) [toit.scheduler] INFO: going into deep sleep {ms: 4989}
(4.592645) [toit.metrics] INFO: persisting metrics
(4.846216) [toit] INFO: <- OK
(4.954108) [toit] INFO: entering deep sleep {ms: 4989}
Entering deep sleep for 4989ms
ets Jun 8 2016 00:22:57
rst:0x5 (DEEPSLEEP_RESET),boot:0x13 (SPI_FAST_FLASH_BOOT)
configsip: 0, SPIWP:0xee
clk_drv:0x00,q_drv:0x00,d_drv:0x00,cs0_drv:0x00,hd_drv:0x00,wp_drv:0x00
mode:DIO, clock div:2
load:0x3fff0030,len:188
ho 0 tail 12 room 4
load:0x40078000,len:12180
load:0x40080400,len:2944
entry 0x400805c8
[flash reg] address 0x3f46c000, size 0x00134000
[IDENTITY] name=4c2a0869-49c3-4fe7-834e-ce3a6a9e2b7c.744.2021-09-26.device.toitware.com,model=esp32-4mb
(0.015787) [toit] INFO: booting toit {sdk: v1.6.0-pre.32+621224922, model: esp32-4mb}
(0.021972) [toit.scheduler] INFO: set initial run_level {run_level: 4}
(0.053332) [toit.state] INFO: resumed session {session_id: 1, session_time: 183.132931}
[gc @ 0x3ffcfa58* | soft limit reached (20480 >= 18432)]
[gc @ 0x3ffcfa58* | heap: 20kb -> 8kb | external: 0kb -> 0kb | used: 93kb | free: 9kb->149kb (largest 108kb) 2.769ms]
(0.076200) [toit.kernel] INFO: using model parameters {cellular.rts: 19, cellular.rx: 23, cellular.tx: 5, cellular.cts: 18, cellular.enabled: 1, cellular.monarch: 1, cellular.pwr: 27}
(0.111717) [toit.console_sync] INFO: loaded last console sync {time: 177.634439}
[gc @ 0x3ffcfa58* | soft limit reached (24576 >= 24576)]
[gc @ 0x3ffcfa58* | byte array allocation failed, length = 511 (heap)]
[gc @ 0x3ffcfa58* | heap: 24kb -> 12kb | external: 0kb -> 0kb | used: 102kb | free: 8kb->140kb (largest 108kb) 4.590ms]
(0.147058) [toit.scheduler] INFO: running job {job: console_sync}
(0.150058) [toit.scheduler] INFO: running job {job: poll_metric_flash_usage}
(0.152443) [toit.scheduler] INFO: running job {job: poll_metric_memory_profile}
(0.161047) [toit.console] INFO: waiting for console connection to be established
(0.165483) [toit.network] DEBUG: connecting {network: Cellular (nbiot.tdc.dk)}
[gc @ 0x3ffcfa58* | soft limit reached (32796 >= 30720)]
[gc @ 0x3ffcfa58* | heap: 32kb -> 20kb | external: 0kb -> 0kb | used: 118kb | free: 12kb->124kb (largest 108kb) 5.349ms]
[gc @ 0x3ffcfa58* | soft limit reached (45084 >= 43050)]
[gc @ 0x3ffcfa58* | byte array allocation failed, length = 511 (heap)]
[gc @ 0x3ffcfa58* | heap: 44kb -> 20kb | external: 0kb -> 0kb | used: 142kb | free: 12kb->100kb (largest 88kb) 5.813ms]
(0.239359) [toit] INFO: -> AT
(0.500830) [toit] INFO: -> AT
(0.761423) [toit] INFO: -> AT
(1.022496) [toit] INFO: -> AT
(1.282999) [toit] INFO: -> AT
(1.543749) [toit] INFO: -> AT
(1.804494) [toit] INFO: -> AT
(1.981323) [toit] INFO: <- OK
(2.088165) [toit] INFO: -> ATE0
(2.095942) [toit] INFO: <- OK
(2.118549) [toit] INFO: -> AT+CMEE=2
(2.124297) [toit] INFO: <- OK
(2.146603) [toit] INFO: -> AT+CGMM
(2.151831) [toit] INFO: <- FiPy
(2.155005) [toit] INFO: <- OK
(2.177575) [toit] INFO: -> AT+CGMR
(2.198054) [toit] INFO: <- UE5.4.0.2
(2.200741) [toit] INFO: <- OK
(2.222806) [toit] INFO: -> AT+SQNCCID?
(2.228448) [toit] INFO: <- +SQNCCID: "",""
(2.231047) [toit] INFO: <- OK
(2.233767) [toit.cellular] DEBUG: initialized {model: FiPy, version: UE5.4.0.2, iccid: }
(2.253529) [toit] INFO: -> AT+CFUN=4
(2.320430) [toit] INFO: <- OK
(2.342743) [toit] INFO: -> AT+CPIN?
[gc @ 0x3ffcfa58* | soft limit reached (45084 >= 43050)]
[gc @ 0x3ffcfa58* | byte array allocation failed, length = 30 (heap)]
[gc @ 0x3ffcfa58* | heap: 44kb -> 24kb | external: 0kb -> 0kb | used: 149kb | free: 10kb->94kb (largest 84kb) 6.187ms]
(2.361119) [toit] INFO: <- +CME ERROR: SIM not inserted
(2.618247) [toit] INFO: -> AT+CPIN?
(2.624036) [toit] INFO: <- +CME ERROR: SIM not inserted
(2.878642) [toit] INFO: -> AT+CPIN?
(2.970292) [toit] INFO: <- +CPIN: READY
(2.973470) [toit] INFO: <- OK
(2.995857) [toit] INFO: -> AT+CEREG=2
(3.013966) [toit] INFO: <- OK
(3.036503) [toit] INFO: -> AT+CPSMS=0
(3.043749) [toit] INFO: <- OK
(3.066224) [toit] INFO: -> AT+CEDRXS=0
(3.073723) [toit] INFO: <- OK
(3.096204) [toit] INFO: -> AT+SQNIBRCFG=0
(3.125532) [toit] INFO: <- OK
(3.148269) [toit] INFO: -> AT+SQNIPSCFG=1,100
(3.165588) [toit] INFO: <- OK
(3.188332) [toit] INFO: -> AT+SQNBANDSEL=0,"standard","20"
(3.258881) [toit] INFO: <- +SQNBANDSEL: <custom>
(3.261728) [toit] INFO: <- OK
(3.284327) [toit] INFO: -> AT+SQNBANDSEL=1,"standard","20"
(3.291246) [toit] INFO: <- +CME ERROR: operation not supported
(3.313646) [toit] INFO: -> AT+CGDCONT?
(3.320256) [toit] INFO: <- +CGDCONT: 1,"IP","nbiot.tdc.dk",,,,0,0,0,0,0,0,0,,0
(3.322937) [toit] INFO: <- OK
(3.345183) [toit] INFO: -> AT+CFUN=1
(3.416242) [toit] INFO: <- OK
(3.885923) [toit] INFO: <- +CEREG: 2
(3.888545) [toit] INFO: <- [URC] +CEREG [2]
(3.900231) [toit.cellular] DEBUG: connecting {operator: an instance with class-id 352, attempt: 1}
(3.902760) [toit.cellular] DEBUG: connection failed
(3.909782) [toit] INFO: -> AT+CFUN=4
(4.273491) [toit] INFO: <- OK
(4.276658) [toit] INFO: <- +CEREG: 0
(4.278705) [toit] INFO: <- *ignored* [URC] +CEREG [0]
(4.280853) [toit.cellular] DEBUG: failed, detach from network
----
Received a Toit system message but unable to send it to the Toit console for decoding.
Executing the command below will make it human readable:
----
toit serial decode WyNVBVVYU1UXdjEuNi4wLXByZS4zMis2MjEyMjQ5MjJTVQllc3AzMi00bWJbJFUjVRAAAAAAAAAAAAAAAAAAAAAAWyNVBFVFU1UJRVhDRVBUSU9OU1URQ09OTkVDVElPTiBGQUlMRURbI1UCVVNbI1UmWyNVA1VGVQBsAAI9rFsjVQNVRlUBbAACPh1bI1UDVUZVAmwAAjmgWyNVA1VGVQNsAAI5y1sjVQNVRlUEbAACq29bI1UDVUZVBUklaFsjVQNVRlUGSSV9WyNVA1VGVQdJJVpbI1UDVUZVCGwAAqwuWyNVA1VGVQlsAAKsyFsjVQNVRlUKbAACpY5bI1UDVUZVC2wAAp5zWyNVA1VGVQxsAAKtd1sjVQNVRlUNbAACqglbI1UDVUZVDkklaFsjVQNVRlUPSSV9WyNVA1VGVRBJJQJbI1UDVUZVEWwAAqpGWyNVA1VGVRJsAAKp4lsjVQNVRlUTbAABA/hbI1UDVUZVFGwAAaD4WyNVA1VGVRVsAAEUPlsjVQNVRlUWbAABFFdbI1UDVUZVF2wAARPUWyNVA1VGVRhJJWhbI1UDVUZVGUklfVsjVQNVRlUaSSVaWyNVA1VGVRtsAAET7VsjVQNVRlUcbAABFANbI1UDVUZVHUkyqVsjVQNVRlUebAACTEdbI1UDVUZVH0klaFsjVQNVRlUgSSV9WyNVA1VGVSFJJQJbI1UDVUZVImwAAkxcWyNVA1VGVSNsAAJMc1sjVQNVRlUkSTI+WyNVA1VGVSVJMtk=
[gc @ 0x3ffcfa58* | soft limit reached (49773 >= 49194)]
[gc @ 0x3ffcfa58* | byte array allocation failed, length = 511 (heap)]
[gc @ 0x3ffcfa58* | heap: 48kb -> 24kb | external: 0kb -> 0kb | used: 157kb | free: 9kb->86kb (largest 76kb) 6.486ms]
(4.410461) [toit.network] DEBUG: error connecting {network: Cellular (nbiot.tdc.dk), error: CONNECTION FAILED}
(4.420106) [toit.network] WARN: failed to connect to any network
----
Received a Toit system message but unable to send it to the Toit console for decoding.
Executing the command below will make it human readable:
----
toit serial decode WyNVBVVYU1UXdjEuNi4wLXByZS4zMis2MjEyMjQ5MjJTVQllc3AzMi00bWJbJFUjVRAAAAAAAAAAAAAAAAAAAAAAWyNVBFVFU1UJRVhDRVBUSU9OU1URRkFJTEVEIFRPIENPTk5FQ1RbI1UCVVNbI1UbWyNVA1VGVQBsAAKuIFsjVQNVRlUBbAACrX1bI1UDVUZVAmwAAqoJWyNVA1VGVQNJJWhbI1UDVUZVBEklfVsjVQNVRlUFSSUCWyNVA1VGVQZsAAKqRlsjVQNVRlUHbAACqeJbI1UDVUZVCGwAAQP4WyNVA1VGVQlsAAGg+FsjVQNVRlUKbAABFD5bI1UDVUZVC2wAARRXWyNVA1VGVQxsAAET1FsjVQNVRlUNSSVoWyNVA1VGVQ5JJX1bI1UDVUZVD0klWlsjVQNVRlUQbAABE+1bI1UDVUZVEWwAARQDWyNVA1VGVRJJMqlbI1UDVUZVE2wAAkxHWyNVA1VGVRRJJWhbI1UDVUZVFUklfVsjVQNVRlUWSSUCWyNVA1VGVRdsAAJMXFsjVQNVRlUYbAACTHNbI1UDVUZVGUkyPlsjVQNVRlUaSTLZ
(4.510627) [toit] INFO: -> AT+CFUN=0
(4.517463) [toit.scheduler] INFO: changing run level {run_level: 0}
(4.521225) [toit.scheduler] INFO: going into deep sleep {ms: 4988}
(4.523236) [toit.metrics] INFO: persisting metrics
[gc @ 0x3ffcfa58* | soft limit reached (49636 >= 49194)]
[gc @ 0x3ffcfa58* | byte array allocation failed, length = 68 (heap)]
[gc @ 0x3ffcfa58* | heap: 48kb -> 24kb | external: 0kb -> 0kb | used: 157kb | free: 10kb->86kb (largest 76kb) 6.601ms]
(4.763971) [toit] INFO: <- OK
(4.871860) [toit] INFO: entering deep sleep {ms: 4988}
Entering deep sleep for 4988ms
ets Jun 8 2016 00:22:57
rst:0x5 (DEEPSLEEP_RESET),boot:0x13 (SPI_FAST_FLASH_BOOT)
configsip: 0, SPIWP:0xee
clk_drv:0x00,q_drv:0x00,d_drv:0x00,cs0_drv:0x00,hd_drv:0x00,wp_drv:0x00
mode:DIO, clock div:2
load:0x3fff0030,len:188
ho 0 tail 12 room 4
load:0x40078000,len:12180
load:0x40080400,len:2944
entry 0x400805c8
[flash reg] address 0x3f46c000, size 0x00134000
[IDENTITY] name=4c2a0869-49c3-4fe7-834e-ce3a6a9e2b7c.744.2021-09-26.device.toitware.com,model=esp32-4mb
(0.015789) [toit] INFO: booting toit {sdk: v1.6.0-pre.32+621224922, model: esp32-4mb}
(0.021974) [toit.scheduler] INFO: set initial run_level {run_level: 4}
(0.054279) [toit.state] INFO: resumed session {session_id: 1, session_time: 193.060183}
[gc @ 0x3ffcfa58* | soft limit reached (20480 >= 18432)]
[gc @ 0x3ffcfa58* | heap: 20kb -> 8kb | external: 0kb -> 0kb | used: 93kb | free: 9kb->149kb (largest 108kb) 2.809ms]
(0.077180) [toit.kernel] INFO: using model parameters {cellular.rts: 19, cellular.rx: 23, cellular.tx: 5, cellular.cts: 18, cellular.enabled: 1, cellular.monarch: 1, cellular.pwr: 27}
(0.111838) [toit.console_sync] INFO: loaded last console sync {time: 187.574171}
[gc @ 0x3ffcfa58* | soft limit reached (24576 >= 24576)]
[gc @ 0x3ffcfa58* | heap: 24kb -> 12kb | external: 0kb -> 0kb | used: 102kb | free: 8kb->140kb (largest 108kb) 4.549ms]
(0.142539) [toit.scheduler] INFO: running job {job: console_sync}
(0.145542) [toit.scheduler] INFO: running job {job: poll_metric_flash_usage}
(0.147686) [toit.scheduler] INFO: running job {job: poll_metric_memory_profile}
(0.156563) [toit.console] INFO: waiting for console connection to be established
(0.160970) [toit.network] DEBUG: connecting {network: Cellular (nbiot.tdc.dk)}
[gc @ 0x3ffcfa58* | soft limit reached (32796 >= 30720)]
[gc @ 0x3ffcfa58* | heap: 32kb -> 20kb | external: 0kb -> 0kb | used: 118kb | free: 12kb->124kb (largest 108kb) 5.349ms]
[gc @ 0x3ffcfa58* | soft limit reached (45084 >= 43050)]
[gc @ 0x3ffcfa58* | heap: 44kb -> 20kb | external: 0kb -> 0kb | used: 142kb | free: 12kb->100kb (largest 88kb) 5.846ms]
(0.232010) [toit] INFO: -> AT
(0.493457) [toit] INFO: -> AT
(0.754071) [toit] INFO: -> AT
(1.015268) [toit] INFO: -> AT
(1.275780) [toit] INFO: -> AT
(1.536526) [toit] INFO: -> AT
(1.797938) [toit] INFO: -> AT
(1.981057) [toit] INFO: <- OK
(2.087022) [toit] INFO: -> ATE0
(2.094810) [toit] INFO: <- OK
(2.117887) [toit] INFO: -> AT+CMEE=2
(2.123628) [toit] INFO: <- OK
(2.145898) [toit] INFO: -> AT+CGMM
(2.151152) [toit] INFO: <- FiPy
(2.154322) [toit] INFO: <- OK
(2.176440) [toit] INFO: -> AT+CGMR
(2.196854) [toit] INFO: <- UE5.4.0.2
(2.199557) [toit] INFO: <- OK
(2.221708) [toit] INFO: -> AT+SQNCCID?
(2.227243) [toit] INFO: <- +SQNCCID: "",""
(2.229866) [toit] INFO: <- OK
(2.232593) [toit.cellular] DEBUG: initialized {model: FiPy, version: UE5.4.0.2, iccid: }
(2.252308) [toit] INFO: -> AT+CFUN=4
(2.593880) [toit] INFO: <- OK
(2.616222) [toit] INFO: -> AT+CPIN?
[gc @ 0x3ffcfa58* | soft limit reached (45084 >= 43050)]
[gc @ 0x3ffcfa58* | heap: 44kb -> 24kb | external: 0kb -> 0kb | used: 149kb | free: 10kb->94kb (largest 84kb) 6.252ms]
(2.629635) [toit] INFO: <- +CME ERROR: SIM not inserted
(2.887848) [toit] INFO: -> AT+CPIN?
(2.979730) [toit] INFO: <- +CPIN: READY
(2.982892) [toit] INFO: <- OK
(3.006397) [toit] INFO: -> AT+CEREG=2
(3.015879) [toit] INFO: <- OK
(3.038382) [toit] INFO: -> AT+CPSMS=0
(3.046072) [toit] INFO: <- OK
(3.068558) [toit] INFO: -> AT+CEDRXS=0
(3.075890) [toit] INFO: <- OK
(3.106280) [toit] INFO: -> AT+SQNIBRCFG=0
(3.125190) [toit] INFO: <- OK
(3.148013) [toit] INFO: -> AT+SQNIPSCFG=1,100
(3.164965) [toit] INFO: <- OK
(3.188062) [toit] INFO: -> AT+SQNBANDSEL=0,"standard","20"
(3.257965) [toit] INFO: <- +SQNBANDSEL: <custom>
(3.260676) [toit] INFO: <- OK
(3.283190) [toit] INFO: -> AT+SQNBANDSEL=1,"standard","20"
(3.290329) [toit] INFO: <- +CME ERROR: operation not supported
(3.312714) [toit] INFO: -> AT+CGDCONT?
(3.319483) [toit] INFO: <- +CGDCONT: 1,"IP","nbiot.tdc.dk",,,,0,0,0,0,0,0,0,,0
(3.322231) [toit] INFO: <- OK
(3.344482) [toit] INFO: -> AT+CFUN=1
(3.415594) [toit] INFO: <- OK
(3.973547) [toit] INFO: <- +CEREG: 2
(3.976081) [toit] INFO: <- [URC] +CEREG [2]
(3.978311) [toit.cellular] DEBUG: attempt modem's automatic connect
(3.990742) [toit.cellular] DEBUG: connecting {operator: null, attempt: 2}
(4.006228) [toit] INFO: -> AT+COPS?
(4.013203) [toit] INFO: <- +COPS: 0
(4.015851) [toit] INFO: <- OK
(4.038430) [toit] INFO: -> AT+CEREG=2
(4.048041) [toit] INFO: <- OK
(4.070385) [toit] INFO: -> AT+CEREG?
(4.075693) [toit] INFO: <- +CEREG: 2,2
(4.078322) [toit] INFO: <- OK
(5.083315) [toit] INFO: -> AT
(5.088146) [toit] INFO: <- OK
(6.092824) [toit] INFO: -> AT
(6.106493) [toit] INFO: <- OK
(7.111013) [toit] INFO: -> AT
(7.116036) [toit] INFO: <- OK
(8.120747) [toit] INFO: -> AT
(8.125616) [toit] INFO: <- OK
(8.881357) [toit] INFO: <- +CEREG: 5,"1BBC","0059C514",7
(8.883849) [toit] INFO: <- [URC] +CEREG [5, 1BBC, 0059C514, 7]
(8.886407) [toit.cellular] DEBUG: connected successfully
(8.906290) [toit] INFO: -> AT+COPS?
(8.914349) [toit] INFO: <- +COPS: 0,2,"23801",7
(8.917050) [toit] INFO: <- OK
(8.936547) [toit.network] DEBUG: connected {network: Cellular (nbiot.tdc.dk)}
[gc @ 0x3ffcfa58* | soft limit reached (53276 >= 49194)]
[gc @ 0x3ffcfa58* | byte array allocation failed, length = 511 (heap)]
[gc @ 0x3ffcfa58* | heap: 52kb -> 24kb | external: 0kb -> 0kb | used: 165kb | free: 10kb->78kb (largest 68kb) 6.460ms]
(8.968517) [toit] INFO: -> AT+CSQ
(8.976181) [toit] INFO: <- +CSQ: 16,99
(8.979397) [toit] INFO: <- OK
(8.982993) [toit.console_conn] INFO: initialized {identity: 4c2a0869-49c3-4fe7-834e-ce3a6a9e2b7c.744.2021-09-26.device.toitware.com}
(9.051263) [toit.console_conn] INFO: connecting to console {host: 35.228.86.200:9426, common_name: device.toit.io, protocol: tls}
(9.056115) [toit] INFO: -> AT+SQNSCFG=1,1,300,0,80,50
(9.070525) [toit] INFO: <- OK
(9.094326) [toit] INFO: -> AT+SQNSD=1,0,9426,"35.228.86.200",0,0,1
(9.678406) [toit] INFO: <- OK
(9.702194) [toit] INFO: -> AT+SQNSSENDEXT=1,969
(9.708974) [toit] INFO: <- >
(9.710792) [toit] INFO: -> <969 bytes>
(9.724542) [toit] INFO: <-
(9.726928) [toit] INFO: <- OK
(9.922672) [toit] INFO: <- +SQNSRING: 1
(9.924889) [toit] INFO: <- [URC] +SQNSRING [1]
(9.945429) [toit] INFO: -> AT+SQNSI=1
(9.951561) [toit] INFO: <- +SQNSI: 1,969,143,143,0
(9.954386) [toit] INFO: <- OK
(9.976806) [toit] INFO: -> AT+SQNSRECV=1,1500
(9.982730) [toit] INFO: <- +SQNSRECV: <custom>
(9.985665) [toit] INFO: <- OK
(10.008350) [toit] INFO: -> AT+SQNSSENDEXT=1,51
(10.015150) [toit] INFO: <- >
(10.017229) [toit] INFO: -> <51 bytes>
(10.019221) [toit] INFO: <-
(10.021148) [toit] INFO: <- OK
(10.046661) [toit.console_conn] INFO: established
(10.051848) [toit] INFO: -> AT+SQNSSENDEXT=1,31
(10.058890) [toit] INFO: <- >
(10.060750) [toit] INFO: -> <31 bytes>
(10.062631) [toit] INFO: <-
[gc @ 0x3ffcfa58* | soft limit reached (53276 >= 49194)]
[gc @ 0x3ffcfa58* | heap: 52kb -> 28kb | external: 0kb -> 0kb | used: 189kb | free: 13kb->53kb (largest 40kb) 7.096ms]
(10.074786) [toit] INFO: <- OK
(10.079412) [toit.console] INFO: connected, starting main loop
(10.100725) [toit] INFO: -> AT+SQNSSENDEXT=1,52
(10.107626) [toit] INFO: <- >
(10.109197) [toit] INFO: -> <52 bytes>
(10.111072) [toit] INFO: <-
(10.113014) [toit] INFO: <- OK
(10.135667) [toit] INFO: -> AT+SQNSI=1
(10.144791) [toit] INFO: <- +SQNSI: 1,1103,143,0,134
(10.147800) [toit] INFO: <- OK
(10.159842) [toit.scheduler] INFO: running job {job: persist_metrics}
(10.170127) [toit] INFO: -> AT+SQNSSENDEXT=1,70
(10.175996) [toit] INFO: <- >
(10.177748) [toit] INFO: -> <70 bytes>
(10.179702) [toit] INFO: <-
(10.181569) [toit] INFO: <- OK
(10.204291) [toit] INFO: <- +SQNSRING: 1
(10.206569) [toit] INFO: <- [URC] +SQNSRING [1]
(10.226813) [toit] INFO: -> AT+CSQ
(10.232670) [toit] INFO: <- +CSQ: 17,99
(10.235357) [toit] INFO: <- OK
(10.238502) [toit.metrics] INFO: persisting metrics
[gc @ 0x3ffcfa58* | soft limit reached (57372 >= 55338)]
[gc @ 0x3ffcfa58* | heap: 56kb -> 32kb | external: 0kb -> 0kb | used: 197kb | free: 13kb->45kb (largest 32kb) 7.536ms]
(10.267243) [toit] INFO: -> AT+SQNSI=1
(10.271250) [toit.scheduler] INFO: running job {job: poll_metric_memory_profile}
(10.280011) [toit] INFO: <- +SQNSI: 1,1173,182,39,153
(10.282713) [toit] INFO: <- OK
(10.306105) [toit] INFO: -> AT+SQNSRECV=1,1500
(10.312656) [toit] INFO: <- +SQNSRECV: <custom>
(10.315592) [toit] INFO: <- OK
(10.338048) [toit] INFO: -> AT+SQNSI=1
(10.343866) [toit] INFO: <- +SQNSI: 1,1173,182,0,153
(10.346927) [toit] INFO: <- OK
(10.384865) [toit] INFO: <- +SQNSRING: 1
(10.387429) [toit] INFO: <- [URC] +SQNSRING [1]
(10.407979) [toit] INFO: -> AT+SQNSI=1
(10.415154) [toit] INFO: <- +SQNSI: 1,1173,253,71,0
(10.418011) [toit] INFO: <- OK
(10.440352) [toit] INFO: -> AT+SQNSRECV=1,1500
(10.446548) [toit] INFO: <- +SQNSRECV: <custom>
(10.449565) [toit] INFO: <- OK
(10.459835) [toit.time] INFO: adjusting time by -82.943991ms, accuracy: 406023332 -> 363490000 ns
(10.471878) [toit.console] INFO: synchronized with console
(10.482271) [toit] INFO: -> AT+SQNSI=1
[gc @ 0x3ffcfa58* | soft limit reached (65564 >= 61482)]
[gc @ 0x3ffcfa58* | byte array allocation failed, length = 958 (heap)]
[gc @ 0x3ffcfa58* | heap: 64kb -> 36kb | external: 0kb -> 0kb | used: 213kb | free: 13kb->29kb (largest 16kb) 8.350ms]
(10.519405) [toit] INFO: <- +SQNSI: 1,1173,253,0,0
(10.522224) [toit] INFO: <- OK
(10.545999) [toit] INFO: -> AT+SQNSSENDEXT=1,55
(10.552200) [toit] INFO: <- >
(10.553768) [toit] INFO: -> <55 bytes>
(10.555685) [toit] INFO: <-
(10.557880) [toit] INFO: <- OK
(10.580278) [toit] INFO: -> AT+SQNSSENDEXT=1,1406
(10.586269) [toit] INFO: <- >
(10.587884) [toit] INFO: -> <1406 bytes>
(10.606961) [toit] INFO: <-
(10.609100) [toit] INFO: <- OK
(10.631445) [toit] INFO: -> AT+SQNSSENDEXT=1,56
(10.637591) [toit] INFO: <- >
(10.639622) [toit] INFO: -> <56 bytes>
(10.641439) [toit] INFO: <-
(10.643311) [toit] INFO: <- OK
(10.665572) [toit] INFO: -> AT+SQNSSENDEXT=1,1237
(10.671541) [toit] INFO: <- >
(10.673131) [toit] INFO: -> <1237 bytes>
(10.690357) [toit] INFO: <-
(10.692480) [toit] INFO: <- OK
(10.714979) [toit] INFO: -> AT+SQNSSENDEXT=1,56
[gc @ 0x3ffcfa58* | soft limit reached (69660 >= 67626)]
[gc @ 0x3ffcfa58* | byte array allocation failed, length = 6 (heap)]
[gc @ 0x3ffcfa58* | heap: 68kb -> 36kb | external: 0kb -> 0kb | used: 221kb | free: 13kb->21kb (largest 8kb) 8.744ms]
(10.721254) [toit] INFO: <- >
(10.736198) [toit] INFO: -> <56 bytes>
(10.740126) [toit] INFO: <-
(10.742581) [toit] INFO: <- OK
(10.765779) [toit] INFO: -> AT+SQNSSENDEXT=1,1237
(10.771726) [toit] INFO: <- >
(10.773347) [toit] INFO: -> <1237 bytes>
(10.900670) [toit] INFO: <-
(10.902725) [toit] INFO: <- OK
(10.925085) [toit] INFO: -> AT+SQNSSENDEXT=1,56
(10.931293) [toit] INFO: <- >
(10.933092) [toit] INFO: -> <56 bytes>
(10.934909) [toit] INFO: <-
(10.936777) [toit] INFO: <- OK
(10.959000) [toit] INFO: -> AT+SQNSSENDEXT=1,560
(10.964997) [toit] INFO: <- >
(10.966634) [toit] INFO: -> <560 bytes>
(10.972930) [toit] INFO: <-
(10.975013) [toit] INFO: <- OK
(10.998472) [toit] INFO: -> AT+SQNSSENDEXT=1,55
(11.004481) [toit] INFO: <- >
(11.006359) [toit] INFO: -> <55 bytes>
(11.008455) [toit] INFO: <-
(11.010327) [toit] INFO: <- OK
(11.526842) [toit] INFO: -> AT
(11.531815) [toit] INFO: <- OK
(11.829087) [toit] INFO: <- +SQNSRING: 1
(11.831350) [toit] INFO: <- [URC] +SQNSRING [1]
(11.852001) [toit] INFO: -> AT+SQNSI=1
(11.858206) [toit] INFO: <- +SQNSI: 1,5891,296,43,0
(11.861045) [toit] INFO: <- OK
(11.883443) [toit] INFO: -> AT+SQNSRECV=1,1500
(11.889611) [toit] INFO: <- +SQNSRECV: <custom>
(11.892519) [toit] INFO: <- OK
(11.915833) [toit] INFO: -> AT+SQNSI=1
(11.922691) [toit] INFO: <- +SQNSI: 1,5891,296,0,0
(11.926057) [toit] INFO: <- OK
(12.145101) [toit] INFO: <- +SQNSRING: 1
(12.147463) [toit] INFO: <- [URC] +SQNSRING [1]
(12.168028) [toit] INFO: -> AT+SQNSI=1
(12.174361) [toit] INFO: <- +SQNSI: 1,5891,339,43,0
(12.177250) [toit] INFO: <- OK
(12.199578) [toit] INFO: -> AT+SQNSRECV=1,1500
(12.205783) [toit] INFO: <- +SQNSRECV: <custom>
(12.208990) [toit] INFO: <- OK
(12.232571) [toit] INFO: -> AT+SQNSI=1
(12.239577) [toit] INFO: <- +SQNSI: 1,5891,339,0,0
(12.242943) [toit] INFO: <- OK
(12.493865) [toit] INFO: <- +SQNSRING: 1
(12.496123) [toit] INFO: <- [URC] +SQNSRING [1]
(12.516848) [toit] INFO: -> AT+SQNSI=1
(12.522978) [toit] INFO: <- +SQNSI: 1,5891,382,43,0
(12.525805) [toit] INFO: <- OK
(12.548216) [toit] INFO: -> AT+SQNSRECV=1,1500
(12.554033) [toit] INFO: <- +SQNSRECV: <custom>
(12.556913) [toit] INFO: <- OK
(12.641444) [toit] INFO: -> AT+SQNSI=1
(12.649463) [toit] INFO: <- +SQNSI: 1,5891,382,0,0
(12.652394) [toit] INFO: <- OK
(12.664186) [toit] INFO: <- +SQNSRING: 1
(12.666436) [toit] INFO: <- [URC] +SQNSRING [1]
(12.674033) [toit] INFO: <- +SQNSRING: 1
(12.676308) [toit] INFO: <- [URC] +SQNSRING [1]
(12.696683) [toit] INFO: -> AT+SQNSI=1
(12.703052) [toit] INFO: <- +SQNSI: 1,5891,464,82,0
(12.705906) [toit] INFO: <- OK
(12.728277) [toit] INFO: -> AT+SQNSRECV=1,1500
(12.734547) [toit] INFO: <- +SQNSRECV: <custom>
(12.737576) [toit] INFO: <- OK
[gc @ 0x3ffcfa58* | soft limit reached (69660 >= 67626)]
[gc @ 0x3ffcfa58* | heap: 68kb -> 32kb | external: 0kb -> 0kb | used: 221kb | free: 13kb->21kb (largest 8kb) 7.699ms]
(12.774001) [toit] INFO: -> AT+SQNSI=1
(12.781612) [toit] INFO: <- +SQNSI: 1,5891,464,0,0
(12.784539) [toit] INFO: <- OK
(12.806870) [toit] INFO: -> AT+SQNSSENDEXT=1,55
(12.813155) [toit] INFO: <- >
(12.814707) [toit] INFO: -> <55 bytes>
(12.816458) [toit] INFO: <-
(12.818768) [toit] INFO: <- OK
(12.841443) [toit] INFO: -> AT+SQNSSENDEXT=1,36
(12.847223) [toit] INFO: <- >
(12.848839) [toit] INFO: -> <36 bytes>
(12.850593) [toit] INFO: <-
(12.852493) [toit] INFO: <- OK
(12.874715) [toit] INFO: -> AT+SQNSSENDEXT=1,56
(12.880433) [toit] INFO: <- >
(12.882111) [toit] INFO: -> <56 bytes>
(12.883877) [toit] INFO: <-
(12.885756) [toit] INFO: <- OK
(13.789393) [toit] INFO: -> AT
(13.794153) [toit] INFO: <- OK
(14.798521) [toit] INFO: -> AT
(14.803223) [toit] INFO: <- OK
(15.808405) [toit] INFO: -> AT
(15.813286) [toit] INFO: <- OK
(16.818413) [toit] INFO: -> AT
(16.823307) [toit] INFO: <- OK
(17.828604) [toit] INFO: -> AT
(17.833535) [toit] INFO: <- OK
(18.838295) [toit] INFO: -> AT
(18.842882) [toit] INFO: <- OK
(19.847277) [toit] INFO: -> AT
(19.851866) [toit] INFO: <- OK
(20.305091) [toit.scheduler] INFO: running job {job: persist_metrics}
(20.309707) [toit] INFO: -> AT+CSQ
(20.316757) [toit] INFO: <- +CSQ: 16,99
(20.319319) [toit] INFO: <- OK
(20.322364) [toit.metrics] INFO: persisting metrics
(20.336105) [toit.scheduler] INFO: running job {job: poll_metric_memory_profile}
[gc @ 0x3ffcfa58* | soft limit reached (65564 >= 61482)]
[gc @ 0x3ffcfa58* | heap: 64kb -> 32kb | external: 0kb -> 0kb | used: 213kb | free: 17kb->29kb (largest 12kb) 7.731ms]
(20.858050) [toit] INFO: -> AT
(20.863649) [toit] INFO: <- OK
(21.868332) [toit] INFO: -> AT
(21.872989) [toit] INFO: <- OK
(22.877626) [toit] INFO: -> AT
(22.882784) [toit] INFO: <- OK
(23.887339) [toit] INFO: -> AT
(23.891844) [toit] INFO: <- OK
(24.896286) [toit] INFO: -> AT
(24.900800) [toit] INFO: <- OK
(25.905134) [toit] INFO: -> AT
(25.909956) [toit] INFO: <- OK
(26.914790) [toit] INFO: -> AT
(26.919410) [toit] INFO: <- OK
(27.923807) [toit] INFO: -> AT
(27.928745) [toit] INFO: <- OK
(28.933141) [toit] INFO: -> AT
(28.937870) [toit] INFO: <- OK
(29.942406) [toit] INFO: -> AT
(29.947086) [toit] INFO: <- OK
(30.378428) [toit.scheduler] INFO: running job {job: persist_metrics}
(30.382970) [toit] INFO: -> AT+CSQ
(30.389115) [toit] INFO: <- +CSQ: 17,99
(30.392245) [toit] INFO: <- OK
(30.395357) [toit.metrics] INFO: persisting metrics
(30.416715) [toit.scheduler] INFO: running job {job: poll_metric_memory_profile}
(30.952777) [toit] INFO: -> AT
(30.958298) [toit] INFO: <- OK
(31.963013) [toit] INFO: -> AT
(31.968377) [toit] INFO: <- OK
(32.972704) [toit] INFO: -> AT
(32.977378) [toit] INFO: <- OK
(33.981839) [toit] INFO: -> AT
(33.986423) [toit] INFO: <- OK
(34.990891) [toit] INFO: -> AT
(34.995512) [toit] INFO: <- OK
[gc @ 0x3ffcfa58* | soft limit reached (65564 >= 61482)]
[gc @ 0x3ffcfa58* | string allocation failed, length = 2 (heap)]
[gc @ 0x3ffcfa58* | heap: 64kb -> 32kb | external: 0kb -> 0kb | used: 213kb | free: 17kb->29kb (largest 12kb) 7.638ms]
(36.013648) [toit] INFO: -> AT
(36.018814) [toit] INFO: <- OK
(37.023396) [toit] INFO: -> AT
(37.028102) [toit] INFO: <- OK
(38.032517) [toit] INFO: -> AT
(38.037410) [toit] INFO: <- OK
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment