1637847063.maturix-sim-9690.hardware_id-a966ebf8-6407-4964-9685-95ac15170168.firmware-v1.5.0-rc.1.log

$ toit serial reinstall --firmware v1.5.0-rc.1 -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=internet --port=/dev/ttyUSB0
Detecting chip type. This can take up to 10s.
Reinstalling hardware with firmware: v1.5.0-rc.1 (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/797797621/otadata959218128 0x10000 /tmp/797797621/ota_0569561839"
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:cf:a4:30
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 23409.8 kbit/s)...
Hash of data verified.
Compressed 1323840 bytes to 804567...
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 1323840 bytes (804567 compressed) at 0x00010000 in 13.5 seconds (effective 786.8 kbit/s)...
Hash of data verified.

Leaving...
Hard resetting via RTS pin...
Hardware successfully reinstalled with the Toit firmware.
The hardware has been reinstalled with the following configurations:
+------------------------+----------------+
|        SETTING         |     VALUE      |
+------------------------+----------------+
| context                | remote         |
| model.cellular.enabled | 1              |
| model.cellular.rx      | 23             |
| model.cellular.pwr     | 27             |
| model                  | esp32-4mb      |
| broker.host            | device.toit.io |
| broker.cn              | device.toit.io |
| cellular.apn           | internet       |
| model.cellular.rts     | 19             |
| model.cellular.cts     | 18             |
| broker.port            | 9426           |
| model.cellular.monarch | 1              |
| model.cellular.tx      | 5              |
+------------------------+----------------+


$ toit serial info --output table
+-------------------------------------------------------------------------+-----------+--------------------------------------+
|                                  NAME                                   |   MODEL   |             HARDWARE ID              |
+-------------------------------------------------------------------------+-----------+--------------------------------------+
| a966ebf8-6407-4964-9685-95ac15170168.743.2021-09-26.device.toitware.com | esp32-4mb | a966ebf8-6407-4964-9685-95ac15170168 |
+-------------------------------------------------------------------------+-----------+--------------------------------------+


