1637844347.toit-sim-4786.hardware_id-e1ddb74a-18cb-4b55-8fdf-f214061b6327.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/208506711/otadata059244234 0x10000 /tmp/208506711/ota_0821454753"
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: 24:0a:c4:c7:26:10
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 23008.1 kbit/s)...
Hash of data verified.
Compressed 1323840 bytes to 804566...
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 (804566 compressed) at 0x00010000 in 13.2 seconds (effective 799.9 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.cts     | 18             |
| model.cellular.pwr     | 27             |
| model.cellular.monarch | 1              |
| model.cellular.enabled | 1              |
| model                  | esp32-4mb      |
| broker.host            | device.toit.io |
| cellular.apn           | internet       |
| model.cellular.tx      | 5              |
| model.cellular.rx      | 23             |
| model.cellular.rts     | 19             |
+------------------------+----------------+


$ toit serial info --output table
+-------------------------------------------------------------------------+-----------+--------------------------------------+
|                                  NAME                                   |   MODEL   |             HARDWARE ID              |
+-------------------------------------------------------------------------+-----------+--------------------------------------+
| e1ddb74a-18cb-4b55-8fdf-f214061b6327.457.2021-09-26.device.toitware.com | esp32-4mb | e1ddb74a-18cb-4b55-8fdf-f214061b6327 |
+-------------------------------------------------------------------------+-----------+--------------------------------------+


$ 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=e1ddb74a-18cb-4b55-8fdf-f214061b6327.457.2021-09-26.device.toitware.com,model=esp32-4mb
(0.015893) [toit] INFO: booting toit {sdk: v1.5.0-rc.1, model: esp32-4mb}
(0.020877) [toit.scheduler] INFO: set initial run_level {run_level: 4}
(0.405345) [toit.state] INFO: starting new session {session_id: 1, session_time: 0.412688}
[gc @ 0x3ffcf9f0* | soft limit reached (20480 >= 18432)]
[gc @ 0x3ffcf9f0* | heap: 20kb -> 8kb | external: 0kb -> 0kb | free: 9kb->9kb 1.154ms]
(0.494977) [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.597303) [toit.console_sync] INFO: loaded last console sync {time: null}
(0.609258) [toit.scheduler] INFO: running job {job: console_sync}
(0.612156) [toit.scheduler] INFO: running job {job: flush_metrics}
(0.614335) [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.619255) [toit.scheduler] INFO: running job {job: poll_metric_memory_profile}
(0.648110) [toit.console] INFO: waiting for console connection to be established
(0.652480) [toit.network] DEBUG: connecting {network: Cellular (internet)}
(0.659787) [toit.metrics] INFO: persisting metrics
(0.662927) [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]
(1.010738) [toit] INFO: -> AT
(1.272126) [toit] INFO: -> AT
(1.532717) [toit] INFO: -> AT
(1.793418) [toit] INFO: -> AT
(2.054212) [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 | free: 6kb->6kb 2.325ms]
(2.314898) [toit] INFO: -> AT
(2.575771) [toit] INFO: -> AT
(2.836363) [toit] INFO: -> AT
(3.103716) [toit] INFO: -> AT
(3.364411) [toit] INFO: -> AT
(3.625203) [toit] INFO: -> AT
(3.885931) [toit] INFO: -> AT
(4.146724) [toit] INFO: -> AT
(4.407792) [toit] INFO: -> AT
(4.412617) [toit] INFO: <- OK
(4.517982) [toit] INFO: -> ATE0
(4.522462) [toit] INFO: <- OK
(4.545176) [toit] INFO: -> AT+CMEE=2
(4.549344) [toit] INFO: <- OK
(4.571769) [toit] INFO: -> AT+CGMM
(4.576437) [toit] INFO: <- FiPy
(4.579340) [toit] INFO: <- OK
(4.601464) [toit] INFO: -> AT+CGMR
(4.674944) [toit] INFO: <- UE5.0.0.0d
(4.677882) [toit] INFO: <- OK
(4.700105) [toit] INFO: -> AT+SQNCCID?
(4.705528) [toit] INFO: <- +SQNCCID: "89882390000069114786",""
(4.707795) [toit] INFO: <- OK
(4.710279) [toit.cellular] DEBUG: initialized {model: FiPy, version: UE5.0.0.0d, iccid: 89882390000069114786}
(4.730423) [toit] INFO: -> AT+CFUN=4
(5.022820) [toit] INFO: <- OK
(5.045748) [toit] INFO: -> AT+CPIN?
(5.050222) [toit] INFO: <- +CME ERROR: SIM not inserted
(5.127034) [toit] INFO: <- *ignored* [URC] +SYSSTART []
(5.305144) [toit] INFO: -> AT+CPIN?
(5.309697) [toit] INFO: <- +CME ERROR: SIM not inserted
(5.564230) [toit] INFO: -> AT+CPIN?
(5.576136) [toit] INFO: <- +CME ERROR: SIM not inserted
(5.830849) [toit] INFO: -> AT+CPIN?
(6.173996) [toit] INFO: <- +CPIN: READY
(6.176561) [toit] INFO: <- OK
(6.199255) [toit] INFO: -> AT+CPSMS=0
(6.250008) [toit] INFO: <- OK
(6.272903) [toit] INFO: -> AT+CEDRXS=0
(6.277375) [toit] INFO: <- OK
(6.300247) [toit] INFO: -> AT+SQNIBRCFG=0
(6.392970) [toit] INFO: <- OK
(6.416045) [toit] INFO: -> AT+SQNIPSCFG=1,100
(7.000926) [toit] INFO: <- OK
(7.024127) [toit] INFO: -> AT+SQNBANDSEL=0,"standard","20"
(7.029524) [toit] INFO: <- +CME ERROR: operation not supported
(7.052207) [toit] INFO: -> AT+SQNBANDSEL=1,"standard","20"
(7.056827) [toit] INFO: <- +CME ERROR: operation not supported
(7.079036) [toit] INFO: -> AT+CGDCONT?
(7.084287) [toit] INFO: <- +CGDCONT: 1,"IP","internet",,,,0,0,0,0,0,0
(7.092893) [toit] INFO: <- OK
(7.115431) [toit] INFO: -> AT+CFUN=1
(7.224739) [toit] INFO: <- OK
(10.604523) [toit.scheduler] INFO: running job {job: persist_metrics}
[gc @ 0x3ffcf9f0* | soft limit reached (57372 >= 55338)]
[gc @ 0x3ffcf9f0* | heap: 56kb -> 24kb | external: 0kb -> 0kb | free: 10kb->10kb 2.270ms]
(10.607800) [toit.metrics] INFO: persisting metrics
(11.006144) [toit.scheduler] INFO: running job {job: poll_metric_memory_profile}
(12.100083) [toit] INFO: -> AT+CFUN=4
(12.319109) [toit] INFO: <- OK
(12.321975) [toit] INFO: <- +CEREG: 0
(12.323976) [toit] INFO: <- *ignored* [URC] +CEREG [0]
(12.325976) [toit.cellular] DEBUG: failed, detach from network
(12.340383) [toit.network] DEBUG: error connecting {network: Cellular (internet), error: DEADLINE_EXCEEDED}
(12.347803) [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* | heap: 48kb -> 24kb | external: 0kb -> 0kb | free: 10kb->6kb 2.334ms]
(12.446039) [toit] INFO: -> AT+CFUN=0
(12.453007) [toit.scheduler] INFO: changing run level {run_level: 0}
(12.456737) [toit.scheduler] INFO: going into deep sleep {ms: 4985}
(12.460053) [toit.metrics] INFO: persisting metrics
(12.839614) [toit] INFO: <- OK
(12.947539) [toit] INFO: entering deep sleep {ms: 4985}
Entering deep sleep for 4985ms
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=e1ddb74a-18cb-4b55-8fdf-f214061b6327.457.2021-09-26.device.toitware.com,model=esp32-4mb
(0.015887) [toit] INFO: booting toit {sdk: v1.5.0-rc.1, model: esp32-4mb}
(0.020872) [toit.scheduler] INFO: set initial run_level {run_level: 4}
(0.052761) [toit.state] INFO: resumed session {session_id: 1, session_time: 18.067653}
[gc @ 0x3ffcfa58* | soft limit reached (20480 >= 18432)]
[gc @ 0x3ffcfa58* | heap: 20kb -> 8kb | external: 0kb -> 0kb | free: 9kb->9kb 1.081ms]
(0.069742) [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.110060) [toit.console_sync] INFO: loaded last console sync {time: 12.441947}
(0.126023) [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.744ms]
(0.129025) [toit.scheduler] INFO: running job {job: poll_metric_flash_usage}
(0.143029) [toit.scheduler] INFO: running job {job: poll_metric_memory_profile}
(0.152911) [toit.console] INFO: waiting for console connection to be established
(0.157124) [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.918ms]
(0.199426) [toit] INFO: -> AT
(0.460814) [toit] INFO: -> AT
(0.721388) [toit] INFO: -> AT
(0.982086) [toit] INFO: -> AT
(1.242917) [toit] INFO: -> AT
(1.503632) [toit] INFO: -> AT
(1.764341) [toit] INFO: -> AT
(2.025136) [toit] INFO: -> AT
(2.285824) [toit] INFO: -> AT
(2.546564) [toit] INFO: -> AT
(2.816420) [toit] INFO: -> AT
(3.077957) [toit] INFO: -> AT
(3.338640) [toit] INFO: -> AT
(3.343706) [toit] INFO: <- OK
(3.448065) [toit] INFO: -> ATE0
(3.452459) [toit] INFO: <- OK
[gc @ 0x3ffcfa58* | soft limit reached (45084 >= 43050)]
[gc @ 0x3ffcfa58* | string allocation failed, length = 6 (heap)]
[gc @ 0x3ffcfa58* | heap: 44kb -> 24kb | external: 0kb -> 0kb | free: 10kb->10kb 2.297ms]
(3.475113) [toit] INFO: -> AT+CMEE=2
(3.490097) [toit] INFO: <- OK
(3.512614) [toit] INFO: -> AT+CGMM
(3.517238) [toit] INFO: <- FiPy
(3.520189) [toit] INFO: <- OK
(3.542368) [toit] INFO: -> AT+CGMR
(3.805913) [toit] INFO: <- UE5.0.0.0d
(3.809078) [toit] INFO: <- OK
(3.831346) [toit] INFO: -> AT+SQNCCID?
(3.836844) [toit] INFO: <- +SQNCCID: "89882390000069114786",""
(3.839104) [toit] INFO: <- OK
(3.841592) [toit.cellular] DEBUG: initialized {model: FiPy, version: UE5.0.0.0d, iccid: 89882390000069114786}
(3.861627) [toit] INFO: -> AT+CFUN=4
(4.152635) [toit] INFO: <- OK
(4.175460) [toit] INFO: -> AT+CPIN?
(4.179784) [toit] INFO: <- +CME ERROR: SIM not inserted
(4.200357) [toit] INFO: <- *ignored* [URC] +SYSSTART []
(4.434753) [toit] INFO: -> AT+CPIN?
(4.439327) [toit] INFO: <- +CME ERROR: SIM not inserted
(4.693928) [toit] INFO: -> AT+CPIN?
(4.705902) [toit] INFO: <- +CME ERROR: SIM not inserted
(4.960885) [toit] INFO: -> AT+CPIN?
(5.306811) [toit] INFO: <- +CPIN: READY
(5.309022) [toit] INFO: <- OK
(5.331670) [toit] INFO: -> AT+CPSMS=0
(5.382373) [toit] INFO: <- OK
(5.405283) [toit] INFO: -> AT+CEDRXS=0
(5.409914) [toit] INFO: <- OK
(5.432781) [toit] INFO: -> AT+SQNIBRCFG=0
(5.525428) [toit] INFO: <- OK
(5.548594) [toit] INFO: -> AT+SQNIPSCFG=1,100
(6.021414) [toit] INFO: <- OK
(6.044617) [toit] INFO: -> AT+SQNBANDSEL=0,"standard","20"
(6.049551) [toit] INFO: <- +CME ERROR: operation not supported
(6.072230) [toit] INFO: -> AT+SQNBANDSEL=1,"standard","20"
(6.076951) [toit] INFO: <- +CME ERROR: operation not supported
(6.099164) [toit] INFO: -> AT+CGDCONT?
(6.104412) [toit] INFO: <- +CGDCONT: 1,"IP","internet",,,,0,0,0,0,0,0
(6.107344) [toit] INFO: <- OK
(6.129772) [toit] INFO: -> AT+CFUN=1
(6.239000) [toit] INFO: <- OK
(10.135886) [toit.scheduler] INFO: running job {job: persist_metrics}
(10.139658) [toit.metrics] INFO: persisting metrics
(10.211793) [toit.scheduler] INFO: running job {job: poll_metric_memory_profile}
[gc @ 0x3ffcfa58* | soft limit reached (53276 >= 49194)]
[gc @ 0x3ffcfa58* | heap: 52kb -> 24kb | external: 0kb -> 0kb | free: 10kb->10kb 2.208ms]
(11.114242) [toit] INFO: -> AT+CFUN=4
(12.184967) [toit] INFO: <- OK
(12.188217) [toit] INFO: <- +CEREG: 0
(12.190262) [toit] INFO: <- *ignored* [URC] +CEREG [0]
(12.192213) [toit.cellular] DEBUG: failed, detach from network
(12.211312) [toit.network] DEBUG: error connecting {network: Cellular (internet), error: DEADLINE_EXCEEDED}
(12.218931) [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
(12.315625) [toit] INFO: -> AT+CFUN=0
(12.322388) [toit.scheduler] INFO: changing run level {run_level: 0}
(12.326080) [toit.scheduler] INFO: going into deep sleep {ms: 4988}
(12.328121) [toit.metrics] INFO: persisting metrics
[gc @ 0x3ffcfa58* | soft limit reached (49624 >= 49194)]
[gc @ 0x3ffcfa58* | byte array allocation failed, length = 8 (heap)]
[gc @ 0x3ffcfa58* | heap: 48kb -> 24kb | external: 0kb -> 0kb | free: 10kb->10kb 2.352ms]
(12.526658) [toit] INFO: <- OK
(12.634537) [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=e1ddb74a-18cb-4b55-8fdf-f214061b6327.457.2021-09-26.device.toitware.com,model=esp32-4mb
(0.015890) [toit] INFO: booting toit {sdk: v1.5.0-rc.1, model: esp32-4mb}
(0.020874) [toit.scheduler] INFO: set initial run_level {run_level: 4}
(0.052592) [toit.state] INFO: resumed session {session_id: 1, session_time: 35.741914}
[gc @ 0x3ffcfa58* | soft limit reached (20480 >= 18432)]
[gc @ 0x3ffcfa58* | heap: 20kb -> 8kb | external: 0kb -> 0kb | free: 9kb->9kb 1.060ms]
(0.069662) [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: 30.300073}
(0.126214) [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.136462) [toit.scheduler] INFO: running job {job: poll_metric_flash_usage}
(0.143204) [toit.scheduler] INFO: running job {job: poll_metric_memory_profile}
(0.153114) [toit.console] INFO: waiting for console connection to be established
(0.157308) [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.200320) [toit] INFO: -> AT
(0.461715) [toit] INFO: -> AT
(0.722290) [toit] INFO: -> AT
(0.982988) [toit] INFO: -> AT
(1.244016) [toit] INFO: -> AT
(1.504549) [toit] INFO: -> AT
(1.765256) [toit] INFO: -> AT
(2.026054) [toit] INFO: -> AT
(2.287700) [toit] INFO: -> AT
(2.548434) [toit] INFO: -> AT
(2.816406) [toit] INFO: -> AT
(3.077960) [toit] INFO: -> AT
(3.338643) [toit] INFO: -> AT
(3.343535) [toit] INFO: <- OK
[gc @ 0x3ffcfa58* | soft limit reached (45084 >= 43050)]
[gc @ 0x3ffcfa58* | string allocation failed, length = 31 (heap)]
[gc @ 0x3ffcfa58* | heap: 44kb -> 24kb | external: 0kb -> 0kb | free: 10kb->10kb 2.261ms]
(3.447913) [toit] INFO: -> ATE0
(3.463831) [toit] INFO: <- OK
(3.486606) [toit] INFO: -> AT+CMEE=2
(3.491072) [toit] INFO: <- OK
(3.513717) [toit] INFO: -> AT+CGMM
(3.518231) [toit] INFO: <- FiPy
(3.521178) [toit] INFO: <- OK
(3.543350) [toit] INFO: -> AT+CGMR
(3.617965) [toit] INFO: <- UE5.0.0.0d
(3.620646) [toit] INFO: <- OK
(3.635038) [toit] INFO: <- *ignored* [URC] +SYSSTART []
(3.655376) [toit] INFO: -> AT+SQNCCID?
(3.660661) [toit] INFO: <- +SQNCCID: "89882390000069114786",""
(3.662917) [toit] INFO: <- OK
(3.665389) [toit.cellular] DEBUG: initialized {model: FiPy, version: UE5.0.0.0d, iccid: 89882390000069114786}
(3.685451) [toit] INFO: -> AT+CFUN=4
(3.778200) [toit] INFO: <- OK
(3.806028) [toit] INFO: -> AT+CPIN?
(3.810161) [toit] INFO: <- +CME ERROR: SIM not inserted
(4.065072) [toit] INFO: -> AT+CPIN?
(4.069212) [toit] INFO: <- +CME ERROR: SIM not inserted
(4.323711) [toit] INFO: -> AT+CPIN?
(4.328070) [toit] INFO: <- +CME ERROR: SIM not inserted
(4.582569) [toit] INFO: -> AT+CPIN?
(4.923251) [toit] INFO: <- +CPIN: READY
(4.925923) [toit] INFO: <- OK
(4.948398) [toit] INFO: -> AT+CPSMS=0
(4.999138) [toit] INFO: <- OK
(5.022286) [toit] INFO: -> AT+CEDRXS=0
(5.026860) [toit] INFO: <- OK
(5.049764) [toit] INFO: -> AT+SQNIBRCFG=0
(5.142401) [toit] INFO: <- OK
(5.165485) [toit] INFO: -> AT+SQNIPSCFG=1,100
(5.345296) [toit] INFO: <- OK
(5.368337) [toit] INFO: -> AT+SQNBANDSEL=0,"standard","20"
(5.374222) [toit] INFO: <- +CME ERROR: operation not supported
(5.396928) [toit] INFO: -> AT+SQNBANDSEL=1,"standard","20"
(5.402462) [toit] INFO: <- +CME ERROR: operation not supported
(5.424746) [toit] INFO: -> AT+CGDCONT?
(5.430084) [toit] INFO: <- +CGDCONT: 1,"IP","internet",,,,0,0,0,0,0,0
(5.432730) [toit] INFO: <- OK
(5.455399) [toit] INFO: -> AT+CFUN=1
(5.878963) [toit] INFO: <- OK
(10.136586) [toit.scheduler] INFO: running job {job: persist_metrics}
(10.140277) [toit.metrics] INFO: persisting metrics
(10.213194) [toit.scheduler] INFO: running job {job: poll_metric_memory_profile}
[gc @ 0x3ffcfa58* | soft limit reached (53276 >= 49194)]
[gc @ 0x3ffcfa58* | heap: 52kb -> 24kb | external: 0kb -> 0kb | free: 10kb->10kb 2.213ms]
(10.439491) [toit] INFO: -> AT+CFUN=4
(10.962139) [toit] INFO: <- OK
(10.965351) [toit] INFO: <- +CEREG: 0
(10.967402) [toit] INFO: <- *ignored* [URC] +CEREG [0]
(10.969358) [toit.cellular] DEBUG: failed, detach from network
(10.980868) [toit.network] DEBUG: error connecting {network: Cellular (internet), error: DEADLINE_EXCEEDED}
(10.988629) [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
(11.085383) [toit] INFO: -> AT+CFUN=0
[gc @ 0x3ffcfa58* | soft limit reached (49624 >= 49194)]
[gc @ 0x3ffcfa58* | heap: 48kb -> 24kb | external: 0kb -> 0kb | free: 10kb->10kb 2.328ms]
(11.092160) [toit.scheduler] INFO: changing run level {run_level: 0}
(11.103029) [toit.scheduler] INFO: going into deep sleep {ms: 4989}
(11.107397) [toit.metrics] INFO: persisting metrics
(11.285673) [toit] INFO: <- OK
(11.393657) [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=e1ddb74a-18cb-4b55-8fdf-f214061b6327.457.2021-09-26.device.toitware.com,model=esp32-4mb
(0.015888) [toit] INFO: booting toit {sdk: v1.5.0-rc.1, model: esp32-4mb}
(0.020872) [toit.scheduler] INFO: set initial run_level {run_level: 4}
(0.052432) [toit.state] INFO: resumed session {session_id: 1, session_time: 52.177883}
[gc @ 0x3ffcfa58* | soft limit reached (20480 >= 18432)]
[gc @ 0x3ffcfa58* | heap: 20kb -> 8kb | external: 0kb -> 0kb | free: 9kb->9kb 1.051ms]
(0.069599) [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.110225) [toit.console_sync] INFO: loaded last console sync {time: 46.746093}
(0.126395) [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.137008) [toit.scheduler] INFO: running job {job: poll_metric_flash_usage}
(0.143357) [toit.scheduler] INFO: running job {job: poll_metric_memory_profile}
(0.153303) [toit.console] INFO: waiting for console connection to be established
(0.157489) [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.201376) [toit] INFO: -> AT
(0.462762) [toit] INFO: -> AT
(0.723337) [toit] INFO: -> AT
(0.984035) [toit] INFO: -> AT
(1.244866) [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.275ms]
(1.505590) [toit] INFO: -> AT
(1.766437) [toit] INFO: -> AT
(2.027953) [toit] INFO: -> AT
(2.288645) [toit] INFO: -> AT
(2.549378) [toit] INFO: -> AT
(2.816416) [toit] INFO: -> AT
(3.077949) [toit] INFO: -> AT
(3.338645) [toit] INFO: -> AT
(3.343640) [toit] INFO: <- OK
(3.447995) [toit] INFO: -> ATE0
(3.452237) [toit] INFO: <- OK
(3.474879) [toit] INFO: -> AT+CMEE=2
(3.478962) [toit] INFO: <- OK
(3.501450) [toit] INFO: -> AT+CGMM
(3.506517) [toit] INFO: <- FiPy
(3.509076) [toit] INFO: <- OK
(3.531156) [toit] INFO: -> AT+CGMR
(3.604269) [toit] INFO: <- UE5.0.0.0d
(3.607454) [toit] INFO: <- OK
(3.610350) [toit] INFO: <- *ignored* [URC] +SYSSTART []
(3.630630) [toit] INFO: -> AT+SQNCCID?
(3.636001) [toit] INFO: <- +SQNCCID: "89882390000069114786",""
(3.638252) [toit] INFO: <- OK
(3.640736) [toit.cellular] DEBUG: initialized {model: FiPy, version: UE5.0.0.0d, iccid: 89882390000069114786}
(3.660780) [toit] INFO: -> AT+CFUN=4
(3.753593) [toit] INFO: <- OK
(3.776323) [toit] INFO: -> AT+CPIN?
(3.780500) [toit] INFO: <- +CME ERROR: SIM not inserted
(4.035397) [toit] INFO: -> AT+CPIN?
(4.039570) [toit] INFO: <- +CME ERROR: SIM not inserted
(4.294042) [toit] INFO: -> AT+CPIN?
(4.303507) [toit] INFO: <- +CME ERROR: SIM not inserted
(4.558010) [toit] INFO: -> AT+CPIN?
(4.901477) [toit] INFO: <- +CPIN: READY
(4.904140) [toit] INFO: <- OK
(4.926603) [toit] INFO: -> AT+CPSMS=0
(4.977041) [toit] INFO: <- OK
(5.000248) [toit] INFO: -> AT+CEDRXS=0
(5.004790) [toit] INFO: <- OK
(5.027840) [toit] INFO: -> AT+SQNIBRCFG=0
(5.120450) [toit] INFO: <- OK
(5.143521) [toit] INFO: -> AT+SQNIPSCFG=1,100
(5.323052) [toit] INFO: <- OK
(5.346135) [toit] INFO: -> AT+SQNBANDSEL=0,"standard","20"
(5.351989) [toit] INFO: <- +CME ERROR: operation not supported
(5.374674) [toit] INFO: -> AT+SQNBANDSEL=1,"standard","20"
(5.379927) [toit] INFO: <- +CME ERROR: operation not supported
(5.402141) [toit] INFO: -> AT+CGDCONT?
(5.407265) [toit] INFO: <- +CGDCONT: 1,"IP","internet",,,,0,0,0,0,0,0
(5.409919) [toit] INFO: <- OK
(5.432610) [toit] INFO: -> AT+CFUN=1
(5.840975) [toit] INFO: <- OK
(10.141324) [toit.scheduler] INFO: running job {job: persist_metrics}
[gc @ 0x3ffcfa58* | soft limit reached (53276 >= 49194)]
[gc @ 0x3ffcfa58* | heap: 52kb -> 24kb | external: 0kb -> 0kb | free: 10kb->10kb 2.257ms]
(10.144664) [toit.metrics] INFO: persisting metrics
(10.219138) [toit.scheduler] INFO: running job {job: poll_metric_memory_profile}
(10.416932) [toit] INFO: -> AT+CFUN=4
(10.682400) [toit] INFO: <- OK
(10.685592) [toit] INFO: <- +CEREG: 0
(10.687646) [toit] INFO: <- *ignored* [URC] +CEREG [0]
(10.689594) [toit.cellular] DEBUG: failed, detach from network
(10.700322) [toit.network] DEBUG: error connecting {network: Cellular (internet), error: DEADLINE_EXCEEDED}
(10.708618) [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 @ 0x3ffcfa58* | soft limit reached (49624 >= 49194)]
[gc @ 0x3ffcfa58* | heap: 48kb -> 20kb | external: 0kb -> 0kb | free: 10kb->6kb 2.270ms]
(10.809069) [toit] INFO: -> AT+CFUN=0
(10.815827) [toit.scheduler] INFO: changing run level {run_level: 0}
(10.819842) [toit.scheduler] INFO: going into deep sleep {ms: 4985}
(10.821854) [toit.metrics] INFO: persisting metrics
(11.205037) [toit] INFO: <- OK
(11.313082) [toit] INFO: entering deep sleep {ms: 4985}
Entering deep sleep for 4985ms
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=e1ddb74a-18cb-4b55-8fdf-f214061b6327.457.2021-09-26.device.toitware.com,model=esp32-4mb
(0.015888) [toit] INFO: booting toit {sdk: v1.5.0-rc.1, model: esp32-4mb}
(0.020872) [toit.scheduler] INFO: set initial run_level {run_level: 4}
(0.052249) [toit.state] INFO: resumed session {session_id: 1, session_time: 68.524447}
[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.057ms]
(0.076090) [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.119969) [toit.console_sync] INFO: loaded last console sync {time: 62.897643}
[gc @ 0x3ffcfa58* | soft limit reached (24576 >= 24576)]
[gc @ 0x3ffcfa58* | heap: 24kb -> 16kb | external: 0kb -> 0kb | free: 8kb->8kb 1.730ms]
(0.136232) [toit.scheduler] INFO: running job {job: console_sync}
(0.145761) [toit.scheduler] INFO: running job {job: poll_metric_flash_usage}
(0.152329) [toit.scheduler] INFO: running job {job: poll_metric_memory_profile}
(0.162379) [toit.console] INFO: waiting for console connection to be established
(0.166482) [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.211245) [toit] INFO: -> AT
(0.472640) [toit] INFO: -> AT
(0.733228) [toit] INFO: -> AT
(0.993926) [toit] INFO: -> AT
(1.254757) [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.516353) [toit] INFO: -> AT
(1.777998) [toit] INFO: -> AT
(2.038671) [toit] INFO: -> AT
(2.299369) [toit] INFO: -> AT
(2.560090) [toit] INFO: -> AT
(2.820831) [toit] INFO: -> AT
(3.081642) [toit] INFO: -> AT
(3.342326) [toit] INFO: -> AT
(3.348274) [toit] INFO: <- OK
(3.452616) [toit] INFO: -> ATE0
(3.456867) [toit] INFO: <- OK
(3.479509) [toit] INFO: -> AT+CMEE=2
(3.483617) [toit] INFO: <- OK
(3.505999) [toit] INFO: -> AT+CGMM
(3.510679) [toit] INFO: <- FiPy
(3.513633) [toit] INFO: <- OK
(3.535758) [toit] INFO: -> AT+CGMR
(3.608799) [toit] INFO: <- UE5.0.0.0d
(3.611736) [toit] INFO: <- OK
(3.633970) [toit] INFO: -> AT+SQNCCID?
(3.639375) [toit] INFO: <- +SQNCCID: "89882390000069114786",""
(3.641623) [toit] INFO: <- OK
(3.644111) [toit.cellular] DEBUG: initialized {model: FiPy, version: UE5.0.0.0d, iccid: 89882390000069114786}
(3.653563) [toit] INFO: <- *ignored* [URC] +SYSSTART []
(3.674349) [toit] INFO: -> AT+CFUN=4
(3.766522) [toit] INFO: <- OK
(3.789098) [toit] INFO: -> AT+CPIN?
(3.793139) [toit] INFO: <- +CME ERROR: SIM not inserted
(4.048014) [toit] INFO: -> AT+CPIN?
(4.052187) [toit] INFO: <- +CME ERROR: SIM not inserted
(4.306723) [toit] INFO: -> AT+CPIN?
(4.315070) [toit] INFO: <- +CME ERROR: SIM not inserted
(4.569565) [toit] INFO: -> AT+CPIN?
(4.911808) [toit] INFO: <- +CPIN: READY
(4.914476) [toit] INFO: <- OK
(4.936959) [toit] INFO: -> AT+CPSMS=0
(4.987310) [toit] INFO: <- OK
(5.010482) [toit] INFO: -> AT+CEDRXS=0
(5.015178) [toit] INFO: <- OK
(5.038075) [toit] INFO: -> AT+SQNIBRCFG=0
(5.130456) [toit] INFO: <- OK
(5.153533) [toit] INFO: -> AT+SQNIPSCFG=1,100
(5.333005) [toit] INFO: <- OK
(5.356089) [toit] INFO: -> AT+SQNBANDSEL=0,"standard","20"
(5.361333) [toit] INFO: <- +CME ERROR: operation not supported
(5.384016) [toit] INFO: -> AT+SQNBANDSEL=1,"standard","20"
(5.389088) [toit] INFO: <- +CME ERROR: operation not supported
(5.411311) [toit] INFO: -> AT+CGDCONT?
(5.416464) [toit] INFO: <- +CGDCONT: 1,"IP","internet",,,,0,0,0,0,0,0
(5.419117) [toit] INFO: <- OK
(5.441827) [toit] INFO: -> AT+CFUN=1
(5.767197) [toit] INFO: <- OK
���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=e1ddb74a-18cb-4b55-8fdf-f214061b6327.457.2021-09-26.device.toitware.com,model=esp32-4mb
(0.015893) [toit] INFO: booting toit {sdk: v1.5.0-rc.1, model: esp32-4mb}
(0.020877) [toit.scheduler] INFO: set initial run_level {run_level: 4}
(0.060535) [toit.state] INFO: starting new session {session_id: 2, session_time: 0.067997}
[gc @ 0x3ffcf9f0* | soft limit reached (20480 >= 18432)]
[gc @ 0x3ffcf9f0* | heap: 20kb -> 8kb | external: 0kb -> 0kb | free: 9kb->9kb 1.036ms]
(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.120065) [toit.console_sync] INFO: loaded last console sync {time: null}
(0.133601) [toit.scheduler] INFO: running job {job: console_sync}
[gc @ 0x3ffcf9f0* | soft limit reached (24576 >= 24576)]
[gc @ 0x3ffcf9f0* | heap: 24kb -> 16kb | external: 0kb -> 0kb | free: 8kb->8kb 1.730ms]
(0.144106) [toit.scheduler] INFO: running job {job: flush_metrics}
(0.149599) [toit.scheduler] INFO: running job {job: poll_metric_flash_usage}
(0.156370) [toit.scheduler] INFO: running job {job: poll_metric_memory_profile}
(0.166290) [toit.console] INFO: waiting for console connection to be established
(0.170581) [toit.network] DEBUG: connecting {network: Cellular (internet)}
(0.177864) [toit.metrics] INFO: persisting metrics
(0.181031) [toit.metrics] INFO: flushing metrics
[gc @ 0x3ffcf9f0* | soft limit reached (36892 >= 36864)]
[gc @ 0x3ffcf9f0* | heap: 36kb -> 20kb | external: 0kb -> 0kb | free: 12kb->12kb 1.972ms]
[gc @ 0x3ffcf9f0* | soft limit reached (45084 >= 43050)]
[gc @ 0x3ffcf9f0* | heap: 44kb -> 20kb | external: 0kb -> 0kb | free: 6kb->6kb 2.097ms]
(0.397759) [toit] INFO: -> AT
(0.663243) [toit] INFO: -> AT
(0.923796) [toit] INFO: -> AT
(1.184613) [toit] INFO: -> AT
(1.445506) [toit] INFO: -> AT
(1.706033) [toit] INFO: -> AT
(1.967790) [toit] INFO: -> AT
(2.228538) [toit] INFO: -> AT
(2.233514) [toit] INFO: <- OK
(2.337950) [toit] INFO: -> ATE0
(2.341868) [toit] INFO: <- OK
(2.364581) [toit] INFO: -> AT+CMEE=2
(2.368711) [toit] INFO: <- OK
(2.391408) [toit] INFO: -> AT+CGMM
(2.395521) [toit] INFO: <- FiPy
(2.398662) [toit] INFO: <- OK
(2.420872) [toit] INFO: -> AT+CGMR
(2.509876) [toit] INFO: <- UE5.0.0.0d
(2.512590) [toit] INFO: <- OK
(2.534796) [toit] INFO: -> AT+SQNCCID?
(2.539554) [toit] INFO: <- +SQNCCID: "",""
(2.542252) [toit] INFO: <- OK
(2.544837) [toit.cellular] DEBUG: initialized {model: FiPy, version: UE5.0.0.0d, iccid: }
(2.564646) [toit] INFO: -> AT+CFUN=4
(2.867575) [toit] INFO: <- OK
(2.890337) [toit] INFO: -> AT+CPIN?
(2.894677) [toit] INFO: <- +CME ERROR: SIM not inserted
(3.149600) [toit] INFO: -> AT+CPIN?
(3.155842) [toit] INFO: <- +CME ERROR: SIM not inserted
(3.296261) [toit] INFO: <- *ignored* [URC] +SYSSTART []
(3.410568) [toit] INFO: -> AT+CPIN?
(3.429461) [toit] INFO: <- +CME ERROR: SIM not inserted
[gc @ 0x3ffcf9f0* | soft limit reached (45084 >= 43050)]
[gc @ 0x3ffcf9f0* | byte array allocation failed, length = 606 (heap)]
[gc @ 0x3ffcf9f0* | heap: 44kb -> 24kb | external: 0kb -> 0kb | free: 6kb->6kb 2.217ms]
(3.695748) [toit] INFO: -> AT+CPIN?
(4.028825) [toit] INFO: <- +CPIN: READY
(4.031655) [toit] INFO: <- OK
(4.054152) [toit] INFO: -> AT+CPSMS=0
(4.104594) [toit] INFO: <- OK
(4.127824) [toit] INFO: -> AT+CEDRXS=0
(4.132344) [toit] INFO: <- OK
(4.155213) [toit] INFO: -> AT+SQNIBRCFG=0
(4.247921) [toit] INFO: <- OK
(4.270982) [toit] INFO: -> AT+SQNIPSCFG=1,100
(4.705286) [toit] INFO: <- OK
(4.728145) [toit] INFO: -> AT+SQNBANDSEL=0,"standard","20"
(4.733518) [toit] INFO: <- +CME ERROR: operation not supported
(4.756098) [toit] INFO: -> AT+SQNBANDSEL=1,"standard","20"
(4.760881) [toit] INFO: <- +CME ERROR: operation not supported
(4.783128) [toit] INFO: -> AT+CGDCONT?
(4.788401) [toit] INFO: <- +CGDCONT: 1,"IP","internet",,,,0,0,0,0,0,0
(4.791095) [toit] INFO: <- OK
(4.813622) [toit] INFO: -> AT+CFUN=1
(4.922928) [toit] INFO: <- OK
(9.798309) [toit] INFO: -> AT+CFUN=4
(10.138419) [toit.scheduler] INFO: running job {job: persist_metrics}
(10.141952) [toit.metrics] INFO: persisting metrics
(10.400598) [toit.scheduler] INFO: running job {job: poll_metric_memory_profile}
(10.410928) [toit] INFO: <- OK
(10.414041) [toit] INFO: <- +CEREG: 0
(10.416042) [toit] INFO: <- *ignored* [URC] +CEREG [0]
(10.418338) [toit.cellular] DEBUG: failed, detach from network
(10.430388) [toit.network] DEBUG: error connecting {network: Cellular (internet), error: DEADLINE_EXCEEDED}
(10.437868) [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 (53720 >= 49194)]
[gc @ 0x3ffcf9f0* | string allocation failed, length = 591 (heap)]
[gc @ 0x3ffcf9f0* | heap: 52kb -> 24kb | external: 0kb -> 0kb | free: 6kb->6kb 2.298ms]
toit serial decode WyNVBVVYU1ULdjEuNS4wLXJjLjFTVQllc3AzMi00bWJbJFUjVRAAAAAAAAAAAAAAAAAAAAAAWyNVBFVFU1UJRVhDRVBUSU9OU1URRkFJTEVEIFRPIENPTk5FQ1RbI1UCVVNbI1UbWyNVA1VGVQBsAAKppVsjVQNVRlUBbAACqQJbI1UDVUZVAmwAAqWOWyNVA1VGVQNJJNhbI1UDVUZVBEkk7VsjVQNVRlUFSSRyWyNVA1VGVQZsAAKly1sjVQNVRlUHbAACpWdbI1UDVUZVCGwAAQFHWyNVA1VGVQlsAAGcN1sjVQNVRlUKbAABEVdbI1UDVUZVC2wAARFwWyNVA1VGVQxsAAEQ7VsjVQNVRlUNSSTYWyNVA1VGVQ5JJO1bI1UDVUZVD0kkylsjVQNVRlUQbAABEQZbI1UDVUZVEWwAAREcWyNVA1VGVRJJMfNbI1UDVUZVE2wAAkdwWyNVA1VGVRRJJNhbI1UDVUZVFUkk7VsjVQNVRlUWSSRyWyNVA1VGVRdsAAJHhVsjVQNVRlUYbAACR5xbI1UDVUZVGUkxiFsjVQNVRlUaSTIj
(10.561165) [toit] INFO: -> AT+CFUN=0
(10.568257) [toit.scheduler] INFO: changing run level {run_level: 0}
(10.571967) [toit.scheduler] INFO: going into deep sleep {ms: 4980}
(10.573989) [toit.metrics] INFO: persisting metrics
(10.764181) [toit] INFO: <- OK
(10.872338) [toit] INFO: entering deep sleep {ms: 4980}
Entering deep sleep for 4980ms
ets Jun  8 2016 00:22:57

rst:0x5 (DEEPSLEEP_RESET),boot:0x13 (SPI_FAST_FLASH_BOOT)
configsip: 0, SPIWP:0xee
clk_drv:0x00,q_drv:0x00,d_drv:0x00,cs0_drv:0x00,hd_drv:0x00,wp_drv:0x00
mode:DIO, clock div:2
load:0x3fff0030,len:188
ho 0 tail 12 room 4
load:0x40078000,len:12180
load:0x40080400,len:2944
entry 0x400805c8
[flash reg] address 0x3f46c000, size 0x00134000
[IDENTITY] name=e1ddb74a-18cb-4b55-8fdf-f214061b6327.457.2021-09-26.device.toitware.com,model=esp32-4mb
(0.015890) [toit] INFO: booting toit {sdk: v1.5.0-rc.1, model: esp32-4mb}
(0.020874) [toit.scheduler] INFO: set initial run_level {run_level: 4}
(0.052266) [toit.state] INFO: resumed session {session_id: 2, session_time: 15.975810}
[gc @ 0x3ffcfa58* | soft limit reached (20480 >= 18432)]
[gc @ 0x3ffcfa58* | heap: 20kb -> 8kb | external: 0kb -> 0kb | free: 9kb->9kb 1.036ms]
(0.068954) [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.110464) [toit.console_sync] INFO: loaded last console sync {time: 10.541502}
(0.126692) [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.719ms]
(0.137299) [toit.scheduler] INFO: running job {job: poll_metric_flash_usage}
(0.143647) [toit.scheduler] INFO: running job {job: poll_metric_memory_profile}
(0.153602) [toit.console] INFO: waiting for console connection to be established
(0.157774) [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.914ms]
(0.200179) [toit] INFO: -> AT
(0.461573) [toit] INFO: -> AT
(0.722148) [toit] INFO: -> AT
(0.982846) [toit] INFO: -> AT
(1.243677) [toit] INFO: -> AT
(1.504392) [toit] INFO: -> AT
(1.765101) [toit] INFO: -> AT
(2.025895) [toit] INFO: -> AT
(2.286583) [toit] INFO: -> AT
(2.547904) [toit] INFO: -> AT
(2.816407) [toit] INFO: -> AT
(3.077961) [toit] INFO: -> AT
(3.338644) [toit] INFO: -> AT
(3.599401) [toit] INFO: -> AT
(3.606691) [toit] INFO: <- OK
(3.711024) [toit] INFO: -> ATE0
(3.715274) [toit] INFO: <- OK
[gc @ 0x3ffcfa58* | soft limit reached (45084 >= 43050)]
[gc @ 0x3ffcfa58* | byte array allocation failed, length = 8 (heap)]
[gc @ 0x3ffcfa58* | heap: 44kb -> 24kb | external: 0kb -> 0kb | free: 10kb->10kb 2.297ms]
(3.738027) [toit] INFO: -> AT+CMEE=2
(3.742365) [toit] INFO: <- OK
(3.765070) [toit] INFO: -> AT+CGMM
(3.770010) [toit] INFO: <- FiPy
(3.773161) [toit] INFO: <- OK
(3.795414) [toit] INFO: -> AT+CGMR
(3.868488) [toit] INFO: <- UE5.0.0.0d
(3.871186) [toit] INFO: <- OK
(3.893398) [toit] INFO: -> AT+SQNCCID?
(3.898710) [toit] INFO: <- +SQNCCID: "89882390000069114786",""
(3.901149) [toit] INFO: <- OK
(3.903860) [toit.cellular] DEBUG: initialized {model: FiPy, version: UE5.0.0.0d, iccid: 89882390000069114786}
(3.924051) [toit] INFO: -> AT+CFUN=4
(4.216480) [toit] INFO: <- OK
(4.239096) [toit] INFO: -> AT+CPIN?
(4.243326) [toit] INFO: <- +CME ERROR: SIM not inserted
(4.260609) [toit] INFO: <- *ignored* [URC] +SYSSTART []
(4.498099) [toit] INFO: -> AT+CPIN?
(4.502548) [toit] INFO: <- +CME ERROR: SIM not inserted
(4.757701) [toit] INFO: -> AT+CPIN?
(4.764507) [toit] INFO: <- +CME ERROR: SIM not inserted
(5.019229) [toit] INFO: -> AT+CPIN?
(5.363414) [toit] INFO: <- +CPIN: READY
(5.365667) [toit] INFO: <- OK
(5.388310) [toit] INFO: -> AT+CPSMS=0
(5.438823) [toit] INFO: <- OK
(5.461963) [toit] INFO: -> AT+CEDRXS=0
(5.466448) [toit] INFO: <- OK
(5.489373) [toit] INFO: -> AT+SQNIBRCFG=0
(5.581967) [toit] INFO: <- OK
(5.605135) [toit] INFO: -> AT+SQNIPSCFG=1,100
(6.113728) [toit] INFO: <- OK
(6.136636) [toit] INFO: -> AT+SQNBANDSEL=0,"standard","20"
(6.141417) [toit] INFO: <- +CME ERROR: operation not supported
(6.164080) [toit] INFO: -> AT+SQNBANDSEL=1,"standard","20"
(6.168514) [toit] INFO: <- +CME ERROR: operation not supported
(6.190824) [toit] INFO: -> AT+CGDCONT?
(6.195901) [toit] INFO: <- +CGDCONT: 1,"IP","internet",,,,0,0,0,0,0,0
(6.198596) [toit] INFO: <- OK
(6.221179) [toit] INFO: -> AT+CFUN=1
(6.330398) [toit] INFO: <- OK