slimcdk · November 29, 2021 08:51
diff --git a/1638175491.toit-sim-4786.hardware_id-4c2a0869-49c3-4fe7-834e-ce3a6a9e2b7c.firmware-v1.6.0-pre.25+c8ae5eaa0.log b/1638175491.toit-sim-4786.hardware_id-4c2a0869-49c3-4fe7-834e-ce3a6a9e2b7c.firmware-v1.6.0-pre.25+c8ae5eaa0.log
 $ toit serial reinstall --firmware v1.6.0-pre.25+c8ae5eaa0 -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
 Detecting chip type. This can take up to 10s.
 Reinstalling hardware with firmware: v1.6.0-pre.25+c8ae5eaa0 (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/773656812/otadata024137563 0x10000 /tmp/773656812/ota_0006403326"
 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 23060.2 kbit/s)...
 Hash of data verified.
 Compressed 1328096 bytes to 805206...
 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 1328096 bytes (805206 compressed) at 0x00010000 in 13.1 seconds (effective 810.5 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         |
 | broker.cn              | device.toit.io |
 | broker.port            | 9426           |
 | model.cellular.rx      | 23             |
 | model.cellular.rts     | 19             |
 | model.cellular.pwr     | 27             |
 | model.cellular.monarch | 1              |
 | model                  | esp32-4mb      |
 | broker.host            | device.toit.io |
 | model.cellular.cts     | 18             |
 | cellular.apn           | nbiot.tdc.dk   |
 | model.cellular.enabled | 1              |
 | model.cellular.tx      | 5              |
 +------------------------+----------------+


 $ 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.015330) [toit] INFO: booting toit {sdk: v1.6.0-pre.25+c8ae5eaa0, model: esp32-4mb}
 (0.021534) [toit.scheduler] INFO: set initial run_level {run_level: 4}
 (0.535297) [toit.state] INFO: starting new session {session_id: 1, session_time: 0.542471}
 [gc @ 0x3ffcf9f0* | soft limit reached (20480 >= 18432)]
 [gc @ 0x3ffcf9f0* | heap: 20kb -> 8kb | external: 0kb -> 0kb | used: 94kb | free: 9kb->149kb (largest 108kb) 1.286ms]
 (0.616434) [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.727110) [toit.console_sync] INFO: loaded last console sync {time: null}
 (0.738646) [toit.scheduler] INFO: running job {job: console_sync}
 (0.741314) [toit.scheduler] INFO: running job {job: flush_metrics}
 (0.743172) [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) 1.905ms]
 (0.748424) [toit.scheduler] INFO: running job {job: poll_metric_memory_profile}
 (0.779894) [toit.console] INFO: waiting for console connection to be established
 (0.784441) [toit.network] DEBUG: connecting {network: Cellular (nbiot.tdc.dk)}
 (0.792119) [toit.metrics] INFO: persisting metrics
 (0.795301) [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) 2.142ms]
 (1.002897) [toit] INFO: -> AT
 (1.264228) [toit] INFO: -> AT
 (1.524710) [toit] INFO: -> AT
 (1.785297) [toit] INFO: -> AT
 (2.045911) [toit] INFO: -> AT
 [gc @ 0x3ffcf9f0* | soft limit reached (45084 >= 43050)]
 [gc @ 0x3ffcf9f0* | string allocation failed, length = 6 (heap)]
 [gc @ 0x3ffcf9f0* | heap: 44kb -> 28kb | external: 0kb -> 0kb | used: 149kb | free: 6kb->94kb (largest 88kb) 2.475ms]
 (2.306484) [toit] INFO: -> AT
 (2.567863) [toit] INFO: -> AT
 (2.740218) [toit] INFO: <- OK
 (2.845234) [toit] INFO: -> ATE0
 (2.852888) [toit] INFO: <- OK
 (2.875268) [toit] INFO: -> AT+CMEE=2
 (2.881072) [toit] INFO: <- OK
 (2.903455) [toit] INFO: -> AT+CGMM
 (2.909134) [toit] INFO: <- FiPy
 (2.911772) [toit] INFO: <- OK
 (2.933633) [toit] INFO: -> AT+CGMR
 (2.954395) [toit] INFO: <- UE5.4.0.2
 (2.957061) [toit] INFO: <- OK
 (2.979010) [toit] INFO: -> AT+SQNCCID?
 (2.984551) [toit] INFO: <- +SQNCCID: "",""
 (2.987126) [toit] INFO: <- OK
 (2.989645) [toit.cellular] DEBUG: initialized {model: FiPy, version: UE5.4.0.2, iccid: }
 (3.009391) [toit] INFO: -> AT+CFUN=4
 (3.075952) [toit] INFO: <- OK
 (3.098236) [toit] INFO: -> AT+CPIN?
 (3.103979) [toit] INFO: <- +CME ERROR: SIM not inserted
 (3.358579) [toit] INFO: -> AT+CPIN?
 (3.364247) [toit] INFO: <- +CME ERROR: SIM not inserted
 (3.618574) [toit] INFO: -> AT+CPIN?
 (3.738380) [toit] INFO: <- +CPIN: READY
 (3.740843) [toit] INFO: <- OK
 (3.763041) [toit] INFO: -> AT+CPSMS=0
 (3.770448) [toit] INFO: <- OK
 (3.793072) [toit] INFO: -> AT+CEDRXS=0
 (3.801007) [toit] INFO: <- OK
 (3.823402) [toit] INFO: -> AT+SQNIBRCFG=0
 (3.843236) [toit] INFO: <- OK
 (3.865786) [toit] INFO: -> AT+SQNIPSCFG=1,100
 (3.883550) [toit] INFO: <- OK
 (3.906111) [toit] INFO: -> AT+SQNBANDSEL=0,"standard","20"
 (3.975918) [toit] INFO: <- +SQNBANDSEL: <custom>
 (3.978496) [toit] INFO: <- OK
 (4.000843) [toit] INFO: -> AT+SQNBANDSEL=1,"standard","20"
 (4.008686) [toit] INFO: <- +CME ERROR: operation not supported
 (4.030748) [toit] INFO: -> AT+CGDCONT?
 (4.037047) [toit] INFO: <- +CGDCONT: 1,"IP","nbiot.tdc.dk",,,,0,0,0,0,0,0,0,,0
 (4.039857) [toit] INFO: <- OK
 (4.062071) [toit] INFO: -> AT+CFUN=1,1
 (4.121336) [toit] INFO: <- OK
 (4.176838) [toit] INFO: <- *ignored* [URC] +SHUTDOWN []
 (5.840490) [toit] INFO: <- *ignored* [URC] +SYSSTART []
 (9.046558) [toit] INFO: -> AT+CFUN=4
 (9.949139) [toit] INFO: <- OK
 (9.951936) [toit.cellular] DEBUG: failed, detach from network
 (9.970322) [toit.network] DEBUG: error connecting {network: Cellular (nbiot.tdc.dk), error: DEADLINE_EXCEEDED}
 (9.977480) [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:
 ----
 [gc @ 0x3ffcf9f0* | soft limit reached (57828 >= 55338)]
 [gc @ 0x3ffcf9f0* | byte array allocation failed, length = 441 (heap)]
 [gc @ 0x3ffcf9f0* | heap: 56kb -> 24kb | external: 0kb -> 0kb | used: 173kb | free: 10kb->70kb (largest 60kb) 2.492ms]
 toit serial decode WyNVBVVYU1UXdjEuNi4wLXByZS4yNStjOGFlNWVhYTBTVQllc3AzMi00bWJbJFUjVRAAAAAAAAAAAAAAAAAAAAAAWyNVBFVFU1UJRVhDRVBUSU9OU1URRkFJTEVEIFRPIENPTk5FQ1RbI1UCVVNbI1UbWyNVA1VGVQBsAAKuMVsjVQNVRlUBbAACrY5bI1UDVUZVAmwAAqoaWyNVA1VGVQNJJWhbI1UDVUZVBEklfVsjVQNVRlUFSSUCWyNVA1VGVQZsAAKqV1sjVQNVRlUHbAACqfNbI1UDVUZVCGwAAQP4WyNVA1VGVQlsAAGg+FsjVQNVRlUKbAABFD5bI1UDVUZVC2wAARRXWyNVA1VGVQxsAAET1FsjVQNVRlUNSSVoWyNVA1VGVQ5JJX1bI1UDVUZVD0klWlsjVQNVRlUQbAABE+1bI1UDVUZVEWwAARQDWyNVA1VGVRJJMqlbI1UDVUZVE2wAAkxYWyNVA1VGVRRJJWhbI1UDVUZVFUklfVsjVQNVRlUWSSUCWyNVA1VGVRdsAAJMbVsjVQNVRlUYbAACTIRbI1UDVUZVGUkyPlsjVQNVRlUaSTLZ
 (10.096857) [toit] INFO: -> AT+CFUN=0
 (10.102575) [toit.scheduler] INFO: changing run level {run_level: 0}
 (10.106042) [toit.scheduler] INFO: going into deep sleep {ms: 4984}
 (10.108017) [toit.metrics] INFO: persisting metrics
 (10.350752) [toit] INFO: <- OK
 (10.459359) [toit] INFO: entering deep sleep {ms: 4984}
 Entering deep sleep for 4984ms
 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.015316) [toit] INFO: booting toit {sdk: v1.6.0-pre.25+c8ae5eaa0, model: esp32-4mb}
 (0.021520) [toit.scheduler] INFO: set initial run_level {run_level: 4}
 (0.054469) [toit.state] INFO: resumed session {session_id: 1, session_time: 15.571689}
 [gc @ 0x3ffcfa58* | soft limit reached (20480 >= 18432)]
 [gc @ 0x3ffcfa58* | heap: 20kb -> 8kb | external: 0kb -> 0kb | used: 93kb | free: 9kb->149kb (largest 108kb) 1.208ms]
 (0.073476) [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.110030) [toit.console_sync] INFO: loaded last console sync {time: 10.083517}
 (0.125710) [toit.scheduler] INFO: running job {job: console_sync}
 [gc @ 0x3ffcfa58* | soft limit reached (24576 >= 24576)]
 [gc @ 0x3ffcfa58* | heap: 24kb -> 16kb | external: 0kb -> 0kb | used: 102kb | free: 8kb->140kb (largest 108kb) 1.864ms]
 (0.128491) [toit.scheduler] INFO: running job {job: poll_metric_flash_usage}
 (0.145388) [toit.scheduler] INFO: running job {job: poll_metric_memory_profile}
 (0.154941) [toit.console] INFO: waiting for console connection to be established
 (0.159494) [toit.network] DEBUG: connecting {network: Cellular (nbiot.tdc.dk)}
 [gc @ 0x3ffcfa58* | soft limit reached (36892 >= 36864)]
 [gc @ 0x3ffcfa58* | heap: 36kb -> 20kb | external: 0kb -> 0kb | used: 126kb | free: 12kb->116kb (largest 104kb) 2.029ms]
 (0.206708) [toit] INFO: -> AT
 (0.468640) [toit] INFO: -> AT
 (0.729128) [toit] INFO: -> AT
 (0.989708) [toit] INFO: -> AT
 (1.250410) [toit] INFO: -> AT
 (1.516182) [toit] INFO: -> AT
 (1.777698) [toit] INFO: -> AT
 (2.038313) [toit] INFO: -> AT
 (2.298923) [toit] INFO: -> AT
 (2.559511) [toit] INFO: -> AT
 (2.820150) [toit] INFO: -> AT
 (2.847323) [toit] INFO: <- OK
 (2.952353) [toit] INFO: -> ATE0
 (2.960028) [toit] INFO: <- OK
 (2.982436) [toit] INFO: -> AT+CMEE=2
 [gc @ 0x3ffcfa58* | soft limit reached (45084 >= 43050)]
 [gc @ 0x3ffcfa58* | string allocation failed, length = 2 (heap)]
 [gc @ 0x3ffcfa58* | heap: 44kb -> 24kb | external: 0kb -> 0kb | used: 149kb | free: 10kb->94kb (largest 84kb) 2.447ms]
 (3.005794) [toit] INFO: <- OK
 (3.028052) [toit] INFO: -> AT+CGMM
 (3.033575) [toit] INFO: <- FiPy
 (3.036217) [toit] INFO: <- OK
 (3.058086) [toit] INFO: -> AT+CGMR
 (3.079310) [toit] INFO: <- UE5.4.0.2
 (3.081932) [toit] INFO: <- OK
 (3.103918) [toit] INFO: -> AT+SQNCCID?
 (3.109595) [toit] INFO: <- +SQNCCID: "",""
 (3.112120) [toit] INFO: <- OK
 (3.114588) [toit.cellular] DEBUG: initialized {model: FiPy, version: UE5.4.0.2, iccid: }
 (3.134336) [toit] INFO: -> AT+CFUN=4
 (3.201293) [toit] INFO: <- OK
 (3.223844) [toit] INFO: -> AT+CPIN?
 (3.229535) [toit] INFO: <- +CME ERROR: SIM not inserted
 (3.483950) [toit] INFO: -> AT+CPIN?
 (3.489627) [toit] INFO: <- +CME ERROR: SIM not inserted
 (3.743953) [toit] INFO: -> AT+CPIN?
 (3.866344) [toit] INFO: <- +CPIN: READY
 (3.869066) [toit] INFO: <- OK
 (3.891244) [toit] INFO: -> AT+CPSMS=0
 (3.905946) [toit] INFO: <- OK
 (3.928538) [toit] INFO: -> AT+CEDRXS=0
 (3.935944) [toit] INFO: <- OK
 (3.958332) [toit] INFO: -> AT+SQNIBRCFG=0
 (3.977759) [toit] INFO: <- OK
 (4.000313) [toit] INFO: -> AT+SQNIPSCFG=1,100
 (4.017690) [toit] INFO: <- OK
 (4.040330) [toit] INFO: -> AT+SQNBANDSEL=0,"standard","20"
 (4.109306) [toit] INFO: <- +SQNBANDSEL: <custom>
 (4.112062) [toit] INFO: <- OK
 (4.134389) [toit] INFO: -> AT+SQNBANDSEL=1,"standard","20"
 (4.142079) [toit] INFO: <- +CME ERROR: operation not supported
 (4.164045) [toit] INFO: -> AT+CGDCONT?
 (4.170517) [toit] INFO: <- +CGDCONT: 1,"IP","nbiot.tdc.dk",,,,0,0,0,0,0,0,0,,0
 (4.172969) [toit] INFO: <- OK
 (4.195525) [toit] INFO: -> AT+CFUN=1,1
 (4.323843) [toit] INFO: <- OK
 (4.684849) [toit] INFO: <- *ignored* [URC] +SHUTDOWN []
 (6.406803) [toit] INFO: <- *ignored* [URC] +SYSSTART []
 (9.179592) [toit] INFO: -> AT+CFUN=4
 (9.406384) [toit] INFO: <- OK
 (9.408950) [toit.cellular] DEBUG: failed, detach from network
 (9.420281) [toit.network] DEBUG: error connecting {network: Cellular (nbiot.tdc.dk), error: DEADLINE_EXCEEDED}
 (9.428217) [toit.network] WARN: failed to connect to any network
 [gc @ 0x3ffcfa58* | soft limit reached (53276 >= 49194)]
 [gc @ 0x3ffcfa58* | byte array allocation failed, length = 68 (heap)]
 [gc @ 0x3ffcfa58* | heap: 52kb -> 24kb | external: 0kb -> 0kb | used: 165kb | free: 10kb->78kb (largest 68kb) 2.423ms]
 ----
 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 WyNVBVVYU1UXdjEuNi4wLXByZS4yNStjOGFlNWVhYTBTVQllc3AzMi00bWJbJFUjVRAAAAAAAAAAAAAAAAAAAAAAWyNVBFVFU1UJRVhDRVBUSU9OU1URRkFJTEVEIFRPIENPTk5FQ1RbI1UCVVNbI1UbWyNVA1VGVQBsAAKuMVsjVQNVRlUBbAACrY5bI1UDVUZVAmwAAqoaWyNVA1VGVQNJJWhbI1UDVUZVBEklfVsjVQNVRlUFSSUCWyNVA1VGVQZsAAKqV1sjVQNVRlUHbAACqfNbI1UDVUZVCGwAAQP4WyNVA1VGVQlsAAGg+FsjVQNVRlUKbAABFD5bI1UDVUZVC2wAARRXWyNVA1VGVQxsAAET1FsjVQNVRlUNSSVoWyNVA1VGVQ5JJX1bI1UDVUZVD0klWlsjVQNVRlUQbAABE+1bI1UDVUZVEWwAARQDWyNVA1VGVRJJMqlbI1UDVUZVE2wAAkxYWyNVA1VGVRRJJWhbI1UDVUZVFUklfVsjVQNVRlUWSSUCWyNVA1VGVRdsAAJMbVsjVQNVRlUYbAACTIRbI1UDVUZVGUkyPlsjVQNVRlUaSTLZ
 (9.544140) [toit] INFO: -> AT+CFUN=0
 (9.549890) [toit.scheduler] INFO: changing run level {run_level: 0}
 (9.553624) [toit.scheduler] INFO: going into deep sleep {ms: 4989}
 (9.555502) [toit.metrics] INFO: persisting metrics
 (9.804499) [toit] INFO: <- OK
 (9.912240) [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.015317) [toit] INFO: booting toit {sdk: v1.6.0-pre.25+c8ae5eaa0, model: esp32-4mb}
 (0.021521) [toit.scheduler] INFO: set initial run_level {run_level: 4}
 (0.054313) [toit.state] INFO: resumed session {session_id: 1, session_time: 30.523158}
 [gc @ 0x3ffcfa58* | soft limit reached (20480 >= 18432)]
 [gc @ 0x3ffcfa58* | heap: 20kb -> 8kb | external: 0kb -> 0kb | used: 93kb | free: 9kb->149kb (largest 108kb) 1.194ms]
 (0.073391) [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.110128) [toit.console_sync] INFO: loaded last console sync {time: 25.030823}
 (0.125972) [toit.scheduler] INFO: running job {job: console_sync}
 [gc @ 0x3ffcfa58* | soft limit reached (24576 >= 24576)]
 [gc @ 0x3ffcfa58* | heap: 24kb -> 16kb | external: 0kb -> 0kb | used: 102kb | free: 8kb->140kb (largest 108kb) 1.838ms]
 (0.138959) [toit.scheduler] INFO: running job {job: poll_metric_flash_usage}
 (0.145550) [toit.scheduler] INFO: running job {job: poll_metric_memory_profile}
 (0.155185) [toit.console] INFO: waiting for console connection to be established
 (0.159755) [toit.network] DEBUG: connecting {network: Cellular (nbiot.tdc.dk)}
 [gc @ 0x3ffcfa58* | soft limit reached (36892 >= 36864)]
 [gc @ 0x3ffcfa58* | heap: 36kb -> 20kb | external: 0kb -> 0kb | used: 126kb | free: 12kb->116kb (largest 104kb) 2.034ms]
 (0.208061) [toit] INFO: -> AT
 (0.469417) [toit] INFO: -> AT
 (0.729901) [toit] INFO: -> AT
 (0.990481) [toit] INFO: -> AT
 (1.251373) [toit] INFO: -> AT
 (1.516188) [toit] INFO: -> AT
 (1.777697) [toit] INFO: -> AT
 (1.948590) [toit] INFO: <- OK
 (2.053828) [toit] INFO: -> ATE0
 (2.061607) [toit] INFO: <- OK
 (2.084092) [toit] INFO: -> AT+CMEE=2
 (2.089685) [toit] INFO: <- OK
 [gc @ 0x3ffcfa58* | soft limit reached (45084 >= 43050)]
 [gc @ 0x3ffcfa58* | byte array allocation failed, length = 64 (heap)]
 [gc @ 0x3ffcfa58* | heap: 44kb -> 20kb | external: 0kb -> 0kb | used: 149kb | free: 10kb->94kb (largest 84kb) 2.351ms]
 (2.123500) [toit] INFO: -> AT+CGMM
 (2.129581) [toit] INFO: <- FiPy
 (2.132123) [toit] INFO: <- OK
 (2.154075) [toit] INFO: -> AT+CGMR
 (2.174756) [toit] INFO: <- UE5.4.0.2
 (2.177343) [toit] INFO: <- OK
 (2.206008) [toit] INFO: -> AT+SQNCCID?
 (2.211393) [toit] INFO: <- +SQNCCID: "",""
 (2.213731) [toit] INFO: <- OK
 (2.216176) [toit.cellular] DEBUG: initialized {model: FiPy, version: UE5.4.0.2, iccid: }
 (2.235922) [toit] INFO: -> AT+CFUN=4
 (2.302134) [toit] INFO: <- OK
 (2.324388) [toit] INFO: -> AT+CPIN?
 (2.330046) [toit] INFO: <- +CME ERROR: SIM not inserted
 (2.584738) [toit] INFO: -> AT+CPIN?
 (2.590349) [toit] INFO: <- +CME ERROR: SIM not inserted
 (2.844661) [toit] INFO: -> AT+CPIN?
 (2.969301) [toit] INFO: <- +CPIN: READY
 (2.971637) [toit] INFO: <- OK
 (2.993833) [toit] INFO: -> AT+CPSMS=0
 (3.001072) [toit] INFO: <- OK
 (3.023857) [toit] INFO: -> AT+CEDRXS=0
 (3.031009) [toit] INFO: <- OK
 (3.053445) [toit] INFO: -> AT+SQNIBRCFG=0
 (3.073039) [toit] INFO: <- OK
 (3.095632) [toit] INFO: -> AT+SQNIPSCFG=1,100
 (3.121390) [toit] INFO: <- OK
 (3.144017) [toit] INFO: -> AT+SQNBANDSEL=0,"standard","20"
 (3.216990) [toit] INFO: <- +SQNBANDSEL: <custom>
 (3.219418) [toit] INFO: <- OK
 (3.241781) [toit] INFO: -> AT+SQNBANDSEL=1,"standard","20"
 (3.249366) [toit] INFO: <- +CME ERROR: operation not supported
 (3.271586) [toit] INFO: -> AT+CGDCONT?
 (3.278236) [toit] INFO: <- +CGDCONT: 1,"IP","nbiot.tdc.dk",,,,0,0,0,0,0,0,0,,0
 (3.280775) [toit] INFO: <- OK
 (3.303248) [toit] INFO: -> AT+CFUN=1,1
 (3.362414) [toit] INFO: <- OK
 (3.417728) [toit] INFO: <- *ignored* [URC] +SHUTDOWN []
 (5.221785) [toit] INFO: <- *ignored* [URC] +SYSSTART []
 (8.287788) [toit] INFO: -> AT+CFUN=4
 (9.231435) [toit] INFO: <- OK
 (9.233694) [toit.cellular] DEBUG: failed, detach from network
 (9.250804) [toit.network] DEBUG: error connecting {network: Cellular (nbiot.tdc.dk), error: DEADLINE_EXCEEDED}
 [gc @ 0x3ffcfa58* | soft limit reached (45084 >= 43050)]
 [gc @ 0x3ffcfa58* | byte array allocation failed, length = 511 (heap)]
 [gc @ 0x3ffcfa58* | heap: 44kb -> 24kb | external: 0kb -> 0kb | used: 149kb | free: 10kb->94kb (largest 84kb) 2.402ms]
 (9.276934) [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 WyNVBVVYU1UXdjEuNi4wLXByZS4yNStjOGFlNWVhYTBTVQllc3AzMi00bWJbJFUjVRAAAAAAAAAAAAAAAAAAAAAAWyNVBFVFU1UJRVhDRVBUSU9OU1URRkFJTEVEIFRPIENPTk5FQ1RbI1UCVVNbI1UbWyNVA1VGVQBsAAKuMVsjVQNVRlUBbAACrY5bI1UDVUZVAmwAAqoaWyNVA1VGVQNJJWhbI1UDVUZVBEklfVsjVQNVRlUFSSUCWyNVA1VGVQZsAAKqV1sjVQNVRlUHbAACqfNbI1UDVUZVCGwAAQP4WyNVA1VGVQlsAAGg+FsjVQNVRlUKbAABFD5bI1UDVUZVC2wAARRXWyNVA1VGVQxsAAET1FsjVQNVRlUNSSVoWyNVA1VGVQ5JJX1bI1UDVUZVD0klWlsjVQNVRlUQbAABE+1bI1UDVUZVEWwAARQDWyNVA1VGVRJJMqlbI1UDVUZVE2wAAkxYWyNVA1VGVRRJJWhbI1UDVUZVFUklfVsjVQNVRlUWSSUCWyNVA1VGVRdsAAJMbVsjVQNVRlUYbAACTIRbI1UDVUZVGUkyPlsjVQNVRlUaSTLZ
 (9.374722) [toit] INFO: -> AT+CFUN=0
 (9.380425) [toit.scheduler] INFO: changing run level {run_level: 0}
 (9.383873) [toit.scheduler] INFO: going into deep sleep {ms: 4989}
 (9.385751) [toit.metrics] INFO: persisting metrics
 (9.631454) [toit] INFO: <- OK
 (9.739444) [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.015332) [toit] INFO: booting toit {sdk: v1.6.0-pre.25+c8ae5eaa0, model: esp32-4mb}
 (0.021536) [toit.scheduler] INFO: set initial run_level {run_level: 4}
 (0.054199) [toit.state] INFO: resumed session {session_id: 1, session_time: 45.309801}
 [gc @ 0x3ffcfa58* | soft limit reached (20480 >= 18432)]
 [gc @ 0x3ffcfa58* | heap: 20kb -> 8kb | external: 0kb -> 0kb | used: 93kb | free: 9kb->149kb (largest 108kb) 1.185ms]
 (0.073526) [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.110215) [toit.console_sync] INFO: loaded last console sync {time: 39.820683}
 [gc @ 0x3ffcfa58* | soft limit reached (24576 >= 24576)]
 [gc @ 0x3ffcfa58* | heap: 24kb -> 16kb | external: 0kb -> 0kb | used: 102kb | free: 8kb->140kb (largest 108kb) 1.752ms]
 (0.131857) [toit.scheduler] INFO: running job {job: console_sync}
 (0.134663) [toit.scheduler] INFO: running job {job: poll_metric_flash_usage}
 (0.140121) [toit.scheduler] INFO: running job {job: poll_metric_memory_profile}
 (0.149794) [toit.console] INFO: waiting for console connection to be established
 (0.154359) [toit.network] DEBUG: connecting {network: Cellular (nbiot.tdc.dk)}
 [gc @ 0x3ffcfa58* | soft limit reached (36892 >= 36864)]
 [gc @ 0x3ffcfa58* | heap: 36kb -> 20kb | external: 0kb -> 0kb | used: 126kb | free: 12kb->116kb (largest 104kb) 2.052ms]
 (0.203743) [toit] INFO: -> AT
 (0.465097) [toit] INFO: -> AT
 (0.725593) [toit] INFO: -> AT
 (0.986170) [toit] INFO: -> AT
 (1.247797) [toit] INFO: -> AT
 [gc @ 0x3ffcfa58* | soft limit reached (45084 >= 43050)]
 [gc @ 0x3ffcfa58* | string allocation failed, length = 6 (heap)]
 [gc @ 0x3ffcfa58* | heap: 44kb -> 24kb | external: 0kb -> 0kb | used: 149kb | free: 6kb->94kb (largest 88kb) 2.431ms]
 (1.516178) [toit] INFO: -> AT
 (1.777809) [toit] INFO: -> AT
 (1.946534) [toit] INFO: <- OK
 (2.051764) [toit] INFO: -> ATE0
 (2.059436) [toit] INFO: <- OK
 (2.082202) [toit] INFO: -> AT+CMEE=2
 (2.087700) [toit] INFO: <- OK
 (2.110203) [toit] INFO: -> AT+CGMM
 (2.115577) [toit] INFO: <- FiPy
 (2.118151) [toit] INFO: <- OK
 (2.140091) [toit] INFO: -> AT+CGMR
 (2.160776) [toit] INFO: <- UE5.4.0.2
 (2.163330) [toit] INFO: <- OK
 (2.185341) [toit] INFO: -> AT+SQNCCID?
 (2.190677) [toit] INFO: <- +SQNCCID: "",""
 (2.193071) [toit] INFO: <- OK
 (2.195531) [toit.cellular] DEBUG: initialized {model: FiPy, version: UE5.4.0.2, iccid: }
 (2.215335) [toit] INFO: -> AT+CFUN=4
 (2.281552) [toit] INFO: <- OK
 (2.305977) [toit] INFO: -> AT+CPIN?
 (2.311618) [toit] INFO: <- +CME ERROR: SIM not inserted
 (2.566234) [toit] INFO: -> AT+CPIN?
 (2.571820) [toit] INFO: <- +CME ERROR: SIM not inserted
 (2.826055) [toit] INFO: -> AT+CPIN?
 (2.946793) [toit] INFO: <- +CPIN: READY
 (2.949144) [toit] INFO: <- OK
 (2.971320) [toit] INFO: -> AT+CPSMS=0
 (2.978669) [toit] INFO: <- OK
 (3.006529) [toit] INFO: -> AT+CEDRXS=0
 (3.013796) [toit] INFO: <- OK
 (3.036222) [toit] INFO: -> AT+SQNIBRCFG=0
 (3.055842) [toit] INFO: <- OK
 (3.078396) [toit] INFO: -> AT+SQNIPSCFG=1,100
 (3.095614) [toit] INFO: <- OK
 (3.118305) [toit] INFO: -> AT+SQNBANDSEL=0,"standard","20"
 (3.187958) [toit] INFO: <- +SQNBANDSEL: <custom>
 (3.190397) [toit] INFO: <- OK
 (3.212745) [toit] INFO: -> AT+SQNBANDSEL=1,"standard","20"
 (3.220496) [toit] INFO: <- +CME ERROR: operation not supported
 (3.242478) [toit] INFO: -> AT+CGDCONT?
 (3.249205) [toit] INFO: <- +CGDCONT: 1,"IP","nbiot.tdc.dk",,,,0,0,0,0,0,0,0,,0
 (3.251740) [toit] INFO: <- OK
 (3.274071) [toit] INFO: -> AT+CFUN=1,1
 (3.333635) [toit] INFO: <- OK
 (3.388934) [toit] INFO: <- *ignored* [URC] +SHUTDOWN []
 (5.020990) [toit] INFO: <- *ignored* [URC] +SYSSTART []
 (8.259090) [toit] INFO: -> AT+CFUN=4
 (9.774878) [toit] INFO: <- OK
 (9.777451) [toit.cellular] DEBUG: failed, detach from network
 (9.790808) [toit.network] DEBUG: error connecting {network: Cellular (nbiot.tdc.dk), error: DEADLINE_EXCEEDED}
 [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) 2.415ms]
 (9.816976) [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 WyNVBVVYU1UXdjEuNi4wLXByZS4yNStjOGFlNWVhYTBTVQllc3AzMi00bWJbJFUjVRAAAAAAAAAAAAAAAAAAAAAAWyNVBFVFU1UJRVhDRVBUSU9OU1URRkFJTEVEIFRPIENPTk5FQ1RbI1UCVVNbI1UbWyNVA1VGVQBsAAKuMVsjVQNVRlUBbAACrY5bI1UDVUZVAmwAAqoaWyNVA1VGVQNJJWhbI1UDVUZVBEklfVsjVQNVRlUFSSUCWyNVA1VGVQZsAAKqV1sjVQNVRlUHbAACqfNbI1UDVUZVCGwAAQP4WyNVA1VGVQlsAAGg+FsjVQNVRlUKbAABFD5bI1UDVUZVC2wAARRXWyNVA1VGVQxsAAET1FsjVQNVRlUNSSVoWyNVA1VGVQ5JJX1bI1UDVUZVD0klWlsjVQNVRlUQbAABE+1bI1UDVUZVEWwAARQDWyNVA1VGVRJJMqlbI1UDVUZVE2wAAkxYWyNVA1VGVRRJJWhbI1UDVUZVFUklfVsjVQNVRlUWSSUCWyNVA1VGVRdsAAJMbVsjVQNVRlUYbAACTIRbI1UDVUZVGUkyPlsjVQNVRlUaSTLZ
 (9.915669) [toit] INFO: -> AT+CFUN=0
 (9.921219) [toit.scheduler] INFO: changing run level {run_level: 0}
 (9.924964) [toit.scheduler] INFO: going into deep sleep {ms: 4989}
 (9.926843) [toit.metrics] INFO: persisting metrics
 (10.172474) [toit] INFO: <- OK
 (10.280177) [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.015316) [toit] INFO: booting toit {sdk: v1.6.0-pre.25+c8ae5eaa0, model: esp32-4mb}
 (0.021520) [toit.scheduler] INFO: set initial run_level {run_level: 4}
 (0.053999) [toit.state] INFO: resumed session {session_id: 1, session_time: 60.629178}
 [gc @ 0x3ffcfa58* | soft limit reached (20480 >= 18432)]
 [gc @ 0x3ffcfa58* | byte array allocation failed, length = 20 (heap)]
 [gc @ 0x3ffcfa58* | heap: 20kb -> 8kb | external: 0kb -> 0kb | used: 94kb | free: 9kb->149kb (largest 108kb) 1.201ms]
 (0.079897) [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.119986) [toit.console_sync] INFO: loaded last console sync {time: 55.140531}
 [gc @ 0x3ffcfa58* | soft limit reached (24576 >= 24576)]
 [gc @ 0x3ffcfa58* | heap: 24kb -> 16kb | external: 0kb -> 0kb | used: 102kb | free: 8kb->140kb (largest 108kb) 1.862ms]
 (0.136188) [toit.scheduler] INFO: running job {job: console_sync}
 (0.148446) [toit.scheduler] INFO: running job {job: poll_metric_flash_usage}
 (0.154959) [toit.scheduler] INFO: running job {job: poll_metric_memory_profile}
 (0.164724) [toit.console] INFO: waiting for console connection to be established
 (0.169216) [toit.network] DEBUG: connecting {network: Cellular (nbiot.tdc.dk)}
 [gc @ 0x3ffcfa58* | soft limit reached (36892 >= 36864)]
 [gc @ 0x3ffcfa58* | heap: 36kb -> 20kb | external: 0kb -> 0kb | used: 126kb | free: 12kb->116kb (largest 104kb) 2.022ms]
 (0.219945) [toit] INFO: -> AT
 (0.481309) [toit] INFO: -> AT
 (0.741795) [toit] INFO: -> AT
 (1.006069) [toit] INFO: -> AT
 (1.267565) [toit] INFO: -> AT
 [gc @ 0x3ffcfa58* | soft limit reached (45084 >= 43050)]
 [gc @ 0x3ffcfa58* | string allocation failed, length = 6 (heap)]
 [gc @ 0x3ffcfa58* | heap: 44kb -> 24kb | external: 0kb -> 0kb | used: 149kb | free: 6kb->94kb (largest 88kb) 2.424ms]
 (1.528150) [toit] INFO: -> AT
 (1.788850) [toit] INFO: -> AT
 (1.964034) [toit] INFO: <- OK
 (2.069273) [toit] INFO: -> ATE0
 (2.077151) [toit] INFO: <- OK
 (2.106325) [toit] INFO: -> AT+CMEE=2
 (2.112262) [toit] INFO: <- OK
 (2.134323) [toit] INFO: -> AT+CGMM
 (2.139621) [toit] INFO: <- FiPy
 (2.142191) [toit] INFO: <- OK
 (2.164089) [toit] INFO: -> AT+CGMR
 (2.184928) [toit] INFO: <- UE5.4.0.2
 (2.187487) [toit] INFO: <- OK
 (2.209493) [toit] INFO: -> AT+SQNCCID?
 (2.214842) [toit] INFO: <- +SQNCCID: "",""
 (2.217244) [toit] INFO: <- OK
 (2.219713) [toit.cellular] DEBUG: initialized {model: FiPy, version: UE5.4.0.2, iccid: }
 (2.239505) [toit] INFO: -> AT+CFUN=4
 (2.305740) [toit] INFO: <- OK
 (2.327997) [toit] INFO: -> AT+CPIN?
 (2.333585) [toit] INFO: <- +CME ERROR: SIM not inserted
 (2.588188) [toit] INFO: -> AT+CPIN?
 (2.593766) [toit] INFO: <- +CME ERROR: SIM not inserted
 (2.848005) [toit] INFO: -> AT+CPIN?
 (2.973598) [toit] INFO: <- +CPIN: READY
 (2.975946) [toit] INFO: <- OK
 (3.006392) [toit] INFO: -> AT+CPSMS=0
 (3.013674) [toit] INFO: <- OK
 (3.036250) [toit] INFO: -> AT+CEDRXS=0
 (3.043411) [toit] INFO: <- OK
 (3.065834) [toit] INFO: -> AT+SQNIBRCFG=0
 (3.085400) [toit] INFO: <- OK
 (3.108045) [toit] INFO: -> AT+SQNIPSCFG=1,100
 (3.125051) [toit] INFO: <- OK
 (3.147967) [toit] INFO: -> AT+SQNBANDSEL=0,"standard","20"
 (3.217979) [toit] INFO: <- +SQNBANDSEL: <custom>
 (3.220395) [toit] INFO: <- OK
 (3.242759) [toit] INFO: -> AT+SQNBANDSEL=1,"standard","20"
 (3.250544) [toit] INFO: <- +CME ERROR: operation not supported
 (3.272515) [toit] INFO: -> AT+CGDCONT?
 (3.279287) [toit] INFO: <- +CGDCONT: 1,"IP","nbiot.tdc.dk",,,,0,0,0,0,0,0,0,,0
 (3.281826) [toit] INFO: <- OK
 (3.304236) [toit] INFO: -> AT+CFUN=1,1
 (3.363652) [toit] INFO: <- OK
 (3.510080) [toit] INFO: <- *ignored* [URC] +SHUTDOWN []
 (5.629040) [toit] INFO: <- *ignored* [URC] +SYSSTART []
 (8.289138) [toit] INFO: -> AT+CFUN=4
 (9.150994) [toit] INFO: <- OK
 (9.153237) [toit.cellular] DEBUG: failed, detach from network
 (9.170814) [toit.network] DEBUG: error connecting {network: Cellular (nbiot.tdc.dk), error: DEADLINE_EXCEEDED}
 [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) 2.411ms]
 (9.197198) [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 WyNVBVVYU1UXdjEuNi4wLXByZS4yNStjOGFlNWVhYTBTVQllc3AzMi00bWJbJFUjVRAAAAAAAAAAAAAAAAAAAAAAWyNVBFVFU1UJRVhDRVBUSU9OU1URRkFJTEVEIFRPIENPTk5FQ1RbI1UCVVNbI1UbWyNVA1VGVQBsAAKuMVsjVQNVRlUBbAACrY5bI1UDVUZVAmwAAqoaWyNVA1VGVQNJJWhbI1UDVUZVBEklfVsjVQNVRlUFSSUCWyNVA1VGVQZsAAKqV1sjVQNVRlUHbAACqfNbI1UDVUZVCGwAAQP4WyNVA1VGVQlsAAGg+FsjVQNVRlUKbAABFD5bI1UDVUZVC2wAARRXWyNVA1VGVQxsAAET1FsjVQNVRlUNSSVoWyNVA1VGVQ5JJX1bI1UDVUZVD0klWlsjVQNVRlUQbAABE+1bI1UDVUZVEWwAARQDWyNVA1VGVRJJMqlbI1UDVUZVE2wAAkxYWyNVA1VGVRRJJWhbI1UDVUZVFUklfVsjVQNVRlUWSSUCWyNVA1VGVRdsAAJMbVsjVQNVRlUYbAACTIRbI1UDVUZVGUkyPlsjVQNVRlUaSTLZ
 (9.295649) [toit] INFO: -> AT+CFUN=0
 (9.301555) [toit.scheduler] INFO: changing run level {run_level: 0}
 (9.305040) [toit.scheduler] INFO: going into deep sleep {ms: 4989}
 (9.306941) [toit.metrics] INFO: persisting metrics
 (9.887320) [toit] INFO: <- OK
 (9.995036) [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.015332) [toit] INFO: booting toit {sdk: v1.6.0-pre.25+c8ae5eaa0, model: esp32-4mb}
 (0.021536) [toit.scheduler] INFO: set initial run_level {run_level: 4}
 (0.053853) [toit.state] INFO: resumed session {session_id: 1, session_time: 75.674914}
 [gc @ 0x3ffcfa58* | soft limit reached (20480 >= 18432)]
 [gc @ 0x3ffcfa58* | heap: 20kb -> 8kb | external: 0kb -> 0kb | used: 93kb | free: 9kb->149kb (largest 108kb) 1.128ms]
 (0.072426) [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.110435) [toit.console_sync] INFO: loaded last console sync {time: 69.844418}
 [gc @ 0x3ffcfa58* | soft limit reached (24576 >= 24576)]
 [gc @ 0x3ffcfa58* | heap: 24kb -> 16kb | external: 0kb -> 0kb | used: 102kb | free: 8kb->140kb (largest 108kb) 1.817ms]
 (0.132332) [toit.scheduler] INFO: running job {job: console_sync}
 (0.135102) [toit.scheduler] INFO: running job {job: poll_metric_flash_usage}
 (0.141443) [toit.scheduler] INFO: running job {job: poll_metric_memory_profile}
 (0.151094) [toit.console] INFO: waiting for console connection to be established
 (0.155684) [toit.network] DEBUG: connecting {network: Cellular (nbiot.tdc.dk)}
 [gc @ 0x3ffcfa58* | soft limit reached (36892 >= 36864)]
 [gc @ 0x3ffcfa58* | heap: 36kb -> 20kb | external: 0kb -> 0kb | used: 126kb | free: 12kb->116kb (largest 104kb) 2.036ms]
 (0.206979) [toit] INFO: -> AT
 (0.468635) [toit] INFO: -> AT
 (0.729128) [toit] INFO: -> AT
 (0.989704) [toit] INFO: -> AT
 (1.250406) [toit] INFO: -> AT
 [gc @ 0x3ffcfa58* | soft limit reached (45084 >= 43050)]
 [gc @ 0x3ffcfa58* | string allocation failed, length = 6 (heap)]
 [gc @ 0x3ffcfa58* | heap: 44kb -> 24kb | external: 0kb -> 0kb | used: 149kb | free: 6kb->94kb (largest 88kb) 2.432ms]
 (1.516161) [toit] INFO: -> AT
 (1.777810) [toit] INFO: -> AT
 (1.952512) [toit] INFO: <- OK
 (2.057933) [toit] INFO: -> ATE0
 (2.065730) [toit] INFO: <- OK
 (2.088255) [toit] INFO: -> AT+CMEE=2
 (2.094144) [toit] INFO: <- OK
 (2.116311) [toit] INFO: -> AT+CGMM
 (2.121623) [toit] INFO: <- FiPy
 (2.124197) [toit] INFO: <- OK
 (2.146094) [toit] INFO: -> AT+CGMR
 (2.166824) [toit] INFO: <- UE5.4.0.2
 (2.169379) [toit] INFO: <- OK
 (2.191376) [toit] INFO: -> AT+SQNCCID?
 (2.196725) [toit] INFO: <- +SQNCCID: "",""
 (2.199154) [toit] INFO: <- OK
 (2.201645) [toit.cellular] DEBUG: initialized {model: FiPy, version: UE5.4.0.2, iccid: }
 (2.221360) [toit] INFO: -> AT+CFUN=4
 (2.564337) [toit] INFO: <- OK
 (2.586596) [toit] INFO: -> AT+CPIN?
 (2.592215) [toit] INFO: <- +CME ERROR: SIM not inserted
 (2.847574) [toit] INFO: -> AT+CPIN?
 (2.949311) [toit] INFO: <- +CPIN: READY
 (2.951699) [toit] INFO: <- OK
 (2.973869) [toit] INFO: -> AT+CPSMS=0
 (2.980990) [toit] INFO: <- OK
 (3.006311) [toit] INFO: -> AT+CEDRXS=0
 (3.013600) [toit] INFO: <- OK
 (3.036003) [toit] INFO: -> AT+SQNIBRCFG=0
 (3.055203) [toit] INFO: <- OK
 (3.078081) [toit] INFO: -> AT+SQNIPSCFG=1,100
 (3.095310) [toit] INFO: <- OK
 (3.118018) [toit] INFO: -> AT+SQNBANDSEL=0,"standard","20"
 (3.187884) [toit] INFO: <- +SQNBANDSEL: <custom>
 (3.190304) [toit] INFO: <- OK
 (3.212697) [toit] INFO: -> AT+SQNBANDSEL=1,"standard","20"
 (3.220381) [toit] INFO: <- +CME ERROR: operation not supported
 (3.242600) [toit] INFO: -> AT+CGDCONT?
 (3.249165) [toit] INFO: <- +CGDCONT: 1,"IP","nbiot.tdc.dk",,,,0,0,0,0,0,0,0,,0
 (3.252031) [toit] INFO: <- OK
 (3.274438) [toit] INFO: -> AT+CFUN=1,1
 (3.333570) [toit] INFO: <- OK
 (3.725004) [toit] INFO: <- *ignored* [URC] +SHUTDOWN []
 (5.448984) [toit] INFO: <- *ignored* [URC] +SYSSTART []
 (8.259441) [toit] INFO: -> AT+CFUN=4
 (8.955388) [toit] INFO: <- OK
 (8.957922) [toit.cellular] DEBUG: failed, detach from network
 (8.970812) [toit.network] DEBUG: error connecting {network: Cellular (nbiot.tdc.dk), error: DEADLINE_EXCEEDED}
 [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) 2.419ms]
 (8.995145) [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 WyNVBVVYU1UXdjEuNi4wLXByZS4yNStjOGFlNWVhYTBTVQllc3AzMi00bWJbJFUjVRAAAAAAAAAAAAAAAAAAAAAAWyNVBFVFU1UJRVhDRVBUSU9OU1URRkFJTEVEIFRPIENPTk5FQ1RbI1UCVVNbI1UbWyNVA1VGVQBsAAKuMVsjVQNVRlUBbAACrY5bI1UDVUZVAmwAAqoaWyNVA1VGVQNJJWhbI1UDVUZVBEklfVsjVQNVRlUFSSUCWyNVA1VGVQZsAAKqV1sjVQNVRlUHbAACqfNbI1UDVUZVCGwAAQP4WyNVA1VGVQlsAAGg+FsjVQNVRlUKbAABFD5bI1UDVUZVC2wAARRXWyNVA1VGVQxsAAET1FsjVQNVRlUNSSVoWyNVA1VGVQ5JJX1bI1UDVUZVD0klWlsjVQNVRlUQbAABE+1bI1UDVUZVEWwAARQDWyNVA1VGVRJJMqlbI1UDVUZVE2wAAkxYWyNVA1VGVRRJJWhbI1UDVUZVFUklfVsjVQNVRlUWSSUCWyNVA1VGVRdsAAJMbVsjVQNVRlUYbAACTIRbI1UDVUZVGUkyPlsjVQNVRlUaSTLZ
 (9.095791) [toit] INFO: -> AT+CFUN=0
 (9.101629) [toit.scheduler] INFO: changing run level {run_level: 0}
 (9.105434) [toit.scheduler] INFO: going into deep sleep {ms: 4989}
 (9.107329) [toit.metrics] INFO: persisting metrics
 (9.348582) [toit] INFO: <- OK
 (9.456273) [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.015316) [toit] INFO: booting toit {sdk: v1.6.0-pre.25+c8ae5eaa0, model: esp32-4mb}
 (0.021520) [toit.scheduler] INFO: set initial run_level {run_level: 4}
 (0.053711) [toit.state] INFO: resumed session {session_id: 1, session_time: 90.176783}
 [gc @ 0x3ffcfa58* | soft limit reached (20480 >= 18432)]
 [gc @ 0x3ffcfa58* | heap: 20kb -> 8kb | external: 0kb -> 0kb | used: 93kb | free: 9kb->149kb (largest 108kb) 1.132ms]
 (0.072284) [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.110534) [toit.console_sync] INFO: loaded last console sync {time: 84.692437}
 [gc @ 0x3ffcfa58* | soft limit reached (24576 >= 24576)]
 [gc @ 0x3ffcfa58* | heap: 24kb -> 16kb | external: 0kb -> 0kb | used: 102kb | free: 8kb->140kb (largest 108kb) 1.784ms]
 (0.132777) [toit.scheduler] INFO: running job {job: console_sync}
 (0.135576) [toit.scheduler] INFO: running job {job: poll_metric_flash_usage}
 (0.141428) [toit.scheduler] INFO: running job {job: poll_metric_memory_profile}
 (0.151085) [toit.console] INFO: waiting for console connection to be established
 (0.155669) [toit.network] DEBUG: connecting {network: Cellular (nbiot.tdc.dk)}
 [gc @ 0x3ffcfa58* | soft limit reached (36892 >= 36864)]
 [gc @ 0x3ffcfa58* | heap: 36kb -> 20kb | external: 0kb -> 0kb | used: 126kb | free: 12kb->116kb (largest 104kb) 2.037ms]
 (0.208040) [toit] INFO: -> AT
 (0.469393) [toit] INFO: -> AT
 (0.729882) [toit] INFO: -> AT
 (0.990457) [toit] INFO: -> AT
 (1.251145) [toit] INFO: -> AT
 [gc @ 0x3ffcfa58* | soft limit reached (45084 >= 43050)]
 [gc @ 0x3ffcfa58* | string allocation failed, length = 6 (heap)]
 [gc @ 0x3ffcfa58* | heap: 44kb -> 24kb | external: 0kb -> 0kb | used: 149kb | free: 6kb->94kb (largest 88kb) 2.428ms]
 (1.516178) [toit] INFO: -> AT
 (1.777806) [toit] INFO: -> AT
 (1.950865) [toit] INFO: <- OK
 (2.056094) [toit] INFO: -> ATE0
 (2.063889) [toit] INFO: <- OK
 (2.086411) [toit] INFO: -> AT+CMEE=2
 (2.092291) [toit] INFO: <- OK
 (2.114444) [toit] INFO: -> AT+CGMM
 (2.119830) [toit] INFO: <- FiPy
 (2.122403) [toit] INFO: <- OK
 (2.144300) [toit] INFO: -> AT+CGMR
 (2.165016) [toit] INFO: <- UE5.4.0.2
 (2.167578) [toit] INFO: <- OK
 (2.189576) [toit] INFO: -> AT+SQNCCID?
 (2.194926) [toit] INFO: <- +SQNCCID: "",""
 (2.197348) [toit] INFO: <- OK
 (2.199842) [toit.cellular] DEBUG: initialized {model: FiPy, version: UE5.4.0.2, iccid: }
 (2.219561) [toit] INFO: -> AT+CFUN=4
 (2.285939) [toit] INFO: <- OK
 (2.308253) [toit] INFO: -> AT+CPIN?
 (2.313892) [toit] INFO: <- +CME ERROR: SIM not inserted
 (2.568489) [toit] INFO: -> AT+CPIN?
 (2.574065) [toit] INFO: <- +CME ERROR: SIM not inserted
 (2.828307) [toit] INFO: -> AT+CPIN?
 (2.949115) [toit] INFO: <- +CPIN: READY
 (2.951463) [toit] INFO: <- OK
 (2.973643) [toit] INFO: -> AT+CPSMS=0
 (2.981034) [toit] INFO: <- OK
 (3.006538) [toit] INFO: -> AT+CEDRXS=0
 (3.013835) [toit] INFO: <- OK
 (3.036243) [toit] INFO: -> AT+SQNIBRCFG=0
 (3.055507) [toit] INFO: <- OK
 (3.078126) [toit] INFO: -> AT+SQNIPSCFG=1,100
 (3.095575) [toit] INFO: <- OK
 (3.118218) [toit] INFO: -> AT+SQNBANDSEL=0,"standard","20"
 (3.187996) [toit] INFO: <- +SQNBANDSEL: <custom>
 (3.190432) [toit] INFO: <- OK
 (3.212774) [toit] INFO: -> AT+SQNBANDSEL=1,"standard","20"
 (3.220574) [toit] INFO: <- +CME ERROR: operation not supported
 (3.242549) [toit] INFO: -> AT+CGDCONT?
 (3.249356) [toit] INFO: <- +CGDCONT: 1,"IP","nbiot.tdc.dk",,,,0,0,0,0,0,0,0,,0
 (3.251891) [toit] INFO: <- OK
 (3.274074) [toit] INFO: -> AT+CFUN=1,1
 (3.333644) [toit] INFO: <- OK
 (3.388836) [toit] INFO: <- *ignored* [URC] +SHUTDOWN []
 (4.996428) [toit] INFO: <- *ignored* [URC] +SYSSTART []
 (8.259105) [toit] INFO: -> AT+CFUN=4
 (8.844099) [toit] INFO: <- OK
 (8.846666) [toit.cellular] DEBUG: failed, detach from network
 (8.862459) [toit.network] DEBUG: error connecting {network: Cellular (nbiot.tdc.dk), error: DEADLINE_EXCEEDED}
 [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) 2.420ms]
 (8.890135) [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 WyNVBVVYU1UXdjEuNi4wLXByZS4yNStjOGFlNWVhYTBTVQllc3AzMi00bWJbJFUjVRAAAAAAAAAAAAAAAAAAAAAAWyNVBFVFU1UJRVhDRVBUSU9OU1URRkFJTEVEIFRPIENPTk5FQ1RbI1UCVVNbI1UbWyNVA1VGVQBsAAKuMVsjVQNVRlUBbAACrY5bI1UDVUZVAmwAAqoaWyNVA1VGVQNJJWhbI1UDVUZVBEklfVsjVQNVRlUFSSUCWyNVA1VGVQZsAAKqV1sjVQNVRlUHbAACqfNbI1UDVUZVCGwAAQP4WyNVA1VGVQlsAAGg+FsjVQNVRlUKbAABFD5bI1UDVUZVC2wAARRXWyNVA1VGVQxsAAET1FsjVQNVRlUNSSVoWyNVA1VGVQ5JJX1bI1UDVUZVD0klWlsjVQNVRlUQbAABE+1bI1UDVUZVEWwAARQDWyNVA1VGVRJJMqlbI1UDVUZVE2wAAkxYWyNVA1VGVRRJJWhbI1UDVUZVFUklfVsjVQNVRlUWSSUCWyNVA1VGVRdsAAJMbVsjVQNVRlUYbAACTIRbI1UDVUZVGUkyPlsjVQNVRlUaSTLZ
 (8.996959) [toit] INFO: -> AT+CFUN=0
 (9.003091) [toit.scheduler] INFO: changing run level {run_level: 0}
 (9.006568) [toit.scheduler] INFO: going into deep sleep {ms: 4988}
 (9.008457) [toit.metrics] INFO: persisting metrics
 (9.252473) [toit] INFO: <- OK
 (9.360180) [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.015332) [toit] INFO: booting toit {sdk: v1.6.0-pre.25+c8ae5eaa0, model: esp32-4mb}
 (0.021536) [toit.scheduler] INFO: set initial run_level {run_level: 4}
 (0.054877) [toit.state] INFO: resumed session {session_id: 1, session_time: 104.576917}
 [gc @ 0x3ffcfa58* | soft limit reached (20480 >= 18432)]
 [gc @ 0x3ffcfa58* | heap: 20kb -> 8kb | external: 0kb -> 0kb | used: 93kb | free: 9kb->149kb (largest 108kb) 1.140ms]
 (0.073529) [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.110586) [toit.console_sync] INFO: loaded last console sync {time: 99.089295}
 [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) 1.763ms]
 (0.139098) [toit.scheduler] INFO: running job {job: console_sync}
 (0.141901) [toit.scheduler] INFO: running job {job: poll_metric_flash_usage}
 (0.146126) [toit.scheduler] INFO: running job {job: poll_metric_memory_profile}
 (0.155754) [toit.console] INFO: waiting for console connection to be established
 (0.160372) [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) 2.088ms]
 (0.221137) [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) 2.402ms]
 (0.482740) [toit] INFO: -> AT
 (0.743094) [toit] INFO: -> AT
 (1.004056) [toit] INFO: -> AT
 (1.264724) [toit] INFO: -> AT
 (1.525321) [toit] INFO: -> AT
 (1.785908) [toit] INFO: -> AT
 (1.962696) [toit] INFO: <- OK
 (2.067933) [toit] INFO: -> ATE0
 (2.075717) [toit] INFO: <- OK
 (2.106333) [toit] INFO: -> AT+CMEE=2
 (2.111940) [toit] INFO: <- OK
 (2.134001) [toit] INFO: -> AT+CGMM
 (2.139485) [toit] INFO: <- FiPy
 (2.142067) [toit] INFO: <- OK
 (2.163928) [toit] INFO: -> AT+CGMR
 (2.184717) [toit] INFO: <- UE5.4.0.2
 (2.187233) [toit] INFO: <- OK
 (2.209233) [toit] INFO: -> AT+SQNCCID?
 (2.214591) [toit] INFO: <- +SQNCCID: "",""
 (2.216954) [toit] INFO: <- OK
 (2.219428) [toit.cellular] DEBUG: initialized {model: FiPy, version: UE5.4.0.2, iccid: }
 (2.239212) [toit] INFO: -> AT+CFUN=4
 (2.720722) [toit] INFO: <- OK
 (2.742975) [toit] INFO: -> AT+CPIN?
 (2.748653) [toit] INFO: <- +CME ERROR: SIM busy
 (3.003312) [toit] INFO: -> AT+CPIN?
 (3.008889) [toit] INFO: <- +CPIN: READY
 (3.011296) [toit] INFO: <- OK
 (3.033487) [toit] INFO: -> AT+CPSMS=0
 (3.040598) [toit] INFO: <- OK
 (3.063048) [toit] INFO: -> AT+CEDRXS=0
 (3.070323) [toit] INFO: <- OK
 (3.092734) [toit] INFO: -> AT+SQNIBRCFG=0
 (3.111847) [toit] INFO: <- OK
 (3.134431) [toit] INFO: -> AT+SQNIPSCFG=1,100
 (3.151739) [toit] INFO: <- OK
 (3.174556) [toit] INFO: -> AT+SQNBANDSEL=0,"standard","20"
 (3.243877) [toit] INFO: <- +SQNBANDSEL: <custom>
 (3.246305) [toit] INFO: <- OK
 (3.268683) [toit] INFO: -> AT+SQNBANDSEL=1,"standard","20"
 (3.276372) [toit] INFO: <- +CME ERROR: operation not supported
 (3.306293) [toit] INFO: -> AT+CGDCONT?
 (3.312885) [toit] INFO: <- +CGDCONT: 1,"IP","nbiot.tdc.dk",,,,0,0,0,0,0,0,0,,0
 (3.315435) [toit] INFO: <- OK
 (3.337767) [toit] INFO: -> AT+CFUN=1,1
 (3.491344) [toit] INFO: <- OK
 (3.877388) [toit] INFO: <- *ignored* [URC] +SHUTDOWN []
 (5.506693) [toit] INFO: <- *ignored* [URC] +SYSSTART []
 (8.322813) [toit] INFO: -> AT+CFUN=4
 (8.488893) [toit] INFO: <- OK
 [gc @ 0x3ffcfa58* | soft limit reached (53276 >= 49194)]
 [gc @ 0x3ffcfa58* | byte array allocation failed, length = 8 (heap)]
 [gc @ 0x3ffcfa58* | heap: 52kb -> 24kb | external: 0kb -> 0kb | used: 165kb | free: 10kb->78kb (largest 68kb) 2.354ms]
 (8.504158) [toit.cellular] DEBUG: failed, detach from network
 (8.520806) [toit.network] DEBUG: error connecting {network: Cellular (nbiot.tdc.dk), error: DEADLINE_EXCEEDED}
 (8.530006) [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 WyNVBVVYU1UXdjEuNi4wLXByZS4yNStjOGFlNWVhYTBTVQllc3AzMi00bWJbJFUjVRAAAAAAAAAAAAAAAAAAAAAAWyNVBFVFU1UJRVhDRVBUSU9OU1URRkFJTEVEIFRPIENPTk5FQ1RbI1UCVVNbI1UbWyNVA1VGVQBsAAKuMVsjVQNVRlUBbAACrY5bI1UDVUZVAmwAAqoaWyNVA1VGVQNJJWhbI1UDVUZVBEklfVsjVQNVRlUFSSUCWyNVA1VGVQZsAAKqV1sjVQNVRlUHbAACqfNbI1UDVUZVCGwAAQP4WyNVA1VGVQlsAAGg+FsjVQNVRlUKbAABFD5bI1UDVUZVC2wAARRXWyNVA1VGVQxsAAET1FsjVQNVRlUNSSVoWyNVA1VGVQ5JJX1bI1UDVUZVD0klWlsjVQNVRlUQbAABE+1bI1UDVUZVEWwAARQDWyNVA1VGVRJJMqlbI1UDVUZVE2wAAkxYWyNVA1VGVRRJJWhbI1UDVUZVFUklfVsjVQNVRlUWSSUCWyNVA1VGVRdsAAJMbVsjVQNVRlUYbAACTIRbI1UDVUZVGUkyPlsjVQNVRlUaSTLZ
 (8.626552) [toit] INFO: -> AT+CFUN=0
 (8.632271) [toit.scheduler] INFO: changing run level {run_level: 0}
 (8.635713) [toit.scheduler] INFO: going into deep sleep {ms: 4989}
 (8.637590) [toit.metrics] INFO: persisting metrics
 [gc @ 0x3ffcfa58* | soft limit reached (49636 >= 49194)]
 [gc @ 0x3ffcfa58* | string allocation failed, length = 5 (heap)]
 [gc @ 0x3ffcfa58* | heap: 48kb -> 24kb | external: 0kb -> 0kb | used: 157kb | free: 10kb->86kb (largest 68kb) 2.472ms]
 (8.892570) [toit] INFO: <- OK
 (9.000763) [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.015316) [toit] INFO: booting toit {sdk: v1.6.0-pre.25+c8ae5eaa0, model: esp32-4mb}
 (0.021520) [toit.scheduler] INFO: set initial run_level {run_level: 4}
 (0.054698) [toit.state] INFO: resumed session {session_id: 1, session_time: 118.628620}
 [gc @ 0x3ffcfa58* | soft limit reached (20480 >= 18432)]
 [gc @ 0x3ffcfa58* | heap: 20kb -> 8kb | external: 0kb -> 0kb | used: 93kb | free: 9kb->149kb (largest 108kb) 1.140ms]
 (0.073352) [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.110747) [toit.console_sync] INFO: loaded last console sync {time: 113.129567}
 [gc @ 0x3ffcfa58* | soft limit reached (24576 >= 24576)]
 [gc @ 0x3ffcfa58* | byte array allocation failed, length = 16 (heap)]
 [gc @ 0x3ffcfa58* | heap: 24kb -> 16kb | external: 0kb -> 0kb | used: 102kb | free: 8kb->140kb (largest 108kb) 1.766ms]
 (0.139658) [toit.scheduler] INFO: running job {job: console_sync}
 (0.142461) [toit.scheduler] INFO: running job {job: poll_metric_flash_usage}
 (0.146467) [toit.scheduler] INFO: running job {job: poll_metric_memory_profile}
 (0.155862) [toit.console] INFO: waiting for console connection to be established
 (0.160481) [toit.network] DEBUG: connecting {network: Cellular (nbiot.tdc.dk)}
 [gc @ 0x3ffcfa58* | soft limit reached (36892 >= 36864)]
 [gc @ 0x3ffcfa58* | heap: 36kb -> 20kb | external: 0kb -> 0kb | used: 126kb | free: 12kb->116kb (largest 104kb) 2.090ms]
 (0.216110) [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) 2.386ms]
 (0.478004) [toit] INFO: -> AT
 (0.738354) [toit] INFO: -> AT
 (0.999114) [toit] INFO: -> AT
 (1.259778) [toit] INFO: -> AT
 (1.520378) [toit] INFO: -> AT
 (1.780961) [toit] INFO: -> AT
 (1.967074) [toit] INFO: <- OK
 (2.072317) [toit] INFO: -> ATE0
 (2.079969) [toit] INFO: <- OK
 (2.106340) [toit] INFO: -> AT+CMEE=2
 (2.111919) [toit] INFO: <- OK
 (2.133983) [toit] INFO: -> AT+CGMM
 (2.139367) [toit] INFO: <- FiPy
 (2.141942) [toit] INFO: <- OK
 (2.163879) [toit] INFO: -> AT+CGMR
 (2.184597) [toit] INFO: <- UE5.4.0.2
 (2.187139) [toit] INFO: <- OK
 (2.209141) [toit] INFO: -> AT+SQNCCID?
 (2.214853) [toit] INFO: <- +SQNCCID: "",""
 (2.217226) [toit] INFO: <- OK
 (2.219690) [toit.cellular] DEBUG: initialized {model: FiPy, version: UE5.4.0.2, iccid: }
 (2.239480) [toit] INFO: -> AT+CFUN=4
 (2.305742) [toit] INFO: <- OK
 (2.327997) [toit] INFO: -> AT+CPIN?
 (2.333704) [toit] INFO: <- +CME ERROR: SIM not inserted
 (2.588301) [toit] INFO: -> AT+CPIN?
 (2.593943) [toit] INFO: <- +CME ERROR: SIM not inserted
 (2.848228) [toit] INFO: -> AT+CPIN?
 (2.958853) [toit] INFO: <- +CPIN: READY
 (2.961204) [toit] INFO: <- OK
 (2.983390) [toit] INFO: -> AT+CPSMS=0
 (2.990678) [toit] INFO: <- OK
 (3.013416) [toit] INFO: -> AT+CEDRXS=0
 (3.021090) [toit] INFO: <- OK
 (3.043507) [toit] INFO: -> AT+SQNIBRCFG=0
 (3.063051) [toit] INFO: <- OK
 (3.085650) [toit] INFO: -> AT+SQNIPSCFG=1,100
 (3.103121) [toit] INFO: <- OK
 (3.125779) [toit] INFO: -> AT+SQNBANDSEL=0,"standard","20"
 (3.195469) [toit] INFO: <- +SQNBANDSEL: <custom>
 (3.197948) [toit] INFO: <- OK
 (3.220303) [toit] INFO: -> AT+SQNBANDSEL=1,"standard","20"
 (3.228105) [toit] INFO: <- +CME ERROR: operation not supported
 (3.250340) [toit] INFO: -> AT+CGDCONT?
 (3.257195) [toit] INFO: <- +CGDCONT: 1,"IP","nbiot.tdc.dk",,,,0,0,0,0,0,0,0,,0
 (3.259730) [toit] INFO: <- OK
 (3.281852) [toit] INFO: -> AT+CFUN=1,1
 (3.341011) [toit] INFO: <- OK
 [gc @ 0x3ffcfa58* | soft limit reached (53276 >= 49194)]
 [gc @ 0x3ffcfa58* | string allocation failed, length = 55 (heap)]
 [gc @ 0x3ffcfa58* | heap: 52kb -> 24kb | external: 0kb -> 0kb | used: 165kb | free: 10kb->78kb (largest 68kb) 2.365ms]
 (3.396320) [toit] INFO: <- *ignored* [URC] +SHUTDOWN []
 (5.111051) [toit] INFO: <- *ignored* [URC] +SYSSTART []
 (8.266971) [toit] INFO: -> AT+CFUN=4
 (8.611083) [toit] INFO: <- OK
 (8.613825) [toit.cellular] DEBUG: failed, detach from network
 (8.630811) [toit.network] DEBUG: error connecting {network: Cellular (nbiot.tdc.dk), error: DEADLINE_EXCEEDED}
 (8.640214) [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 WyNVBVVYU1UXdjEuNi4wLXByZS4yNStjOGFlNWVhYTBTVQllc3AzMi00bWJbJFUjVRAAAAAAAAAAAAAAAAAAAAAAWyNVBFVFU1UJRVhDRVBUSU9OU1URRkFJTEVEIFRPIENPTk5FQ1RbI1UCVVNbI1UbWyNVA1VGVQBsAAKuMVsjVQNVRlUBbAACrY5bI1UDVUZVAmwAAqoaWyNVA1VGVQNJJWhbI1UDVUZVBEklfVsjVQNVRlUFSSUCWyNVA1VGVQZsAAKqV1sjVQNVRlUHbAACqfNbI1UDVUZVCGwAAQP4WyNVA1VGVQlsAAGg+FsjVQNVRlUKbAABFD5bI1UDVUZVC2wAARRXWyNVA1VGVQxsAAET1FsjVQNVRlUNSSVoWyNVA1VGVQ5JJX1bI1UDVUZVD0klWlsjVQNVRlUQbAABE+1bI1UDVUZVEWwAARQDWyNVA1VGVRJJMqlbI1UDVUZVE2wAAkxYWyNVA1VGVRRJJWhbI1UDVUZVFUklfVsjVQNVRlUWSSUCWyNVA1VGVRdsAAJMbVsjVQNVRlUYbAACTIRbI1UDVUZVGUkyPlsjVQNVRlUaSTLZ
 (8.737443) [toit] INFO: -> AT+CFUN=0
 [gc @ 0x3ffcfa58* | soft limit reached (49636 >= 49194)]
 [gc @ 0x3ffcfa58* | byte array allocation failed, length = 6 (heap)]
 [gc @ 0x3ffcfa58* | heap: 48kb -> 24kb | external: 0kb -> 0kb | used: 157kb | free: 10kb->86kb (largest 68kb) 2.509ms]
 (8.742976) [toit.scheduler] INFO: changing run level {run_level: 0}
 (8.762684) [toit.scheduler] INFO: going into deep sleep {ms: 4989}
 (8.767058) [toit.metrics] INFO: persisting metrics
 (9.405773) [toit] INFO: <- OK
 (9.513488) [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.015316) [toit] INFO: booting toit {sdk: v1.6.0-pre.25+c8ae5eaa0, model: esp32-4mb}
 (0.021520) [toit.scheduler] INFO: set initial run_level {run_level: 4}
 (0.054543) [toit.state] INFO: resumed session {session_id: 1, session_time: 133.188429}
 [gc @ 0x3ffcfa58* | soft limit reached (20480 >= 18432)]
 [gc @ 0x3ffcfa58* | heap: 20kb -> 8kb | external: 0kb -> 0kb | used: 93kb | free: 9kb->149kb (largest 108kb) 1.145ms]
 (0.073271) [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.110851) [toit.console_sync] INFO: loaded last console sync {time: 127.288363}
 [gc @ 0x3ffcfa58* | soft limit reached (24576 >= 24576)]
 [gc @ 0x3ffcfa58* | byte array allocation failed, length = 16 (heap)]
 [gc @ 0x3ffcfa58* | heap: 24kb -> 16kb | external: 0kb -> 0kb | used: 102kb | free: 8kb->140kb (largest 108kb) 1.767ms]
 (0.139986) [toit.scheduler] INFO: running job {job: console_sync}
 (0.142789) [toit.scheduler] INFO: running job {job: poll_metric_flash_usage}
 (0.146571) [toit.scheduler] INFO: running job {job: poll_metric_memory_profile}
 (0.155965) [toit.console] INFO: waiting for console connection to be established
 (0.160584) [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) 2.073ms]
 (0.230140) [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) 2.390ms]
 (0.491737) [toit] INFO: -> AT
 (0.752098) [toit] INFO: -> AT
 (1.012944) [toit] INFO: -> AT
 (1.273514) [toit] INFO: -> AT
 (1.534115) [toit] INFO: -> AT
 (1.794698) [toit] INFO: -> AT
 (1.980754) [toit] INFO: <- OK
 (2.085996) [toit] INFO: -> ATE0
 (2.093775) [toit] INFO: <- OK
 (2.116259) [toit] INFO: -> AT+CMEE=2
 (2.121739) [toit] INFO: <- OK
 (2.143871) [toit] INFO: -> AT+CGMM
 (2.149277) [toit] INFO: <- FiPy
 (2.151848) [toit] INFO: <- OK
 (2.173696) [toit] INFO: -> AT+CGMR
 (2.194459) [toit] INFO: <- UE5.4.0.2
 (2.197008) [toit] INFO: <- OK
 (2.219023) [toit] INFO: -> AT+SQNCCID?
 (2.224375) [toit] INFO: <- +SQNCCID: "",""
 (2.226738) [toit] INFO: <- OK
 (2.229211) [toit.cellular] DEBUG: initialized {model: FiPy, version: UE5.4.0.2, iccid: }
 (2.249002) [toit] INFO: -> AT+CFUN=4
 (2.603239) [toit] INFO: <- OK
 (2.625523) [toit] INFO: -> AT+CPIN?
 (2.631201) [toit] INFO: <- +CME ERROR: SIM not inserted
 (2.885756) [toit] INFO: -> AT+CPIN?
 (2.978856) [toit] INFO: <- +CPIN: READY
 (2.981255) [toit] INFO: <- OK
 (3.006437) [toit] INFO: -> AT+CPSMS=0
 (3.014023) [toit] INFO: <- OK
 (3.036389) [toit] INFO: -> AT+CEDRXS=0
 (3.043991) [toit] INFO: <- OK
 (3.066405) [toit] INFO: -> AT+SQNIBRCFG=0
 (3.085526) [toit] INFO: <- OK
 (3.108353) [toit] INFO: -> AT+SQNIPSCFG=1,100
 (3.125821) [toit] INFO: <- OK
 (3.148453) [toit] INFO: -> AT+SQNBANDSEL=0,"standard","20"
 (3.218441) [toit] INFO: <- +SQNBANDSEL: <custom>
 (3.220880) [toit] INFO: <- OK
 (3.243244) [toit] INFO: -> AT+SQNBANDSEL=1,"standard","20"
 (3.250924) [toit] INFO: <- +CME ERROR: operation not supported
 (3.273154) [toit] INFO: -> AT+CGDCONT?
 (3.280077) [toit] INFO: <- +CGDCONT: 1,"IP","nbiot.tdc.dk",,,,0,0,0,0,0,0,0,,0
 (3.282624) [toit] INFO: <- OK
 (3.305042) [toit] INFO: -> AT+CFUN=1,1
 (3.364509) [toit] INFO: <- OK
 (3.710740) [toit] INFO: <- *ignored* [URC] +SHUTDOWN []
 (5.355614) [toit] INFO: <- *ignored* [URC] +SYSSTART []
 [gc @ 0x3ffcfa58* | soft limit reached (53276 >= 49194)]
 [gc @ 0x3ffcfa58* | string allocation failed, length = 6 (heap)]
 [gc @ 0x3ffcfa58* | heap: 52kb -> 24kb | external: 0kb -> 0kb | used: 165kb | free: 10kb->78kb (largest 68kb) 2.434ms]
 (8.290127) [toit] INFO: -> AT+CFUN=4
 (8.599326) [toit] INFO: <- OK
 (8.601596) [toit.cellular] DEBUG: failed, detach from network
 (8.620813) [toit.network] DEBUG: error connecting {network: Cellular (nbiot.tdc.dk), error: DEADLINE_EXCEEDED}
 (8.630407) [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 WyNVBVVYU1UXdjEuNi4wLXByZS4yNStjOGFlNWVhYTBTVQllc3AzMi00bWJbJFUjVRAAAAAAAAAAAAAAAAAAAAAAWyNVBFVFU1UJRVhDRVBUSU9OU1URRkFJTEVEIFRPIENPTk5FQ1RbI1UCVVNbI1UbWyNVA1VGVQBsAAKuMVsjVQNVRlUBbAACrY5bI1UDVUZVAmwAAqoaWyNVA1VGVQNJJWhbI1UDVUZVBEklfVsjVQNVRlUFSSUCWyNVA1VGVQZsAAKqV1sjVQNVRlUHbAACqfNbI1UDVUZVCGwAAQP4WyNVA1VGVQlsAAGg+FsjVQNVRlUKbAABFD5bI1UDVUZVC2wAARRXWyNVA1VGVQxsAAET1FsjVQNVRlUNSSVoWyNVA1VGVQ5JJX1bI1UDVUZVD0klWlsjVQNVRlUQbAABE+1bI1UDVUZVEWwAARQDWyNVA1VGVRJJMqlbI1UDVUZVE2wAAkxYWyNVA1VGVRRJJWhbI1UDVUZVFUklfVsjVQNVRlUWSSUCWyNVA1VGVRdsAAJMbVsjVQNVRlUYbAACTIRbI1UDVUZVGUkyPlsjVQNVRlUaSTLZ
 (8.726775) [toit] INFO: -> AT+CFUN=0
 [gc @ 0x3ffcfa58* | soft limit reached (49636 >= 49194)]
 [gc @ 0x3ffcfa58* | byte array allocation failed, length = 6 (heap)]
 [gc @ 0x3ffcfa58* | heap: 48kb -> 24kb | external: 0kb -> 0kb | used: 157kb | free: 10kb->86kb (largest 68kb) 2.513ms]
 (8.732484) [toit.scheduler] INFO: changing run level {run_level: 0}
 (8.752018) [toit.scheduler] INFO: going into deep sleep {ms: 4989}
 (8.756402) [toit.metrics] INFO: persisting metrics
 (8.984397) [toit] INFO: <- OK
 (9.092121) [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.015316) [toit] INFO: booting toit {sdk: v1.6.0-pre.25+c8ae5eaa0, model: esp32-4mb}
 (0.021520) [toit.scheduler] INFO: set initial run_level {run_level: 4}
 (0.054411) [toit.state] INFO: resumed session {session_id: 1, session_time: 147.332465}
 [gc @ 0x3ffcfa58* | soft limit reached (20480 >= 18432)]
 [gc @ 0x3ffcfa58* | heap: 20kb -> 8kb | external: 0kb -> 0kb | used: 93kb | free: 9kb->149kb (largest 108kb) 1.161ms]
 (0.073916) [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.110937) [toit.console_sync] INFO: loaded last console sync {time: 141.842462}
 [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) 1.751ms]
 (0.141430) [toit.scheduler] INFO: running job {job: console_sync}
 (0.144253) [toit.scheduler] INFO: running job {job: poll_metric_flash_usage}
 (0.146524) [toit.scheduler] INFO: running job {job: poll_metric_memory_profile}
 (0.156160) [toit.console] INFO: waiting for console connection to be established
 (0.160778) [toit.network] DEBUG: connecting {network: Cellular (nbiot.tdc.dk)}
 [gc @ 0x3ffcfa58* | soft limit reached (36892 >= 36864)]
 [gc @ 0x3ffcfa58* | byte array allocation failed, length = 10 (heap)]
 [gc @ 0x3ffcfa58* | heap: 36kb -> 20kb | external: 0kb -> 0kb | used: 127kb | free: 12kb->116kb (largest 104kb) 2.080ms]
 [gc @ 0x3ffcfa58* | soft limit reached (45084 >= 43050)]
 [gc @ 0x3ffcfa58* | heap: 44kb -> 24kb | external: 0kb -> 0kb | used: 148kb | free: 6kb->94kb (largest 88kb) 2.345ms]
 (0.235761) [toit] INFO: -> AT
 (0.498014) [toit] INFO: -> AT
 (0.758480) [toit] INFO: -> AT
 (1.019156) [toit] INFO: -> AT
 (1.279749) [toit] INFO: -> AT
 (1.540337) [toit] INFO: -> AT
 (1.800952) [toit] INFO: -> AT
 (1.980281) [toit] INFO: <- OK
 (2.085521) [toit] INFO: -> ATE0
 (2.093185) [toit] INFO: <- OK
 (2.115628) [toit] INFO: -> AT+CMEE=2
 (2.121108) [toit] INFO: <- OK
 (2.143211) [toit] INFO: -> AT+CGMM
 (2.148602) [toit] INFO: <- FiPy
 (2.151168) [toit] INFO: <- OK
 (2.173006) [toit] INFO: -> AT+CGMR
 (2.193848) [toit] INFO: <- UE5.4.0.2
 (2.196367) [toit] INFO: <- OK
 (2.218404) [toit] INFO: -> AT+SQNCCID?
 (2.223741) [toit] INFO: <- +SQNCCID: "",""
 (2.226112) [toit] INFO: <- OK
 (2.228567) [toit.cellular] DEBUG: initialized {model: FiPy, version: UE5.4.0.2, iccid: }
 (2.248370) [toit] INFO: -> AT+CFUN=4
 (2.675585) [toit] INFO: <- OK
 (2.706001) [toit] INFO: -> AT+CPIN?
 (2.711679) [toit] INFO: <- +CME ERROR: SIM busy
 (2.965997) [toit] INFO: -> AT+CPIN?
 (2.979341) [toit] INFO: <- +CPIN: READY
 (2.981724) [toit] INFO: <- OK
 (3.006420) [toit] INFO: -> AT+CPSMS=0
 (3.013507) [toit] INFO: <- OK
 (3.035950) [toit] INFO: -> AT+CEDRXS=0
 (3.043143) [toit] INFO: <- OK
 (3.065555) [toit] INFO: -> AT+SQNIBRCFG=0
 (3.084583) [toit] INFO: <- OK
 (3.107125) [toit] INFO: -> AT+SQNIPSCFG=1,100
 (3.124192) [toit] INFO: <- OK
 (3.147024) [toit] INFO: -> AT+SQNBANDSEL=0,"standard","20"
 (3.216983) [toit] INFO: <- +SQNBANDSEL: <custom>
 (3.219412) [toit] INFO: <- OK
 (3.241839) [toit] INFO: -> AT+SQNBANDSEL=1,"standard","20"
 (3.249564) [toit] INFO: <- +CME ERROR: operation not supported
 (3.271784) [toit] INFO: -> AT+CGDCONT?
 (3.278785) [toit] INFO: <- +CGDCONT: 1,"IP","nbiot.tdc.dk",,,,0,0,0,0,0,0,0,,0
 (3.281647) [toit] INFO: <- OK
 (3.303907) [toit] INFO: -> AT+CFUN=1,1
 (3.538336) [toit] INFO: <- OK
 (3.863903) [toit] INFO: <- *ignored* [URC] +SHUTDOWN []
 (5.482761) [toit] INFO: <- *ignored* [URC] +SYSSTART []
 (8.289315) [toit] INFO: -> AT+CFUN=4
 [gc @ 0x3ffcfa58* | soft limit reached (53276 >= 49194)]
 [gc @ 0x3ffcfa58* | string allocation failed, length = 5 (heap)]
 [gc @ 0x3ffcfa58* | heap: 52kb -> 24kb | external: 0kb -> 0kb | used: 165kb | free: 10kb->78kb (largest 68kb) 2.347ms]
 (9.045772) [toit] INFO: <- OK
 (9.049232) [toit.cellular] DEBUG: failed, detach from network
 (9.070815) [toit.network] DEBUG: error connecting {network: Cellular (nbiot.tdc.dk), error: DEADLINE_EXCEEDED}
 (9.080643) [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 WyNVBVVYU1UXdjEuNi4wLXByZS4yNStjOGFlNWVhYTBTVQllc3AzMi00bWJbJFUjVRAAAAAAAAAAAAAAAAAAAAAAWyNVBFVFU1UJRVhDRVBUSU9OU1URRkFJTEVEIFRPIENPTk5FQ1RbI1UCVVNbI1UbWyNVA1VGVQBsAAKuMVsjVQNVRlUBbAACrY5bI1UDVUZVAmwAAqoaWyNVA1VGVQNJJWhbI1UDVUZVBEklfVsjVQNVRlUFSSUCWyNVA1VGVQZsAAKqV1sjVQNVRlUHbAACqfNbI1UDVUZVCGwAAQP4WyNVA1VGVQlsAAGg+FsjVQNVRlUKbAABFD5bI1UDVUZVC2wAARRXWyNVA1VGVQxsAAET1FsjVQNVRlUNSSVoWyNVA1VGVQ5JJX1bI1UDVUZVD0klWlsjVQNVRlUQbAABE+1bI1UDVUZVEWwAARQDWyNVA1VGVRJJMqlbI1UDVUZVE2wAAkxYWyNVA1VGVRRJJWhbI1UDVUZVFUklfVsjVQNVRlUWSSUCWyNVA1VGVRdsAAJMbVsjVQNVRlUYbAACTIRbI1UDVUZVGUkyPlsjVQNVRlUaSTLZ
 (9.177463) [toit] INFO: -> AT+CFUN=0
 [gc @ 0x3ffcfa58* | soft limit reached (49636 >= 49194)]
 [gc @ 0x3ffcfa58* | byte array allocation failed, length = 6 (heap)]
 [gc @ 0x3ffcfa58* | heap: 48kb -> 24kb | external: 0kb -> 0kb | used: 157kb | free: 10kb->86kb (largest 68kb) 2.513ms]
 (9.183156) [toit.scheduler] INFO: changing run level {run_level: 0}
 (9.202711) [toit.scheduler] INFO: going into deep sleep {ms: 4989}
 (9.207070) [toit.metrics] INFO: persisting metrics
 (9.437669) [toit] INFO: <- OK
 (9.545370) [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.015316) [toit] INFO: booting toit {sdk: v1.6.0-pre.25+c8ae5eaa0, model: esp32-4mb}
 (0.021520) [toit.scheduler] INFO: set initial run_level {run_level: 4}
 (0.054269) [toit.state] INFO: resumed session {session_id: 1, session_time: 161.925537}
 [gc @ 0x3ffcfa58* | soft limit reached (20480 >= 18432)]
 [gc @ 0x3ffcfa58* | heap: 20kb -> 8kb | external: 0kb -> 0kb | used: 93kb | free: 9kb->149kb (largest 108kb) 1.159ms]
 (0.073895) [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.111044) [toit.console_sync] INFO: loaded last console sync {time: 156.433374}
 [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) 1.751ms]
 (0.141666) [toit.scheduler] INFO: running job {job: console_sync}
 (0.144488) [toit.scheduler] INFO: running job {job: poll_metric_flash_usage}
 (0.146632) [toit.scheduler] INFO: running job {job: poll_metric_memory_profile}
 (0.156266) [toit.console] INFO: waiting for console connection to be established
 (0.160885) [toit.network] DEBUG: connecting {network: Cellular (nbiot.tdc.dk)}
 [gc @ 0x3ffcfa58* | soft limit reached (36892 >= 36864)]
 [gc @ 0x3ffcfa58* | heap: 36kb -> 20kb | external: 0kb -> 0kb | used: 126kb | free: 12kb->116kb (largest 104kb) 2.049ms]
 [gc @ 0x3ffcfa58* | soft limit reached (45084 >= 43050)]
 [gc @ 0x3ffcfa58* | heap: 44kb -> 20kb | external: 0kb -> 0kb | used: 148kb | free: 6kb->94kb (largest 88kb) 2.151ms]
 (0.225707) [toit] INFO: -> AT
 (0.487788) [toit] INFO: -> AT
 (0.748262) [toit] INFO: -> AT
 (1.009094) [toit] INFO: -> AT
 (1.269870) [toit] INFO: -> AT
 (1.530269) [toit] INFO: -> AT
 (1.790851) [toit] INFO: -> AT
 (1.967677) [toit] INFO: <- OK
 (2.072914) [toit] INFO: -> ATE0
 (2.080566) [toit] INFO: <- OK
 (2.106332) [toit] INFO: -> AT+CMEE=2
 (2.111908) [toit] INFO: <- OK
 (2.133961) [toit] INFO: -> AT+CGMM
 (2.139202) [toit] INFO: <- FiPy
 (2.142030) [toit] INFO: <- OK
 (2.163904) [toit] INFO: -> AT+CGMR
 (2.184637) [toit] INFO: <- UE5.4.0.2
 (2.186904) [toit] INFO: <- OK
 (2.208843) [toit] INFO: -> AT+SQNCCID?
 (2.214627) [toit] INFO: <- +SQNCCID: "",""
 (2.217014) [toit] INFO: <- OK
 (2.219473) [toit.cellular] DEBUG: initialized {model: FiPy, version: UE5.4.0.2, iccid: }
 (2.239262) [toit] INFO: -> AT+CFUN=4
 (2.305450) [toit] INFO: <- OK
 (2.327728) [toit] INFO: -> AT+CPIN?
 (2.333331) [toit] INFO: <- +CME ERROR: SIM not inserted
 (2.588003) [toit] INFO: -> AT+CPIN?
 (2.593587) [toit] INFO: <- +CME ERROR: SIM not inserted
 (2.847825) [toit] INFO: -> AT+CPIN?
 (2.954715) [toit] INFO: <- +CPIN: READY
 (2.957051) [toit] INFO: <- OK
 (2.979234) [toit] INFO: -> AT+CPSMS=0
 [gc @ 0x3ffcfa58* | soft limit reached (45084 >= 43050)]
 [gc @ 0x3ffcfa58* | heap: 44kb -> 24kb | external: 0kb -> 0kb | used: 149kb | free: 6kb->94kb (largest 88kb) 2.359ms]
 (2.986573) [toit] INFO: <- OK
 (3.015495) [toit] INFO: -> AT+CEDRXS=0
 (3.022689) [toit] INFO: <- OK
 (3.045163) [toit] INFO: -> AT+SQNIBRCFG=0
 (3.064872) [toit] INFO: <- OK
 (3.087473) [toit] INFO: -> AT+SQNIPSCFG=1,100
 (3.104499) [toit] INFO: <- OK
 (3.127150) [toit] INFO: -> AT+SQNBANDSEL=0,"standard","20"
 (3.200090) [toit] INFO: <- +SQNBANDSEL: <custom>
 (3.202837) [toit] INFO: <- OK
 (3.225265) [toit] INFO: -> AT+SQNBANDSEL=1,"standard","20"
 (3.232763) [toit] INFO: <- +CME ERROR: operation not supported
 (3.254990) [toit] INFO: -> AT+CGDCONT?
 (3.261699) [toit] INFO: <- +CGDCONT: 1,"IP","nbiot.tdc.dk",,,,0,0,0,0,0,0,0,,0
 (3.264230) [toit] INFO: <- OK
 (3.286354) [toit] INFO: -> AT+CFUN=1,1
 (3.345906) [toit] INFO: <- OK
 (3.400719) [toit] INFO: <- *ignored* [URC] +SHUTDOWN []
 (5.113802) [toit] INFO: <- *ignored* [URC] +SYSSTART []
 (8.271672) [toit] INFO: -> AT+CFUN=4
 (9.773611) [toit] INFO: <- OK
 (9.776198) [toit.cellular] DEBUG: failed, detach from network
 (9.791090) [toit.network] DEBUG: error connecting {network: Cellular (nbiot.tdc.dk), error: DEADLINE_EXCEEDED}
 (9.801150) [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 WyNVBVVYU1UXdjEuNi4wLXByZS4yNStjOGFlNWVhYTBTVQllc3AzMi00bWJbJFUjVRAAAAAAAAAAAAAAAAAAAAAAWyNVBFVFU1UJRVhDRVBUSU9OU1URRkFJTEVEIFRPIENPTk5FQ1RbI1UCVVNbI1UbWyNVA1VGVQBsAAKuMVsjVQNVRlUBbAACrY5bI1UDVUZVAmwAAqoaWyNVA1VGVQNJJWhbI1UDVUZVBEklfVsjVQNVRlUFSSUCWyNVA1VGVQZsAAKqV1sjVQNVRlUHbAACqfNbI1UDVUZVCGwAAQP4WyNVA1VGVQlsAAGg+FsjVQNVRlUKbAABFD5bI1UDVUZVC2wAARRXWyNVA1VGVQxsAAET1FsjVQNVRlUNSSVoWyNVA1VGVQ5JJX1bI1UDVUZVD0klWlsjVQNVRlUQbAABE+1bI1UDVUZVEWwAARQDWyNVA1VGVRJJMqlbI1UDVUZVE2wAAkxYWyNVA1VGVRRJJWhbI1UDVUZVFUklfVsjVQNVRlUWSSUCWyNVA1VGVRdsAAJMbVsjVQNVRlUYbAACTIRbI1UDVUZVGUkyPlsjVQNVRlUaSTLZ
 [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) 2.633ms]
 (9.922748) [toit] INFO: -> AT+CFUN=0
 (9.928302) [toit.scheduler] INFO: changing run level {run_level: 0}
 (9.932045) [toit.scheduler] INFO: going into deep sleep {ms: 4986}
 (9.933916) [toit.metrics] INFO: persisting metrics
 (10.350469) [toit] INFO: <- OK
 (10.458180) [toit] INFO: entering deep sleep {ms: 4986}
 Entering deep sleep for 4986ms
 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.015316) [toit] INFO: booting toit {sdk: v1.6.0-pre.25+c8ae5eaa0, model: esp32-4mb}
 (0.021520) [toit.scheduler] INFO: set initial run_level {run_level: 4}
 (0.054182) [toit.state] INFO: resumed session {session_id: 1, session_time: 177.430675}
 [gc @ 0x3ffcfa58* | soft limit reached (20480 >= 18432)]
 [gc @ 0x3ffcfa58* | string allocation failed, length = 12 (heap)]
 [gc @ 0x3ffcfa58* | heap: 20kb -> 8kb | external: 0kb -> 0kb | used: 93kb | free: 9kb->149kb (largest 108kb) 1.149ms]
 (0.079679) [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.122402) [toit.console_sync] INFO: loaded last console sync {time: 171.770685}
 [gc @ 0x3ffcfa58* | soft limit reached (24576 >= 24576)]
 [gc @ 0x3ffcfa58* | string allocation failed, length = 23 (heap)]
 [gc @ 0x3ffcfa58* | heap: 24kb -> 12kb | external: 0kb -> 0kb | used: 102kb | free: 12kb->140kb (largest 108kb) 1.714ms]
 (0.153884) [toit.scheduler] INFO: running job {job: console_sync}
 (0.156703) [toit.scheduler] INFO: running job {job: poll_metric_flash_usage}
 (0.158615) [toit.scheduler] INFO: running job {job: poll_metric_memory_profile}
 (0.167567) [toit.console] INFO: waiting for console connection to be established
 (0.172191) [toit.network] DEBUG: connecting {network: Cellular (nbiot.tdc.dk)}
 [gc @ 0x3ffcfa58* | soft limit reached (32796 >= 30720)]
 [gc @ 0x3ffcfa58* | byte array allocation failed, length = 511 (heap)]
 [gc @ 0x3ffcfa58* | heap: 32kb -> 20kb | external: 0kb -> 0kb | used: 119kb | free: 12kb->124kb (largest 108kb) 2.036ms]
 [gc @ 0x3ffcfa58* | soft limit reached (45084 >= 43050)]
 [gc @ 0x3ffcfa58* | heap: 44kb -> 20kb | external: 0kb -> 0kb | used: 143kb | free: 12kb->100kb (largest 88kb) 2.119ms]
 (0.256066) [toit] INFO: -> AT
 (0.517869) [toit] INFO: -> AT
 (0.778333) [toit] INFO: -> AT
 (1.039024) [toit] INFO: -> AT
 (1.299628) [toit] INFO: -> AT
 (1.560214) [toit] INFO: -> AT
 (1.820801) [toit] INFO: -> AT
 (2.006835) [toit] INFO: <- OK
 (2.112006) [toit] INFO: -> ATE0
 (2.119604) [toit] INFO: <- OK
 (2.142061) [toit] INFO: -> AT+CMEE=2
 (2.147667) [toit] INFO: <- OK
 (2.170054) [toit] INFO: -> AT+CGMM
 (2.175256) [toit] INFO: <- FiPy
 (2.177803) [toit] INFO: <- OK
 (2.205983) [toit] INFO: -> AT+CGMR
 [gc @ 0x3ffcfa58* | soft limit reached (45084 >= 43050)]
 [gc @ 0x3ffcfa58* | string allocation failed, length = 1 (heap)]
 [gc @ 0x3ffcfa58* | heap: 44kb -> 24kb | external: 0kb -> 0kb | used: 149kb | free: 10kb->94kb (largest 84kb) 2.342ms]
 (2.226754) [toit] INFO: <- UE5.4.0.2
 (2.242008) [toit] INFO: <- OK
 (2.264505) [toit] INFO: -> AT+SQNCCID?
 (2.269899) [toit] INFO: <- +SQNCCID: "",""
 (2.272298) [toit] INFO: <- OK
 (2.274766) [toit.cellular] DEBUG: initialized {model: FiPy, version: UE5.4.0.2, iccid: }
 (2.294546) [toit] INFO: -> AT+CFUN=4
 (2.361129) [toit] INFO: <- OK
 (2.383354) [toit] INFO: -> AT+CPIN?
 (2.389283) [toit] INFO: <- +CME ERROR: SIM not inserted
 (2.643918) [toit] INFO: -> AT+CPIN?
 (2.649524) [toit] INFO: <- +CME ERROR: SIM not inserted
 (2.903910) [toit] INFO: -> AT+CPIN?
 (3.024926) [toit] INFO: <- +CPIN: READY
 (3.027319) [toit] INFO: <- OK
 (3.049591) [toit] INFO: -> AT+CPSMS=0
 (3.056728) [toit] INFO: <- OK
 (3.079397) [toit] INFO: -> AT+CEDRXS=0
 (3.086635) [toit] INFO: <- OK
 (3.108997) [toit] INFO: -> AT+SQNIBRCFG=0
 (3.128615) [toit] INFO: <- OK
 (3.151185) [toit] INFO: -> AT+SQNIPSCFG=1,100
 (3.168361) [toit] INFO: <- OK
 (3.190965) [toit] INFO: -> AT+SQNBANDSEL=0,"standard","20"
 (3.260489) [toit] INFO: <- +SQNBANDSEL: <custom>
 (3.262928) [toit] INFO: <- OK
 (3.285277) [toit] INFO: -> AT+SQNBANDSEL=1,"standard","20"
 (3.292799) [toit] INFO: <- +CME ERROR: operation not supported
 (3.315057) [toit] INFO: -> AT+CGDCONT?
 (3.321310) [toit] INFO: <- +CGDCONT: 1,"IP","nbiot.tdc.dk",,,,0,0,0,0,0,0,0,,0
 (3.324017) [toit] INFO: <- OK
 (3.346294) [toit] INFO: -> AT+CFUN=1,1
 (3.405441) [toit] INFO: <- OK
 (3.461092) [toit] INFO: <- *ignored* [URC] +SHUTDOWN []
 (5.176079) [toit] INFO: <- *ignored* [URC] +SYSSTART []
 (8.331232) [toit] INFO: -> AT+CFUN=4
 (10.153430) [toit.scheduler] INFO: running job {job: persist_metrics}
 (10.156723) [toit.metrics] INFO: persisting metrics
 (10.274166) [toit.scheduler] INFO: running job {job: poll_metric_memory_profile}
 (12.032537) [toit] INFO: <- OK
 (12.035236) [toit.cellular] DEBUG: failed, detach from network
 [gc @ 0x3ffcfa58* | soft limit reached (53276 >= 49194)]
 [gc @ 0x3ffcfa58* | heap: 52kb -> 24kb | external: 0kb -> 0kb | used: 165kb | free: 10kb->78kb (largest 68kb) 2.380ms]
 (12.060810) [toit.network] DEBUG: error connecting {network: Cellular (nbiot.tdc.dk), error: DEADLINE_EXCEEDED}
 (12.071034) [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 WyNVBVVYU1UXdjEuNi4wLXByZS4yNStjOGFlNWVhYTBTVQllc3AzMi00bWJbJFUjVRAAAAAAAAAAAAAAAAAAAAAAWyNVBFVFU1UJRVhDRVBUSU9OU1URRkFJTEVEIFRPIENPTk5FQ1RbI1UCVVNbI1UbWyNVA1VGVQBsAAKuMVsjVQNVRlUBbAACrY5bI1UDVUZVAmwAAqoaWyNVA1VGVQNJJWhbI1UDVUZVBEklfVsjVQNVRlUFSSUCWyNVA1VGVQZsAAKqV1sjVQNVRlUHbAACqfNbI1UDVUZVCGwAAQP4WyNVA1VGVQlsAAGg+FsjVQNVRlUKbAABFD5bI1UDVUZVC2wAARRXWyNVA1VGVQxsAAET1FsjVQNVRlUNSSVoWyNVA1VGVQ5JJX1bI1UDVUZVD0klWlsjVQNVRlUQbAABE+1bI1UDVUZVEWwAARQDWyNVA1VGVRJJMqlbI1UDVUZVE2wAAkxYWyNVA1VGVRRJJWhbI1UDVUZVFUklfVsjVQNVRlUWSSUCWyNVA1VGVRdsAAJMbVsjVQNVRlUYbAACTIRbI1UDVUZVGUkyPlsjVQNVRlUaSTLZ
 (12.178727) [toit] INFO: -> AT+CFUN=0
 [gc @ 0x3ffcfa58* | soft limit reached (49636 >= 49194)]
 [gc @ 0x3ffcfa58* | heap: 48kb -> 24kb | external: 0kb -> 0kb | used: 157kb | free: 10kb->86kb (largest 68kb) 2.525ms]
 (12.185112) [toit.scheduler] INFO: changing run level {run_level: 0}
 (12.198357) [toit.scheduler] INFO: going into deep sleep {ms: 4989}
 (12.202848) [toit.metrics] INFO: persisting metrics
 (12.434343) [toit] INFO: <- OK
 (12.542059) [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.015316) [toit] INFO: booting toit {sdk: v1.6.0-pre.25+c8ae5eaa0, model: esp32-4mb}
 (0.021520) [toit.scheduler] INFO: set initial run_level {run_level: 4}
 (0.055282) [toit.state] INFO: resumed session {session_id: 1, session_time: 195.007702}
 [gc @ 0x3ffcfa58* | soft limit reached (20480 >= 18432)]
 [gc @ 0x3ffcfa58* | heap: 20kb -> 8kb | external: 0kb -> 0kb | used: 93kb | free: 9kb->149kb (largest 108kb) 1.161ms]
 (0.075047) [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.120870) [toit.console_sync] INFO: loaded last console sync {time: 189.518667}
 [gc @ 0x3ffcfa58* | soft limit reached (24576 >= 24576)]
 [gc @ 0x3ffcfa58* | heap: 24kb -> 12kb | external: 0kb -> 0kb | used: 102kb | free: 8kb->140kb (largest 108kb) 1.728ms]
 (0.146695) [toit.scheduler] INFO: running job {job: console_sync}
 (0.149527) [toit.scheduler] INFO: running job {job: poll_metric_flash_usage}
 (0.151428) [toit.scheduler] INFO: running job {job: poll_metric_memory_profile}
 (0.160452) [toit.console] INFO: waiting for console connection to be established
 (0.165015) [toit.network] DEBUG: connecting {network: Cellular (nbiot.tdc.dk)}
 [gc @ 0x3ffcfa58* | soft limit reached (32796 >= 30720)]
 [gc @ 0x3ffcfa58* | byte array allocation failed, length = 511 (heap)]
 [gc @ 0x3ffcfa58* | heap: 32kb -> 20kb | external: 0kb -> 0kb | used: 118kb | free: 12kb->124kb (largest 108kb) 2.041ms]
 [gc @ 0x3ffcfa58* | soft limit reached (45084 >= 43050)]
 [gc @ 0x3ffcfa58* | byte array allocation failed, length = 117 (heap)]
 [gc @ 0x3ffcfa58* | heap: 44kb -> 20kb | external: 0kb -> 0kb | used: 142kb | free: 12kb->100kb (largest 88kb) 2.115ms]
 (0.251599) [toit] INFO: -> AT
 (0.517779) [toit] INFO: -> AT
 (0.778253) [toit] INFO: -> AT
 (1.038929) [toit] INFO: -> AT
 (1.299539) [toit] INFO: -> AT
 (1.560118) [toit] INFO: -> AT
 (1.820716) [toit] INFO: -> AT
 (2.005806) [toit] INFO: <- OK
 (2.111582) [toit] INFO: -> ATE0
 (2.119142) [toit] INFO: <- OK
 (2.141547) [toit] INFO: -> AT+CMEE=2
 (2.147379) [toit] INFO: <- OK
 (2.169596) [toit] INFO: -> AT+CGMM
 (2.175087) [toit] INFO: <- FiPy
 (2.177735) [toit] INFO: <- OK
 (2.199561) [toit] INFO: -> AT+CGMR
 [gc @ 0x3ffcfa58* | soft limit reached (45084 >= 43050)]
 [gc @ 0x3ffcfa58* | string allocation failed, length = 6 (heap)]
 [gc @ 0x3ffcfa58* | heap: 44kb -> 24kb | external: 0kb -> 0kb | used: 149kb | free: 10kb->94kb (largest 84kb) 2.377ms]
 (2.220453) [toit] INFO: <- UE5.4.0.2
 (2.236128) [toit] INFO: <- OK
 (2.258440) [toit] INFO: -> AT+SQNCCID?
 (2.263992) [toit] INFO: <- +SQNCCID: "",""
 (2.266395) [toit] INFO: <- OK
 (2.268888) [toit.cellular] DEBUG: initialized {model: FiPy, version: UE5.4.0.2, iccid: }
 (2.288607) [toit] INFO: -> AT+CFUN=4
 (2.811466) [toit] INFO: <- OK
 (2.833992) [toit] INFO: -> AT+CPIN?
 (2.840145) [toit] INFO: <- +CME ERROR: SIM busy
 (3.094847) [toit] INFO: -> AT+CPIN?
 (3.100715) [toit] INFO: <- +CPIN: READY
 (3.103194) [toit] INFO: <- OK
 (3.125328) [toit] INFO: -> AT+CPSMS=0
 (3.132777) [toit] INFO: <- OK
 (3.155192) [toit] INFO: -> AT+CEDRXS=0
 (3.162661) [toit] INFO: <- OK
 (3.185028) [toit] INFO: -> AT+SQNIBRCFG=0
 (3.204513) [toit] INFO: <- OK
 (3.227260) [toit] INFO: -> AT+SQNIPSCFG=1,100
 (3.246017) [toit] INFO: <- OK
 (3.268600) [toit] INFO: -> AT+SQNBANDSEL=0,"standard","20"
 (3.337612) [toit] INFO: <- +SQNBANDSEL: <custom>
 (3.340119) [toit] INFO: <- OK
 (3.362430) [toit] INFO: -> AT+SQNBANDSEL=1,"standard","20"
 (3.369382) [toit] INFO: <- +CME ERROR: operation not supported
 (3.391646) [toit] INFO: -> AT+CGDCONT?
 (3.398252) [toit] INFO: <- +CGDCONT: 1,"IP","nbiot.tdc.dk",,,,0,0,0,0,0,0,0,,0
 (3.400703) [toit] INFO: <- OK
 (3.423143) [toit] INFO: -> AT+CFUN=1,1
 (3.782280) [toit] INFO: <- OK
 (3.837301) [toit] INFO: <- *ignored* [URC] +SHUTDOWN []
 (5.321980) [toit] INFO: <- *ignored* [URC] +SYSSTART []
 (8.409293) [toit] INFO: -> AT+CFUN=4
 (8.605078) [toit] INFO: <- OK
 (8.611642) [toit.cellular] DEBUG: failed, detach from network
 (8.630281) [toit.network] DEBUG: error connecting {network: Cellular (nbiot.tdc.dk), error: DEADLINE_EXCEEDED}
 (8.640712) [toit.network] WARN: failed to connect to any network
 [gc @ 0x3ffcfa58* | soft limit reached (53276 >= 49194)]
 [gc @ 0x3ffcfa58* | byte array allocation failed, length = 24 (heap)]
 [gc @ 0x3ffcfa58* | heap: 52kb -> 24kb | external: 0kb -> 0kb | used: 165kb | free: 10kb->78kb (largest 68kb) 2.395ms]
 ----
 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 WyNVBVVYU1UXdjEuNi4wLXByZS4yNStjOGFlNWVhYTBTVQllc3AzMi00bWJbJFUjVRAAAAAAAAAAAAAAAAAAAAAAWyNVBFVFU1UJRVhDRVBUSU9OU1URRkFJTEVEIFRPIENPTk5FQ1RbI1UCVVNbI1UbWyNVA1VGVQBsAAKuMVsjVQNVRlUBbAACrY5bI1UDVUZVAmwAAqoaWyNVA1VGVQNJJWhbI1UDVUZVBEklfVsjVQNVRlUFSSUCWyNVA1VGVQZsAAKqV1sjVQNVRlUHbAACqfNbI1UDVUZVCGwAAQP4WyNVA1VGVQlsAAGg+FsjVQNVRlUKbAABFD5bI1UDVUZVC2wAARRXWyNVA1VGVQxsAAET1FsjVQNVRlUNSSVoWyNVA1VGVQ5JJX1bI1UDVUZVD0klWlsjVQNVRlUQbAABE+1bI1UDVUZVEWwAARQDWyNVA1VGVRJJMqlbI1UDVUZVE2wAAkxYWyNVA1VGVRRJJWhbI1UDVUZVFUklfVsjVQNVRlUWSSUCWyNVA1VGVRdsAAJMbVsjVQNVRlUYbAACTIRbI1UDVUZVGUkyPlsjVQNVRlUaSTLZ
 (8.768066) [toit] INFO: -> AT+CFUN=0
 (8.773803) [toit.scheduler] INFO: changing run level {run_level: 0}
 (8.777322) [toit.scheduler] INFO: going into deep sleep {ms: 4989}
 (8.779193) [toit.metrics] INFO: persisting metrics
 (9.240931) [toit] INFO: <- OK
 (9.349500) [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.015316) [toit] INFO: booting toit {sdk: v1.6.0-pre.25+c8ae5eaa0, model: esp32-4mb}
 (0.021520) [toit.scheduler] INFO: set initial run_level {run_level: 4}
 (0.055145) [toit.state] INFO: resumed session {session_id: 1, session_time: 209.401086}
 [gc @ 0x3ffcfa58* | soft limit reached (20480 >= 18432)]
 [gc @ 0x3ffcfa58* | heap: 20kb -> 8kb | external: 0kb -> 0kb | used: 93kb | free: 9kb->149kb (largest 108kb) 1.156ms]
 (0.075017) [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.120969) [toit.console_sync] INFO: loaded last console sync {time: 203.694638}
 [gc @ 0x3ffcfa58* | soft limit reached (24576 >= 24576)]
 [gc @ 0x3ffcfa58* | heap: 24kb -> 12kb | external: 0kb -> 0kb | used: 102kb | free: 8kb->140kb (largest 108kb) 1.719ms]
 (0.147005) [toit.scheduler] INFO: running job {job: console_sync}
 (0.149839) [toit.scheduler] INFO: running job {job: poll_metric_flash_usage}
 (0.151740) [toit.scheduler] INFO: running job {job: poll_metric_memory_profile}
 (0.160727) [toit.console] INFO: waiting for console connection to be established
 (0.165315) [toit.network] DEBUG: connecting {network: Cellular (nbiot.tdc.dk)}
 [gc @ 0x3ffcfa58* | soft limit reached (32796 >= 30720)]
 [gc @ 0x3ffcfa58* | byte array allocation failed, length = 511 (heap)]
 [gc @ 0x3ffcfa58* | heap: 32kb -> 20kb | external: 0kb -> 0kb | used: 118kb | free: 12kb->124kb (largest 108kb) 2.041ms]
 [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) 2.111ms]
 (0.253074) [toit] INFO: -> AT
 (0.517780) [toit] INFO: -> AT
 (0.778266) [toit] INFO: -> AT
 (1.038950) [toit] INFO: -> AT
 (1.299560) [toit] INFO: -> AT
 (1.560148) [toit] INFO: -> AT
 (1.820743) [toit] INFO: -> AT
 (2.004456) [toit] INFO: <- OK
 (2.109835) [toit] INFO: -> ATE0
 (2.117404) [toit] INFO: <- OK
 (2.139799) [toit] INFO: -> AT+CMEE=2
 (2.145610) [toit] INFO: <- OK
 (2.167945) [toit] INFO: -> AT+CGMM
 (2.173161) [toit] INFO: <- FiPy
 (2.175791) [toit] INFO: <- OK
 [gc @ 0x3ffcfa58* | soft limit reached (45084 >= 43050)]
 [gc @ 0x3ffcfa58* | string allocation failed, length = 34 (heap)]
 [gc @ 0x3ffcfa58* | heap: 44kb -> 24kb | external: 0kb -> 0kb | used: 149kb | free: 10kb->94kb (largest 84kb) 2.359ms]
 (2.197671) [toit] INFO: -> AT+CGMR
 (2.232244) [toit] INFO: <- UE5.4.0.2
 (2.234621) [toit] INFO: <- OK
 (2.256627) [toit] INFO: -> AT+SQNCCID?
 (2.262389) [toit] INFO: <- +SQNCCID: "",""
 (2.264787) [toit] INFO: <- OK
 (2.267275) [toit.cellular] DEBUG: initialized {model: FiPy, version: UE5.4.0.2, iccid: }
 (2.287005) [toit] INFO: -> AT+CFUN=4
 (2.353950) [toit] INFO: <- OK
 (2.376167) [toit] INFO: -> AT+CPIN?
 (2.382290) [toit] INFO: <- +CME ERROR: SIM not inserted
 (2.637609) [toit] INFO: -> AT+CPIN?
 (2.643368) [toit] INFO: <- +CME ERROR: SIM not inserted
 (2.897796) [toit] INFO: -> AT+CPIN?
 (3.013377) [toit] INFO: <- +CPIN: READY
 (3.015827) [toit] INFO: <- OK
 (3.038040) [toit] INFO: -> AT+CPSMS=0
 (3.045534) [toit] INFO: <- OK
 (3.067925) [toit] INFO: -> AT+CEDRXS=0
 (3.075802) [toit] INFO: <- OK
 (3.098198) [toit] INFO: -> AT+SQNIBRCFG=0
 (3.117752) [toit] INFO: <- OK
 (3.140312) [toit] INFO: -> AT+SQNIPSCFG=1,100
 (3.157885) [toit] INFO: <- OK
 (3.180453) [toit] INFO: -> AT+SQNBANDSEL=0,"standard","20"
 (3.250159) [toit] INFO: <- +SQNBANDSEL: <custom>
 (3.252666) [toit] INFO: <- OK
 (3.275254) [toit] INFO: -> AT+SQNBANDSEL=1,"standard","20"
 (3.282990) [toit] INFO: <- +CME ERROR: operation not supported
 (3.305246) [toit] INFO: -> AT+CGDCONT?
 (3.312362) [toit] INFO: <- +CGDCONT: 1,"IP","nbiot.tdc.dk",,,,0,0,0,0,0,0,0,,0
 (3.314817) [toit] INFO: <- OK
 (3.337729) [toit] INFO: -> AT+CFUN=1,1
 (3.397261) [toit] INFO: <- OK
 (3.452449) [toit] INFO: <- *ignored* [URC] +SHUTDOWN []
 (5.057595) [toit] INFO: <- *ignored* [URC] +SYSSTART []
 (8.322191) [toit] INFO: -> AT+CFUN=4
 (9.054220) [toit] INFO: <- OK
 (9.057027) [toit.cellular] DEBUG: failed, detach from network
 (9.070284) [toit.network] DEBUG: error connecting {network: Cellular (nbiot.tdc.dk), error: DEADLINE_EXCEEDED}
 (9.081071) [toit.network] WARN: failed to connect to any network
 [gc @ 0x3ffcfa58* | soft limit reached (53276 >= 49194)]
 [gc @ 0x3ffcfa58* | byte array allocation failed, length = 68 (heap)]
 [gc @ 0x3ffcfa58* | heap: 52kb -> 24kb | external: 0kb -> 0kb | used: 165kb | free: 10kb->78kb (largest 68kb) 2.437ms]
 ----
 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 WyNVBVVYU1UXdjEuNi4wLXByZS4yNStjOGFlNWVhYTBTVQllc3AzMi00bWJbJFUjVRAAAAAAAAAAAAAAAAAAAAAAWyNVBFVFU1UJRVhDRVBUSU9OU1URRkFJTEVEIFRPIENPTk5FQ1RbI1UCVVNbI1UbWyNVA1VGVQBsAAKuMVsjVQNVRlUBbAACrY5bI1UDVUZVAmwAAqoaWyNVA1VGVQNJJWhbI1UDVUZVBEklfVsjVQNVRlUFSSUCWyNVA1VGVQZsAAKqV1sjVQNVRlUHbAACqfNbI1UDVUZVCGwAAQP4WyNVA1VGVQlsAAGg+FsjVQNVRlUKbAABFD5bI1UDVUZVC2wAARRXWyNVA1VGVQxsAAET1FsjVQNVRlUNSSVoWyNVA1VGVQ5JJX1bI1UDVUZVD0klWlsjVQNVRlUQbAABE+1bI1UDVUZVEWwAARQDWyNVA1VGVRJJMqlbI1UDVUZVE2wAAkxYWyNVA1VGVRRJJWhbI1UDVUZVFUklfVsjVQNVRlUWSSUCWyNVA1VGVRdsAAJMbVsjVQNVRlUYbAACTIRbI1UDVUZVGUkyPlsjVQNVRlUaSTLZ
 (9.211134) [toit] INFO: -> AT+CFUN=0
 (9.216794) [toit.scheduler] INFO: changing run level {run_level: 0}
 (9.220542) [toit.scheduler] INFO: going into deep sleep {ms: 4987}
 (9.222428) [toit.metrics] INFO: persisting metrics
 (9.465681) [toit] INFO: <- OK
 (9.573418) [toit] INFO: entering deep sleep {ms: 4987}
 Entering deep sleep for 4987ms
 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.015316) [toit] INFO: booting toit {sdk: v1.6.0-pre.25+c8ae5eaa0, model: esp32-4mb}
 (0.021520) [toit.scheduler] INFO: set initial run_level {run_level: 4}
 (0.054967) [toit.state] INFO: resumed session {session_id: 1, session_time: 224.018282}
 [gc @ 0x3ffcfa58* | soft limit reached (20480 >= 18432)]
 [gc @ 0x3ffcfa58* | string allocation failed, length = 16 (heap)]
 [gc @ 0x3ffcfa58* | heap: 20kb -> 8kb | external: 0kb -> 0kb | used: 93kb | free: 9kb->149kb (largest 108kb) 1.146ms]
 (0.080723) [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.121080) [toit.console_sync] INFO: loaded last console sync {time: 218.530142}
 [gc @ 0x3ffcfa58* | soft limit reached (24576 >= 24576)]
 [gc @ 0x3ffcfa58* | heap: 24kb -> 12kb | external: 0kb -> 0kb | used: 102kb | free: 8kb->140kb (largest 108kb) 1.730ms]
 (0.147801) [toit.scheduler] INFO: running job {job: console_sync}
 (0.150636) [toit.scheduler] INFO: running job {job: poll_metric_flash_usage}
 (0.152540) [toit.scheduler] INFO: running job {job: poll_metric_memory_profile}
 (0.161708) [toit.console] INFO: waiting for console connection to be established
 (0.166116) [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) 1.999ms]
 [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) 2.098ms]
 (0.252891) [toit] INFO: -> AT
 (0.517783) [toit] INFO: -> AT
 (0.778253) [toit] INFO: -> AT
 (1.038933) [toit] INFO: -> AT
 (1.299742) [toit] INFO: -> AT
 (1.560118) [toit] INFO: -> AT
 (1.820711) [toit] INFO: -> AT
 (1.997280) [toit] INFO: <- OK
 (2.106826) [toit] INFO: -> ATE0
 (2.114741) [toit] INFO: <- OK
 (2.137232) [toit] INFO: -> AT+CMEE=2
 (2.142820) [toit] INFO: <- OK
 [gc @ 0x3ffcfa58* | soft limit reached (45084 >= 43050)]
 [gc @ 0x3ffcfa58* | byte array allocation failed, length = 511 (heap)]
 [gc @ 0x3ffcfa58* | heap: 44kb -> 24kb | external: 0kb -> 0kb | used: 148kb | free: 10kb->94kb (largest 84kb) 2.309ms]
 (2.164897) [toit] INFO: -> AT+CGMM
 (2.170301) [toit] INFO: <- FiPy
 (2.173211) [toit] INFO: <- OK
 (2.195121) [toit] INFO: -> AT+CGMR
 (2.224339) [toit] INFO: <- UE5.4.0.2
 (2.226632) [toit] INFO: <- OK
 (2.248548) [toit] INFO: -> AT+SQNCCID?
 (2.253984) [toit] INFO: <- +SQNCCID: "",""
 (2.256338) [toit] INFO: <- OK
 (2.258806) [toit.cellular] DEBUG: initialized {model: FiPy, version: UE5.4.0.2, iccid: }
 (2.278603) [toit] INFO: -> AT+CFUN=4
 (2.689437) [toit] INFO: <- OK
 (2.711707) [toit] INFO: -> AT+CPIN?
 (2.717695) [toit] INFO: <- +CME ERROR: SIM not inserted
 (2.972090) [toit] INFO: -> AT+CPIN?
 (3.010057) [toit] INFO: <- +CPIN: READY
 (3.012440) [toit] INFO: <- OK
 (3.034702) [toit] INFO: -> AT+CPSMS=0
 (3.041776) [toit] INFO: <- OK
 (3.064214) [toit] INFO: -> AT+CEDRXS=0
 (3.071412) [toit] INFO: <- OK
 (3.093826) [toit] INFO: -> AT+SQNIBRCFG=0
 (3.113449) [toit] INFO: <- OK
 (3.136058) [toit] INFO: -> AT+SQNIPSCFG=1,100
 (3.153268) [toit] INFO: <- OK
 (3.175888) [toit] INFO: -> AT+SQNBANDSEL=0,"standard","20"
 (3.245785) [toit] INFO: <- +SQNBANDSEL: <custom>
 (3.248229) [toit] INFO: <- OK
 (3.270592) [toit] INFO: -> AT+SQNBANDSEL=1,"standard","20"
 (3.278259) [toit] INFO: <- +CME ERROR: operation not supported
 (3.306383) [toit] INFO: -> AT+CGDCONT?
 (3.312839) [toit] INFO: <- +CGDCONT: 1,"IP","nbiot.tdc.dk",,,,0,0,0,0,0,0,0,,0
 (3.315662) [toit] INFO: <- OK
 (3.337827) [toit] INFO: -> AT+CFUN=1,1
 (3.405917) [toit] INFO: <- OK
 (3.517644) [toit] INFO: <- *ignored* [URC] +SHUTDOWN []
 (5.582856) [toit] INFO: <- *ignored* [URC] +SYSSTART []
 (8.323670) [toit] INFO: -> AT+CFUN=4
 (9.079678) [toit] INFO: <- OK
 (9.081925) [toit.cellular] DEBUG: failed, detach from network
 (9.100813) [toit.network] DEBUG: error connecting {network: Cellular (nbiot.tdc.dk), error: DEADLINE_EXCEEDED}
 (9.112018) [toit.network] WARN: failed to connect to any network
 [gc @ 0x3ffcfa58* | soft limit reached (53276 >= 49194)]
 [gc @ 0x3ffcfa58* | heap: 52kb -> 24kb | external: 0kb -> 0kb | used: 165kb | free: 10kb->78kb (largest 68kb) 2.458ms]
 ----
 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 WyNVBVVYU1UXdjEuNi4wLXByZS4yNStjOGFlNWVhYTBTVQllc3AzMi00bWJbJFUjVRAAAAAAAAAAAAAAAAAAAAAAWyNVBFVFU1UJRVhDRVBUSU9OU1URRkFJTEVEIFRPIENPTk5FQ1RbI1UCVVNbI1UbWyNVA1VGVQBsAAKuMVsjVQNVRlUBbAACrY5bI1UDVUZVAmwAAqoaWyNVA1VGVQNJJWhbI1UDVUZVBEklfVsjVQNVRlUFSSUCWyNVA1VGVQZsAAKqV1sjVQNVRlUHbAACqfNbI1UDVUZVCGwAAQP4WyNVA1VGVQlsAAGg+FsjVQNVRlUKbAABFD5bI1UDVUZVC2wAARRXWyNVA1VGVQxsAAET1FsjVQNVRlUNSSVoWyNVA1VGVQ5JJX1bI1UDVUZVD0klWlsjVQNVRlUQbAABE+1bI1UDVUZVEWwAARQDWyNVA1VGVRJJMqlbI1UDVUZVE2wAAkxYWyNVA1VGVRRJJWhbI1UDVUZVFUklfVsjVQNVRlUWSSUCWyNVA1VGVRdsAAJMbVsjVQNVRlUYbAACTIRbI1UDVUZVGUkyPlsjVQNVRlUaSTLZ
 (9.239708) [toit] INFO: -> AT+CFUN=0
 (9.245391) [toit.scheduler] INFO: changing run level {run_level: 0}
 (9.248828) [toit.scheduler] INFO: going into deep sleep {ms: 4989}
 (9.250706) [toit.metrics] INFO: persisting metrics
 (9.499407) [toit] INFO: <- OK
 (9.607066) [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.015332) [toit] INFO: booting toit {sdk: v1.6.0-pre.25+c8ae5eaa0, model: esp32-4mb}
 (0.021536) [toit.scheduler] INFO: set initial run_level {run_level: 4}
 (0.054830) [toit.state] INFO: resumed session {session_id: 1, session_time: 238.669618}
 [gc @ 0x3ffcfa58* | soft limit reached (20480 >= 18432)]
 [gc @ 0x3ffcfa58* | heap: 20kb -> 8kb | external: 0kb -> 0kb | used: 93kb | free: 9kb->149kb (largest 108kb) 1.143ms]
 (0.074886) [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.121172) [toit.console_sync] INFO: loaded last console sync {time: 233.177337}
 [gc @ 0x3ffcfa58* | soft limit reached (24576 >= 24576)]
 [gc @ 0x3ffcfa58* | byte array allocation failed, length = 25 (heap)]
 [gc @ 0x3ffcfa58* | heap: 24kb -> 12kb | external: 0kb -> 0kb | used: 102kb | free: 8kb->140kb (largest 108kb) 1.698ms]
 (0.155408) [toit.scheduler] INFO: running job {job: console_sync}
 (0.158242) [toit.scheduler] INFO: running job {job: poll_metric_flash_usage}
 (0.160130) [toit.scheduler] INFO: running job {job: poll_metric_memory_profile}
 (0.169353) [toit.console] INFO: waiting for console connection to be established
 (0.173734) [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) 2.002ms]
 [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) 2.099ms]
 (0.261910) [toit] INFO: -> AT
 (0.523287) [toit] INFO: -> AT
 (0.783754) [toit] INFO: -> AT
 (1.044436) [toit] INFO: -> AT
 (1.305254) [toit] INFO: -> AT
 (1.565624) [toit] INFO: -> AT
 (1.826211) [toit] INFO: -> AT
 (2.006703) [toit] INFO: <- OK
 (2.112128) [toit] INFO: -> ATE0
 (2.119629) [toit] INFO: <- OK
 (2.142108) [toit] INFO: -> AT+CMEE=2
 (2.147864) [toit] INFO: <- OK
 [gc @ 0x3ffcfa58* | soft limit reached (45084 >= 43050)]
 [gc @ 0x3ffcfa58* | byte array allocation failed, length = 511 (heap)]
 [gc @ 0x3ffcfa58* | heap: 44kb -> 24kb | external: 0kb -> 0kb | used: 148kb | free: 10kb->94kb (largest 84kb) 2.350ms]
 (2.170048) [toit] INFO: -> AT+CGMM
 (2.175526) [toit] INFO: <- FiPy
 (2.178465) [toit] INFO: <- OK
 (2.200337) [toit] INFO: -> AT+CGMR
 (2.221104) [toit] INFO: <- UE5.4.0.2
 (2.223473) [toit] INFO: <- OK
 (2.245317) [toit] INFO: -> AT+SQNCCID?
 (2.250795) [toit] INFO: <- +SQNCCID: "",""
 (2.253231) [toit] INFO: <- OK
 (2.255699) [toit.cellular] DEBUG: initialized {model: FiPy, version: UE5.4.0.2, iccid: }
 (2.275436) [toit] INFO: -> AT+CFUN=4
 (2.341937) [toit] INFO: <- OK
 (2.364166) [toit] INFO: -> AT+CPIN?
 (2.370147) [toit] INFO: <- +CME ERROR: SIM not inserted
 (2.624588) [toit] INFO: -> AT+CPIN?
 (2.630333) [toit] INFO: <- +CME ERROR: SIM not inserted
 (2.884760) [toit] INFO: -> AT+CPIN?
 (3.003753) [toit] INFO: <- +CPIN: READY
 (3.006208) [toit] INFO: <- OK
 (3.028716) [toit] INFO: -> AT+CPSMS=0
 (3.036238) [toit] INFO: <- OK
 (3.058698) [toit] INFO: -> AT+CEDRXS=0
 (3.066611) [toit] INFO: <- OK
 (3.089039) [toit] INFO: -> AT+SQNIBRCFG=0
 (3.108556) [toit] INFO: <- OK
 (3.131119) [toit] INFO: -> AT+SQNIPSCFG=1,100
 (3.148879) [toit] INFO: <- OK
 (3.171468) [toit] INFO: -> AT+SQNBANDSEL=0,"standard","20"
 (3.241276) [toit] INFO: <- +SQNBANDSEL: <custom>
 (3.243823) [toit] INFO: <- OK
 (3.266430) [toit] INFO: -> AT+SQNBANDSEL=1,"standard","20"
 (3.274221) [toit] INFO: <- +CME ERROR: operation not supported
 (3.297433) [toit] INFO: -> AT+CGDCONT?
 (3.304041) [toit] INFO: <- +CGDCONT: 1,"IP","nbiot.tdc.dk",,,,0,0,0,0,0,0,0,,0
 (3.306477) [toit] INFO: <- OK
 (3.329103) [toit] INFO: -> AT+CFUN=1,1
 (3.388422) [toit] INFO: <- OK
 (3.443721) [toit] INFO: <- *ignored* [URC] +SHUTDOWN []
 (5.227175) [toit] INFO: <- *ignored* [URC] +SYSSTART []
 (8.314005) [toit] INFO: -> AT+CFUN=4
 (8.903413) [toit] INFO: <- OK
 (8.906230) [toit.cellular] DEBUG: failed, detach from network
 (8.930281) [toit.network] DEBUG: error connecting {network: Cellular (nbiot.tdc.dk), error: DEADLINE_EXCEEDED}
 (8.941838) [toit.network] WARN: failed to connect to any network
 [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) 2.446ms]
 ----
 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 WyNVBVVYU1UXdjEuNi4wLXByZS4yNStjOGFlNWVhYTBTVQllc3AzMi00bWJbJFUjVRAAAAAAAAAAAAAAAAAAAAAAWyNVBFVFU1UJRVhDRVBUSU9OU1URRkFJTEVEIFRPIENPTk5FQ1RbI1UCVVNbI1UbWyNVA1VGVQBsAAKuMVsjVQNVRlUBbAACrY5bI1UDVUZVAmwAAqoaWyNVA1VGVQNJJWhbI1UDVUZVBEklfVsjVQNVRlUFSSUCWyNVA1VGVQZsAAKqV1sjVQNVRlUHbAACqfNbI1UDVUZVCGwAAQP4WyNVA1VGVQlsAAGg+FsjVQNVRlUKbAABFD5bI1UDVUZVC2wAARRXWyNVA1VGVQxsAAET1FsjVQNVRlUNSSVoWyNVA1VGVQ5JJX1bI1UDVUZVD0klWlsjVQNVRlUQbAABE+1bI1UDVUZVEWwAARQDWyNVA1VGVRJJMqlbI1UDVUZVE2wAAkxYWyNVA1VGVRRJJWhbI1UDVUZVFUklfVsjVQNVRlUWSSUCWyNVA1VGVRdsAAJMbVsjVQNVRlUYbAACTIRbI1UDVUZVGUkyPlsjVQNVRlUaSTLZ
 (9.069890) [toit] INFO: -> AT+CFUN=0
 (9.075483) [toit.scheduler] INFO: changing run level {run_level: 0}
 (9.079033) [toit.scheduler] INFO: going into deep sleep {ms: 4989}
 (9.080922) [toit.metrics] INFO: persisting metrics
 (9.752493) [toit] INFO: <- OK
 (9.860224) [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.015332) [toit] INFO: booting toit {sdk: v1.6.0-pre.25+c8ae5eaa0, model: esp32-4mb}
 (0.021536) [toit.scheduler] INFO: set initial run_level {run_level: 4}
 (0.054668) [toit.state] INFO: resumed session {session_id: 1, session_time: 253.580984}
 [gc @ 0x3ffcfa58* | soft limit reached (20480 >= 18432)]
 [gc @ 0x3ffcfa58* | heap: 20kb -> 8kb | external: 0kb -> 0kb | used: 93kb | free: 9kb->149kb (largest 108kb) 1.123ms]
 (0.074925) [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.121273) [toit.console_sync] INFO: loaded last console sync {time: 247.665213}
 [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) 1.701ms]
 (0.155891) [toit.scheduler] INFO: running job {job: console_sync}
 (0.158732) [toit.scheduler] INFO: running job {job: poll_metric_flash_usage}
 (0.160852) [toit.scheduler] INFO: running job {job: poll_metric_memory_profile}
 (0.169647) [toit.console] INFO: waiting for console connection to be established
 (0.174216) [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) 2.017ms]
 [gc @ 0x3ffcfa58* | soft limit reached (45084 >= 43050)]
 [gc @ 0x3ffcfa58* | byte array allocation failed, length = 16 (heap)]
 [gc @ 0x3ffcfa58* | heap: 44kb -> 20kb | external: 0kb -> 0kb | used: 142kb | free: 12kb->100kb (largest 88kb) 2.110ms]
 (0.263615) [toit] INFO: -> AT
 (0.525008) [toit] INFO: -> AT
 (0.785479) [toit] INFO: -> AT
 (1.046161) [toit] INFO: -> AT
 (1.316176) [toit] INFO: -> AT
 [gc @ 0x3ffcfa58* | soft limit reached (45084 >= 43050)]
 [gc @ 0x3ffcfa58* | string allocation failed, length = 6 (heap)]
 [gc @ 0x3ffcfa58* | heap: 44kb -> 24kb | external: 0kb -> 0kb | used: 149kb | free: 6kb->94kb (largest 88kb) 2.447ms]
 (1.577698) [toit] INFO: -> AT
 (1.838382) [toit] INFO: -> AT
 (2.009399) [toit] INFO: <- OK
 (2.114586) [toit] INFO: -> ATE0
 (2.122408) [toit] INFO: <- OK
 (2.144849) [toit] INFO: -> AT+CMEE=2
 (2.150348) [toit] INFO: <- OK
 (2.172627) [toit] INFO: -> AT+CGMM
 (2.178106) [toit] INFO: <- FiPy
 (2.180676) [toit] INFO: <- OK
 (2.206010) [toit] INFO: -> AT+CGMR
 (2.226778) [toit] INFO: <- UE5.4.0.2
 (2.229297) [toit] INFO: <- OK
 (2.251306) [toit] INFO: -> AT+SQNCCID?
 (2.256728) [toit] INFO: <- +SQNCCID: "",""
 (2.259117) [toit] INFO: <- OK
 (2.261565) [toit.cellular] DEBUG: initialized {model: FiPy, version: UE5.4.0.2, iccid: }
 (2.281348) [toit] INFO: -> AT+CFUN=4
 (2.347589) [toit] INFO: <- OK
 (2.369804) [toit] INFO: -> AT+CPIN?
 (2.375464) [toit] INFO: <- +CME ERROR: SIM not inserted
 (2.630064) [toit] INFO: -> AT+CPIN?
 (2.635663) [toit] INFO: <- +CME ERROR: SIM not inserted
 (2.889895) [toit] INFO: -> AT+CPIN?
 (2.998983) [toit] INFO: <- +CPIN: READY
 (3.001348) [toit] INFO: <- OK
 (3.023630) [toit] INFO: -> AT+CPSMS=0
 (3.030731) [toit] INFO: <- OK
 (3.053400) [toit] INFO: -> AT+CEDRXS=0
 (3.060532) [toit] INFO: <- OK
 (3.082949) [toit] INFO: -> AT+SQNIBRCFG=0
 (3.102351) [toit] INFO: <- OK
 (3.124961) [toit] INFO: -> AT+SQNIPSCFG=1,100
 (3.141995) [toit] INFO: <- OK
 (3.164608) [toit] INFO: -> AT+SQNBANDSEL=0,"standard","20"
 (3.234101) [toit] INFO: <- +SQNBANDSEL: <custom>
 (3.236537) [toit] INFO: <- OK
 (3.258871) [toit] INFO: -> AT+SQNBANDSEL=1,"standard","20"
 (3.266983) [toit] INFO: <- +CME ERROR: operation not supported
 (3.289173) [toit] INFO: -> AT+CGDCONT?
 (3.295909) [toit] INFO: <- +CGDCONT: 1,"IP","nbiot.tdc.dk",,,,0,0,0,0,0,0,0,,0
 (3.307416) [toit] INFO: <- OK
 (3.329766) [toit] INFO: -> AT+CFUN=1,1
 (3.388848) [toit] INFO: <- OK
 (3.444073) [toit] INFO: <- *ignored* [URC] +SHUTDOWN []
 (5.466115) [toit] INFO: <- *ignored* [URC] +SYSSTART []
 (8.315311) [toit] INFO: -> AT+CFUN=4
 (10.163454) [toit.scheduler] INFO: running job {job: persist_metrics}
 [gc @ 0x3ffcfa58* | soft limit reached (53276 >= 49194)]
 [gc @ 0x3ffcfa58* | heap: 52kb -> 24kb | external: 0kb -> 0kb | used: 165kb | free: 10kb->78kb (largest 68kb) 2.402ms]
 (10.176162) [toit.metrics] INFO: persisting metrics
 (10.275999) [toit.scheduler] INFO: running job {job: poll_metric_memory_profile}
 (11.529039) [toit] INFO: <- OK
 (11.531831) [toit.cellular] DEBUG: failed, detach from network
 (11.550283) [toit.network] DEBUG: error connecting {network: Cellular (nbiot.tdc.dk), error: DEADLINE_EXCEEDED}
 (11.561812) [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 WyNVBVVYU1UXdjEuNi4wLXByZS4yNStjOGFlNWVhYTBTVQllc3AzMi00bWJbJFUjVRAAAAAAAAAAAAAAAAAAAAAAWyNVBFVFU1UJRVhDRVBUSU9OU1URRkFJTEVEIFRPIENPTk5FQ1RbI1UCVVNbI1UbWyNVA1VGVQBsAAKuMVsjVQNVRlUBbAACrY5bI1UDVUZVAmwAAqoaWyNVA1VGVQNJJWhbI1UDVUZVBEklfVsjVQNVRlUFSSUCWyNVA1VGVQZsAAKqV1sjVQNVRlUHbAACqfNbI1UDVUZVCGwAAQP4WyNVA1VGVQlsAAGg+FsjVQNVRlUKbAABFD5bI1UDVUZVC2wAARRXWyNVA1VGVQxsAAET1FsjVQNVRlUNSSVoWyNVA1VGVQ5JJX1bI1UDVUZVD0klWlsjVQNVRlUQbAABE+1bI1UDVUZVEWwAARQDWyNVA1VGVRJJMqlbI1UDVUZVE2wAAkxYWyNVA1VGVRRJJWhbI1UDVUZVFUklfVsjVQNVRlUWSSUCWyNVA1VGVRdsAAJMbVsjVQNVRlUYbAACTIRbI1UDVUZVGUkyPlsjVQNVRlUaSTLZ
 [gc @ 0x3ffcfa58* | soft limit reached (49636 >= 49194)]
 [gc @ 0x3ffcfa58* | heap: 48kb -> 24kb | external: 0kb -> 0kb | used: 157kb | free: 10kb->86kb (largest 68kb) 2.428ms]
 (11.771467) [toit] INFO: -> AT+CFUN=0
 (11.778032) [toit.scheduler] INFO: changing run level {run_level: 0}
 (11.781789) [toit.scheduler] INFO: going into deep sleep {ms: 4979}
 (11.783669) [toit.metrics] INFO: persisting metrics
 (12.029296) [toit] INFO: <- OK
 (12.137075) [toit] INFO: entering deep sleep {ms: 4979}
 Entering deep sleep for 4979ms
 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.015316) [toit] INFO: booting toit {sdk: v1.6.0-pre.25+c8ae5eaa0, model: esp32-4mb}
 (0.021520) [toit.scheduler] INFO: set initial run_level {run_level: 4}
 (0.055830) [toit.state] INFO: resumed session {session_id: 1, session_time: 270.750956}
 [gc @ 0x3ffcfa58* | soft limit reached (20480 >= 18432)]
 [gc @ 0x3ffcfa58* | heap: 20kb -> 8kb | external: 0kb -> 0kb | used: 93kb | free: 9kb->149kb (largest 108kb) 1.138ms]
 (0.076629) [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.121375) [toit.console_sync] INFO: loaded last console sync {time: 265.259108}
 [gc @ 0x3ffcfa58* | soft limit reached (24576 >= 24576)]
 [gc @ 0x3ffcfa58* | byte array allocation failed, length = 16 (heap)]
 [gc @ 0x3ffcfa58* | heap: 24kb -> 16kb | external: 0kb -> 0kb | used: 102kb | free: 8kb->140kb (largest 108kb) 1.714ms]
 (0.156560) [toit.scheduler] INFO: running job {job: console_sync}
 (0.159626) [toit.scheduler] INFO: running job {job: poll_metric_flash_usage}
 (0.161543) [toit.scheduler] INFO: running job {job: poll_metric_memory_profile}
 (0.170309) [toit.console] INFO: waiting for console connection to be established
 (0.174883) [toit.network] DEBUG: connecting {network: Cellular (nbiot.tdc.dk)}
 [gc @ 0x3ffcfa58* | soft limit reached (36892 >= 36864)]
 [gc @ 0x3ffcfa58* | heap: 36kb -> 20kb | external: 0kb -> 0kb | used: 126kb | free: 12kb->116kb (largest 104kb) 2.066ms]
 [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) 2.114ms]
 (0.260947) [toit] INFO: -> AT
 (0.522317) [toit] INFO: -> AT
 (0.782791) [toit] INFO: -> AT
 (1.043473) [toit] INFO: -> AT
 (1.304084) [toit] INFO: -> AT
 (1.564674) [toit] INFO: -> AT
 (1.825269) [toit] INFO: -> AT
 (2.006785) [toit] INFO: <- OK
 (2.112194) [toit] INFO: -> ATE0
 (2.119933) [toit] INFO: <- OK
 (2.142390) [toit] INFO: -> AT+CMEE=2
 (2.148002) [toit] INFO: <- OK
 [gc @ 0x3ffcfa58* | soft limit reached (45084 >= 43050)]
 [gc @ 0x3ffcfa58* | byte array allocation failed, length = 511 (heap)]
 [gc @ 0x3ffcfa58* | heap: 44kb -> 24kb | external: 0kb -> 0kb | used: 148kb | free: 10kb->94kb (largest 84kb) 2.322ms]
 (2.170058) [toit] INFO: -> AT+CGMM
 (2.175630) [toit] INFO: <- FiPy
 (2.178314) [toit] INFO: <- OK
 (2.205976) [toit] INFO: -> AT+CGMR
 (2.227008) [toit] INFO: <- UE5.4.0.2
 (2.229274) [toit] INFO: <- OK
 (2.251227) [toit] INFO: -> AT+SQNCCID?
 (2.256556) [toit] INFO: <- +SQNCCID: "",""
 (2.258923) [toit] INFO: <- OK
 (2.261384) [toit.cellular] DEBUG: initialized {model: FiPy, version: UE5.4.0.2, iccid: }
 (2.281077) [toit] INFO: -> AT+CFUN=4
 (2.737143) [toit] INFO: <- OK
 (2.759426) [toit] INFO: -> AT+CPIN?
 (2.765456) [toit] INFO: <- +CME ERROR: SIM busy
 (3.019887) [toit] INFO: -> AT+CPIN?
 (3.026447) [toit] INFO: <- +CPIN: READY
 (3.028835) [toit] INFO: <- OK
 (3.051000) [toit] INFO: -> AT+CPSMS=0
 (3.058065) [toit] INFO: <- OK
 (3.080483) [toit] INFO: -> AT+CEDRXS=0
 (3.087575) [toit] INFO: <- OK
 (3.109970) [toit] INFO: -> AT+SQNIBRCFG=0
 (3.129450) [toit] INFO: <- OK
 (3.152060) [toit] INFO: -> AT+SQNIPSCFG=1,100
 (3.169063) [toit] INFO: <- OK
 (3.191660) [toit] INFO: -> AT+SQNBANDSEL=0,"standard","20"
 (3.261535) [toit] INFO: <- +SQNBANDSEL: <custom>
 (3.263959) [toit] INFO: <- OK
 (3.286306) [toit] INFO: -> AT+SQNBANDSEL=1,"standard","20"
 (3.294035) [toit] INFO: <- +CME ERROR: operation not supported
 (3.316244) [toit] INFO: -> AT+CGDCONT?
 (3.323599) [toit] INFO: <- +CGDCONT: 1,"IP","nbiot.tdc.dk",,,,0,0,0,0,0,0,0,,0
 (3.326702) [toit] INFO: <- OK
 (3.349077) [toit] INFO: -> AT+CFUN=1,1
 (3.807554) [toit] INFO: <- OK
 (3.862729) [toit] INFO: <- *ignored* [URC] +SHUTDOWN []
 (5.336204) [toit] INFO: <- *ignored* [URC] +SYSSTART []
 (8.334916) [toit] INFO: -> AT+CFUN=4
 (8.431519) [toit] INFO: <- OK
 (8.433766) [toit.cellular] DEBUG: failed, detach from network
 (8.450810) [toit.network] DEBUG: error connecting {network: Cellular (nbiot.tdc.dk), error: DEADLINE_EXCEEDED}
 (8.462808) [toit.network] WARN: failed to connect to any network
 [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) 2.459ms]
 ----
 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 WyNVBVVYU1UXdjEuNi4wLXByZS4yNStjOGFlNWVhYTBTVQllc3AzMi00bWJbJFUjVRAAAAAAAAAAAAAAAAAAAAAAWyNVBFVFU1UJRVhDRVBUSU9OU1URRkFJTEVEIFRPIENPTk5FQ1RbI1UCVVNbI1UbWyNVA1VGVQBsAAKuMVsjVQNVRlUBbAACrY5bI1UDVUZVAmwAAqoaWyNVA1VGVQNJJWhbI1UDVUZVBEklfVsjVQNVRlUFSSUCWyNVA1VGVQZsAAKqV1sjVQNVRlUHbAACqfNbI1UDVUZVCGwAAQP4WyNVA1VGVQlsAAGg+FsjVQNVRlUKbAABFD5bI1UDVUZVC2wAARRXWyNVA1VGVQxsAAET1FsjVQNVRlUNSSVoWyNVA1VGVQ5JJX1bI1UDVUZVD0klWlsjVQNVRlUQbAABE+1bI1UDVUZVEWwAARQDWyNVA1VGVRJJMqlbI1UDVUZVE2wAAkxYWyNVA1VGVRRJJWhbI1UDVUZVFUklfVsjVQNVRlUWSSUCWyNVA1VGVRdsAAJMbVsjVQNVRlUYbAACTIRbI1UDVUZVGUkyPlsjVQNVRlUaSTLZ
 (8.590568) [toit] INFO: -> AT+CFUN=0
 (8.596269) [toit.scheduler] INFO: changing run level {run_level: 0}
 (8.599850) [toit.scheduler] INFO: going into deep sleep {ms: 4989}
 (8.601744) [toit.metrics] INFO: persisting metrics
 (8.846158) [toit] INFO: <- OK
 (8.953864) [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.015316) [toit] INFO: booting toit {sdk: v1.6.0-pre.25+c8ae5eaa0, model: esp32-4mb}
 (0.021520) [toit.scheduler] INFO: set initial run_level {run_level: 4}
 (0.055663) [toit.state] INFO: resumed session {session_id: 1, session_time: 284.751698}
 [gc @ 0x3ffcfa58* | soft limit reached (20480 >= 18432)]
 [gc @ 0x3ffcfa58* | heap: 20kb -> 8kb | external: 0kb -> 0kb | used: 93kb | free: 9kb->149kb (largest 108kb) 1.152ms]
 (0.076855) [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}
 [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) 1.697ms]
 (0.133231) [toit.console_sync] INFO: loaded last console sync {time: 279.262589}
 (0.153126) [toit.scheduler] INFO: running job {job: console_sync}
 (0.155893) [toit.scheduler] INFO: running job {job: poll_metric_flash_usage}
 (0.158041) [toit.scheduler] INFO: running job {job: poll_metric_memory_profile}
 (0.166768) [toit.console] INFO: waiting for console connection to be established
 [gc @ 0x3ffcfa58* | soft limit reached (32796 >= 30720)]
 [gc @ 0x3ffcfa58* | heap: 32kb -> 20kb | external: 0kb -> 0kb | used: 118kb | free: 12kb->124kb (largest 108kb) 2.006ms]
 (0.171338) [toit.network] DEBUG: connecting {network: Cellular (nbiot.tdc.dk)}
 [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) 2.108ms]
 (0.263501) [toit] INFO: -> AT
 (0.524862) [toit] INFO: -> AT
 (0.785345) [toit] INFO: -> AT
 [gc @ 0x3ffcfa58* | soft limit reached (45084 >= 43050)]
 [gc @ 0x3ffcfa58* | byte array allocation failed, length = 64 (heap)]
 [gc @ 0x3ffcfa58* | heap: 44kb -> 24kb | external: 0kb -> 0kb | used: 149kb | free: 6kb->94kb (largest 88kb) 2.453ms]
 (1.057337) [toit] INFO: -> AT
 (1.307617) [toit] INFO: -> AT
 (1.568208) [toit] INFO: -> AT
 (1.828781) [toit] INFO: -> AT
 (2.006903) [toit] INFO: <- OK
 (2.112728) [toit] INFO: -> ATE0
 (2.120357) [toit] INFO: <- OK
 (2.142814) [toit] INFO: -> AT+CMEE=2
 (2.148490) [toit] INFO: <- OK
 (2.170842) [toit] INFO: -> AT+CGMM
 (2.176218) [toit] INFO: <- FiPy
 (2.178773) [toit] INFO: <- OK
 (2.206012) [toit] INFO: -> AT+CGMR
 (2.227030) [toit] INFO: <- UE5.4.0.2
 (2.229545) [toit] INFO: <- OK
 (2.251542) [toit] INFO: -> AT+SQNCCID?
 (2.256888) [toit] INFO: <- +SQNCCID: "",""
 (2.259254) [toit] INFO: <- OK
 (2.261702) [toit.cellular] DEBUG: initialized {model: FiPy, version: UE5.4.0.2, iccid: }
 (2.281480) [toit] INFO: -> AT+CFUN=4
 (2.643649) [toit] INFO: <- OK
 (2.665877) [toit] INFO: -> AT+CPIN?
 (2.671499) [toit] INFO: <- +CME ERROR: SIM not inserted
 (2.926030) [toit] INFO: -> AT+CPIN?
 (3.004766) [toit] INFO: <- +CPIN: READY
 (3.007167) [toit] INFO: <- OK
 (3.029404) [toit] INFO: -> AT+CPSMS=0
 (3.036548) [toit] INFO: <- OK
 (3.058991) [toit] INFO: -> AT+CEDRXS=0
 (3.066162) [toit] INFO: <- OK
 (3.088582) [toit] INFO: -> AT+SQNIBRCFG=0
 (3.108008) [toit] INFO: <- OK
 (3.130809) [toit] INFO: -> AT+SQNIPSCFG=1,100
 (3.147859) [toit] INFO: <- OK
 (3.170484) [toit] INFO: -> AT+SQNBANDSEL=0,"standard","20"
 (3.240343) [toit] INFO: <- +SQNBANDSEL: <custom>
 (3.242771) [toit] INFO: <- OK
 (3.265119) [toit] INFO: -> AT+SQNBANDSEL=1,"standard","20"
 (3.272791) [toit] INFO: <- +CME ERROR: operation not supported
 (3.295015) [toit] INFO: -> AT+CGDCONT?
 (3.306487) [toit] INFO: <- +CGDCONT: 1,"IP","nbiot.tdc.dk",,,,0,0,0,0,0,0,0,,0
 (3.309056) [toit] INFO: <- OK
 (3.331202) [toit] INFO: -> AT+CFUN=1,1
 (3.709035) [toit] INFO: <- OK
 (3.763939) [toit] INFO: <- *ignored* [URC] +SHUTDOWN []
 (5.419429) [toit] INFO: <- *ignored* [URC] +SYSSTART []
 (8.317441) [toit] INFO: -> AT+CFUN=4
 (8.896382) [toit] INFO: <- OK
 (8.898640) [toit.cellular] DEBUG: failed, detach from network
 (8.910810) [toit.network] DEBUG: error connecting {network: Cellular (nbiot.tdc.dk), error: DEADLINE_EXCEEDED}
 [gc @ 0x3ffcfa58* | soft limit reached (53276 >= 49194)]
 [gc @ 0x3ffcfa58* | byte array allocation failed, length = 16 (heap)]
 [gc @ 0x3ffcfa58* | heap: 52kb -> 24kb | external: 0kb -> 0kb | used: 165kb | free: 10kb->78kb (largest 68kb) 2.443ms]
 (8.941004) [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 WyNVBVVYU1UXdjEuNi4wLXByZS4yNStjOGFlNWVhYTBTVQllc3AzMi00bWJbJFUjVRAAAAAAAAAAAAAAAAAAAAAAWyNVBFVFU1UJRVhDRVBUSU9OU1URRkFJTEVEIFRPIENPTk5FQ1RbI1UCVVNbI1UbWyNVA1VGVQBsAAKuMVsjVQNVRlUBbAACrY5bI1UDVUZVAmwAAqoaWyNVA1VGVQNJJWhbI1UDVUZVBEklfVsjVQNVRlUFSSUCWyNVA1VGVQZsAAKqV1sjVQNVRlUHbAACqfNbI1UDVUZVCGwAAQP4WyNVA1VGVQlsAAGg+FsjVQNVRlUKbAABFD5bI1UDVUZVC2wAARRXWyNVA1VGVQxsAAET1FsjVQNVRlUNSSVoWyNVA1VGVQ5JJX1bI1UDVUZVD0klWlsjVQNVRlUQbAABE+1bI1UDVUZVEWwAARQDWyNVA1VGVRJJMqlbI1UDVUZVE2wAAkxYWyNVA1VGVRRJJWhbI1UDVUZVFUklfVsjVQNVRlUWSSUCWyNVA1VGVRdsAAJMbVsjVQNVRlUYbAACTIRbI1UDVUZVGUkyPlsjVQNVRlUaSTLZ
 [gc @ 0x3ffcfa58* | soft limit reached (49636 >= 49194)]
 [gc @ 0x3ffcfa58* | heap: 48kb -> 24kb | external: 0kb -> 0kb | used: 157kb | free: 10kb->86kb (largest 68kb) 2.457ms]
 (9.055464) [toit] INFO: -> AT+CFUN=0
 (9.061045) [toit.scheduler] INFO: changing run level {run_level: 0}
 (9.064477) [toit.scheduler] INFO: going into deep sleep {ms: 4984}
 (9.068818) [toit.metrics] INFO: persisting metrics
 (9.318011) [toit] INFO: <- OK
 (9.425719) [toit] INFO: entering deep sleep {ms: 4984}
 Entering deep sleep for 4984ms
 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.015332) [toit] INFO: booting toit {sdk: v1.6.0-pre.25+c8ae5eaa0, model: esp32-4mb}
 (0.021536) [toit.scheduler] INFO: set initial run_level {run_level: 4}
 (0.055543) [toit.state] INFO: resumed session {session_id: 1, session_time: 299.214577}
 [gc @ 0x3ffcfa58* | soft limit reached (20480 >= 18432)]
 [gc @ 0x3ffcfa58* | heap: 20kb -> 8kb | external: 0kb -> 0kb | used: 93kb | free: 9kb->149kb (largest 108kb) 1.152ms]
 (0.076789) [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}
 [gc @ 0x3ffcfa58* | soft limit reached (24576 >= 24576)]
 [gc @ 0x3ffcfa58* | heap: 24kb -> 12kb | external: 0kb -> 0kb | used: 102kb | free: 8kb->140kb (largest 108kb) 1.643ms]
 (0.127152) [toit.console_sync] INFO: loaded last console sync {time: 293.718953}
 (0.146214) [toit.scheduler] INFO: running job {job: console_sync}
 (0.148996) [toit.scheduler] INFO: running job {job: poll_metric_flash_usage}
 (0.151142) [toit.scheduler] INFO: running job {job: poll_metric_memory_profile}
 (0.159873) [toit.console] INFO: waiting for console connection to be established
 [gc @ 0x3ffcfa58* | soft limit reached (32796 >= 30720)]
 [gc @ 0x3ffcfa58* | heap: 32kb -> 20kb | external: 0kb -> 0kb | used: 118kb | free: 12kb->124kb (largest 108kb) 1.984ms]
 (0.164452) [toit.network] DEBUG: connecting {network: Cellular (nbiot.tdc.dk)}
 [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) 2.099ms]
 (0.256300) [toit] INFO: -> AT
 (0.517881) [toit] INFO: -> AT
 (0.778337) [toit] INFO: -> AT
 [gc @ 0x3ffcfa58* | soft limit reached (45084 >= 43050)]
 [gc @ 0x3ffcfa58* | byte array allocation failed, length = 64 (heap)]
 [gc @ 0x3ffcfa58* | heap: 44kb -> 24kb | external: 0kb -> 0kb | used: 149kb | free: 6kb->94kb (largest 88kb) 2.438ms]
 (1.050343) [toit] INFO: -> AT
 (1.299675) [toit] INFO: -> AT
 (1.560264) [toit] INFO: -> AT
 (1.820839) [toit] INFO: -> AT
 (1.999202) [toit] INFO: <- OK
 (2.106391) [toit] INFO: -> ATE0
 (2.114037) [toit] INFO: <- OK
 (2.136490) [toit] INFO: -> AT+CMEE=2
 (2.142182) [toit] INFO: <- OK
 (2.164590) [toit] INFO: -> AT+CGMM
 (2.169912) [toit] INFO: <- FiPy
 (2.172458) [toit] INFO: <- OK
 (2.194292) [toit] INFO: -> AT+CGMR
 (2.214956) [toit] INFO: <- UE5.4.0.2
 (2.217513) [toit] INFO: <- OK
 (2.239489) [toit] INFO: -> AT+SQNCCID?
 (2.244850) [toit] INFO: <- +SQNCCID: "",""
 (2.247222) [toit] INFO: <- OK
 (2.249678) [toit.cellular] DEBUG: initialized {model: FiPy, version: UE5.4.0.2, iccid: }
 (2.269444) [toit] INFO: -> AT+CFUN=4
 (2.752085) [toit] INFO: <- OK
 (2.774323) [toit] INFO: -> AT+CPIN?
 (2.779960) [toit] INFO: <- +CME ERROR: SIM busy
 (3.034509) [toit] INFO: -> AT+CPIN?
 (3.039892) [toit] INFO: <- +CPIN: READY
 (3.042235) [toit] INFO: <- OK
 (3.064382) [toit] INFO: -> AT+CPSMS=0
 (3.071499) [toit] INFO: <- OK
 (3.093938) [toit] INFO: -> AT+CEDRXS=0
 (3.101067) [toit] INFO: <- OK
 (3.123491) [toit] INFO: -> AT+SQNIBRCFG=0
 (3.142561) [toit] INFO: <- OK
 (3.165136) [toit] INFO: -> AT+SQNIPSCFG=1,100
 (3.182408) [toit] INFO: <- OK
 (3.206714) [toit] INFO: -> AT+SQNBANDSEL=0,"standard","20"
 (3.280186) [toit] INFO: <- +SQNBANDSEL: <custom>
 (3.282586) [toit] INFO: <- OK
 (3.306493) [toit] INFO: -> AT+SQNBANDSEL=1,"standard","20"
 (3.314168) [toit] INFO: <- +CME ERROR: operation not supported
 (3.336398) [toit] INFO: -> AT+CGDCONT?
 (3.342947) [toit] INFO: <- +CGDCONT: 1,"IP","nbiot.tdc.dk",,,,0,0,0,0,0,0,0,,0
 (3.345486) [toit] INFO: <- OK
 (3.367787) [toit] INFO: -> AT+CFUN=1,1
 (3.762159) [toit] INFO: <- OK
 (3.817175) [toit] INFO: <- *ignored* [URC] +SHUTDOWN []
 (5.435646) [toit] INFO: <- *ignored* [URC] +SYSSTART []
 (8.353857) [toit] INFO: -> AT+CFUN=4
 (8.509650) [toit] INFO: <- OK
 (8.511881) [toit.cellular] DEBUG: failed, detach from network
 (8.530808) [toit.network] DEBUG: error connecting {network: Cellular (nbiot.tdc.dk), error: DEADLINE_EXCEEDED}
 [gc @ 0x3ffcfa58* | soft limit reached (53276 >= 49194)]
 [gc @ 0x3ffcfa58* | byte array allocation failed, length = 16 (heap)]
 [gc @ 0x3ffcfa58* | heap: 52kb -> 24kb | external: 0kb -> 0kb | used: 165kb | free: 10kb->78kb (largest 68kb) 2.443ms]
 (8.560290) [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 WyNVBVVYU1UXdjEuNi4wLXByZS4yNStjOGFlNWVhYTBTVQllc3AzMi00bWJbJFUjVRAAAAAAAAAAAAAAAAAAAAAAWyNVBFVFU1UJRVhDRVBUSU9OU1URRkFJTEVEIFRPIENPTk5FQ1RbI1UCVVNbI1UbWyNVA1VGVQBsAAKuMVsjVQNVRlUBbAACrY5bI1UDVUZVAmwAAqoaWyNVA1VGVQNJJWhbI1UDVUZVBEklfVsjVQNVRlUFSSUCWyNVA1VGVQZsAAKqV1sjVQNVRlUHbAACqfNbI1UDVUZVCGwAAQP4WyNVA1VGVQlsAAGg+FsjVQNVRlUKbAABFD5bI1UDVUZVC2wAARRXWyNVA1VGVQxsAAET1FsjVQNVRlUNSSVoWyNVA1VGVQ5JJX1bI1UDVUZVD0klWlsjVQNVRlUQbAABE+1bI1UDVUZVEWwAARQDWyNVA1VGVRJJMqlbI1UDVUZVE2wAAkxYWyNVA1VGVRRJJWhbI1UDVUZVFUklfVsjVQNVRlUWSSUCWyNVA1VGVRdsAAJMbVsjVQNVRlUYbAACTIRbI1UDVUZVGUkyPlsjVQNVRlUaSTLZ
 [gc @ 0x3ffcfa58* | soft limit reached (49636 >= 49194)]
 [gc @ 0x3ffcfa58* | heap: 48kb -> 20kb | external: 0kb -> 0kb | used: 157kb | free: 10kb->86kb (largest 68kb) 2.456ms]
 (8.676430) [toit] INFO: -> AT+CFUN=0
 (8.682003) [toit.scheduler] INFO: changing run level {run_level: 0}
 (8.685712) [toit.scheduler] INFO: going into deep sleep {ms: 4984}
 (8.689503) [toit.metrics] INFO: persisting metrics
 (8.930717) [toit] INFO: <- OK
 (9.038435) [toit] INFO: entering deep sleep {ms: 4984}
 Entering deep sleep for 4984ms
 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.015316) [toit] INFO: booting toit {sdk: v1.6.0-pre.25+c8ae5eaa0, model: esp32-4mb}
 (0.021520) [toit.scheduler] INFO: set initial run_level {run_level: 4}
 (0.055388) [toit.state] INFO: resumed session {session_id: 1, session_time: 313.290631}
 [gc @ 0x3ffcfa58* | soft limit reached (20480 >= 18432)]
 [gc @ 0x3ffcfa58* | heap: 20kb -> 8kb | external: 0kb -> 0kb | used: 93kb | free: 9kb->149kb (largest 108kb) 1.153ms]
 (0.076633) [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}
 [gc @ 0x3ffcfa58* | soft limit reached (24576 >= 24576)]
 [gc @ 0x3ffcfa58* | heap: 24kb -> 12kb | external: 0kb -> 0kb | used: 102kb | free: 8kb->140kb (largest 108kb) 1.645ms]
 (0.127253) [toit.console_sync] INFO: loaded last console sync {time: 307.803149}
 (0.146478) [toit.scheduler] INFO: running job {job: console_sync}
 (0.149268) [toit.scheduler] INFO: running job {job: poll_metric_flash_usage}
 (0.151445) [toit.scheduler] INFO: running job {job: poll_metric_memory_profile}
 (0.160147) [toit.console] INFO: waiting for console connection to be established
 [gc @ 0x3ffcfa58* | soft limit reached (32796 >= 30720)]
 [gc @ 0x3ffcfa58* | heap: 32kb -> 20kb | external: 0kb -> 0kb | used: 118kb | free: 12kb->124kb (largest 108kb) 1.984ms]
 (0.164724) [toit.network] DEBUG: connecting {network: Cellular (nbiot.tdc.dk)}
 [gc @ 0x3ffcfa58* | soft limit reached (45084 >= 43050)]
 [gc @ 0x3ffcfa58* | byte array allocation failed, length = 16 (heap)]
 [gc @ 0x3ffcfa58* | heap: 44kb -> 20kb | external: 0kb -> 0kb | used: 142kb | free: 12kb->100kb (largest 88kb) 2.102ms]
 (0.257647) [toit] INFO: -> AT
 (0.518999) [toit] INFO: -> AT
 (0.779476) [toit] INFO: -> AT
 [gc @ 0x3ffcfa58* | soft limit reached (45084 >= 43050)]
 [gc @ 0x3ffcfa58* | byte array allocation failed, length = 64 (heap)]
 [gc @ 0x3ffcfa58* | heap: 44kb -> 24kb | external: 0kb -> 0kb | used: 149kb | free: 6kb->94kb (largest 88kb) 2.434ms]
 (1.051478) [toit] INFO: -> AT
 (1.300823) [toit] INFO: -> AT
 (1.561410) [toit] INFO: -> AT
 (1.821985) [toit] INFO: -> AT
 (2.082808) [toit] INFO: -> AT
 (2.343190) [toit] INFO: -> AT
 �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.015330) [toit] INFO: booting toit {sdk: v1.6.0-pre.25+c8ae5eaa0, model: esp32-4mb}
 (0.021534) [toit.scheduler] INFO: set initial run_level {run_level: 4}
 (0.060477) [toit.state] INFO: starting new session {session_id: 2, session_time: 0.067880}
 [gc @ 0x3ffcf9f0* | soft limit reached (20480 >= 18432)]
 [gc @ 0x3ffcf9f0* | heap: 20kb -> 8kb | external: 0kb -> 0kb | used: 93kb | free: 9kb->149kb (largest 108kb) 1.173ms]
 (0.082058) [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}
 [gc @ 0x3ffcf9f0* | soft limit reached (24576 >= 24576)]
 [gc @ 0x3ffcf9f0* | heap: 24kb -> 12kb | external: 0kb -> 0kb | used: 102kb | free: 8kb->140kb (largest 108kb) 1.645ms]
 (0.127341) [toit.console_sync] INFO: loaded last console sync {time: null}
 (0.143956) [toit.scheduler] INFO: running job {job: console_sync}
 (0.146660) [toit.scheduler] INFO: running job {job: flush_metrics}
 (0.148753) [toit.scheduler] INFO: running job {job: poll_metric_flash_usage}
 (0.153766) [toit.scheduler] INFO: running job {job: poll_metric_memory_profile}
 (0.163447) [toit.console] INFO: waiting for console connection to be established
 [gc @ 0x3ffcf9f0* | soft limit reached (32796 >= 30720)]
 [gc @ 0x3ffcf9f0* | heap: 32kb -> 20kb | external: 0kb -> 0kb | used: 118kb | free: 12kb->124kb (largest 108kb) 1.991ms]
 (0.168078) [toit.network] DEBUG: connecting {network: Cellular (nbiot.tdc.dk)}
 (0.191486) [toit.metrics] INFO: persisting metrics
 (0.194583) [toit.metrics] INFO: flushing metrics
 [gc @ 0x3ffcf9f0* | soft limit reached (45084 >= 43050)]
 [gc @ 0x3ffcf9f0* | byte array allocation failed, length = 16 (heap)]
 [gc @ 0x3ffcf9f0* | heap: 44kb -> 20kb | external: 0kb -> 0kb | used: 142kb | free: 12kb->100kb (largest 88kb) 2.119ms]
 [gc @ 0x3ffcf9f0* | soft limit reached (45084 >= 43050)]
 [gc @ 0x3ffcf9f0* | heap: 44kb -> 24kb | external: 0kb -> 0kb | used: 143kb | free: 12kb->100kb (largest 88kb) 2.233ms]
 (0.519955) [toit] INFO: -> AT
 (0.660311) [toit] INFO: <- *ignored* [URC] +SYSSTART []
 (0.780868) [toit] INFO: -> AT
 (1.041668) [toit] INFO: -> AT
 (1.302268) [toit] INFO: -> AT
 (1.554441) [toit] INFO: <- OK
 (1.576801) [toit] INFO: -> AT
 (1.581464) [toit] INFO: <- OK
 (1.685372) [toit] INFO: -> ATE0
 (1.692751) [toit] INFO: <- OK
 (1.715148) [toit] INFO: -> AT+CMEE=2
 (1.720584) [toit] INFO: <- OK
 (1.742694) [toit] INFO: -> AT+CGMM
 (1.747659) [toit] INFO: <- FiPy
 (1.750261) [toit] INFO: <- OK
 (1.772199) [toit] INFO: -> AT+CGMR
 (1.792952) [toit] INFO: <- UE5.4.0.2
 (1.795254) [toit] INFO: <- OK
 (1.817530) [toit] INFO: -> AT+SQNCCID?
 (1.822892) [toit] INFO: <- +SQNCCID: "",""
 (1.825254) [toit] INFO: <- OK
 (1.827709) [toit.cellular] DEBUG: initialized {model: FiPy, version: UE5.4.0.2, iccid: }
 (1.847839) [toit] INFO: -> AT+CFUN=4
 (2.366645) [toit] INFO: <- OK
 (2.388950) [toit] INFO: -> AT+CPIN?
 (2.394583) [toit] INFO: <- +CME ERROR: SIM busy
 (2.649253) [toit] INFO: -> AT+CPIN?
 (2.655000) [toit] INFO: <- +CPIN: READY
 (2.657388) [toit] INFO: <- OK
 (2.679591) [toit] INFO: -> AT+CPSMS=0
 (2.686638) [toit] INFO: <- OK
 (2.709058) [toit] INFO: -> AT+CEDRXS=0
 (2.716247) [toit] INFO: <- OK
 (2.738650) [toit] INFO: -> AT+SQNIBRCFG=0
 (2.750485) [toit] INFO: <- OK
 (2.773267) [toit] INFO: -> AT+SQNIPSCFG=1,100
 (2.790563) [toit] INFO: <- OK
 (2.813189) [toit] INFO: -> AT+SQNBANDSEL=0,"standard","20"
 (2.882140) [toit] INFO: <- +SQNBANDSEL: <custom>
 (2.884580) [toit] INFO: <- OK
 (2.907001) [toit] INFO: -> AT+SQNBANDSEL=1,"standard","20"
 (2.914761) [toit] INFO: <- +CME ERROR: operation not supported
 (2.937464) [toit] INFO: -> AT+CGDCONT?
 (2.944048) [toit] INFO: <- +CGDCONT: 1,"IP","nbiot.tdc.dk",,,,0,0,0,0,0,0,0,,0
 [gc @ 0x3ffcf9f0* | soft limit reached (53276 >= 49194)]
 [gc @ 0x3ffcf9f0* | string allocation failed, length = 29 (heap)]
 [gc @ 0x3ffcf9f0* | heap: 52kb -> 24kb | external: 0kb -> 0kb | used: 165kb | free: 10kb->78kb (largest 68kb) 2.439ms]
 (2.946579) [toit] INFO: <- OK
 (2.986190) [toit] INFO: -> AT+CFUN=1,1
 (3.045752) [toit] INFO: <- OK
 (3.100507) [toit] INFO: <- *ignored* [URC] +SHUTDOWN []
 (4.576037) [toit] INFO: <- *ignored* [URC] +SYSSTART []
 (7.972429) [toit] INFO: -> AT+CFUN=4
 (8.047600) [toit] INFO: <- OK
 (8.050171) [toit.cellular] DEBUG: failed, detach from network
 (8.060855) [toit.network] DEBUG: error connecting {network: Cellular (nbiot.tdc.dk), error: DEADLINE_EXCEEDED}
 (8.068031) [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 WyNVBVVYU1UXdjEuNi4wLXByZS4yNStjOGFlNWVhYTBTVQllc3AzMi00bWJbJFUjVRAAAAAAAAAAAAAAAAAAAAAAWyNVBFVFU1UJRVhDRVBUSU9OU1URRkFJTEVEIFRPIENPTk5FQ1RbI1UCVVNbI1UbWyNVA1VGVQBsAAKuMVsjVQNVRlUBbAACrY5bI1UDVUZVAmwAAqoaWyNVA1VGVQNJJWhbI1UDVUZVBEklfVsjVQNVRlUFSSUCWyNVA1VGVQZsAAKqV1sjVQNVRlUHbAACqfNbI1UDVUZVCGwAAQP4WyNVA1VGVQlsAAGg+FsjVQNVRlUKbAABFD5bI1UDVUZVC2wAARRXWyNVA1VGVQxsAAET1FsjVQNVRlUNSSVoWyNVA1VGVQ5JJX1bI1UDVUZVD0klWlsjVQNVRlUQbAABE+1bI1UDVUZVEWwAARQDWyNVA1VGVRJJMqlbI1UDVUZVE2wAAkxYWyNVA1VGVRRJJWhbI1UDVUZVFUklfVsjVQNVRlUWSSUCWyNVA1VGVRdsAAJMbVsjVQNVRlUYbAACTIRbI1UDVUZVGUkyPlsjVQNVRlUaSTLZ
 (8.162490) [toit] INFO: -> AT+CFUN=0
 (8.168412) [toit.scheduler] INFO: changing run level {run_level: 0}
 (8.171903) [toit.scheduler] INFO: going into deep sleep {ms: 4989}
 (8.173783) [toit.metrics] INFO: persisting metrics
 (8.423439) [toit] INFO: <- OK
 [gc @ 0x3ffcf9f0* | soft limit reached (49636 >= 49194)]
 [gc @ 0x3ffcf9f0* | byte array allocation failed, length = 350 (heap)]
 [gc @ 0x3ffcf9f0* | heap: 48kb -> 24kb | external: 0kb -> 0kb | used: 151kb | free: 15kb->92kb (largest 68kb) 2.513ms]
 (8.531106) [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.015316) [toit] INFO: booting toit {sdk: v1.6.0-pre.25+c8ae5eaa0, model: esp32-4mb}
 (0.021520) [toit.scheduler] INFO: set initial run_level {run_level: 4}
 (0.056523) [toit.state] INFO: resumed session {session_id: 2, session_time: 13.644132}
 [gc @ 0x3ffcfa58* | soft limit reached (20480 >= 18432)]
 [gc @ 0x3ffcfa58* | heap: 20kb -> 8kb | external: 0kb -> 0kb | used: 93kb | free: 9kb->149kb (largest 108kb) 1.151ms]
 (0.077722) [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.112122) [toit.console_sync] INFO: loaded last console sync {time: 8.148742}
 [gc @ 0x3ffcfa58* | soft limit reached (24576 >= 24576)]
 [gc @ 0x3ffcfa58* | heap: 24kb -> 16kb | external: 0kb -> 0kb | used: 102kb | free: 8kb->140kb (largest 108kb) 1.712ms]
 (0.138392) [toit.scheduler] INFO: running job {job: console_sync}
 (0.141223) [toit.scheduler] INFO: running job {job: poll_metric_flash_usage}
 (0.143115) [toit.scheduler] INFO: running job {job: poll_metric_memory_profile}
 (0.152076) [toit.console] INFO: waiting for console connection to be established
 (0.156707) [toit.network] DEBUG: connecting {network: Cellular (nbiot.tdc.dk)}
 [gc @ 0x3ffcfa58* | soft limit reached (36892 >= 36864)]
 [gc @ 0x3ffcfa58* | heap: 36kb -> 20kb | external: 0kb -> 0kb | used: 126kb | free: 12kb->116kb (largest 104kb) 2.112ms]
 (0.205130) [toit] INFO: -> AT
 (0.466482) [toit] INFO: -> AT
 (0.727727) [toit] INFO: -> AT
 (0.988300) [toit] INFO: -> AT
 (1.248994) [toit] INFO: -> AT
 [gc @ 0x3ffcfa58* | soft limit reached (45084 >= 43050)]
 [gc @ 0x3ffcfa58* | string allocation failed, length = 6 (heap)]
 [gc @ 0x3ffcfa58* | heap: 44kb -> 24kb | external: 0kb -> 0kb | used: 149kb | free: 6kb->94kb (largest 88kb) 2.458ms]
 (1.516190) [toit] INFO: -> AT
 (1.777802) [toit] INFO: -> AT
 (1.948429) [toit] INFO: <- OK
 (2.053658) [toit] INFO: -> ATE0
 (2.061327) [toit] INFO: <- OK
 (2.084019) [toit] INFO: -> AT+CMEE=2
 (2.089623) [toit] INFO: <- OK
 (2.111784) [toit] INFO: -> AT+CGMM
 (2.117202) [toit] INFO: <- FiPy
 (2.119776) [toit] INFO: <- OK
 (2.141663) [toit] INFO: -> AT+CGMR
 (2.162383) [toit] INFO: <- UE5.4.0.2
 (2.164912) [toit] INFO: <- OK
 (2.187470) [toit] INFO: -> AT+SQNCCID?
 (2.192809) [toit] INFO: <- +SQNCCID: "",""
 (2.195179) [toit] INFO: <- OK
 (2.197676) [toit.cellular] DEBUG: initialized {model: FiPy, version: UE5.4.0.2, iccid: }
 (2.217814) [toit] INFO: -> AT+CFUN=4
 (2.545795) [toit] INFO: <- OK
 (2.568055) [toit] INFO: -> AT+CPIN?
 (2.573691) [toit] INFO: <- +CME ERROR: SIM not inserted
 (2.828265) [toit] INFO: -> AT+CPIN?
 (2.946096) [toit] INFO: <- +CPIN: READY
 (2.948485) [toit] INFO: <- OK
 (2.970665) [toit] INFO: -> AT+CPSMS=0
 (2.977948) [toit] INFO: <- OK
 (3.006335) [toit] INFO: -> AT+CEDRXS=0
 (3.013571) [toit] INFO: <- OK
 (3.035973) [toit] INFO: -> AT+SQNIBRCFG=0
 (3.055802) [toit] INFO: <- OK
 (3.078613) [toit] INFO: -> AT+SQNIPSCFG=1,100
 (3.095703) [toit] INFO: <- OK
 (3.118403) [toit] INFO: -> AT+SQNBANDSEL=0,"standard","20"
 (3.191783) [toit] INFO: <- +SQNBANDSEL: <custom>
 (3.194222) [toit] INFO: <- OK
 (3.216659) [toit] INFO: -> AT+SQNBANDSEL=1,"standard","20"
 (3.224329) [toit] INFO: <- +CME ERROR: operation not supported
 (3.247403) [toit] INFO: -> AT+CGDCONT?
 (3.254102) [toit] INFO: <- +CGDCONT: 1,"IP","nbiot.tdc.dk",,,,0,0,0,0,0,0,0,,0
 (3.256652) [toit] INFO: <- OK
 (3.279280) [toit] INFO: -> AT+CFUN=1,1
 (3.338892) [toit] INFO: <- OK
 (3.677103) [toit] INFO: <- *ignored* [URC] +SHUTDOWN []
 (5.386795) [toit] INFO: <- *ignored* [URC] +SYSSTART []
 (8.265114) [toit] INFO: -> AT+CFUN=4
 (8.339494) [toit] INFO: <- OK
 (8.341749) [toit.cellular] DEBUG: failed, detach from network
 (8.360816) [toit.network] DEBUG: error connecting {network: Cellular (nbiot.tdc.dk), error: DEADLINE_EXCEEDED}
 [gc @ 0x3ffcfa58* | soft limit reached (53276 >= 49194)]
 [gc @ 0x3ffcfa58* | heap: 52kb -> 24kb | external: 0kb -> 0kb | used: 165kb | free: 10kb->78kb (largest 68kb) 2.437ms]
 (8.379602) [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 WyNVBVVYU1UXdjEuNi4wLXByZS4yNStjOGFlNWVhYTBTVQllc3AzMi00bWJbJFUjVRAAAAAAAAAAAAAAAAAAAAAAWyNVBFVFU1UJRVhDRVBUSU9OU1URRkFJTEVEIFRPIENPTk5FQ1RbI1UCVVNbI1UbWyNVA1VGVQBsAAKuMVsjVQNVRlUBbAACrY5bI1UDVUZVAmwAAqoaWyNVA1VGVQNJJWhbI1UDVUZVBEklfVsjVQNVRlUFSSUCWyNVA1VGVQZsAAKqV1sjVQNVRlUHbAACqfNbI1UDVUZVCGwAAQP4WyNVA1VGVQlsAAGg+FsjVQNVRlUKbAABFD5bI1UDVUZVC2wAARRXWyNVA1VGVQxsAAET1FsjVQNVRlUNSSVoWyNVA1VGVQ5JJX1bI1UDVUZVD0klWlsjVQNVRlUQbAABE+1bI1UDVUZVEWwAARQDWyNVA1VGVRJJMqlbI1UDVUZVE2wAAkxYWyNVA1VGVRRJJWhbI1UDVUZVFUklfVsjVQNVRlUWSSUCWyNVA1VGVRdsAAJMbVsjVQNVRlUYbAACTIRbI1UDVUZVGUkyPlsjVQNVRlUaSTLZ
 (8.474627) [toit] INFO: -> AT+CFUN=0
 (8.480335) [toit.scheduler] INFO: changing run level {run_level: 0}
 (8.484076) [toit.scheduler] INFO: going into deep sleep {ms: 4989}
 (8.485951) [toit.metrics] INFO: persisting metrics
 (8.760339) [toit] INFO: <- OK
 (8.868047) [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.015317) [toit] INFO: booting toit {sdk: v1.6.0-pre.25+c8ae5eaa0, model: esp32-4mb}
 (0.021521) [toit.scheduler] INFO: set initial run_level {run_level: 4}
 (0.056399) [toit.state] INFO: resumed session {session_id: 2, session_time: 27.557051}
 [gc @ 0x3ffcfa58* | soft limit reached (20480 >= 18432)]
 [gc @ 0x3ffcfa58* | heap: 20kb -> 8kb | external: 0kb -> 0kb | used: 93kb | free: 9kb->149kb (largest 108kb) 1.155ms]
 (0.077605) [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.112214) [toit.console_sync] INFO: loaded last console sync {time: 22.037658}
 [gc @ 0x3ffcfa58* | soft limit reached (24576 >= 24576)]
 [gc @ 0x3ffcfa58* | byte array allocation failed, length = 16 (heap)]
 [gc @ 0x3ffcfa58* | heap: 24kb -> 16kb | external: 0kb -> 0kb | used: 102kb | free: 8kb->140kb (largest 108kb) 1.716ms]
 (0.145378) [toit.scheduler] INFO: running job {job: console_sync}
 (0.148219) [toit.scheduler] INFO: running job {job: poll_metric_flash_usage}
 (0.150107) [toit.scheduler] INFO: running job {job: poll_metric_memory_profile}
 (0.159263) [toit.console] INFO: waiting for console connection to be established
 (0.163703) [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) 2.107ms]
 (0.226164) [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) 2.412ms]
 (0.488014) [toit] INFO: -> AT
 (0.748380) [toit] INFO: -> AT
 (1.009233) [toit] INFO: -> AT
 (1.269805) [toit] INFO: -> AT
 (1.530400) [toit] INFO: -> AT
 (1.790987) [toit] INFO: -> AT
 (1.975821) [toit] INFO: <- OK
 (2.081067) [toit] INFO: -> ATE0
 (2.088846) [toit] INFO: <- OK
 (2.111339) [toit] INFO: -> AT+CMEE=2
 (2.116997) [toit] INFO: <- OK
 (2.139085) [toit] INFO: -> AT+CGMM
 (2.144455) [toit] INFO: <- FiPy
 (2.147010) [toit] INFO: <- OK
 (2.168874) [toit] INFO: -> AT+CGMR
 (2.189663) [toit] INFO: <- UE5.4.0.2
 (2.192182) [toit] INFO: <- OK
 (2.214191) [toit] INFO: -> AT+SQNCCID?
 (2.219576) [toit] INFO: <- +SQNCCID: "",""
 (2.221923) [toit] INFO: <- OK
 (2.224387) [toit.cellular] DEBUG: initialized {model: FiPy, version: UE5.4.0.2, iccid: }
 (2.244178) [toit] INFO: -> AT+CFUN=4
 (2.700682) [toit] INFO: <- OK
 (2.722956) [toit] INFO: -> AT+CPIN?
 (2.728624) [toit] INFO: <- +CME ERROR: SIM busy
 (2.983124) [toit] INFO: -> AT+CPIN?
 (2.991926) [toit] INFO: <- +CPIN: READY
 (2.994293) [toit] INFO: <- OK
 (3.016542) [toit] INFO: -> AT+CPSMS=0
 (3.023717) [toit] INFO: <- OK
 (3.046165) [toit] INFO: -> AT+CEDRXS=0
 (3.053323) [toit] INFO: <- OK
 (3.075748) [toit] INFO: -> AT+SQNIBRCFG=0
 (3.095126) [toit] INFO: <- OK
 (3.117867) [toit] INFO: -> AT+SQNIPSCFG=1,100
 (3.135095) [toit] INFO: <- OK
 (3.158222) [toit] INFO: -> AT+SQNBANDSEL=0,"standard","20"
 (3.228120) [toit] INFO: <- +SQNBANDSEL: <custom>
 (3.230520) [toit] INFO: <- OK
 (3.252908) [toit] INFO: -> AT+SQNBANDSEL=1,"standard","20"
 (3.260566) [toit] INFO: <- +CME ERROR: operation not supported
 (3.282783) [toit] INFO: -> AT+CGDCONT?
 (3.289138) [toit] INFO: <- +CGDCONT: 1,"IP","nbiot.tdc.dk",,,,0,0,0,0,0,0,0,,0
 (3.291857) [toit] INFO: <- OK
 (3.314079) [toit] INFO: -> AT+CFUN=1,1
 (3.439410) [toit] INFO: <- OK
 (3.860466) [toit] INFO: <- *ignored* [URC] +SHUTDOWN []
 (5.509704) [toit] INFO: <- *ignored* [URC] +SYSSTART []
 [gc @ 0x3ffcfa58* | soft limit reached (53276 >= 49194)]
 [gc @ 0x3ffcfa58* | byte array allocation failed, length = 610 (heap)]
 [gc @ 0x3ffcfa58* | heap: 52kb -> 24kb | external: 0kb -> 0kb | used: 165kb | free: 10kb->78kb (largest 68kb) 2.382ms]
 (8.312034) [toit] INFO: -> AT+CFUN=4
 (8.424544) [toit] INFO: <- OK
 (8.427026) [toit.cellular] DEBUG: failed, detach from network
 (8.440282) [toit.network] DEBUG: error connecting {network: Cellular (nbiot.tdc.dk), error: DEADLINE_EXCEEDED}
 (8.448148) [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 WyNVBVVYU1UXdjEuNi4wLXByZS4yNStjOGFlNWVhYTBTVQllc3AzMi00bWJbJFUjVRAAAAAAAAAAAAAAAAAAAAAAWyNVBFVFU1UJRVhDRVBUSU9OU1URRkFJTEVEIFRPIENPTk5FQ1RbI1UCVVNbI1UbWyNVA1VGVQBsAAKuMVsjVQNVRlUBbAACrY5bI1UDVUZVAmwAAqoaWyNVA1VGVQNJJWhbI1UDVUZVBEklfVsjVQNVRlUFSSUCWyNVA1VGVQZsAAKqV1sjVQNVRlUHbAACqfNbI1UDVUZVCGwAAQP4WyNVA1VGVQlsAAGg+FsjVQNVRlUKbAABFD5bI1UDVUZVC2wAARRXWyNVA1VGVQxsAAET1FsjVQNVRlUNSSVoWyNVA1VGVQ5JJX1bI1UDVUZVD0klWlsjVQNVRlUQbAABE+1bI1UDVUZVEWwAARQDWyNVA1VGVRJJMqlbI1UDVUZVE2wAAkxYWyNVA1VGVRRJJWhbI1UDVUZVFUklfVsjVQNVRlUWSSUCWyNVA1VGVRdsAAJMbVsjVQNVRlUYbAACTIRbI1UDVUZVGUkyPlsjVQNVRlUaSTLZ
 (8.604508) [toit] INFO: -> AT+CFUN=0
 (8.618648) [toit.scheduler] INFO: changing run level {run_level: 0}
 (8.622113) [toit.scheduler] INFO: going into deep sleep {ms: 4981}
 (8.623988) [toit.metrics] INFO: persisting metrics
 [gc @ 0x3ffcfa58* | soft limit reached (49636 >= 49194)]
 [gc @ 0x3ffcfa58* | string allocation failed, length = 5 (heap)]
 [gc @ 0x3ffcfa58* | heap: 48kb -> 24kb | external: 0kb -> 0kb | used: 157kb | free: 10kb->86kb (largest 68kb) 2.502ms]
 (9.268395) [toit] INFO: <- OK
 (9.376717) [toit] INFO: entering deep sleep {ms: 4981}
 Entering deep sleep for 4981ms
 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.015332) [toit] INFO: booting toit {sdk: v1.6.0-pre.25+c8ae5eaa0, model: esp32-4mb}
 (0.021536) [toit.scheduler] INFO: set initial run_level {run_level: 4}
 (0.057409) [toit.state] INFO: resumed session {session_id: 2, session_time: 41.965371}
 [gc @ 0x3ffcfa58* | soft limit reached (20480 >= 18432)]
 [gc @ 0x3ffcfa58* | heap: 20kb -> 8kb | external: 0kb -> 0kb | used: 93kb | free: 9kb->149kb (largest 108kb) 1.168ms]
 (0.078866) [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.121961) [toit.console_sync] INFO: loaded last console sync {time: 36.074733}
 [gc @ 0x3ffcfa58* | soft limit reached (24576 >= 24576)]
 [gc @ 0x3ffcfa58* | byte array allocation failed, length = 16 (heap)]
 [gc @ 0x3ffcfa58* | heap: 24kb -> 16kb | external: 0kb -> 0kb | used: 102kb | free: 8kb->140kb (largest 108kb) 1.726ms]
 (0.155596) [toit.scheduler] INFO: running job {job: console_sync}
 (0.158441) [toit.scheduler] INFO: running job {job: poll_metric_flash_usage}
 (0.160330) [toit.scheduler] INFO: running job {job: poll_metric_memory_profile}
 (0.169346) [toit.console] INFO: waiting for console connection to be established
 (0.173921) [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) 2.116ms]
 [gc @ 0x3ffcfa58* | soft limit reached (45084 >= 43050)]
 [gc @ 0x3ffcfa58* | heap: 44kb -> 24kb | external: 0kb -> 0kb | used: 148kb | free: 6kb->94kb (largest 88kb) 2.398ms]
 (0.242056) [toit] INFO: -> AT
 (0.503288) [toit] INFO: -> AT
 (0.763762) [toit] INFO: -> AT
 (1.024448) [toit] INFO: -> AT
 (1.285039) [toit] INFO: -> AT
 (1.545632) [toit] INFO: -> AT
 (1.806234) [toit] INFO: -> AT
 (1.986291) [toit] INFO: <- OK
 (2.091521) [toit] INFO: -> ATE0
 (2.099195) [toit] INFO: <- OK
 (2.121660) [toit] INFO: -> AT+CMEE=2
 (2.127194) [toit] INFO: <- OK
 (2.149280) [toit] INFO: -> AT+CGMM
 (2.154530) [toit] INFO: <- FiPy
 (2.157349) [toit] INFO: <- OK
 (2.179240) [toit] INFO: -> AT+CGMR
 (2.199871) [toit] INFO: <- UE5.4.0.2
 (2.202162) [toit] INFO: <- OK
 (2.224115) [toit] INFO: -> AT+SQNCCID?
 (2.229481) [toit] INFO: <- +SQNCCID: "",""
 (2.231831) [toit] INFO: <- OK
 (2.234280) [toit.cellular] DEBUG: initialized {model: FiPy, version: UE5.4.0.2, iccid: }
 (2.254062) [toit] INFO: -> AT+CFUN=4
 (2.320492) [toit] INFO: <- OK
 (2.342695) [toit] INFO: -> AT+CPIN?
 (2.348331) [toit] INFO: <- +CME ERROR: SIM not inserted
 (2.603040) [toit] INFO: -> AT+CPIN?
 (2.608641) [toit] INFO: <- +CME ERROR: SIM not inserted
 (2.862939) [toit] INFO: -> AT+CPIN?
 (2.982368) [toit] INFO: <- +CPIN: READY
 (2.984692) [toit] INFO: <- OK
 (3.007011) [toit] INFO: -> AT+CPSMS=0
 (3.014319) [toit] INFO: <- OK
 (3.036943) [toit] INFO: -> AT+CEDRXS=0
 (3.044255) [toit] INFO: <- OK
 (3.066684) [toit] INFO: -> AT+SQNIBRCFG=0
 (3.086091) [toit] INFO: <- OK
 (3.108677) [toit] INFO: -> AT+SQNIPSCFG=1,100
 (3.125669) [toit] INFO: <- OK
 (3.148302) [toit] INFO: -> AT+SQNBANDSEL=0,"standard","20"
 (3.217803) [toit] INFO: <- +SQNBANDSEL: <custom>
 (3.220211) [toit] INFO: <- OK
 (3.242536) [toit] INFO: -> AT+SQNBANDSEL=1,"standard","20"
 (3.250890) [toit] INFO: <- +CME ERROR: operation not supported
 (3.273104) [toit] INFO: -> AT+CGDCONT?
 (3.279781) [toit] INFO: <- +CGDCONT: 1,"IP","nbiot.tdc.dk",,,,0,0,0,0,0,0,0,,0
 (3.282623) [toit] INFO: <- OK
 [gc @ 0x3ffcfa58* | soft limit reached (53276 >= 49194)]
 [gc @ 0x3ffcfa58* | heap: 52kb -> 24kb | external: 0kb -> 0kb | used: 165kb | free: 6kb->78kb (largest 72kb) 2.394ms]
 (3.304950) [toit] INFO: -> AT+CFUN=1,1
 (3.364495) [toit] INFO: <- OK
 (3.419505) [toit] INFO: <- *ignored* [URC] +SHUTDOWN []
 (4.906795) [toit] INFO: <- *ignored* [URC] +SYSSTART []
 (8.306926) [toit] INFO: -> AT+CFUN=4
 (8.688289) [toit] INFO: <- OK
 (8.690860) [toit.cellular] DEBUG: failed, detach from network
 (8.701181) [toit.network] DEBUG: error connecting {network: Cellular (nbiot.tdc.dk), error: DEADLINE_EXCEEDED}
 (8.709799) [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 WyNVBVVYU1UXdjEuNi4wLXByZS4yNStjOGFlNWVhYTBTVQllc3AzMi00bWJbJFUjVRAAAAAAAAAAAAAAAAAAAAAAWyNVBFVFU1UJRVhDRVBUSU9OU1URRkFJTEVEIFRPIENPTk5FQ1RbI1UCVVNbI1UbWyNVA1VGVQBsAAKuMVsjVQNVRlUBbAACrY5bI1UDVUZVAmwAAqoaWyNVA1VGVQNJJWhbI1UDVUZVBEklfVsjVQNVRlUFSSUCWyNVA1VGVQZsAAKqV1sjVQNVRlUHbAACqfNbI1UDVUZVCGwAAQP4WyNVA1VGVQlsAAGg+FsjVQNVRlUKbAABFD5bI1UDVUZVC2wAARRXWyNVA1VGVQxsAAET1FsjVQNVRlUNSSVoWyNVA1VGVQ5JJX1bI1UDVUZVD0klWlsjVQNVRlUQbAABE+1bI1UDVUZVEWwAARQDWyNVA1VGVRJJMqlbI1UDVUZVE2wAAkxYWyNVA1VGVRRJJWhbI1UDVUZVFUklfVsjVQNVRlUWSSUCWyNVA1VGVRdsAAJMbVsjVQNVRlUYbAACTIRbI1UDVUZVGUkyPlsjVQNVRlUaSTLZ
 (8.805993) [toit] INFO: -> AT+CFUN=0
 [gc @ 0x3ffcfa58* | soft limit reached (49636 >= 49194)]
 [gc @ 0x3ffcfa58* | byte array allocation failed, length = 6 (heap)]
 [gc @ 0x3ffcfa58* | heap: 48kb -> 24kb | external: 0kb -> 0kb | used: 157kb | free: 10kb->86kb (largest 72kb) 2.535ms]
 (8.811533) [toit.scheduler] INFO: changing run level {run_level: 0}
 (8.831231) [toit.scheduler] INFO: going into deep sleep {ms: 4989}
 (8.835618) [toit.metrics] INFO: persisting metrics
 (9.421262) [toit] INFO: <- OK
 (9.529689) [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.015332) [toit] INFO: booting toit {sdk: v1.6.0-pre.25+c8ae5eaa0, model: esp32-4mb}
 (0.021536) [toit.scheduler] INFO: set initial run_level {run_level: 4}
 (0.057368) [toit.state] INFO: resumed session {session_id: 2, session_time: 56.542752}
 [gc @ 0x3ffcfa58* | soft limit reached (20480 >= 18432)]
 [gc @ 0x3ffcfa58* | heap: 20kb -> 8kb | external: 0kb -> 0kb | used: 93kb | free: 9kb->149kb (largest 108kb) 1.168ms]
 (0.078836) [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.122051) [toit.console_sync] INFO: loaded last console sync {time: 50.691481}
 [gc @ 0x3ffcfa58* | soft limit reached (24576 >= 24576)]
 [gc @ 0x3ffcfa58* | heap: 24kb -> 16kb | external: 0kb -> 0kb | used: 102kb | free: 8kb->140kb (largest 108kb) 1.732ms]
 (0.150140) [toit.scheduler] INFO: running job {job: console_sync}
 (0.152963) [toit.scheduler] INFO: running job {job: poll_metric_flash_usage}
 (0.155099) [toit.scheduler] INFO: running job {job: poll_metric_memory_profile}
 (0.163847) [toit.console] INFO: waiting for console connection to be established
 (0.168443) [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) 2.126ms]
 [gc @ 0x3ffcfa58* | soft limit reached (45084 >= 43050)]
 [gc @ 0x3ffcfa58* | heap: 44kb -> 24kb | external: 0kb -> 0kb | used: 148kb | free: 6kb->94kb (largest 88kb) 2.383ms]
 (0.234524) [toit] INFO: -> AT
 (0.495735) [toit] INFO: -> AT
 (0.756218) [toit] INFO: -> AT
 (1.017618) [toit] INFO: -> AT
 (1.278199) [toit] INFO: -> AT
 (1.538791) [toit] INFO: -> AT
 (1.799397) [toit] INFO: -> AT
 (1.979280) [toit] INFO: <- OK
 (2.084514) [toit] INFO: -> ATE0
 (2.092171) [toit] INFO: <- OK
 (2.114635) [toit] INFO: -> AT+CMEE=2
 (2.120098) [toit] INFO: <- OK
 (2.142241) [toit] INFO: -> AT+CGMM
 (2.147520) [toit] INFO: <- FiPy
 (2.150321) [toit] INFO: <- OK
 (2.172217) [toit] INFO: -> AT+CGMR
 (2.192971) [toit] INFO: <- UE5.4.0.2
 (2.195218) [toit] INFO: <- OK
 (2.217462) [toit] INFO: -> AT+SQNCCID?
 (2.222767) [toit] INFO: <- +SQNCCID: "",""
 (2.225106) [toit] INFO: <- OK
 (2.227553) [toit.cellular] DEBUG: initialized {model: FiPy, version: UE5.4.0.2, iccid: }
 (2.247810) [toit] INFO: -> AT+CFUN=4
 (2.313978) [toit] INFO: <- OK
 (2.336228) [toit] INFO: -> AT+CPIN?
 (2.341901) [toit] INFO: <- +CME ERROR: SIM not inserted
 (2.605143) [toit] INFO: -> AT+CPIN?
 (2.610720) [toit] INFO: <- +CME ERROR: SIM not inserted
 (2.864949) [toit] INFO: -> AT+CPIN?
 (2.977552) [toit] INFO: <- +CPIN: READY
 (2.979876) [toit] INFO: <- OK
 (3.006417) [toit] INFO: -> AT+CPSMS=0
 (3.013629) [toit] INFO: <- OK
 (3.036219) [toit] INFO: -> AT+CEDRXS=0
 (3.043508) [toit] INFO: <- OK
 (3.065927) [toit] INFO: -> AT+SQNIBRCFG=0
 (3.085147) [toit] INFO: <- OK
 (3.107865) [toit] INFO: -> AT+SQNIPSCFG=1,100
 (3.125337) [toit] INFO: <- OK
 (3.148011) [toit] INFO: -> AT+SQNBANDSEL=0,"standard","20"
 (3.217683) [toit] INFO: <- +SQNBANDSEL: <custom>
 (3.220071) [toit] INFO: <- OK
 (3.242444) [toit] INFO: -> AT+SQNBANDSEL=1,"standard","20"
 (3.250287) [toit] INFO: <- +CME ERROR: operation not supported
 (3.272494) [toit] INFO: -> AT+CGDCONT?
 (3.279273) [toit] INFO: <- +CGDCONT: 1,"IP","nbiot.tdc.dk",,,,0,0,0,0,0,0,0,,0
 (3.281801) [toit] INFO: <- OK
 [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: 6kb->78kb (largest 72kb) 2.393ms]
 (3.308099) [toit] INFO: -> AT+CFUN=1,1
 (3.367701) [toit] INFO: <- OK
 (3.423147) [toit] INFO: <- *ignored* [URC] +SHUTDOWN []
 (5.177136) [toit] INFO: <- *ignored* [URC] +SYSSTART []
 (8.308829) [toit] INFO: -> AT+CFUN=4
 (8.615543) [toit] INFO: <- OK
 (8.618376) [toit.cellular] DEBUG: failed, detach from network
 (8.630280) [toit.network] DEBUG: error connecting {network: Cellular (nbiot.tdc.dk), error: DEADLINE_EXCEEDED}
 (8.639008) [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 WyNVBVVYU1UXdjEuNi4wLXByZS4yNStjOGFlNWVhYTBTVQllc3AzMi00bWJbJFUjVRAAAAAAAAAAAAAAAAAAAAAAWyNVBFVFU1UJRVhDRVBUSU9OU1URRkFJTEVEIFRPIENPTk5FQ1RbI1UCVVNbI1UbWyNVA1VGVQBsAAKuMVsjVQNVRlUBbAACrY5bI1UDVUZVAmwAAqoaWyNVA1VGVQNJJWhbI1UDVUZVBEklfVsjVQNVRlUFSSUCWyNVA1VGVQZsAAKqV1sjVQNVRlUHbAACqfNbI1UDVUZVCGwAAQP4WyNVA1VGVQlsAAGg+FsjVQNVRlUKbAABFD5bI1UDVUZVC2wAARRXWyNVA1VGVQxsAAET1FsjVQNVRlUNSSVoWyNVA1VGVQ5JJX1bI1UDVUZVD0klWlsjVQNVRlUQbAABE+1bI1UDVUZVEWwAARQDWyNVA1VGVRJJMqlbI1UDVUZVE2wAAkxYWyNVA1VGVRRJJWhbI1UDVUZVFUklfVsjVQNVRlUWSSUCWyNVA1VGVRdsAAJMbVsjVQNVRlUYbAACTIRbI1UDVUZVGUkyPlsjVQNVRlUaSTLZ
 [gc @ 0x3ffcfa58* | soft limit reached (49636 >= 49194)]
 [gc @ 0x3ffcfa58* | heap: 48kb -> 24kb | external: 0kb -> 0kb | used: 156kb | free: 6kb->86kb (largest 72kb) 2.472ms]
 (8.740684) [toit] INFO: -> AT+CFUN=0
 (8.746232) [toit.scheduler] INFO: changing run level {run_level: 0}
 (8.749756) [toit.scheduler] INFO: going into deep sleep {ms: 4984}
 (8.754064) [toit.metrics] INFO: persisting metrics
 (8.997281) [toit] INFO: <- OK
 (9.108159) [toit] INFO: entering deep sleep {ms: 4984}
 Entering deep sleep for 4984ms
 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.015332) [toit] INFO: booting toit {sdk: v1.6.0-pre.25+c8ae5eaa0, model: esp32-4mb}
 (0.021536) [toit.scheduler] INFO: set initial run_level {run_level: 4}
 (0.057392) [toit.state] INFO: resumed session {session_id: 2, session_time: 70.693886}
 [gc @ 0x3ffcfa58* | soft limit reached (20480 >= 18432)]
 [gc @ 0x3ffcfa58* | heap: 20kb -> 8kb | external: 0kb -> 0kb | used: 93kb | free: 9kb->149kb (largest 108kb) 1.178ms]
 (0.078901) [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.122318) [toit.console_sync] INFO: loaded last console sync {time: 65.198354}
 [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) 1.730ms]
 (0.157606) [toit.scheduler] INFO: running job {job: console_sync}
 (0.160437) [toit.scheduler] INFO: running job {job: poll_metric_flash_usage}
 (0.162574) [toit.scheduler] INFO: running job {job: poll_metric_memory_profile}
 (0.171312) [toit.console] INFO: waiting for console connection to be established
 (0.175910) [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) 2.034ms]
 [gc @ 0x3ffcfa58* | soft limit reached (45084 >= 43050)]
 [gc @ 0x3ffcfa58* | heap: 44kb -> 20kb | external: 0kb -> 0kb | used: 142kb | free: 12kb->100kb (largest 88kb) 2.169ms]
 (0.250033) [toit] INFO: -> AT
 (0.517780) [toit] INFO: -> AT
 (0.778258) [toit] INFO: -> AT
 (1.038946) [toit] INFO: -> AT
 (1.299556) [toit] INFO: -> AT
 (1.560135) [toit] INFO: -> AT
 (1.820729) [toit] INFO: -> AT
 (1.997628) [toit] INFO: <- OK
 (2.106827) [toit] INFO: -> ATE0
 (2.114717) [toit] INFO: <- OK
 (2.137184) [toit] INFO: -> AT+CMEE=2
 (2.142762) [toit] INFO: <- OK
 (2.165006) [toit] INFO: -> AT+CGMM
 (2.170272) [toit] INFO: <- FiPy
 (2.173094) [toit] INFO: <- OK
 (2.194982) [toit] INFO: -> AT+CGMR
 (2.215736) [toit] INFO: <- UE5.4.0.2
 (2.218040) [toit] INFO: <- OK
 (2.239946) [toit] INFO: -> AT+SQNCCID?
 (2.245605) [toit] INFO: <- +SQNCCID: "",""
 (2.247986) [toit] INFO: <- OK
 (2.250427) [toit.cellular] DEBUG: initialized {model: FiPy, version: UE5.4.0.2, iccid: }
 (2.270228) [toit] INFO: -> AT+CFUN=4
 (2.556054) [toit] INFO: <- OK
 (2.578383) [toit] INFO: -> AT+CPIN?
 [gc @ 0x3ffcfa58* | soft limit reached (45084 >= 43050)]
 [gc @ 0x3ffcfa58* | string allocation failed, length = 31 (heap)]
 [gc @ 0x3ffcfa58* | heap: 44kb -> 24kb | external: 0kb -> 0kb | used: 149kb | free: 10kb->94kb (largest 84kb) 2.355ms]
 (2.595598) [toit] INFO: <- +CME ERROR: SIM not inserted
 (2.853271) [toit] INFO: -> AT+CPIN?
 (2.985669) [toit] INFO: <- +CPIN: READY
 (2.988502) [toit] INFO: <- OK
 (3.010836) [toit] INFO: -> AT+CPSMS=0
 (3.018095) [toit] INFO: <- OK
 (3.040604) [toit] INFO: -> AT+CEDRXS=0
 (3.047780) [toit] INFO: <- OK
 (3.070229) [toit] INFO: -> AT+SQNIBRCFG=0
 (3.089922) [toit] INFO: <- OK
 (3.112522) [toit] INFO: -> AT+SQNIPSCFG=1,100
 (3.129808) [toit] INFO: <- OK
 (3.152432) [toit] INFO: -> AT+SQNBANDSEL=0,"standard","20"
 (3.222432) [toit] INFO: <- +SQNBANDSEL: <custom>
 (3.224872) [toit] INFO: <- OK
 (3.247240) [toit] INFO: -> AT+SQNBANDSEL=1,"standard","20"
 (3.254928) [toit] INFO: <- +CME ERROR: operation not supported
 (3.276971) [toit] INFO: -> AT+CGDCONT?
 (3.283493) [toit] INFO: <- +CGDCONT: 1,"IP","nbiot.tdc.dk",,,,0,0,0,0,0,0,0,,0
 (3.286283) [toit] INFO: <- OK
 (3.308671) [toit] INFO: -> AT+CFUN=1,1
 (3.367629) [toit] INFO: <- OK
 (3.423151) [toit] INFO: <- *ignored* [URC] +SHUTDOWN []
 (5.187064) [toit] INFO: <- *ignored* [URC] +SYSSTART []
 (8.294675) [toit] INFO: -> AT+CFUN=4
 (10.169479) [toit.scheduler] INFO: running job {job: persist_metrics}
 (10.172768) [toit.metrics] INFO: persisting metrics
 (10.259971) [toit.scheduler] INFO: running job {job: poll_metric_memory_profile}
 (10.729332) [toit] INFO: <- OK
 (10.731995) [toit.cellular] DEBUG: failed, detach from network
 (10.750808) [toit.network] DEBUG: error connecting {network: Cellular (nbiot.tdc.dk), error: DEADLINE_EXCEEDED}
 [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) 2.464ms]
 (10.774273) [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 WyNVBVVYU1UXdjEuNi4wLXByZS4yNStjOGFlNWVhYTBTVQllc3AzMi00bWJbJFUjVRAAAAAAAAAAAAAAAAAAAAAAWyNVBFVFU1UJRVhDRVBUSU9OU1URRkFJTEVEIFRPIENPTk5FQ1RbI1UCVVNbI1UbWyNVA1VGVQBsAAKuMVsjVQNVRlUBbAACrY5bI1UDVUZVAmwAAqoaWyNVA1VGVQNJJWhbI1UDVUZVBEklfVsjVQNVRlUFSSUCWyNVA1VGVQZsAAKqV1sjVQNVRlUHbAACqfNbI1UDVUZVCGwAAQP4WyNVA1VGVQlsAAGg+FsjVQNVRlUKbAABFD5bI1UDVUZVC2wAARRXWyNVA1VGVQxsAAET1FsjVQNVRlUNSSVoWyNVA1VGVQ5JJX1bI1UDVUZVD0klWlsjVQNVRlUQbAABE+1bI1UDVUZVEWwAARQDWyNVA1VGVRJJMqlbI1UDVUZVE2wAAkxYWyNVA1VGVRRJJWhbI1UDVUZVFUklfVsjVQNVRlUWSSUCWyNVA1VGVRdsAAJMbVsjVQNVRlUYbAACTIRbI1UDVUZVGUkyPlsjVQNVRlUaSTLZ
 (10.876427) [toit] INFO: -> AT+CFUN=0
 (10.882867) [toit.scheduler] INFO: changing run level {run_level: 0}
 (10.886602) [toit.scheduler] INFO: going into deep sleep {ms: 4989}
 (10.888490) [toit.metrics] INFO: persisting metrics
 (11.497115) [toit] INFO: <- OK
 (11.604857) [toit] INFO: entering deep sleep {ms: 4989}
 Entering deep sleep for 4989ms