$ 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=a966ebf8-6407-4964-9685-95ac15170168.743.2021-09-26.device.toitware.com,model=esp32-4mb
(0.015876) [toit] INFO: booting toit {sdk: v1.5.0-rc.1, model: esp32-4mb}
(0.020852) [toit.scheduler] INFO: set initial run_level {run_level: 4}
(0.435340) [toit.state] INFO: starting new session {session_id: 1, session_time: 0.442631}
[gc @ 0x3ffcf9f0* | soft limit reached (20480 >= 18432)]
[gc @ 0x3ffcf9f0* | heap: 20kb -> 8kb | external: 0kb -> 0kb | free: 9kb->9kb 1.153ms]
(0.524770) [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.637246) [toit.console_sync] INFO: loaded last console sync {time: null}
(0.649200) [toit.scheduler] INFO: running job {job: console_sync}
(0.652097) [toit.scheduler] INFO: running job {job: flush_metrics}
(0.654277) [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 | free: 12kb->12kb 1.774ms]
(0.659196) [toit.scheduler] INFO: running job {job: poll_metric_memory_profile}
(0.688129) [toit.console] INFO: waiting for console connection to be established
(0.692422) [toit.network] DEBUG: connecting {network: Cellular (internet)}
(0.699729) [toit.metrics] INFO: persisting metrics
(0.702868) [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 | free: 12kb->12kb 1.976ms]
(0.972082) [toit] INFO: -> AT
(0.984328) [toit] INFO: <- OK
(1.089038) [toit] INFO: -> ATE0
(1.094367) [toit] INFO: <- OK
(1.117107) [toit] INFO: -> AT+CMEE=2
(1.122475) [toit] INFO: <- OK
[gc @ 0x3ffcf9f0* | soft limit reached (45084 >= 43050)]
[gc @ 0x3ffcf9f0* | byte array allocation failed, length = 511 (heap)]
[gc @ 0x3ffcf9f0* | heap: 44kb -> 24kb | external: 0kb -> 0kb | free: 6kb->6kb 2.218ms]
(1.145317) [toit] INFO: -> AT+CGMM
(1.151684) [toit] INFO: <- FiPy
(1.154286) [toit] INFO: <- OK
(1.176317) [toit] INFO: -> AT+CGMR
(1.181376) [toit] INFO: <- UE5.4.0.2
(1.184049) [toit] INFO: <- OK
(1.206243) [toit] INFO: -> AT+SQNCCID?
(1.211952) [toit] INFO: <- +SQNCCID: "89450100200903189690",""
(1.214639) [toit] INFO: <- OK
(1.217221) [toit.cellular] DEBUG: initialized {model: FiPy, version: UE5.4.0.2, iccid: 89450100200903189690}
(1.237907) [toit] INFO: -> AT+CFUN=4
(1.464107) [toit] INFO: <- OK
(1.468688) [toit] INFO: <- +SQNSH: 1
(1.470947) [toit] INFO: <- [URC] +SQNSH [1]
(1.491232) [toit] INFO: -> AT+CPIN?
(1.497177) [toit] INFO: <- +CPIN: READY
(1.499800) [toit] INFO: <- OK
(1.522367) [toit] INFO: -> AT+CPSMS=0
(1.529628) [toit] INFO: <- OK
(1.552332) [toit] INFO: -> AT+CEDRXS=0
(1.560175) [toit] INFO: <- OK
(1.582893) [toit] INFO: -> AT+SQNIBRCFG=0
(1.594396) [toit] INFO: <- OK
(1.617277) [toit] INFO: -> AT+SQNIPSCFG=1,100
(1.634165) [toit] INFO: <- OK
(1.657246) [toit] INFO: -> AT+SQNBANDSEL=0,"standard","20"
(1.722817) [toit] INFO: <- +SQNBANDSEL: <custom>
(1.725617) [toit] INFO: <- OK
(1.748303) [toit] INFO: -> AT+SQNBANDSEL=1,"standard","20"
(1.755538) [toit] INFO: <- +CME ERROR: operation not supported
(1.777871) [toit] INFO: -> AT+CGDCONT?
(1.783798) [toit] INFO: <- +CGDCONT: 1,"IP","internet",,,,0,0,0,0,0,0,0,,0
(1.786953) [toit] INFO: <- OK
(1.809360) [toit] INFO: -> AT+CFUN=1
(2.020146) [toit] INFO: <- OK
(6.793894) [toit] INFO: -> AT+CFUN=4
(6.880572) [toit] INFO: <- OK
(6.883342) [toit.cellular] DEBUG: failed, detach from network
(6.900382) [toit.network] DEBUG: error connecting {network: Cellular (internet), error: DEADLINE_EXCEEDED}
(6.907249) [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 WyNVBVVYU1ULdjEuNS4wLXJjLjFTVQllc3AzMi00bWJbJFUjVRAAAAAAAAAAAAAAAAAAAAAAWyNVBFVFU1UJRVhDRVBUSU9OU1URRkFJTEVEIFRPIENPTk5FQ1RbI1UCVVNbI1UbWyNVA1VGVQBsAAKppVsjVQNVRlUBbAACqQJbI1UDVUZVAmwAAqWOWyNVA1VGVQNJJNhbI1UDVUZVBEkk7VsjVQNVRlUFSSRyWyNVA1VGVQZsAAKly1sjVQNVRlUHbAACpWdbI1UDVUZVCGwAAQFHWyNVA1VGVQlsAAGcN1sjVQNVRlUKbAABEVdbI1UDVUZVC2wAARFwWyNVA1VGVQxsAAEQ7VsjVQNVRlUNSSTYWyNVA1VGVQ5JJO1bI1UDVUZVD0kkylsjVQNVRlUQbAABEQZbI1UDVUZVEWwAAREcWyNVA1VGVRJJMfNbI1UDVUZVE2wAAkdwWyNVA1VGVRRJJNhbI1UDVUZVFUkk7VsjVQNVRlUWSSRyWyNVA1VGVRdsAAJHhVsjVQNVRlUYbAACR5xbI1UDVUZVGUkxiFsjVQNVRlUaSTIj
[gc @ 0x3ffcf9f0* | soft limit reached (49624 >= 49194)]
[gc @ 0x3ffcf9f0* | byte array allocation failed, length = 452 (heap)]
[gc @ 0x3ffcf9f0* | heap: 48kb -> 24kb | external: 0kb -> 0kb | free: 10kb->10kb 2.369ms]
(7.012321) [toit] INFO: -> AT+CFUN=0
(7.018810) [toit.scheduler] INFO: changing run level {run_level: 0}
(7.022570) [toit.scheduler] INFO: going into deep sleep {ms: 4989}
(7.024606) [toit.metrics] INFO: persisting metrics
(7.485050) [toit] INFO: <- OK
(7.592987) [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=a966ebf8-6407-4964-9685-95ac15170168.743.2021-09-26.device.toitware.com,model=esp32-4mb
(0.015873) [toit] INFO: booting toit {sdk: v1.5.0-rc.1, model: esp32-4mb}
(0.020849) [toit.scheduler] INFO: set initial run_level {run_level: 4}
(0.052765) [toit.state] INFO: resumed session {session_id: 1, session_time: 12.716753}
[gc @ 0x3ffcfa58* | soft limit reached (20480 >= 18432)]
[gc @ 0x3ffcfa58* | heap: 20kb -> 8kb | external: 0kb -> 0kb | free: 9kb->9kb 1.081ms]
(0.069741) [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.110063) [toit.console_sync] INFO: loaded last console sync {time: 7.011888}
(0.126046) [toit.scheduler] INFO: running job {job: console_sync}
[gc @ 0x3ffcfa58* | soft limit reached (24576 >= 24576)]
[gc @ 0x3ffcfa58* | heap: 24kb -> 16kb | external: 0kb -> 0kb | free: 8kb->8kb 1.743ms]
(0.129044) [toit.scheduler] INFO: running job {job: poll_metric_flash_usage}
(0.143055) [toit.scheduler] INFO: running job {job: poll_metric_memory_profile}
(0.152944) [toit.console] INFO: waiting for console connection to be established
(0.157146) [toit.network] DEBUG: connecting {network: Cellular (internet)}
[gc @ 0x3ffcfa58* | soft limit reached (36892 >= 36864)]
[gc @ 0x3ffcfa58* | heap: 36kb -> 20kb | external: 0kb -> 0kb | free: 12kb->12kb 1.915ms]
(0.199448) [toit] INFO: -> AT
(0.460842) [toit] INFO: -> AT
(0.721417) [toit] INFO: -> AT
(0.982115) [toit] INFO: -> AT
(1.242946) [toit] INFO: -> AT
(1.503661) [toit] INFO: -> AT
(1.764370) [toit] INFO: -> AT
(1.941509) [toit] INFO: <- OK
(2.048154) [toit] INFO: -> ATE0
(2.056087) [toit] INFO: <- OK
(2.078821) [toit] INFO: -> AT+CMEE=2
(2.084700) [toit] INFO: <- OK
[gc @ 0x3ffcfa58* | soft limit reached (45084 >= 43050)]
[gc @ 0x3ffcfa58* | heap: 44kb -> 24kb | external: 0kb -> 0kb | free: 10kb->10kb 2.228ms]
(2.107525) [toit] INFO: -> AT+CGMM
(2.117233) [toit] INFO: <- FiPy
(2.120163) [toit] INFO: <- OK
(2.142398) [toit] INFO: -> AT+CGMR
(2.162934) [toit] INFO: <- UE5.4.0.2
(2.165854) [toit] INFO: <- OK
(2.188056) [toit] INFO: -> AT+SQNCCID?
(2.193655) [toit] INFO: <- +SQNCCID: "",""
(2.196350) [toit] INFO: <- OK
(2.198948) [toit.cellular] DEBUG: initialized {model: FiPy, version: UE5.4.0.2, iccid: }
(2.218885) [toit] INFO: -> AT+CFUN=4
(2.284210) [toit] INFO: <- OK
(2.306709) [toit] INFO: -> AT+CPIN?
(2.312453) [toit] INFO: <- +CME ERROR: SIM not inserted
(2.567728) [toit] INFO: -> AT+CPIN?
(2.573506) [toit] INFO: <- +CME ERROR: SIM not inserted
(2.828041) [toit] INFO: -> AT+CPIN?
(2.879842) [toit] INFO: <- +CPIN: READY
(2.882510) [toit] INFO: <- OK
(2.906403) [toit] INFO: -> AT+CPSMS=0
(2.913822) [toit] INFO: <- OK
(2.936641) [toit] INFO: -> AT+CEDRXS=0
(2.944232) [toit] INFO: <- OK
(2.966917) [toit] INFO: -> AT+SQNIBRCFG=0
(2.986476) [toit] INFO: <- OK
(3.009436) [toit] INFO: -> AT+SQNIPSCFG=1,100
(3.026951) [toit] INFO: <- OK
(3.049830) [toit] INFO: -> AT+SQNBANDSEL=0,"standard","20"
(3.118402) [toit] INFO: <- +SQNBANDSEL: <custom>
(3.121193) [toit] INFO: <- OK
(3.143778) [toit] INFO: -> AT+SQNBANDSEL=1,"standard","20"
(3.151638) [toit] INFO: <- +CME ERROR: operation not supported
(3.174099) [toit] INFO: -> AT+CGDCONT?
(3.241076) [toit] INFO: <- +CGDCONT: 1,"IP","internet",,,,0,0,0,0,0,0,0,,0
(3.244325) [toit] INFO: <- OK
(3.266899) [toit] INFO: -> AT+CFUN=1
(3.338594) [toit] INFO: <- OK
(8.251701) [toit] INFO: -> AT+CFUN=4
(8.430410) [toit] INFO: <- OK
(8.433051) [toit.cellular] DEBUG: failed, detach from network
(8.450866) [toit.network] DEBUG: error connecting {network: Cellular (internet), error: DEADLINE_EXCEEDED}
(8.458481) [toit.network] WARN: failed to connect to any network
[gc @ 0x3ffcfa58* | soft limit reached (49624 >= 49194)]
[gc @ 0x3ffcfa58* | byte array allocation failed, length = 429 (heap)]
[gc @ 0x3ffcfa58* | heap: 48kb -> 24kb | external: 0kb -> 0kb | free: 10kb->10kb 2.236ms]
----
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 WyNVBVVYU1ULdjEuNS4wLXJjLjFTVQllc3AzMi00bWJbJFUjVRAAAAAAAAAAAAAAAAAAAAAAWyNVBFVFU1UJRVhDRVBUSU9OU1URRkFJTEVEIFRPIENPTk5FQ1RbI1UCVVNbI1UbWyNVA1VGVQBsAAKppVsjVQNVRlUBbAACqQJbI1UDVUZVAmwAAqWOWyNVA1VGVQNJJNhbI1UDVUZVBEkk7VsjVQNVRlUFSSRyWyNVA1VGVQZsAAKly1sjVQNVRlUHbAACpWdbI1UDVUZVCGwAAQFHWyNVA1VGVQlsAAGcN1sjVQNVRlUKbAABEVdbI1UDVUZVC2wAARFwWyNVA1VGVQxsAAEQ7VsjVQNVRlUNSSTYWyNVA1VGVQ5JJO1bI1UDVUZVD0kkylsjVQNVRlUQbAABEQZbI1UDVUZVEWwAAREcWyNVA1VGVRJJMfNbI1UDVUZVE2wAAkdwWyNVA1VGVRRJJNhbI1UDVUZVFUkk7VsjVQNVRlUWSSRyWyNVA1VGVRdsAAJHhVsjVQNVRlUYbAACR5xbI1UDVUZVGUkxiFsjVQNVRlUaSTIj
(8.575217) [toit] INFO: -> AT+CFUN=0
(8.581095) [toit.scheduler] INFO: changing run level {run_level: 0}
(8.584794) [toit.scheduler] INFO: going into deep sleep {ms: 4989}
(8.586824) [toit.metrics] INFO: persisting metrics
(8.943484) [toit] INFO: <- OK
(9.051524) [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=a966ebf8-6407-4964-9685-95ac15170168.743.2021-09-26.device.toitware.com,model=esp32-4mb
(0.015873) [toit] INFO: booting toit {sdk: v1.5.0-rc.1, model: esp32-4mb}
(0.020850) [toit.scheduler] INFO: set initial run_level {run_level: 4}
(0.052599) [toit.state] INFO: resumed session {session_id: 1, session_time: 26.817900}
[gc @ 0x3ffcfa58* | soft limit reached (20480 >= 18432)]
[gc @ 0x3ffcfa58* | heap: 20kb -> 8kb | external: 0kb -> 0kb | free: 9kb->9kb 1.060ms]
(0.069666) [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.110147) [toit.console_sync] INFO: loaded last console sync {time: 21.218004}
(0.126204) [toit.scheduler] INFO: running job {job: console_sync}
[gc @ 0x3ffcfa58* | soft limit reached (24576 >= 24576)]
[gc @ 0x3ffcfa58* | heap: 24kb -> 16kb | external: 0kb -> 0kb | free: 8kb->8kb 1.729ms]
(0.136449) [toit.scheduler] INFO: running job {job: poll_metric_flash_usage}
(0.143191) [toit.scheduler] INFO: running job {job: poll_metric_memory_profile}
(0.153106) [toit.console] INFO: waiting for console connection to be established
(0.157298) [toit.network] DEBUG: connecting {network: Cellular (internet)}
[gc @ 0x3ffcfa58* | soft limit reached (36892 >= 36864)]
[gc @ 0x3ffcfa58* | heap: 36kb -> 20kb | external: 0kb -> 0kb | free: 12kb->12kb 1.908ms]
(0.200305) [toit] INFO: -> AT
(0.461699) [toit] INFO: -> AT
(0.722273) [toit] INFO: -> AT
(0.982972) [toit] INFO: -> AT
(1.243992) [toit] INFO: -> AT
(1.504525) [toit] INFO: -> AT
(1.765231) [toit] INFO: -> AT
(1.943677) [toit] INFO: <- OK
(2.049680) [toit] INFO: -> ATE0
(2.057632) [toit] INFO: <- OK
(2.080363) [toit] INFO: -> AT+CMEE=2
(2.086291) [toit] INFO: <- OK
[gc @ 0x3ffcfa58* | soft limit reached (45084 >= 43050)]
[gc @ 0x3ffcfa58* | heap: 44kb -> 20kb | external: 0kb -> 0kb | free: 10kb->10kb 2.178ms]
(2.112225) [toit] INFO: -> AT+CGMM
(2.117595) [toit] INFO: <- FiPy
(2.120525) [toit] INFO: <- OK
(2.142684) [toit] INFO: -> AT+CGMR
(2.163209) [toit] INFO: <- UE5.4.0.2
(2.166134) [toit] INFO: <- OK
(2.188329) [toit] INFO: -> AT+SQNCCID?
(2.194095) [toit] INFO: <- +SQNCCID: "",""
(2.196798) [toit] INFO: <- OK
(2.199393) [toit.cellular] DEBUG: initialized {model: FiPy, version: UE5.4.0.2, iccid: }
(2.219328) [toit] INFO: -> AT+CFUN=4
(2.285116) [toit] INFO: <- OK
(2.307865) [toit] INFO: -> AT+CPIN?
(2.313687) [toit] INFO: <- +CME ERROR: SIM not inserted
(2.568588) [toit] INFO: -> AT+CPIN?
(2.574439) [toit] INFO: <- +CME ERROR: SIM not inserted
(2.828988) [toit] INFO: -> AT+CPIN?
(2.879444) [toit] INFO: <- +CPIN: READY
(2.882112) [toit] INFO: <- OK
(2.906321) [toit] INFO: -> AT+CPSMS=0
(2.913890) [toit] INFO: <- OK
(2.936788) [toit] INFO: -> AT+CEDRXS=0
(2.944400) [toit] INFO: <- OK
(2.967051) [toit] INFO: -> AT+SQNIBRCFG=0
(2.986876) [toit] INFO: <- OK
(3.009817) [toit] INFO: -> AT+SQNIPSCFG=1,100
(3.027346) [toit] INFO: <- OK
(3.050317) [toit] INFO: -> AT+SQNBANDSEL=0,"standard","20"
(3.119173) [toit] INFO: <- +SQNBANDSEL: <custom>
(3.121960) [toit] INFO: <- OK
(3.144549) [toit] INFO: -> AT+SQNBANDSEL=1,"standard","20"
(3.152370) [toit] INFO: <- +CME ERROR: operation not supported
(3.174894) [toit] INFO: -> AT+CGDCONT?
(3.181482) [toit] INFO: <- +CGDCONT: 1,"IP","internet",,,,0,0,0,0,0,0,0,,0
(3.184733) [toit] INFO: <- OK
(3.207923) [toit] INFO: -> AT+CFUN=1
(3.310240) [toit] INFO: <- OK
(8.192209) [toit] INFO: -> AT+CFUN=4
(8.752886) [toit] INFO: <- OK
(8.755546) [toit.cellular] DEBUG: failed, detach from network
(8.770869) [toit.network] DEBUG: error connecting {network: Cellular (internet), error: DEADLINE_EXCEEDED}
[gc @ 0x3ffcfa58* | soft limit reached (45084 >= 43050)]
[gc @ 0x3ffcfa58* | heap: 44kb -> 24kb | external: 0kb -> 0kb | free: 10kb->10kb 2.230ms]
(8.787122) [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 WyNVBVVYU1ULdjEuNS4wLXJjLjFTVQllc3AzMi00bWJbJFUjVRAAAAAAAAAAAAAAAAAAAAAAWyNVBFVFU1UJRVhDRVBUSU9OU1URRkFJTEVEIFRPIENPTk5FQ1RbI1UCVVNbI1UbWyNVA1VGVQBsAAKppVsjVQNVRlUBbAACqQJbI1UDVUZVAmwAAqWOWyNVA1VGVQNJJNhbI1UDVUZVBEkk7VsjVQNVRlUFSSRyWyNVA1VGVQZsAAKly1sjVQNVRlUHbAACpWdbI1UDVUZVCGwAAQFHWyNVA1VGVQlsAAGcN1sjVQNVRlUKbAABEVdbI1UDVUZVC2wAARFwWyNVA1VGVQxsAAEQ7VsjVQNVRlUNSSTYWyNVA1VGVQ5JJO1bI1UDVUZVD0kkylsjVQNVRlUQbAABEQZbI1UDVUZVEWwAAREcWyNVA1VGVRJJMfNbI1UDVUZVE2wAAkdwWyNVA1VGVRRJJNhbI1UDVUZVFUkk7VsjVQNVRlUWSSRyWyNVA1VGVRdsAAJHhVsjVQNVRlUYbAACR5xbI1UDVUZVGUkxiFsjVQNVRlUaSTIj
(8.885425) [toit] INFO: -> AT+CFUN=0
(8.891312) [toit.scheduler] INFO: changing run level {run_level: 0}
(8.895249) [toit.scheduler] INFO: going into deep sleep {ms: 4989}
(8.897429) [toit.metrics] INFO: persisting metrics
(9.145233) [toit] INFO: <- OK
(9.253218) [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=a966ebf8-6407-4964-9685-95ac15170168.743.2021-09-26.device.toitware.com,model=esp32-4mb
(0.015872) [toit] INFO: booting toit {sdk: v1.5.0-rc.1, model: esp32-4mb}
(0.020849) [toit.scheduler] INFO: set initial run_level {run_level: 4}
(0.052436) [toit.state] INFO: resumed session {session_id: 1, session_time: 41.119993}
[gc @ 0x3ffcfa58* | soft limit reached (20480 >= 18432)]
[gc @ 0x3ffcfa58* | heap: 20kb -> 8kb | external: 0kb -> 0kb | free: 9kb->9kb 1.051ms]
(0.069602) [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.110228) [toit.console_sync] INFO: loaded last console sync {time: 35.628643}
(0.126396) [toit.scheduler] INFO: running job {job: console_sync}
[gc @ 0x3ffcfa58* | soft limit reached (24576 >= 24576)]
[gc @ 0x3ffcfa58* | heap: 24kb -> 16kb | external: 0kb -> 0kb | free: 8kb->8kb 1.730ms]
(0.137006) [toit.scheduler] INFO: running job {job: poll_metric_flash_usage}
(0.143356) [toit.scheduler] INFO: running job {job: poll_metric_memory_profile}
(0.153318) [toit.console] INFO: waiting for console connection to be established
(0.157488) [toit.network] DEBUG: connecting {network: Cellular (internet)}
[gc @ 0x3ffcfa58* | soft limit reached (36892 >= 36864)]
[gc @ 0x3ffcfa58* | heap: 36kb -> 20kb | external: 0kb -> 0kb | free: 12kb->12kb 1.892ms]
(0.201369) [toit] INFO: -> AT
(0.462757) [toit] INFO: -> AT
(0.723332) [toit] INFO: -> AT
(0.984030) [toit] INFO: -> AT
(1.244861) [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 | free: 6kb->6kb 2.276ms]
(1.505584) [toit] INFO: -> AT
(1.766431) [toit] INFO: -> AT
(1.942266) [toit] INFO: <- OK
(2.048271) [toit] INFO: -> ATE0
(2.056126) [toit] INFO: <- OK
(2.078786) [toit] INFO: -> AT+CMEE=2
(2.084923) [toit] INFO: <- OK
(2.107546) [toit] INFO: -> AT+CGMM
(2.113060) [toit] INFO: <- FiPy
(2.115998) [toit] INFO: <- OK
(2.138184) [toit] INFO: -> AT+CGMR
(2.158619) [toit] INFO: <- UE5.4.0.2
(2.161524) [toit] INFO: <- OK
(2.183759) [toit] INFO: -> AT+SQNCCID?
(2.189347) [toit] INFO: <- +SQNCCID: "",""
(2.192045) [toit] INFO: <- OK
(2.194594) [toit.cellular] DEBUG: initialized {model: FiPy, version: UE5.4.0.2, iccid: }
(2.214488) [toit] INFO: -> AT+CFUN=4
(2.280014) [toit] INFO: <- OK
(2.306027) [toit] INFO: -> AT+CPIN?
(2.311817) [toit] INFO: <- +CME ERROR: SIM not inserted
(2.566665) [toit] INFO: -> AT+CPIN?
(2.572403) [toit] INFO: <- +CME ERROR: SIM not inserted
(2.827696) [toit] INFO: -> AT+CPIN?
(2.860497) [toit] INFO: <- +CPIN: READY
(2.863169) [toit] INFO: <- OK
(2.885656) [toit] INFO: -> AT+CPSMS=0
(2.893100) [toit] INFO: <- OK
(2.916008) [toit] INFO: -> AT+CEDRXS=0
(2.923548) [toit] INFO: <- OK
(2.946243) [toit] INFO: -> AT+SQNIBRCFG=0
(2.965840) [toit] INFO: <- OK
(2.988727) [toit] INFO: -> AT+SQNIPSCFG=1,100
(3.006277) [toit] INFO: <- OK
(3.029336) [toit] INFO: -> AT+SQNBANDSEL=0,"standard","20"
(3.098150) [toit] INFO: <- +SQNBANDSEL: <custom>
(3.100967) [toit] INFO: <- OK
(3.123865) [toit] INFO: -> AT+SQNBANDSEL=1,"standard","20"
(3.131697) [toit] INFO: <- +CME ERROR: operation not supported
(3.154229) [toit] INFO: -> AT+CGDCONT?
(3.629874) [toit] INFO: <- +CGDCONT: 1,"IP","internet",,,,0,0,0,0,0,0,0,,0
(3.633119) [toit] INFO: <- OK
(3.655769) [toit] INFO: -> AT+CFUN=1
(3.727263) [toit] INFO: <- OK
(8.640598) [toit] INFO: -> AT+CFUN=4
(8.804575) [toit] INFO: <- OK
(8.807150) [toit.cellular] DEBUG: failed, detach from network
(8.820870) [toit.network] DEBUG: error connecting {network: Cellular (internet), 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 | free: 10kb->10kb 2.256ms]
(8.843904) [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 WyNVBVVYU1ULdjEuNS4wLXJjLjFTVQllc3AzMi00bWJbJFUjVRAAAAAAAAAAAAAAAAAAAAAAWyNVBFVFU1UJRVhDRVBUSU9OU1URRkFJTEVEIFRPIENPTk5FQ1RbI1UCVVNbI1UbWyNVA1VGVQBsAAKppVsjVQNVRlUBbAACqQJbI1UDVUZVAmwAAqWOWyNVA1VGVQNJJNhbI1UDVUZVBEkk7VsjVQNVRlUFSSRyWyNVA1VGVQZsAAKly1sjVQNVRlUHbAACpWdbI1UDVUZVCGwAAQFHWyNVA1VGVQlsAAGcN1sjVQNVRlUKbAABEVdbI1UDVUZVC2wAARFwWyNVA1VGVQxsAAEQ7VsjVQNVRlUNSSTYWyNVA1VGVQ5JJO1bI1UDVUZVD0kkylsjVQNVRlUQbAABEQZbI1UDVUZVEWwAAREcWyNVA1VGVRJJMfNbI1UDVUZVE2wAAkdwWyNVA1VGVRRJJNhbI1UDVUZVFUkk7VsjVQNVRlUWSSRyWyNVA1VGVRdsAAJHhVsjVQNVRlUYbAACR5xbI1UDVUZVGUkxiFsjVQNVRlUaSTIj
(8.945218) [toit] INFO: -> AT+CFUN=0
(8.951256) [toit.scheduler] INFO: changing run level {run_level: 0}
(8.954970) [toit.scheduler] INFO: going into deep sleep {ms: 4989}
(8.957008) [toit.metrics] INFO: persisting metrics
(9.314205) [toit] INFO: <- OK
(9.422207) [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=a966ebf8-6407-4964-9685-95ac15170168.743.2021-09-26.device.toitware.com,model=esp32-4mb
(0.015873) [toit] INFO: booting toit {sdk: v1.5.0-rc.1, model: esp32-4mb}
(0.020850) [toit.scheduler] INFO: set initial run_level {run_level: 4}
(0.052257) [toit.state] INFO: resumed session {session_id: 1, session_time: 55.586096}
[gc @ 0x3ffcfa58* | soft limit reached (20480 >= 18432)]
[gc @ 0x3ffcfa58* | byte array allocation failed, length = 20 (heap)]
[gc @ 0x3ffcfa58* | heap: 20kb -> 8kb | external: 0kb -> 0kb | free: 9kb->9kb 1.058ms]
(0.076102) [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.119975) [toit.console_sync] INFO: loaded last console sync {time: 49.986449}
[gc @ 0x3ffcfa58* | soft limit reached (24576 >= 24576)]
[gc @ 0x3ffcfa58* | heap: 24kb -> 16kb | external: 0kb -> 0kb | free: 8kb->8kb 1.729ms]
(0.136239) [toit.scheduler] INFO: running job {job: console_sync}
(0.145768) [toit.scheduler] INFO: running job {job: poll_metric_flash_usage}
(0.152337) [toit.scheduler] INFO: running job {job: poll_metric_memory_profile}
(0.162384) [toit.console] INFO: waiting for console connection to be established
(0.166489) [toit.network] DEBUG: connecting {network: Cellular (internet)}
[gc @ 0x3ffcfa58* | soft limit reached (36892 >= 36864)]
[gc @ 0x3ffcfa58* | heap: 36kb -> 20kb | external: 0kb -> 0kb | free: 12kb->12kb 1.892ms]
(0.211261) [toit] INFO: -> AT
(0.472652) [toit] INFO: -> AT
(0.733239) [toit] INFO: -> AT
(0.993937) [toit] INFO: -> AT
(1.254768) [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 | free: 6kb->6kb 2.257ms]
(1.516365) [toit] INFO: -> AT
(1.778001) [toit] INFO: -> AT
(1.953016) [toit] INFO: <- OK
(2.059019) [toit] INFO: -> ATE0
(2.066880) [toit] INFO: <- OK
(2.089533) [toit] INFO: -> AT+CMEE=2
(2.095724) [toit] INFO: <- OK
(2.118209) [toit] INFO: -> AT+CGMM
(2.123681) [toit] INFO: <- FiPy
(2.126631) [toit] INFO: <- OK
(2.148769) [toit] INFO: -> AT+CGMR
(2.169265) [toit] INFO: <- UE5.4.0.2
(2.172170) [toit] INFO: <- OK
(2.194406) [toit] INFO: -> AT+SQNCCID?
(2.200056) [toit] INFO: <- +SQNCCID: "",""
(2.202779) [toit] INFO: <- OK
(2.205392) [toit.cellular] DEBUG: initialized {model: FiPy, version: UE5.4.0.2, iccid: }
(2.225255) [toit] INFO: -> AT+CFUN=4
(2.290959) [toit] INFO: <- OK
(2.313544) [toit] INFO: -> AT+CPIN?
(2.319277) [toit] INFO: <- +CME ERROR: SIM not inserted
(2.574090) [toit] INFO: -> AT+CPIN?
(2.580173) [toit] INFO: <- +CME ERROR: SIM not inserted
(2.834661) [toit] INFO: -> AT+CPIN?
(2.870244) [toit] INFO: <- +CPIN: READY
(2.872916) [toit] INFO: <- OK
(2.895419) [toit] INFO: -> AT+CPSMS=0
(2.911417) [toit] INFO: <- OK
(2.934309) [toit] INFO: -> AT+CEDRXS=0
(2.942224) [toit] INFO: <- OK
(2.964916) [toit] INFO: -> AT+SQNIBRCFG=0
(2.984871) [toit] INFO: <- OK
(3.007898) [toit] INFO: -> AT+SQNIPSCFG=1,100
(3.027489) [toit] INFO: <- OK
(3.050365) [toit] INFO: -> AT+SQNBANDSEL=0,"standard","20"
(3.119979) [toit] INFO: <- +SQNBANDSEL: <custom>
(3.122763) [toit] INFO: <- OK
(3.145372) [toit] INFO: -> AT+SQNBANDSEL=1,"standard","20"
(3.209091) [toit] INFO: <- +CME ERROR: operation not supported
(3.231585) [toit] INFO: -> AT+CGDCONT?
(3.237970) [toit] INFO: <- +CGDCONT: 1,"IP","internet",,,,0,0,0,0,0,0,0,,0
(3.241148) [toit] INFO: <- OK
(3.263578) [toit] INFO: -> AT+CFUN=1
(3.334649) [toit] INFO: <- OK
(8.248087) [toit] INFO: -> AT+CFUN=4
(8.489903) [toit] INFO: <- OK
(8.492705) [toit.cellular] DEBUG: failed, detach from network
(8.510329) [toit.network] DEBUG: error connecting {network: Cellular (internet), 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 | free: 10kb->10kb 2.245ms]
(8.533457) [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 WyNVBVVYU1ULdjEuNS4wLXJjLjFTVQllc3AzMi00bWJbJFUjVRAAAAAAAAAAAAAAAAAAAAAAWyNVBFVFU1UJRVhDRVBUSU9OU1URRkFJTEVEIFRPIENPTk5FQ1RbI1UCVVNbI1UbWyNVA1VGVQBsAAKppVsjVQNVRlUBbAACqQJbI1UDVUZVAmwAAqWOWyNVA1VGVQNJJNhbI1UDVUZVBEkk7VsjVQNVRlUFSSRyWyNVA1VGVQZsAAKly1sjVQNVRlUHbAACpWdbI1UDVUZVCGwAAQFHWyNVA1VGVQlsAAGcN1sjVQNVRlUKbAABEVdbI1UDVUZVC2wAARFwWyNVA1VGVQxsAAEQ7VsjVQNVRlUNSSTYWyNVA1VGVQ5JJO1bI1UDVUZVD0kkylsjVQNVRlUQbAABEQZbI1UDVUZVEWwAAREcWyNVA1VGVRJJMfNbI1UDVUZVE2wAAkdwWyNVA1VGVRRJJNhbI1UDVUZVFUkk7VsjVQNVRlUWSSRyWyNVA1VGVRdsAAJHhVsjVQNVRlUYbAACR5xbI1UDVUZVGUkxiFsjVQNVRlUaSTIj
(8.635045) [toit] INFO: -> AT+CFUN=0
(8.641111) [toit.scheduler] INFO: changing run level {run_level: 0}
(8.644829) [toit.scheduler] INFO: going into deep sleep {ms: 4989}
(8.646953) [toit.metrics] INFO: persisting metrics
(8.935081) [toit] INFO: <- OK
(9.043207) [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=a966ebf8-6407-4964-9685-95ac15170168.743.2021-09-26.device.toitware.com,model=esp32-4mb
(0.015873) [toit] INFO: booting toit {sdk: v1.5.0-rc.1, model: esp32-4mb}
(0.020850) [toit.scheduler] INFO: set initial run_level {run_level: 4}
(0.052092) [toit.state] INFO: resumed session {session_id: 1, session_time: 69.679336}
[gc @ 0x3ffcfa58* | soft limit reached (20480 >= 18432)]
[gc @ 0x3ffcfa58* | heap: 20kb -> 8kb | external: 0kb -> 0kb | free: 9kb->9kb 1.034ms]
(0.068767) [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.110396) [toit.console_sync] INFO: loaded last console sync {time: 64.148005}
[gc @ 0x3ffcfa58* | soft limit reached (24576 >= 24576)]
[gc @ 0x3ffcfa58* | heap: 24kb -> 16kb | external: 0kb -> 0kb | free: 8kb->8kb 1.713ms]
(0.129926) [toit.scheduler] INFO: running job {job: console_sync}
(0.132933) [toit.scheduler] INFO: running job {job: poll_metric_flash_usage}
(0.138692) [toit.scheduler] INFO: running job {job: poll_metric_memory_profile}
(0.148575) [toit.console] INFO: waiting for console connection to be established
(0.152805) [toit.network] DEBUG: connecting {network: Cellular (internet)}
[gc @ 0x3ffcfa58* | soft limit reached (36892 >= 36864)]
[gc @ 0x3ffcfa58* | heap: 36kb -> 20kb | external: 0kb -> 0kb | free: 12kb->12kb 1.903ms]
(0.198228) [toit] INFO: -> AT
(0.459615) [toit] INFO: -> AT
(0.720204) [toit] INFO: -> AT
(0.980897) [toit] INFO: -> AT
(1.241727) [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 | free: 6kb->6kb 2.258ms]
(1.502442) [toit] INFO: -> AT
(1.763290) [toit] INFO: -> AT
(1.933674) [toit] INFO: <- OK
(2.039680) [toit] INFO: -> ATE0
(2.047527) [toit] INFO: <- OK
(2.070178) [toit] INFO: -> AT+CMEE=2
(2.076345) [toit] INFO: <- OK
(2.105983) [toit] INFO: -> AT+CGMM
(2.111500) [toit] INFO: <- FiPy
(2.114438) [toit] INFO: <- OK
(2.136631) [toit] INFO: -> AT+CGMR
(2.157087) [toit] INFO: <- UE5.4.0.2
(2.160011) [toit] INFO: <- OK
(2.182268) [toit] INFO: -> AT+SQNCCID?
(2.187860) [toit] INFO: <- +SQNCCID: "",""
(2.190562) [toit] INFO: <- OK
(2.193107) [toit.cellular] DEBUG: initialized {model: FiPy, version: UE5.4.0.2, iccid: }
(2.213076) [toit] INFO: -> AT+CFUN=4
(2.278716) [toit] INFO: <- OK
(2.306028) [toit] INFO: -> AT+CPIN?
(2.311818) [toit] INFO: <- +CME ERROR: SIM not inserted
(2.566476) [toit] INFO: -> AT+CPIN?
(2.572277) [toit] INFO: <- +CME ERROR: SIM not inserted
(2.827697) [toit] INFO: -> AT+CPIN?
(2.873514) [toit] INFO: <- +CPIN: READY
(2.876194) [toit] INFO: <- OK
(2.906349) [toit] INFO: -> AT+CPSMS=0
(2.913760) [toit] INFO: <- OK
(2.936384) [toit] INFO: -> AT+CEDRXS=0
(2.944301) [toit] INFO: <- OK
(2.967192) [toit] INFO: -> AT+SQNIBRCFG=0
(2.987288) [toit] INFO: <- OK
(3.010260) [toit] INFO: -> AT+SQNIPSCFG=1,100
(3.029960) [toit] INFO: <- OK
(3.052845) [toit] INFO: -> AT+SQNBANDSEL=0,"standard","20"
(3.122182) [toit] INFO: <- +SQNBANDSEL: <custom>
(3.124966) [toit] INFO: <- OK
(3.147596) [toit] INFO: -> AT+SQNBANDSEL=1,"standard","20"
(3.211242) [toit] INFO: <- +CME ERROR: operation not supported
(3.233490) [toit] INFO: -> AT+CGDCONT?
(3.239909) [toit] INFO: <- +CGDCONT: 1,"IP","internet",,,,0,0,0,0,0,0,0,,0
(3.243016) [toit] INFO: <- OK
(3.265498) [toit] INFO: -> AT+CFUN=1
(3.336568) [toit] INFO: <- OK
(8.249824) [toit] INFO: -> AT+CFUN=4
(8.505524) [toit] INFO: <- OK
(8.508654) [toit.cellular] DEBUG: failed, detach from network
(8.520329) [toit.network] DEBUG: error connecting {network: Cellular (internet), 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 | free: 10kb->10kb 2.261ms]
(8.541583) [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 WyNVBVVYU1ULdjEuNS4wLXJjLjFTVQllc3AzMi00bWJbJFUjVRAAAAAAAAAAAAAAAAAAAAAAWyNVBFVFU1UJRVhDRVBUSU9OU1URRkFJTEVEIFRPIENPTk5FQ1RbI1UCVVNbI1UbWyNVA1VGVQBsAAKppVsjVQNVRlUBbAACqQJbI1UDVUZVAmwAAqWOWyNVA1VGVQNJJNhbI1UDVUZVBEkk7VsjVQNVRlUFSSRyWyNVA1VGVQZsAAKly1sjVQNVRlUHbAACpWdbI1UDVUZVCGwAAQFHWyNVA1VGVQlsAAGcN1sjVQNVRlUKbAABEVdbI1UDVUZVC2wAARFwWyNVA1VGVQxsAAEQ7VsjVQNVRlUNSSTYWyNVA1VGVQ5JJO1bI1UDVUZVD0kkylsjVQNVRlUQbAABEQZbI1UDVUZVEWwAAREcWyNVA1VGVRJJMfNbI1UDVUZVE2wAAkdwWyNVA1VGVRRJJNhbI1UDVUZVFUkk7VsjVQNVRlUWSSRyWyNVA1VGVRdsAAJHhVsjVQNVRlUYbAACR5xbI1UDVUZVGUkxiFsjVQNVRlUaSTIj
(8.645619) [toit] INFO: -> AT+CFUN=0
(8.651564) [toit.scheduler] INFO: changing run level {run_level: 0}
(8.655508) [toit.scheduler] INFO: going into deep sleep {ms: 4989}
(8.657641) [toit.metrics] INFO: persisting metrics
(9.414914) [toit] INFO: <- OK
(9.522952) [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=a966ebf8-6407-4964-9685-95ac15170168.743.2021-09-26.device.toitware.com,model=esp32-4mb
(0.015873) [toit] INFO: booting toit {sdk: v1.5.0-rc.1, model: esp32-4mb}
(0.020850) [toit.scheduler] INFO: set initial run_level {run_level: 4}
(0.051891) [toit.state] INFO: resumed session {session_id: 1, session_time: 84.252017}
[gc @ 0x3ffcfa58* | soft limit reached (20480 >= 18432)]
[gc @ 0x3ffcfa58* | heap: 20kb -> 8kb | external: 0kb -> 0kb | free: 9kb->9kb 1.034ms]
(0.068587) [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.110473) [toit.console_sync] INFO: loaded last console sync {time: 78.252451}
[gc @ 0x3ffcfa58* | soft limit reached (24576 >= 24576)]
[gc @ 0x3ffcfa58* | heap: 24kb -> 16kb | external: 0kb -> 0kb | free: 8kb->8kb 1.678ms]
(0.130217) [toit.scheduler] INFO: running job {job: console_sync}
(0.133237) [toit.scheduler] INFO: running job {job: poll_metric_flash_usage}
(0.138620) [toit.scheduler] INFO: running job {job: poll_metric_memory_profile}
(0.148484) [toit.console] INFO: waiting for console connection to be established
(0.152733) [toit.network] DEBUG: connecting {network: Cellular (internet)}
[gc @ 0x3ffcfa58* | soft limit reached (36892 >= 36864)]
[gc @ 0x3ffcfa58* | heap: 36kb -> 20kb | external: 0kb -> 0kb | free: 12kb->12kb 1.891ms]
(0.198904) [toit] INFO: -> AT
(0.460302) [toit] INFO: -> AT
(0.720880) [toit] INFO: -> AT
(0.981573) [toit] INFO: -> AT
(1.242404) [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 | free: 6kb->6kb 2.260ms]
(1.503119) [toit] INFO: -> AT
(1.763966) [toit] INFO: -> AT
(1.944526) [toit] INFO: <- OK
(2.050447) [toit] INFO: -> ATE0
(2.058292) [toit] INFO: <- OK
(2.081062) [toit] INFO: -> AT+CMEE=2
(2.087194) [toit] INFO: <- OK
(2.109934) [toit] INFO: -> AT+CGMM
(2.115369) [toit] INFO: <- FiPy
(2.118329) [toit] INFO: <- OK
(2.140513) [toit] INFO: -> AT+CGMR
(2.160986) [toit] INFO: <- UE5.4.0.2
(2.163910) [toit] INFO: <- OK
(2.186162) [toit] INFO: -> AT+SQNCCID?
(2.191766) [toit] INFO: <- +SQNCCID: "",""
(2.194464) [toit] INFO: <- OK
(2.197028) [toit.cellular] DEBUG: initialized {model: FiPy, version: UE5.4.0.2, iccid: }
(2.216898) [toit] INFO: -> AT+CFUN=4
(2.282684) [toit] INFO: <- OK
(2.306037) [toit] INFO: -> AT+CPIN?
(2.311817) [toit] INFO: <- +CME ERROR: SIM not inserted
(2.566476) [toit] INFO: -> AT+CPIN?
(2.572302) [toit] INFO: <- +CME ERROR: SIM not inserted
(2.827697) [toit] INFO: -> AT+CPIN?
(2.862183) [toit] INFO: <- +CPIN: READY
(2.864842) [toit] INFO: <- OK
(2.887535) [toit] INFO: -> AT+CPSMS=0
(2.895215) [toit] INFO: <- OK
(2.926467) [toit] INFO: -> AT+CEDRXS=0
(2.934108) [toit] INFO: <- OK
(2.956950) [toit] INFO: -> AT+SQNIBRCFG=0
(2.976614) [toit] INFO: <- OK
(2.999480) [toit] INFO: -> AT+SQNIPSCFG=1,100
(3.019226) [toit] INFO: <- OK
(3.042218) [toit] INFO: -> AT+SQNBANDSEL=0,"standard","20"
(3.111817) [toit] INFO: <- +SQNBANDSEL: <custom>
(3.114632) [toit] INFO: <- OK
(3.137352) [toit] INFO: -> AT+SQNBANDSEL=1,"standard","20"
(3.562846) [toit] INFO: <- +CME ERROR: operation not supported
(3.585027) [toit] INFO: -> AT+CGDCONT?
(3.591556) [toit] INFO: <- +CGDCONT: 1,"IP","internet",,,,0,0,0,0,0,0,0,,0
(3.594734) [toit] INFO: <- OK
(3.617242) [toit] INFO: -> AT+CFUN=1
(3.688222) [toit] INFO: <- OK
(8.610557) [toit] INFO: -> AT+CFUN=4
(8.689021) [toit] INFO: <- OK
(8.691616) [toit.cellular] DEBUG: failed, detach from network
(8.712496) [toit.network] DEBUG: error connecting {network: Cellular (internet), 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 | free: 10kb->10kb 2.270ms]
(8.733805) [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 WyNVBVVYU1ULdjEuNS4wLXJjLjFTVQllc3AzMi00bWJbJFUjVRAAAAAAAAAAAAAAAAAAAAAAWyNVBFVFU1UJRVhDRVBUSU9OU1URRkFJTEVEIFRPIENPTk5FQ1RbI1UCVVNbI1UbWyNVA1VGVQBsAAKppVsjVQNVRlUBbAACqQJbI1UDVUZVAmwAAqWOWyNVA1VGVQNJJNhbI1UDVUZVBEkk7VsjVQNVRlUFSSRyWyNVA1VGVQZsAAKly1sjVQNVRlUHbAACpWdbI1UDVUZVCGwAAQFHWyNVA1VGVQlsAAGcN1sjVQNVRlUKbAABEVdbI1UDVUZVC2wAARFwWyNVA1VGVQxsAAEQ7VsjVQNVRlUNSSTYWyNVA1VGVQ5JJO1bI1UDVUZVD0kkylsjVQNVRlUQbAABEQZbI1UDVUZVEWwAAREcWyNVA1VGVRJJMfNbI1UDVUZVE2wAAkdwWyNVA1VGVRRJJNhbI1UDVUZVFUkk7VsjVQNVRlUWSSRyWyNVA1VGVRdsAAJHhVsjVQNVRlUYbAACR5xbI1UDVUZVGUkxiFsjVQNVRlUaSTIj
(8.835794) [toit] INFO: -> AT+CFUN=0
(8.841820) [toit.scheduler] INFO: changing run level {run_level: 0}
(8.845753) [toit.scheduler] INFO: going into deep sleep {ms: 4989}
(8.847798) [toit.metrics] INFO: persisting metrics
(9.286146) [toit] INFO: <- OK
(9.394145) [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=a966ebf8-6407-4964-9685-95ac15170168.743.2021-09-26.device.toitware.com,model=esp32-4mb
(0.015874) [toit] INFO: booting toit {sdk: v1.5.0-rc.1, model: esp32-4mb}
(0.020850) [toit.scheduler] INFO: set initial run_level {run_level: 4}
(0.052967) [toit.state] INFO: resumed session {session_id: 1, session_time: 98.694573}
[gc @ 0x3ffcfa58* | soft limit reached (20480 >= 18432)]
[gc @ 0x3ffcfa58* | heap: 20kb -> 8kb | external: 0kb -> 0kb | free: 9kb->9kb 1.034ms]
(0.069658) [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.110559) [toit.console_sync] INFO: loaded last console sync {time: 93.013140}
[gc @ 0x3ffcfa58* | soft limit reached (24576 >= 24576)]
[gc @ 0x3ffcfa58* | byte array allocation failed, length = 511 (heap)]
[gc @ 0x3ffcfa58* | heap: 24kb -> 16kb | external: 0kb -> 0kb | free: 8kb->8kb 1.643ms]
(0.136755) [toit.scheduler] INFO: running job {job: console_sync}
(0.139818) [toit.scheduler] INFO: running job {job: poll_metric_flash_usage}
(0.143653) [toit.scheduler] INFO: running job {job: poll_metric_memory_profile}
(0.153538) [toit.console] INFO: waiting for console connection to be established
(0.157805) [toit.network] DEBUG: connecting {network: Cellular (internet)}
[gc @ 0x3ffcfa58* | soft limit reached (36892 >= 36864)]
[gc @ 0x3ffcfa58* | byte array allocation failed, length = 511 (heap)]
[gc @ 0x3ffcfa58* | heap: 36kb -> 20kb | external: 0kb -> 0kb | free: 12kb->12kb 1.942ms]
(0.212013) [toit] INFO: -> AT
[gc @ 0x3ffcfa58* | soft limit reached (45084 >= 43050)]
[gc @ 0x3ffcfa58* | heap: 44kb -> 24kb | external: 0kb -> 0kb | free: 6kb->6kb 2.262ms]
(0.473600) [toit] INFO: -> AT
(0.734063) [toit] INFO: -> AT
(0.994940) [toit] INFO: -> AT
(1.255764) [toit] INFO: -> AT
(1.516596) [toit] INFO: -> AT
(1.777874) [toit] INFO: -> AT
(1.953891) [toit] INFO: <- OK
(2.059899) [toit] INFO: -> ATE0
(2.067749) [toit] INFO: <- OK
(2.090391) [toit] INFO: -> AT+CMEE=2
(2.096318) [toit] INFO: <- OK
(2.118792) [toit] INFO: -> AT+CGMM
(2.124042) [toit] INFO: <- FiPy
(2.127190) [toit] INFO: <- OK
(2.149383) [toit] INFO: -> AT+CGMR
(2.169730) [toit] INFO: <- UE5.4.0.2
(2.172403) [toit] INFO: <- OK
(2.194639) [toit] INFO: -> AT+SQNCCID?
(2.200372) [toit] INFO: <- +SQNCCID: "",""
(2.203106) [toit] INFO: <- OK
(2.205716) [toit.cellular] DEBUG: initialized {model: FiPy, version: UE5.4.0.2, iccid: }
(2.225565) [toit] INFO: -> AT+CFUN=4
(2.291182) [toit] INFO: <- OK
(2.313780) [toit] INFO: -> AT+CPIN?
(2.319488) [toit] INFO: <- +CME ERROR: SIM not inserted
(2.574359) [toit] INFO: -> AT+CPIN?
(2.580110) [toit] INFO: <- +CME ERROR: SIM not inserted
(2.834577) [toit] INFO: -> AT+CPIN?
(2.871367) [toit] INFO: <- +CPIN: READY
(2.874034) [toit] INFO: <- OK
(2.905052) [toit] INFO: -> AT+CPSMS=0
(2.912473) [toit] INFO: <- OK
(2.935423) [toit] INFO: -> AT+CEDRXS=0
(2.943302) [toit] INFO: <- OK
(2.966021) [toit] INFO: -> AT+SQNIBRCFG=0
(2.985677) [toit] INFO: <- OK
(3.008678) [toit] INFO: -> AT+SQNIPSCFG=1,100
(3.028275) [toit] INFO: <- OK
(3.051166) [toit] INFO: -> AT+SQNBANDSEL=0,"standard","20"
(3.120616) [toit] INFO: <- +SQNBANDSEL: <custom>
(3.123407) [toit] INFO: <- OK
(3.146019) [toit] INFO: -> AT+SQNBANDSEL=1,"standard","20"
(3.209915) [toit] INFO: <- +CME ERROR: operation not supported
(3.232195) [toit] INFO: -> AT+CGDCONT?
(3.238632) [toit] INFO: <- +CGDCONT: 1,"IP","internet",,,,0,0,0,0,0,0,0,,0
(3.241751) [toit] INFO: <- OK
(3.264276) [toit] INFO: -> AT+CFUN=1
(3.335288) [toit] INFO: <- OK