1637845248.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/637697839/otadata870898882 0x10000 /tmp/637697839/ota_0743187257"
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 23461.8 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.3 seconds (effective 793.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         |
| model.cellular.tx      | 5              |
| model.cellular.pwr     | 27             |
| model.cellular.monarch | 1              |
| cellular.apn           | internet       |
| model.cellular.enabled | 1              |
| broker.host            | device.toit.io |
| broker.cn              | device.toit.io |
| broker.port            | 9426           |
| model                  | esp32-4mb      |
| model.cellular.rx      | 23             |
| model.cellular.rts     | 19             |
| model.cellular.cts     | 18             |
+------------------------+----------------+


$ 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.574819) [toit.state] INFO: starting new session {session_id: 1, session_time: 0.581955}
[gc @ 0x3ffcf9f0* | soft limit reached (20480 >= 18432)]
[gc @ 0x3ffcf9f0* | heap: 20kb -> 8kb | external: 0kb -> 0kb | free: 9kb->9kb 1.154ms]
(0.664954) [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.767346) [toit.console_sync] INFO: loaded last console sync {time: null}
(0.779303) [toit.scheduler] INFO: running job {job: console_sync}
(0.782201) [toit.scheduler] INFO: running job {job: flush_metrics}
(0.784380) [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.789300) [toit.scheduler] INFO: running job {job: poll_metric_memory_profile}
(0.818153) [toit.console] INFO: waiting for console connection to be established
(0.822525) [toit.network] DEBUG: connecting {network: Cellular (internet)}
(0.829832) [toit.metrics] INFO: persisting metrics
(0.832971) [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.178996) [toit] INFO: -> AT
(1.440372) [toit] INFO: -> AT
(1.700962) [toit] INFO: -> AT
(1.961659) [toit] INFO: -> AT
(2.222440) [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.311ms]
(2.483122) [toit] INFO: -> AT
(2.743977) [toit] INFO: -> AT
(3.004581) [toit] INFO: -> AT
(3.265362) [toit] INFO: -> AT
(3.526083) [toit] INFO: -> AT
(3.787815) [toit] INFO: -> AT
(4.048630) [toit] INFO: -> AT
(4.309325) [toit] INFO: -> AT
(4.314280) [toit] INFO: <- OK
(4.418592) [toit] INFO: -> ATE0
(4.422800) [toit] INFO: <- OK
(4.445713) [toit] INFO: -> AT+CMEE=2
(4.450049) [toit] INFO: <- OK
(4.472460) [toit] INFO: -> AT+CGMM
(4.477026) [toit] INFO: <- FiPy
(4.479936) [toit] INFO: <- OK
(4.502084) [toit] INFO: -> AT+CGMR
(4.575975) [toit] INFO: <- UE5.0.0.0d
(4.578949) [toit] INFO: <- OK
(4.601174) [toit] INFO: -> AT+SQNCCID?
(4.606645) [toit] INFO: <- +SQNCCID: "89882390000069114786",""
(4.608915) [toit] INFO: <- OK
(4.611399) [toit.cellular] DEBUG: initialized {model: FiPy, version: UE5.0.0.0d, iccid: 89882390000069114786}
(4.631521) [toit] INFO: -> AT+CFUN=4
(4.924104) [toit] INFO: <- OK
(4.937186) [toit] INFO: <- *ignored* [URC] +SYSSTART []
(4.965161) [toit] INFO: -> AT+CPIN?
(4.969437) [toit] INFO: <- +CME ERROR: SIM not inserted
(5.224493) [toit] INFO: -> AT+CPIN?
(5.228769) [toit] INFO: <- +CME ERROR: SIM not inserted
(5.483268) [toit] INFO: -> AT+CPIN?
(5.487562) [toit] INFO: <- +CME ERROR: SIM not inserted
(5.742051) [toit] INFO: -> AT+CPIN?
(6.071000) [toit] INFO: <- +CPIN: READY
(6.073675) [toit] INFO: <- OK
(6.096269) [toit] INFO: -> AT+CPSMS=0
(6.146889) [toit] INFO: <- OK
(6.169803) [toit] INFO: -> AT+CEDRXS=0
(6.174319) [toit] INFO: <- OK
(6.197944) [toit] INFO: -> AT+SQNIBRCFG=0
(6.290692) [toit] INFO: <- OK
(6.313687) [toit] INFO: -> AT+SQNIPSCFG=1,100
(6.538638) [toit] INFO: <- OK
(6.561547) [toit] INFO: -> AT+SQNBANDSEL=0,"standard","20"
(6.567358) [toit] INFO: <- +CME ERROR: operation not supported
(6.590052) [toit] INFO: -> AT+SQNBANDSEL=1,"standard","20"
(6.595089) [toit] INFO: <- +CME ERROR: operation not supported
(6.617624) [toit] INFO: -> AT+CGDCONT?
(6.622714) [toit] INFO: <- +CGDCONT: 1,"IP","internet",,,,0,0,0,0,0,0
(6.625352) [toit] INFO: <- OK
(6.647863) [toit] INFO: -> AT+CFUN=1
(7.012952) [toit] INFO: <- OK
(10.776416) [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.309ms]
(10.779994) [toit.metrics] INFO: persisting metrics
(11.176921) [toit.scheduler] INFO: running job {job: poll_metric_memory_profile}
(11.632452) [toit] INFO: -> AT+CFUN=4
(12.389839) [toit] INFO: <- OK
(12.392638) [toit] INFO: <- +CEREG: 0
(12.394628) [toit] INFO: <- *ignored* [URC] +CEREG [0]
(12.396619) [toit.cellular] DEBUG: failed, detach from network
(12.410922) [toit.network] DEBUG: error connecting {network: Cellular (internet), error: DEADLINE_EXCEEDED}
(12.418340) [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.516476) [toit] INFO: -> AT+CFUN=0
(12.523414) [toit.scheduler] INFO: changing run level {run_level: 0}
(12.527068) [toit.scheduler] INFO: going into deep sleep {ms: 4985}
(12.530483) [toit.metrics] INFO: persisting metrics
(12.724978) [toit] INFO: <- OK
(12.832912) [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.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.052768) [toit.state] INFO: resumed session {session_id: 1, session_time: 17.948856}
[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.110064) [toit.console_sync] INFO: loaded last console sync {time: 12.508765}
(0.126017) [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.129019) [toit.scheduler] INFO: running job {job: poll_metric_flash_usage}
(0.143022) [toit.scheduler] INFO: running job {job: poll_metric_memory_profile}
(0.152913) [toit.console] INFO: waiting for console connection to be established
(0.157115) [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.916ms]
(0.199416) [toit] INFO: -> AT
(0.460809) [toit] INFO: -> AT
(0.721383) [toit] INFO: -> AT
(0.982081) [toit] INFO: -> AT
(1.242912) [toit] INFO: -> AT
(1.503628) [toit] INFO: -> AT
(1.764336) [toit] INFO: -> AT
(2.025131) [toit] INFO: -> AT
(2.285819) [toit] INFO: -> AT
(2.546559) [toit] INFO: -> AT
(2.816415) [toit] INFO: -> AT
(3.077961) [toit] INFO: -> AT
(3.338644) [toit] INFO: -> AT
(3.599401) [toit] INFO: -> AT
(3.606707) [toit] INFO: <- OK
(3.711041) [toit] INFO: -> ATE0
(3.715308) [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.299ms]
(3.738044) [toit] INFO: -> AT+CMEE=2
(3.742385) [toit] INFO: <- OK
(3.764865) [toit] INFO: -> AT+CGMM
(3.769273) [toit] INFO: <- FiPy
(3.772223) [toit] INFO: <- OK
(3.794439) [toit] INFO: -> AT+CGMR
(3.867912) [toit] INFO: <- UE5.0.0.0d
(3.870605) [toit] INFO: <- OK
(3.892818) [toit] INFO: -> AT+SQNCCID?
(3.898260) [toit] INFO: <- +SQNCCID: "89882390000069114786",""
(3.900597) [toit] INFO: <- OK
(3.903118) [toit.cellular] DEBUG: initialized {model: FiPy, version: UE5.0.0.0d, iccid: 89882390000069114786}
(3.923384) [toit] INFO: -> AT+CFUN=4
(4.217557) [toit] INFO: <- OK
(4.240366) [toit] INFO: -> AT+CPIN?
(4.244687) [toit] INFO: <- +CME ERROR: SIM not inserted
(4.320160) [toit] INFO: <- *ignored* [URC] +SYSSTART []
(4.499426) [toit] INFO: -> AT+CPIN?
(4.504094) [toit] INFO: <- +CME ERROR: SIM not inserted
(4.758949) [toit] INFO: -> AT+CPIN?
(4.763768) [toit] INFO: <- +CME ERROR: SIM not inserted
(5.018367) [toit] INFO: -> AT+CPIN?
(5.362312) [toit] INFO: <- +CPIN: READY
(5.364908) [toit] INFO: <- OK
(5.387641) [toit] INFO: -> AT+CPSMS=0
(5.438351) [toit] INFO: <- OK
(5.461266) [toit] INFO: -> AT+CEDRXS=0
(5.465717) [toit] INFO: <- OK
(5.488842) [toit] INFO: -> AT+SQNIBRCFG=0
(5.581453) [toit] INFO: <- OK
(5.604630) [toit] INFO: -> AT+SQNIPSCFG=1,100
(6.051971) [toit] INFO: <- OK
(6.074967) [toit] INFO: -> AT+SQNBANDSEL=0,"standard","20"
(6.079708) [toit] INFO: <- +CME ERROR: operation not supported
(6.102406) [toit] INFO: -> AT+SQNBANDSEL=1,"standard","20"
(6.106903) [toit] INFO: <- +CME ERROR: operation not supported
(6.129164) [toit] INFO: -> AT+CGDCONT?
(6.134338) [toit] INFO: <- +CGDCONT: 1,"IP","internet",,,,0,0,0,0,0,0
(6.137286) [toit] INFO: <- OK
(6.159737) [toit] INFO: -> AT+CFUN=1
(6.269021) [toit] INFO: <- OK
(10.138105) [toit.scheduler] INFO: running job {job: persist_metrics}
(10.141677) [toit.metrics] INFO: persisting metrics
(10.213601) [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.232ms]
(11.144017) [toit] INFO: -> AT+CFUN=4
(11.756967) [toit] INFO: <- OK
(11.759901) [toit] INFO: <- +CEREG: 0
(11.761878) [toit] INFO: <- *ignored* [URC] +CEREG [0]
(11.763850) [toit.cellular] DEBUG: failed, detach from network
(11.780322) [toit.network] DEBUG: error connecting {network: Cellular (internet), error: DEADLINE_EXCEEDED}
(11.787656) [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 -> 24kb | external: 0kb -> 0kb | free: 10kb->10kb 11.097ms]
(11.906850) [toit] INFO: -> AT+CFUN=0
(11.913635) [toit.scheduler] INFO: changing run level {run_level: 0}
(11.917386) [toit.scheduler] INFO: going into deep sleep {ms: 4976}
(11.919441) [toit.metrics] INFO: persisting metrics
(12.105002) [toit] INFO: <- OK
(12.213029) [toit] INFO: entering deep sleep {ms: 4976}
Entering deep sleep for 4976ms
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.052587) [toit.state] INFO: resumed session {session_id: 1, session_time: 35.188103}
[gc @ 0x3ffcfa58* | soft limit reached (20480 >= 18432)]
[gc @ 0x3ffcfa58* | heap: 20kb -> 8kb | external: 0kb -> 0kb | free: 9kb->9kb 1.060ms]
(0.069655) [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.110140) [toit.console_sync] INFO: loaded last console sync {time: 29.758900}
(0.126199) [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.136446) [toit.scheduler] INFO: running job {job: poll_metric_flash_usage}
(0.143188) [toit.scheduler] INFO: running job {job: poll_metric_memory_profile}
(0.153103) [toit.console] INFO: waiting for console connection to be established
(0.157295) [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.200296) [toit] INFO: -> AT
(0.461691) [toit] INFO: -> AT
(0.722266) [toit] INFO: -> AT
(0.982964) [toit] INFO: -> AT
(1.243984) [toit] INFO: -> AT
(1.504517) [toit] INFO: -> AT
(1.765224) [toit] INFO: -> AT
(2.026022) [toit] INFO: -> AT
(2.287693) [toit] INFO: -> AT
(2.548427) [toit] INFO: -> AT
(2.816399) [toit] INFO: -> AT
(3.077953) [toit] INFO: -> AT
(3.338636) [toit] INFO: -> AT
(3.343573) [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.262ms]
(3.447939) [toit] INFO: -> ATE0
(3.462843) [toit] INFO: <- OK
(3.485615) [toit] INFO: -> AT+CMEE=2
(3.489915) [toit] INFO: <- OK
(3.512560) [toit] INFO: -> AT+CGMM
(3.516848) [toit] INFO: <- FiPy
(3.519799) [toit] INFO: <- OK
(3.541974) [toit] INFO: -> AT+CGMR
(3.615455) [toit] INFO: <- UE5.0.0.0d
(3.618144) [toit] INFO: <- OK
(3.640344) [toit] INFO: -> AT+SQNCCID?
(3.645789) [toit] INFO: <- +SQNCCID: "89882390000069114786",""
(3.648060) [toit] INFO: <- OK
(3.650552) [toit.cellular] DEBUG: initialized {model: FiPy, version: UE5.0.0.0d, iccid: 89882390000069114786}
(3.670625) [toit] INFO: -> AT+CFUN=4
(4.711858) [toit] INFO: <- OK
(4.734699) [toit] INFO: -> AT+CPIN?
(4.739016) [toit] INFO: <- +CME ERROR: SIM not inserted
(4.755805) [toit] INFO: <- *ignored* [URC] +SYSSTART []
(4.993982) [toit] INFO: -> AT+CPIN?
(5.312036) [toit] INFO: <- +CPIN: READY
(5.314261) [toit] INFO: <- OK
(5.336896) [toit] INFO: -> AT+CPSMS=0
(5.387874) [toit] INFO: <- OK
(5.410813) [toit] INFO: -> AT+CEDRXS=0
(5.415323) [toit] INFO: <- OK
(5.438236) [toit] INFO: -> AT+SQNIBRCFG=0
(5.530845) [toit] INFO: <- OK
(5.554267) [toit] INFO: -> AT+SQNIPSCFG=1,100
(5.775070) [toit] INFO: <- OK
(5.806916) [toit] INFO: -> AT+SQNBANDSEL=0,"standard","20"
(5.812348) [toit] INFO: <- +CME ERROR: operation not supported
(5.834941) [toit] INFO: -> AT+SQNBANDSEL=1,"standard","20"
(5.839601) [toit] INFO: <- +CME ERROR: operation not supported
(5.861856) [toit] INFO: -> AT+CGDCONT?
(5.867128) [toit] INFO: <- +CGDCONT: 1,"IP","internet",,,,0,0,0,0,0,0
(5.869818) [toit] INFO: <- OK
(5.892594) [toit] INFO: -> AT+CFUN=1
(6.278859) [toit] INFO: <- OK
(10.144083) [toit.scheduler] INFO: running job {job: persist_metrics}
(10.148080) [toit.metrics] INFO: persisting metrics
(10.220746) [toit.scheduler] INFO: running job {job: poll_metric_memory_profile}
[gc @ 0x3ffcfa58* | soft limit reached (53276 >= 49194)]
[gc @ 0x3ffcfa58* | byte array allocation failed, length = 64 (heap)]
[gc @ 0x3ffcfa58* | heap: 52kb -> 24kb | external: 0kb -> 0kb | free: 10kb->10kb 2.207ms]
(10.885603) [toit] INFO: -> AT+CFUN=4
(11.809721) [toit] INFO: <- OK
(11.812905) [toit] INFO: <- +CEREG: 0
(11.814946) [toit] INFO: <- *ignored* [URC] +CEREG [0]
(11.816891) [toit.cellular] DEBUG: failed, detach from network
(11.830857) [toit.network] DEBUG: error connecting {network: Cellular (internet), error: DEADLINE_EXCEEDED}
(11.838334) [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.935292) [toit] INFO: -> AT+CFUN=0
(11.942060) [toit.scheduler] INFO: changing run level {run_level: 0}
(11.945987) [toit.scheduler] INFO: going into deep sleep {ms: 4989}
(11.948033) [toit.metrics] INFO: persisting metrics
(12.626406) [toit] INFO: <- OK
(12.734392) [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.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.052435) [toit.state] INFO: resumed session {session_id: 1, session_time: 52.950865}
[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: 47.030676}
(0.126400) [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.137011) [toit.scheduler] INFO: running job {job: poll_metric_flash_usage}
(0.143360) [toit.scheduler] INFO: running job {job: poll_metric_memory_profile}
(0.153309) [toit.console] INFO: waiting for console connection to be established
(0.157492) [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.201372) [toit] INFO: -> AT
(0.462765) [toit] INFO: -> AT
(0.723340) [toit] INFO: -> AT
(0.984038) [toit] INFO: -> AT
(1.244869) [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.505593) [toit] INFO: -> AT
(1.766440) [toit] INFO: -> AT
(2.027956) [toit] INFO: -> AT
(2.288648) [toit] INFO: -> AT
(2.549381) [toit] INFO: -> AT
(2.816402) [toit] INFO: -> AT
(3.077952) [toit] INFO: -> AT
(3.082996) [toit] INFO: <- OK
(3.187873) [toit] INFO: -> ATE0
(3.192265) [toit] INFO: <- OK
(3.214895) [toit] INFO: -> AT+CMEE=2
(3.219273) [toit] INFO: <- OK
(3.241745) [toit] INFO: -> AT+CGMM
(3.246269) [toit] INFO: <- FiPy
(3.249202) [toit] INFO: <- OK
(3.271360) [toit] INFO: -> AT+CGMR
(3.344700) [toit] INFO: <- UE5.0.0.0d
(3.347648) [toit] INFO: <- OK
(3.369886) [toit] INFO: -> AT+SQNCCID?
(3.375384) [toit] INFO: <- +SQNCCID: "89882390000069114786",""
(3.377632) [toit] INFO: <- OK
(3.380116) [toit.cellular] DEBUG: initialized {model: FiPy, version: UE5.0.0.0d, iccid: 89882390000069114786}
(3.406434) [toit] INFO: -> AT+CFUN=4
(3.699905) [toit] INFO: <- OK
(3.722749) [toit] INFO: -> AT+CPIN?
(3.727011) [toit] INFO: <- +CME ERROR: SIM not inserted
(3.734790) [toit] INFO: <- *ignored* [URC] +SYSSTART []
(3.981930) [toit] INFO: -> AT+CPIN?
(3.986488) [toit] INFO: <- +CME ERROR: SIM not inserted
(4.241031) [toit] INFO: -> AT+CPIN?
(4.245676) [toit] INFO: <- +CME ERROR: SIM not inserted
(4.500522) [toit] INFO: -> AT+CPIN?
(4.844361) [toit] INFO: <- +CPIN: READY
(4.847029) [toit] INFO: <- OK
(4.869492) [toit] INFO: -> AT+CPSMS=0
(4.919768) [toit] INFO: <- OK
(4.942609) [toit] INFO: -> AT+CEDRXS=0
(4.947087) [toit] INFO: <- OK
(4.969994) [toit] INFO: -> AT+SQNIBRCFG=0
(5.063023) [toit] INFO: <- OK
(5.085936) [toit] INFO: -> AT+SQNIPSCFG=1,100
(5.265619) [toit] INFO: <- OK
(5.288928) [toit] INFO: -> AT+SQNBANDSEL=0,"standard","20"
(5.293826) [toit] INFO: <- +CME ERROR: operation not supported
(5.316548) [toit] INFO: -> AT+SQNBANDSEL=1,"standard","20"
(5.321478) [toit] INFO: <- +CME ERROR: operation not supported
(5.343806) [toit] INFO: -> AT+CGDCONT?
(5.349042) [toit] INFO: <- +CGDCONT: 1,"IP","internet",,,,0,0,0,0,0,0
(5.351709) [toit] INFO: <- OK
(5.374207) [toit] INFO: -> AT+CFUN=1
(5.715798) [toit] INFO: <- OK
(10.138724) [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.238ms]
(10.148996) [toit.metrics] INFO: persisting metrics
(10.216352) [toit.scheduler] INFO: running job {job: poll_metric_memory_profile}
(10.359138) [toit] INFO: -> AT+CFUN=4
(10.591388) [toit] INFO: <- OK
(10.594569) [toit] INFO: <- +CEREG: 0
(10.596629) [toit] INFO: <- *ignored* [URC] +CEREG [0]
(10.598567) [toit.cellular] DEBUG: failed, detach from network
(10.611246) [toit.network] DEBUG: error connecting {network: Cellular (internet), error: DEADLINE_EXCEEDED}
(10.619277) [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.254ms]
(10.720051) [toit] INFO: -> AT+CFUN=0
(10.726776) [toit.scheduler] INFO: changing run level {run_level: 0}
(10.730461) [toit.scheduler] INFO: going into deep sleep {ms: 4985}
(10.732482) [toit.metrics] INFO: persisting metrics
(11.215950) [toit] INFO: <- OK
(11.323952) [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.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.052252) [toit.state] INFO: resumed session {session_id: 1, session_time: 69.310881}
[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.076094) [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.119972) [toit.console_sync] INFO: loaded last console sync {time: 63.584438}
(0.136242) [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.146858) [toit.scheduler] INFO: running job {job: poll_metric_flash_usage}
(0.153208) [toit.scheduler] INFO: running job {job: poll_metric_memory_profile}
(0.163239) [toit.console] INFO: waiting for console connection to be established
(0.167342) [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.212061) [toit] INFO: -> AT
(0.473438) [toit] INFO: -> AT
(0.734021) [toit] INFO: -> AT
(0.994719) [toit] INFO: -> AT
(1.255559) [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.261ms]
(1.516423) [toit] INFO: -> AT
(1.778009) [toit] INFO: -> AT
(2.038690) [toit] INFO: -> AT
(2.299396) [toit] INFO: -> AT
(2.560127) [toit] INFO: -> AT
(2.820875) [toit] INFO: -> AT
(3.081695) [toit] INFO: -> AT
(3.342388) [toit] INFO: -> AT
(3.347375) [toit] INFO: <- OK
(3.451725) [toit] INFO: -> ATE0
(3.455957) [toit] INFO: <- OK
(3.478613) [toit] INFO: -> AT+CMEE=2
(3.482725) [toit] INFO: <- OK
(3.505130) [toit] INFO: -> AT+CGMM
(3.509898) [toit] INFO: <- FiPy
(3.512853) [toit] INFO: <- OK
(3.534972) [toit] INFO: -> AT+CGMR
(3.607898) [toit] INFO: <- UE5.0.0.0d
(3.610835) [toit] INFO: <- OK
(3.633075) [toit] INFO: -> AT+SQNCCID?
(3.638526) [toit] INFO: <- +SQNCCID: "89882390000069114786",""
(3.640774) [toit] INFO: <- OK
(3.643262) [toit.cellular] DEBUG: initialized {model: FiPy, version: UE5.0.0.0d, iccid: 89882390000069114786}
(3.651029) [toit] INFO: <- *ignored* [URC] +SYSSTART []
(3.671700) [toit] INFO: -> AT+CFUN=4
(3.764106) [toit] INFO: <- OK
(3.786567) [toit] INFO: -> AT+CPIN?
(3.790600) [toit] INFO: <- +CME ERROR: SIM not inserted
(4.045475) [toit] INFO: -> AT+CPIN?
(4.049628) [toit] INFO: <- +CME ERROR: SIM not inserted
(4.304177) [toit] INFO: -> AT+CPIN?
(4.317800) [toit] INFO: <- +CME ERROR: SIM not inserted
(4.572280) [toit] INFO: -> AT+CPIN?
(4.916106) [toit] INFO: <- +CPIN: READY
(4.918771) [toit] INFO: <- OK
(4.941243) [toit] INFO: -> AT+CPSMS=0
(4.991697) [toit] INFO: <- OK
(5.014906) [toit] INFO: -> AT+CEDRXS=0
(5.019597) [toit] INFO: <- OK
(5.042496) [toit] INFO: -> AT+SQNIBRCFG=0
(5.135141) [toit] INFO: <- OK
(5.158226) [toit] INFO: -> AT+SQNIPSCFG=1,100
(5.345383) [toit] INFO: <- OK
(5.368441) [toit] INFO: -> AT+SQNBANDSEL=0,"standard","20"
(5.374320) [toit] INFO: <- +CME ERROR: operation not supported
(5.397004) [toit] INFO: -> AT+SQNBANDSEL=1,"standard","20"
(5.402076) [toit] INFO: <- +CME ERROR: operation not supported
(5.424299) [toit] INFO: -> AT+CGDCONT?
(5.429448) [toit] INFO: <- +CGDCONT: 1,"IP","internet",,,,0,0,0,0,0,0
(5.432099) [toit] INFO: <- OK
(5.454844) [toit] INFO: -> AT+CFUN=1
(5.821329) [toit] INFO: <- OK