Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

[Bug]: immediate resume after shutdown #3875

Open
roha-github opened this issue May 13, 2024 · 11 comments
Open

[Bug]: immediate resume after shutdown #3875

roha-github opened this issue May 13, 2024 · 11 comments
Labels
bug Something isn't working

Comments

@roha-github
Copy link

roha-github commented May 13, 2024

Category

Hardware Compatibility

Hardware

T-Lora v2 1.6

Firmware Version

2.3.9.f06c56a

Description

Device: T-LoRa V2.1-1.6
Firmware: 2.3.9.f06c56a Alpha

Error: immediate resume after shutdown
Expected: shutdown forever (until reset button pressed)

The device does not have a push button, only a reset button and power switch for the battery. The shutdown worked up to version 2.3.4. This issue also discussed at https://meshtastic.discourse.group/t/dodeepsleep-with-msectowake/12338/12

This error also affects SDS (super deep sleep) and the protection of the battery against deep discharge.

Relevant log output

ets Jun  8 2016 00:22:57

rst:0x1 (POWERON_RESET),boot:0x13 (SPI_FAST_FLASH_BOOT)
configsip: 188777542, 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:1184
load:0x40078000,len:13192
load:0x40080400,len:3028
entry 0x400805e4
E (823) esp_core_dump_flash: No core dump▒▒ѥѥ▒▒▒found!
E (824) esp_core_dump_flash: No core dump partition found!
[    12][D][esp32-hal-cpu.c:244] setCpuFrequencyMhz(): PLL: 480 / 2 = 240 Mhz, APB: 80000000 Hz
▒▒@INFO  | ??:??:?? 0

//\ E S H T /\ S T / C

INFO  | ??:??:?? 0 Booted, wake cause 0 (boot count 1), reset_reason=reset
DEBUG | ??:??:?? 0 Filesystem files (491520/1048576 Bytes):
DEBUG | ??:??:?? 0  /prefs/channels.proto (57 Bytes)
DEBUG | ??:??:?? 0  /prefs/config.proto (99 Bytes)
DEBUG | ??:??:?? 0  /prefs/db.proto (316 Bytes)
DEBUG | ??:??:?? 0  /prefs/module.proto (106 Bytes)
DEBUG | ??:??:?? 0  /static/.gitkeep (0 Bytes)
DEBUG | ??:??:?? 0  /static/Logo_Black.svg.gz (602 Bytes)
DEBUG | ??:??:?? 0  /static/Logo_White.svg.gz (610 Bytes)
DEBUG | ??:??:?? 0  /static/apple-touch-icon.png.gz (3164 Bytes)
DEBUG | ??:??:?? 0  /static/favicon.ico.gz (2270 Bytes)
DEBUG | ??:??:?? 0  /static/icon.svg.gz (852 Bytes)
DEBUG | ??:??:?? 0  /static/index-BMhLjTmL.css.gz (16270 Bytes)
DEBUG | ??:??:?? 0  /static/index-Bt2MYVVB.js.gz (203021 Bytes)
DEBUG | ??:??:?? 0  /static/index-CGqDWSD_.js.gz (348 Bytes)
DEBUG | ??:??:?? 0  /static/index.html.gz (548 Bytes)
DEBUG | ??:??:?? 0  /static/maplibre-gl-BXZZhNda.js.gz (210451 Bytes)
DEBUG | ??:??:?? 0  /static/robots.txt.gz (42 Bytes)
DEBUG | ??:??:?? 0  /static/site.webmanifest.gz (197 Bytes)
[   556][I][esp32-hal-i2c.c:75] i2cInit(): Initialising I2C Master: sda=21 scl=22 freq=100000
DEBUG | ??:??:?? 0 Using analog input 35 for battery level
INFO  | ??:??:?? 0 ADCmod: ADC characterization based on reference voltage stored in eFuse
INFO  | ??:??:?? 0 Scanning for i2c devices...
[   584][W][Wire.cpp:301] begin(): Bus already started in Master Mode.
DEBUG | ??:??:?? 0 Scanning for i2c devices on port 1
DEBUG | ??:??:?? 0 I2C device found at address 0x3c
INFO  | ??:??:?? 0 ssd1306 display found
INFO  | ??:??:?? 0 ssd1306 display found
DEBUG | ??:??:?? 0 0x7 subtype probed in 2 tries
INFO  | ??:??:?? 0 1 I2C devices found
DEBUG | ??:??:?? 0 acc_info = 0
INFO  | ??:??:?? 0 Meshtastic hwvendor=3, swver=2.3.9.f06c56a
DEBUG | ??:??:?? 0 Setting random seed 2107899965
DEBUG | ??:??:?? 0 Total heap: 197596
DEBUG | ??:??:?? 0 Free heap: 163068
DEBUG | ??:??:?? 0 Total PSRAM: 0
DEBUG | ??:??:?? 0 Free PSRAM: 0
DEBUG | ??:??:?? 0 NVS: UsedEntries 70, FreeEntries 560, AllEntries 630, NameSpaces 3
DEBUG | ??:??:?? 0 Setup Preferences in Flash Storage
DEBUG | ??:??:?? 0 Number of Device Reboots: 3
DEBUG | ??:??:?? 0 OTA firmware version 0.2.1.ceca52c
INFO  | ??:??:?? 0 Initializing NodeDB
INFO  | ??:??:?? 0 Loading /prefs/db.proto
INFO  | ??:??:?? 0 Loaded /prefs/db.proto successfully
INFO  | ??:??:?? 0 Loaded saved devicestate version 22, with nodecount: 1
INFO  | ??:??:?? 0 Loading /prefs/config.proto
INFO  | ??:??:?? 0 Loaded /prefs/config.proto successfully
INFO  | ??:??:?? 0 Loaded saved config version 22
INFO  | ??:??:?? 0 Loading /prefs/module.proto
INFO  | ??:??:?? 0 Loaded /prefs/module.proto successfully
INFO  | ??:??:?? 0 Loaded saved moduleConfig version 22
INFO  | ??:??:?? 0 Loading /prefs/channels.proto
INFO  | ??:??:?? 0 Loaded /prefs/channels.proto successfully
INFO  | ??:??:?? 0 Loaded saved channelFile version 22
[   997][E][vfs_api.cpp:105] open(): /littlefs/oem/oem.proto does not exist, no permits for creation
INFO  | ??:??:?? 1 File /oem/oem.proto not found
DEBUG | ??:??:?? 1 cleanupMeshDB purged 0 entries
DEBUG | ??:??:?? 1 Using nodenum 0x6d00f2f8
DEBUG | ??:??:?? 1 Number of Device Reboots: 3
DEBUG | ??:??:?? 1 Expanding short PSK #1
INFO  | ??:??:?? 1 Wanted region 3, using EU_868
INFO  | ??:??:?? 1 Saving /prefs/db.proto
DEBUG | ??:??:?? 1 Using GPIO12 for button
DEBUG | ??:??:?? 1 SPI.begin(SCK=5, MISO=19, MOSI=27, NSS=18)
DEBUG | ??:??:?? 1 Set Timezone to GMT0
DEBUG | ??:??:?? 1 Read RTC time as 1
DEBUG | ??:??:?? 1 NeighborInfoModule is disabled
INFO  | ??:??:?? 1 External Notification Module Disabled
INFO  | ??:??:?? 1 Turning on screen
DEBUG | ??:??:?? 1 Module wants a UI Frame
DEBUG | ??:??:?? 1 RF95Interface(cs=18, irq=26, rst=23, busy=33)
INFO  | ??:??:?? 1 Starting meshradio init...
DEBUG | ??:??:?? 1 (bw=250, sf=11, cr=4/5) packet symLen=8 ms, payloadSize=0, time 231 ms
DEBUG | ??:??:?? 1 (bw=250, sf=11, cr=4/5) packet symLen=8 ms, payloadSize=253, time 2115 ms
INFO  | ??:??:?? 1 Radio freq=869.525, config.lora.frequency_offset=0.000
INFO  | ??:??:?? 1 Set radio: region=EU_868, name=LongFast, config=0, ch=0, power=27
INFO  | ??:??:?? 1 Radio myRegion->freqStart -> myRegion->freqEnd: 869.400024 -> 869.650024 (0.250000 mhz)
INFO  | ??:??:?? 1 Radio myRegion->numChannels: 1 x 250.000kHz
INFO  | ??:??:?? 1 Radio channel_num: 1
INFO  | ??:??:?? 1 Radio frequency: 869.525024
INFO  | ??:??:?? 1 Slot time: 42 msec
INFO  | ??:??:?? 1 Set radio: final power level=20
DEBUG | ??:??:?? 1 Current limit set to 100.000000
DEBUG | ??:??:?? 1 Current limit set result 0
INFO  | ??:??:?? 1 RF95 init result 0
INFO  | ??:??:?? 1 Frequency set to 869.525024
INFO  | ??:??:?? 1 Bandwidth set to 250.000000
INFO  | ??:??:?? 1 Power output set to 20
INFO  | ??:??:?? 1 RF95 Radio init succeeded, using RF95 radio
INFO  | ??:??:?? 1 Not using WIFI
DEBUG | ??:??:?? 1 (bw=250, sf=11, cr=4/5) packet symLen=8 ms, payloadSize=237, time 2000 ms
DEBUG | ??:??:?? 1`▒oRA bitrate = 118.500000 bytes / sec
INFO  | ??:??:?? 1 PowerFSM init, USB power=1
DEBUG | ??:??:?? 1 Enter state: BOOT
[  1540][D][esp32-hal-cpu.c:244] setCpuFrequencyMhz(): PLL: 320 / 4 = 80 Mhz, APB: 80000000 Hz
DEBUG | ??:??:?? 1 [Power] Battery: usbPower=0, isCharging=0, batMv=3100, batPct=0
DEBUG | ??:??:?? 1 [Screen] Screen: Started...
INFO  | ??:??:?? 1 [RangeTestModule] Range Test Module - Disabled
INFO  | ??:??:?? 4 [PowerFSM] Loss of power in Powered
INFO  | ??:??:?? 4 [PowerFSM] Loss of power in Powered
INFO  | ??:??:?? 4 [PowerFSM] Initialise the NimBLE bluetooth module
DEBUG | ??:??:?? 5 [PowerFSM] Enter state: ON
INFO  | ??:??:?? 7 [Screen] Done with boot screen...
DEBUG | ??:??:?? 7 [Screen] showing standard frames
DEBUG | ??:??:?? 7 [Screen] Showing 0 module frames
DEBUG | ??:??:?? 7 [Screen] Total frame count: 103
DEBUG | ??:??:?? 7 [Screen] Added modules.  numframes: 0
DEBUG | ??:??:?? 7 [Screen] Finished building frames. numframes: 2
INFO  | ??:??:?? 18 Using random passkey
INFO  | ??:??:?? 18 *** Enter passkey 318460 on the peer side ***
DEBUG | ??:??:?? 18 Enter state: ON
DEBUG | ??:??:?? 18 [Screen] showing bluetooth screen
DEBUG | ??:??:?? 21 [Power] Battery: usbPower=1, isCharging=1, batMv=4474, batPct=100
INFO  | ??:??:?? 31 [NodeInfoModule] Sending our nodeinfo to mesh (wantReplies=1)
INFO  | ??:??:?? 31 [NodeInfoModule] sending owner !6d00f2f8/Meshtastic f2f8/f2f8
DEBUG | ??:??:?? 31 [NodeInfoModule] Initial packet id 393269091, numPacketId 4294967295
DEBUG | ??:??:?? 31 [NodeInfoModule] Update DB node 0x6d00f2f8, rx_time=0
DEBUG | ??:??:?? 31 [NodeInfoModule] handleReceived(LOCAL) (id=0x1770cf65 fr=0xf8 to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=4 WANTRESP priority=10)
DEBUG | ??:??:?? 31 [NodeInfoModule] No modules interested in portnum=4, src=LOCAL
DEBUG | ??:??:?? 31 [NodeInfoModule] localSend to channel 0
DEBUG | ??:??:?? 31 [NodeInfoModule] Add packet record (id=0x1770cf65 fr=0xf8 to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=4 WANTRESP priority=10)
DEBUG | ??:??:?? 31 [NodeInfoModule] Expanding short PSK #1
DEBUG | ??:??:?? 31 [NodeInfoModule] Using AES128 key!
DEBUG | ??:??:?? 31 [NodeInfoModule] ESP32 crypt fr=6d00f2f8, num=1770cf65, numBytes=50!
DEBUG | ??:??:?? 31 [NodeInfoModule] enqueuing for send (id=0x1770cf65 fr=0xf8 to=0xff, WantAck=0, HopLim=3 Ch=0x8 encrypted hopStart=3 priority=10)
DEBUG | ??:??:?? 31 [NodeInfoModule] txGood=0,rxGood=0,rxBad=0
DEBUG | ??:??:?? 31 [RadioIf] Starting low level send (id=0x1770cf65 fr=0xf8 to=0xff, WantAck=0, HopLim=3 Ch=0x8 encrypted hopStart=3 priority=10)
DEBUG | ??:??:?? 31 [RadioIf] (bw=250, sf=11, cr=4/5) packet symLen=8 ms, payloadSize=66, time 722 ms
DEBUG | ??:??:?? 31 [RadioIf] AirTime - Packet transmitted : 722ms
DEBUG | ??:??:?? 32 [RadioIf] Completed sending (id=0x1770cf65 fr=0xf8 to=0xff, WantAck=0, HopLim=3 Ch=0x8 encrypted hopStart=3 priority=10)
DEBUG | ??:??:?? 33 [RadioIf] (bw=250, sf=11, cr=4/5) packet symLen=8 ms, payloadSize=84, time 862 ms
DEBUG | ??:??:?? 33 [RadioIf] Lora RX (id=0x6fab914f fr=0xb0 to=0xf8, WantAck=0, HopLim=5 Ch=0x8 encrypted rxSNR=-3.25 rxRSSI=-124)
DEBUG | ??:??:?? 33 [RadioIf] AirTime - Packet received : 862ms
DEBUG | ??:??:?? 33 [Router] Add packet record (id=0x6fab914f fr=0xb0 to=0xf8, WantAck=0, HopLim=5 Ch=0x8 encrypted rxSNR=-3.25 rxRSSI=-124)
DEBUG | ??:??:?? 33 [Router] Using channel 0 (hash 0x8)
DEBUG | ??:??:?? 33 [Router] Expanding short PSK #1
DEBUG | ??:??:?? 33 [Router] Using AES128 key!
DEBUG | ??:??:?? 33 [Router] ESP32 crypt fr=e0d392b0, num=6fab914f, numBytes=68!
DEBUG | ??:??:?? 33 [Router] decoded message (id=0x6fab914f fr=0xb0 to=0xf8, WantAck=0, HopLim=5 Ch=0x0 Portnum=4 requestId=1770cf65 rxSNR=-3.25 rxRSSI=-124)
DEBUG | ??:??:?? 33 [Router] handleReceived(REMOTE) (id=0x6fab914f fr=0xb0 to=0xf8, WantAck=0, HopLim=5 Ch=0x0 Portnum=4 requestId=1770cf65 rxSNR=-3.25 rxRSSI=-124)
DEBUG | ??:??:?? 33 [Router] Module 'nodeinfo' wantsPacket=1
INFO  | ??:??:?? 33 [Router] Received nodeinfo from=0xe0d392b0, id=0x6fab914f, portnum=4, payloadlen=59
DEBUG | ??:??:?? 33 [Router] old user //, channel=0
DEBUG | ??:??:?? 33 [Router] updating changed=1 user !e0d392b0/bit.ly/mesh_dRH5#Robert@Home/dRH5, channel=0
DEBUG | ??:??:?? 33 [Router] Node status update: 2 online, 2 total
INFO  | ??:??:?? 33 [Router] Saving /prefs/db.proto
DEBUG | ??:??:?? 34 [Router] Module 'nodeinfo' considered
DEBUG | ??:??:?? 34 [Router] Module 'routing' wantsPacket=1
INFO  | ??:??:?? 34 [Router] Received routing from=0xe0d392b0, id=0x6fab914f, portnum=4, payloadlen=59
DEBUG | ??:??:?? 34 [Router] Routing sniffing (id=0x6fab914f fr=0xb0 to=0xf8, WantAck=0, HopLim=5 Ch=0x0 Portnum=4 requestId=1770cf65 rxSNR=-3.25 rxRSSI=-124)
DEBUG | ??:??:?? 34 [Router] Received an ack for 0x1770cf65, stopping retransmissions
DEBUG | ??:??:?? 34 [Router] Delivering rx packet (id=0x6fab914f fr=0xb0 to=0xf8, WantAck=0, HopLim=5 Ch=0x0 Portnum=4 requestId=1770cf65 rxSNR=-3.25 rxRSSI=-124)
DEBUG | ??:??:?? 34 [Router] Update DB node 0xe0d392b0, rx_time=0
DEBUG | ??:??:?? 34 [Router] Forwarding to phone (id=0x6fab914f fr=0xb0 to=0xf8, WantAck=0, HopLim=5 Ch=0x0 Portnum=4 requestId=1770cf65 rxSNR=-3.25 rxRSSI=-124)
DEBUG | ??:??:?? 34 [Router] Module 'routing' considered
DEBUG | ??:??:?? 34 [RadioIf] (bw=250, sf=11, cr=4/5) packet symLen=8 ms, payloadSize=66, time 722 ms
DEBUG | ??:??:?? 34 [RadioIf] Lora RX (id=0x1770cf65 fr=0xf8 to=0xff, WantAck=0, HopLim=2 Ch=0x8 encrypted rxSNR=0.75 rxRSSI=-118)
DEBUG | ??:??:?? 34 [RadioIf] AirTime - Packet received : 722ms
DEBUG | ??:??:?? 34 [Router] Rx someone rebroadcasting for us (id=0x1770cf65 fr=0xf8 to=0xff, WantAck=0, HopLim=2 Ch=0x8 encrypted rxSNR=0.75 rxRSSI=-118)
DEBUG | ??:??:?? 34 [Router] didn't find pending packet
DEBUG | ??:??:?? 34 [Router] Found existing packet record for fr=0x6d00f2f8,to=0xffffffff,id=0x1770cf65
DEBUG | ??:??:?? 34 [Router] Found existing packet record for fr=0x6d00f2f8,to=0xffffffff,id=0x1770cf65
DEBUG | ??:??:?? 34 [Router] Add packet record (id=0x1770cf65 fr=0xf8 to=0xff, WantAck=0, HopLim=2 Ch=0x8 encrypted rxSNR=0.75 rxRSSI=-118)
DEBUG | ??:??:?? 34 [Router] Ignoring incoming msg, because we've already seen it (id=0x1770cf65 fr=0xf8 to=0xff, WantAck=0, HopLim=2 Ch=0x8 encrypted rxSNR=0.75 rxRSSI=-118)
DEBUG | ??:??:?? 34 [Router] cancelSending id=0x1770cf65, removed=0
DEBUG | ??:??:?? 34 [Router] Incoming message was filtered 0x6d00f2f8
INFO  | ??:??:?? 34 BLE authentication complete
DEBUG | ??:??:?? 34 [Screen] showing standard frames
DEBUG | ??:??:?? 34 [Screen] Showing 0 module frames
DEBUG | ??:??:?? 34 [Screen] Total frame count: 103
DEBUG | ??:??:?? 34 [Screen] Added modules.  numframes: 0
DEBUG | ??:??:?? 34 [Screen] Finished building frames. numframes: 3
INFO  | ??:??:?? 35 From Radio onread
INFO  | ??:??:?? 35 To Radio onwrite
INFO  | ??:??:?? 35 Client wants config, nonce=2
INFO  | ??:??:?? 35 Starting API client config
INFO  | ??:??:?? 36 From Radio onread
INFO  | ??:??:?? 36 getFromRadio=STATE_SEND_MY_INFO
DEBUG | ??:??:?? 36 encoding toPhone packet to phone variant=3, 14 bytes
INFO  | ??:??:?? 36 From Radio onread
INFO  | ??:??:?? 36 getFromRadio=STATE_SEND_METADATA
DEBUG | ??:??:?? 36 encoding toPhone packet to phone variant=13, 30 bytes
INFO  | ??:??:?? 36 From Radio onread
INFO  | ??:??:?? 36 getFromRadio=STATE_SEND_NODEINFO
INFO  | ??:??:?? 36 nodeinfo: num=0x6d00f2f8, lastseen=0, id=!6d00f2f8, name=Meshtastic f2f8
DEBUG | ??:??:?? 36 encoding toPhone packet to phone variant=4, 58 bytes
INFO  | ??:??:?? 36 From Radio onread
INFO  | ??:??:?? 36 getFromRadio=STATE_SEND_NODEINFO
INFO  | ??:??:?? 36 nodeinfo: num=0xe0d392b0, lastseen=0, id=!e0d392b0, name=bit.ly/mesh_dRH5#Robert@Home
DEBUG | ??:??:?? 36 encoding toPhone packet to phone variant=4, 74 bytes
INFO  | ??:??:?? 36 From Radio onread
INFO  | ??:??:?? 36 getFromRadio=STATE_SEND_NODEINFO
INFO  | ??:??:?? 36 Done sending nodeinfos
INFO  | ??:??:?? 36 getFromRadio=STATE_SEND_CHANNELS
DEBUG | ??:??:?? 36 encoding toPhone packet to phone variant=10, 13 bytes
INFO  | ??:??:?? 36 From Radio onread
INFO  | ??:??:?? 36 getFromRadio=STATE_SEND_CHANNELS
DEBUG | ??:??:?? 36 encoding toPhone packet to phone variant=10, 6 bytes
INFO  | ??:??:?? 36 From Radio onread
INFO  | ??:??:?? 36 getFromRadio=STATE_SEND_CHANNELS
DEBUG | ??:??:?? 36 encoding toPhone packet to phone variant=10, 6 bytes
INFO  | ??:??:?? 36 From Radio onread
INFO  | ??:??:?? 36 getFromRadio=STATE_SEND_CHANNELS
DEBUG | ??:??:?? 36 encoding toPhone packet to phone variant=10, 6 bytes
INFO  | ??:??:?? 37 From Radio onread
INFO  | ??:??:?? 37 getFromRadio=STATE_SEND_CHANNELS
DEBUG | ??:??:?? 37 encoding toPhone packet to phone variant=10, 6 bytes
INFO  | ??:??:?? 37 From Radio onread
INFO  | ??:??:?? 37 getFromRadio=STATE_SEND_CHANNELS
DEBUG | ??:??:?? 37 encoding toPhone packet to phone variant=10, 6 bytes
INFO  | ??:??:?? 37 From Radio onread
INFO  | ??:??:?? 37 getFromRadio=STATE_SEND_CHANNELS
DEBUG | ??:??:?? 37 encoding toPhone packet to phone variant=10, 6 bytes
INFO  | ??:??:?? 37 From Radio onread
INFO  | ??:??:?? 37 getFromRadio=STATE_SEND_CHANNELS
DEBUG | ??:??:?? 37 encoding toPhone packet to phone variant=10, 6 bytes
INFO  | ??:??:?? 37 From Radio onread
INFO  | ??:??:?? 37 getFromRadio=STATE_SEND_CONFIG
DEBUG | ??:??:?? 37 encoding toPhone packet to phone variant=5, 9 bytes
INFO  | ??:??:?? 38 From Radio onread
INFO  | ??:??:?? 38 getFromRadio=STATE_SEND_CONFIG
DEBUG | ??:??:?? 38 encoding toPhone packet to phone variant=5, 20 bytes
INFO  | ??:??:?? 38 From Radio onread
INFO  | ??:??:?? 38 getFromRadio=STATE_SEND_CONFIG
DEBUG | ??:??:?? 38 encoding toPhone packet to phone variant=5, 17 bytes
INFO  | ??:??:?? 38 From Radio onread
INFO  | ??:??:?? 38 getFromRadio=STATE_SEND_CONFIG
DEBUG | ??:??:?? 38 encoding toPhone packet to phone variant=5, 29 bytes
INFO  | ??:??:?? 38 From Radio onread
INFO  | ??:??:?? 38 getFromRadio=STATE_SEND_CONFIG
DEBUG | ??:??:?? 38 encoding toPhone packet to phone variant=5, 7 bytes
INFO  | ??:??:?? 38 From Radio onread
INFO  | ??:??:?? 38 getFromRadio=STATE_SEND_CONFIG
DEBUG | ??:??:?? 38 encoding toPhone packet to phone variant=5, 19 bytes
INFO  | ??:??:?? 39 From Radio onread
INFO  | ??:??:?? 39 getFromRadio=STATE_SEND_CONFIG
DEBUG | ??:??:?? 39 encoding toPhone packet to phone variant=5, 10 bytes
INFO  | ??:??:?? 39 From Radio onread
INFO  | ??:??:?? 39 getFromRadio=STATE_SEND_MODULECONFIG
DEBUG | ??:??:?? 39 encoding toPhone packet to phone variant=9, 60 bytes
INFO  | ??:??:?? 39 From Radio onread
INFO  | ??:??:?? 39 getFromRadio=STATE_SEND_MODULECONFIG
DEBUG | ??:??:?? 39 encoding toPhone packet to phone variant=9, 4 bytes
INFO  | ??:??:?? 39 From Radio onread
INFO  | ??:??:?? 39 getFromRadio=STATE_SEND_MODULECONFIG
DEBUG | ??:??:?? 39 encoding toPhone packet to phone variant=9, 4 bytes
INFO  | ??:??:?? 39 From Radio onread
INFO  | ??:??:?? 39 getFromRadio=STATE_SEND_MODULECONFIG
DEBUG | ??:??:?? 39 encoding toPhone packet to phone variant=9, 4 bytes
INFO  | ??:??:?? 39 From Radio onread
INFO  | ??:??:?? 39 getFromRadio=STATE_SEND_MODULECONFIG
DEBUG | ??:??:?? 39 encoding toPhone packet to phone variant=9, 4 bytes
INFO  | ??:??:?? 39 From Radio onread
INFO  | ??:??:?? 39 getFromRadio=STATE_SEND_MODULECONFIG
DEBUG | ??:??:?? 39 encoding toPhone packet to phone variant=9, 13 bytes
INFO  | ??:??:?? 39 From Radio onread
INFO  | ??:??:?? 39 getFromRadio=STATE_SEND_MODULECONFIG
DEBUG | ??:??:?? 39 encoding toPhone packet to phone variant=9, 4 bytes
INFO  | ??:??:?? 39 [Screen] From Radio onread
INFO  | ??:??:?? 39 [Screen] getFromRadio=STATE_SEND_MODULECONFIG
DEBUG | ??:??:?? 39 [Screen] encoding toPhone packet to phone variant=9, 4 bytes
INFO  | ??:??:?? 39 From Radio onread
INFO  | ??:??:?? 39 getFromRadio=STATE_SEND_MODULECONFIG
DEBUG | ??:??:?? 39 encoding toPhone packet to phone variant=9, 4 bytes
INFO  | ??:??:?? 40 From Radio onread
INFO  | ??:??:?? 40 getFromRadio=STATE_SEND_MODULECONFIG
DEBUG | ??:??:?? 40 encoding toPhone packet to phone variant=9, 7 bytes
INFO  | ??:??:?? 40 [SerialConsole] From Radio onread
INFO  | ??:??:?? 40 getFromRadio=STATE_SEND_MODULECONFIG
DEBUG | ??:??:?? 40 encoding toPhone packet to phone variant=9, 12 bytes
INFO  | ??:??:?? 40 From Radio onread
INFO  | ??:??:?? 40 getFromRadio=STATE_SEND_MODULECONFIG
DEBUG | ??:??:?? 40 encoding toPhone packet to phone variant=9, 8 bytes
INFO  | ??:??:?? 40 From Radio onread
INFO  | ??:??:?? 40 getFromRadio=STATE_SEND_MODULECONFIG
DEBUG | ??:??:?? 40 encoding toPhone packet to phone variant=9, 4 bytes
INFO  | ??:??:?? 40 From Radio onread
INFO  | ??:??:?? 40 getFromRadio=STATE_SEND_COMPLETE_ID
DEBUG | ??:??:?? 40 encoding toPhone packet to phone variant=7, 2 bytes
INFO  | ??:??:?? 40 From Radio onread
INFO  | ??:??:?? 40 getFromRadio=STATE_SEND_PACKETS
DEBUG | ??:??:?? 40 encoding toPhone packet to phone variant=11, 6 bytes
INFO  | ??:??:?? 40 From Radio onread
INFO  | ??:??:?? 40 getFromRadio=STATE_SEND_PACKETS
DEBUG | ??:??:?? 40 encoding toPhone packet to phone variant=11, 12 bytes
INFO  | ??:??:?? 40 From Radio onread
INFO  | ??:??:?? 40 getFromRadio=STATE_SEND_PACKETS
DEBUG | ??:??:?? 40 phone downloaded packet (id=0x6fab914f fr=0xb0 to=0xf8, WantAck=0, HopLim=5 Ch=0x0 Portnum=4 requestId=1770cf65 rxSNR=-3.25 rxRSSI=-124)
DEBUG | ??:??:?? 40 encoding toPhone packet to phone variant=2, 105 bytes
INFO  | ??:??:?? 40 From Radio onread
DEBUG | ??:??:?? 41 [Power] Battery: usbPower=1, isCharging=1, batMv=4340, batPct=100
INFO  | ??:??:?? 46 [DeviceTelemetryModule] (Sending): air_util_tx=0.020056, channel_utilization=3.843333, battery_level=101, voltage=4.340000, uptime=46
DEBUG | ??:??:?? 46 [DeviceTelemetryModule] updateTelemetry LOCAL
DEBUG | ??:??:?? 46 [DeviceTelemetryModule] Node status update: 2 online, 2 total
INFO  | ??:??:?? 46 [DeviceTelemetryModule] Sending packet to mesh
DEBUG | ??:??:?? 46 [DeviceTelemetryModule] Update DB node 0x6d00f2f8, rx_time=0
DEBUG | ??:??:?? 46 [DeviceTelemetryModule] handleReceived(LOCAL) (id=0x1770cf66 fr=0xf8 to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=67 priority=10)
DEBUG | ??:??:?? 46 [DeviceTelemetryModule] No modules interested in portnum=67, src=LOCAL
DEBUG | ??:??:?? 46 [DeviceTelemetryModule] localSend to channel 0
DEBUG | ??:??:?? 46 [DeviceTelemetryModule] Add packet record (id=0x1770cf66 fr=0xf8 to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=67 priority=10)
DEBUG | ??:??:?? 46 [DeviceTelemetryModule] Expanding short PSK #1
DEBUG | ??:??:?? 46 [DeviceTelemetryModule] Using AES128 key!
DEBUG | ??:??:?? 46 [DeviceTelemetryModule] ESP32 crypt fr=6d00f2f8, num=1770cf66, numBytes=30!
DEBUG | ??:??:?? 46 [DeviceTelemetryModule] enqueuing for send (id=0x1770cf66 fr=0xf8 to=0xff, WantAck=0, HopLim=3 Ch=0x8 encrypted hopStart=3 priority=10)
DEBUG | ??:??:?? 46 [DeviceTelemetryModule] txGood=1,rxGood=2,rxBad=0
DEBUG | ??:??:?? 46 [DeviceTelemetryModule] Using channel 0 (hash 0x8)
DEBUG | ??:??:?? 46 [DeviceTelemetryModule] Expanding short PSK #1
DEBUG | ??:??:?? 46 [DeviceTelemetryModule] Using AES128 key!
DEBUG | ??:??:?? 46 [DeviceTelemetryModule] ESP32 crypt fr=6d00f2f8, num=1770cf66, numBytes=30!
DEBUG | ??:??:?? 46 [DeviceTelemetryModule] decoded message (id=0x1770cf66 fr=0xf8 to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=67 hopStart=3 priority=10)
INFO  | ??:??:?? 46 Telling client we have new packets 5
INFO  | ??:??:?? 46 BLE notify fromNum
DEBUG | ??:??:?? 46 [RadioIf] Starting low level send (id=0x1770cf66 fr=0xf8 to=0xff, WantAck=0, HopLim=3 Ch=0x8 encrypted hopStart=3 priority=10)
DEBUG | ??:??:?? 46 [RadioIf] (bw=250, sf=11, cr=4/5) packet symLen=8 ms, payloadSize=46, time 575 ms
DEBUG | ??:??:?? 46 [RadioIf] AirTime - Packet transmitted : 575ms
INFO  | ??:??:?? 46 From Radio onread
INFO  | ??:??:?? 46 getFromRadio=STATE_SEND_PACKETS
DEBUG | ??:??:?? 46 encoding toPhone packet to phone variant=11, 12 bytes
INFO  | ??:??:?? 47 From Radio onread
INFO  | ??:??:?? 47 getFromRadio=STATE_SEND_PACKETS
DEBUG | ??:??:?? 47 phone downloaded packet (id=0x1770cf66 fr=0xf8 to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=67 hopStart=3 priority=10)
DEBUG | ??:??:?? 47 encoding toPhone packet to phone variant=2, 55 bytes
INFO  | ??:??:?? 47 From Radio onread
DEBUG | ??:??:?? 47 [RadioIf] Completed sending (id=0x1770cf66 fr=0xf8 to=0xff, WantAck=0, HopLim=3 Ch=0x8 encrypted hopStart=3 priority=10)
DEBUG | ??:??:?? 48 [RadioIf] (bw=250, sf=11, cr=4/5) packet symLen=8 ms, payloadSize=46, time 575 ms
DEBUG | ??:??:?? 48 [RadioIf] Lora RX (id=0x1770cf66 fr=0xf8 to=0xff, WantAck=0, HopLim=2 Ch=0x8 encrypted rxSNR=3.75 rxRSSI=-116)
DEBUG | ??:??:?? 48 [RadioIf] AirTime - Packet received : 575ms
DEBUG | ??:??:?? 48 [Router] Rx someone rebroadcasting for us (id=0x1770cf66 fr=0xf8 to=0xff, WantAck=0, HopLim=2 Ch=0x8 encrypted rxSNR=3.75 rxRSSI=-116)
DEBUG | ??:??:?? 48 [Router] didn't find pending packet
DEBUG | ??:??:?? 48 [Router] Found existing packet record for fr=0x6d00f2f8,to=0xffffffff,id=0x1770cf66
DEBUG | ??:??:?? 48 [Router] Found existing packet record for fr=0x6d00f2f8,to=0xffffffff,id=0x1770cf66
DEBUG | ??:??:?? 48 [Router] Add packet record (id=0x1770cf66 fr=0xf8 to=0xff, WantAck=0, HopLim=2 Ch=0x8 encrypted rxSNR=3.75 rxRSSI=-116)
DEBUG | ??:??:?? 48 [Router] Ignoring incoming msg, because we've already seen it (id=0x1770cf66 fr=0xf8 to=0xff, WantAck=0, HopLim=2 Ch=0x8 encrypted rxSNR=3.75 rxRSSI=-116)
DEBUG | ??:??:?? 48 [Router] cancelSending id=0x1770cf66, removed=0
DEBUG | ??:??:?? 48 [Router] Incoming message was filtered 0x6d00f2f8
INFO  | ??:??:?? 52 To Radio onwrite
DEBUG | ??:??:?? 52 PACKET FROM PHONE (id=0xd3e8f1fb fr=0x00 to=0xf8, WantAck=1, HopLim=3 Ch=0x0 Portnum=6 priority=70)
DEBUG | ??:??:?? 52 Enqueued local (id=0xd3e8f1fb fr=0x00 to=0xf8, WantAck=1, HopLim=3 Ch=0x0 Portnum=6 priority=70)
DEBUG | ??:??:?? 52 [Router] Add packet record (id=0xd3e8f1fb fr=0x00 to=0xf8, WantAck=1, HopLim=3 Ch=0x0 Portnum=6 priority=70)
DEBUG | ??:??:?? 52 [Router] handleReceived(REMOTE) (id=0xd3e8f1fb fr=0x00 to=0xf8, WantAck=1, HopLim=3 Ch=0x0 Portnum=6 priority=70)
DEBUG | ??:??:?? 52 [Router] Module 'Admin' wantsPacket=1
INFO  | ??:??:?? 52 [Router] Received Admin from=0x0, id=0xd3e8f1fb, portnum=6, payloadlen=3
INFO  | ??:??:?? 52 [Router] Shutdown in 5 seconds
DEBUG | ??:??:?? 52 [Router] Module 'Admin' considered
DEBUG | ??:??:?? 52 [Router] Module 'routing' wantsPacket=1
INFO  | ??:??:?? 52 [Router] Received routing from=0x0, id=0xd3e8f1fb, portnum=6, payloadlen=3
DEBUG | ??:??:?? 52 [Router] Routing sniffing (id=0xd3e8f1fb fr=0x00 to=0xf8, WantAck=1, HopLim=3 Ch=0x0 Portnum=6 priority=70)
DEBUG | ??:??:?? 52 [Router] Enqueued local (id=0x1770cf67 fr=0xf8 to=0xf8, WantAck=0, HopLim=3 Ch=0x0 Portnum=5 requestId=d3e8f1fb priority=120)
DEBUG | ??:??:?? 52 [Router] Module 'routing' considered
DEBUG | ??:??:?? 52 [Router] Rx someone rebroadcasting for us (id=0x1770cf67 fr=0xf8 to=0xf8, WantAck=0, HopLim=3 Ch=0x0 Portnum=5 requestId=d3e8f1fb priority=120)
DEBUG | ??:??:?? 52 [Router] didn't find pending packet
DEBUG | ??:??:?? 52 [Router] Add packet record (id=0x1770cf67 fr=0xf8 to=0xf8, WantAck=0, HopLim=3 Ch=0x0 Portnum=5 requestId=d3e8f1fb priority=120)
DEBUG | ??:??:?? 52 [Router] handleReceived(REMOTE) (id=0x1770cf67 fr=0xf8 to=0xf8, WantAck=0, HopLim=3 Ch=0x0 Portnum=5 requestId=d3e8f1fb priority=120)
DEBUG | ??:??:?? 52 [Router] Module 'canned' wantsPacket=1
DEBUG | ??:??:?? 53 [Router] showing standard frames
DEBUG | ??:??:?? 53 [Router] Showing 0 module frames
DEBUG | ??:??:?? 53 [Router] Total frame count: 103
DEBUG | ??:??:?? 53 [Router] Added modules.  numframes: 0
DEBUG | ??:??:?? 53 [Router] Finished building frames. numframes: 3
DEBUG | ??:??:?? 53 [Router] Module 'canned' considered
DEBUG | ??:??:?? 53 [Router] Module 'routing' wantsPacket=1
INFO  | ??:??:?? 53 [Router] Received routing from=0x6d00f2f8, id=0x1770cf67, portnum=5, payloadlen=2
DEBUG | ??:??:?? 53 [Router] Routing sniffing (id=0x1770cf67 fr=0xf8 to=0xf8, WantAck=0, HopLim=3 Ch=0x0 Portnum=5 requestId=d3e8f1fb priority=120)
DEBUG | ??:??:?? 53 [Router] Received an ack for 0xd3e8f1fb, stopping retransmissions
DEBUG | ??:??:?? 53 [Router] Delivering rx packet (id=0x1770cf67 fr=0xf8 to=0xf8, WantAck=0, HopLim=3 Ch=0x0 Portnum=5 requestId=d3e8f1fb priority=120)
DEBUG | ??:??:?? 53 [Router] Update DB node 0x6d00f2f8, rx_time=0
DEBUG | ??:??:?? 53 [Router] Forwarding to phone (id=0x1770cf67 fr=0xf8 to=0xf8, WantAck=0, HopLim=3 Ch=0x0 Portnum=5 requestId=d3e8f1fb priority=120)
DEBUG | ??:??:?? 53 [Router] Module 'routing' considered
INFO  | ??:??:?? 53 Telling client we have new packets 7
INFO  | ??:??:?? 53 BLE notify fromNum
DEBUG | ??:??:?? 53 [Screen] showing shutdown screen
DEBUG | ??:??:?? 53 [Screen] showing shutdown screen
INFO  | ??:??:?? 53 [Screen] From Radio onread
INFO  | ??:??:?? 53 [Screen] getFromRadio=STATE_SEND_PACKETS
DEBUG | ??:??:?? 53 [Screen] showing shutdown screen
DEBUG | ??:??:?? 53 [Screen] encoding toPhone packet to phone variant=11, 12 bytes
DEBUG | ??:??:?? 53 [Screen] showing shutdown screen
DEBUG | ??:??:?? 53 [Screen] showing shutdown screen
INFO  | ??:??:?? 53 [Screen] From Radio onread
INFO  | ??:??:?? 53 [Screen] getFromRadio=STATE_SEND_PACKETS
DEBUG | ??:??:?? 53 [Screen] showing shutdown screen
DEBUG | ??:??:?? 53 [Screen] phone downloaded packet (id=0x1770cf67 fr=0xf8 to=0xf8, WantAck=0, HopLim=3 Ch=0x0 Portnum=5 requestId=d3e8f1fb priority=120)
DEBUG | ??:??:?? 53 [Screen] showing shutdown screen
DEBUG | ??:??:?? 53 [Screen] encoding toPhone packet to phone variant=2, 34 bytes
DEBUG | ??:??:?? 53 [Screen] showing shutdown screen
DEBUG | ??:??:?? 53 [Screen] showing shutdown screen
INFO  | ??:??:?? 53 From Radio onread
DEBUG | ??:??:?? 53 [Screen] showing shutdown screen
DEBUG | ??:??:?? 57 [Screen] showing shutdown screen
INFO  | ??:??:?? 57 Shutting down from admin command
INFO  | ??:??:?? 57 Shutting down
INFO  | ??:??:?? 57 Entering deep sleep forever
INFO  | ??:??:?? 57 Disable bluetooth until reboot
INFO  | ??:??:?? 58 BLE disconnect
E NimBLEAdvertising: Error enabling advertising; rc=30,
INFO  | ??:??:?? 58 Turning off screen
INFO  | ??:??:?? 58 Saving /prefs/db.proto
INFO  | ??:??:?? 58 Saving /prefs/config.proto
INFO  | ??:??:?? 59 Saving /prefs/module.proto
INFO  | ??:??:?? 59 Saving /prefs/channels.proto
ets Jun  8 2016 00:22:57

rst:0x5 (DEEPSLEEP_RESET),boot:0x13 (SPI_FAST_FLASH_BOOT)
configsip: 188777542, 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:1184
load:0x40078000,len:13192
load:0x40080400,len:3028
entry 0x400805e4
E (71) esp_core_dump_flash: No core dump f▒ѥѥ▒▒▒found!
E (71) esp_core_dump_flash: No core dump partition found!
[    12][D][esp32-hal-cpu.c:244] setCpuFrequencyMhz(): PLL: 480 / 2 = 240 Mhz, APB: 80000000 Hz
▒▒@INFO  | ??:??:?? 0

//\ E S H T /\ S T / C

INFO  | ??:??:?? 0 Booted, wake cause 3 (boot count 2), reset_reason=ext1 RTC_CNTL
DEBUG | ??:??:?? 0 Disabling any holds on RTC IO pads
DEBUG | ??:??:?? 0 Filesystem files (491520/1048576 Bytes):
DEBUG | ??:??:?? 0  /prefs/channels.proto (57 Bytes)
DEBUG | ??:??:?? 0  /prefs/config.proto (99 Bytes)
DEBUG | ??:??:?? 0  /prefs/db.proto (409 Bytes)
DEBUG | ??:??:?? 0  /prefs/module.proto (106 Bytes)
DEBUG | ??:??:?? 0  /static/.gitkeep (0 Bytes)
DEBUG | ??:??:?? 0  /static/Logo_Black.svg.gz (602 Bytes)
DEBUG | ??:??:?? 0  /static/Logo_White.svg.gz (610 Bytes)
DEBUG | ??:??:?? 0  /static/apple-touch-icon.png.gz (3164 Bytes)
DEBUG | ??:??:?? 0  /static/favicon.ico.gz (2270 Bytes)
DEBUG | ??:??:?? 0  /static/icon.svg.gz (852 Bytes)
DEBUG | ??:??:?? 0  /static/index-BMhLjTmL.css.gz (16270 Bytes)
DEBUG | ??:??:?? 0  /static/index-Bt2MYVVB.js.gz (203021 Bytes)
DEBUG | ??:??:?? 0  /static/index-CGqDWSD_.js.gz (348 Bytes)
DEBUG | ??:??:?? 0  /static/index.html.gz (548 Bytes)
DEBUG | ??:??:?? 0  /static/maplibre-gl-BXZZhNda.js.gz (210451 Bytes)
DEBUG | ??:??:?? 0  /static/robots.txt.gz (42 Bytes)
DEBUG | ??:??:?? 0  /static/site.webmanifest.gz (197 Bytes)
[   547][I][esp32-hal-i2c.c:75] i2cInit(): Initialising I2C Master: sda=21 scl=22 freq=100000
DEBUG | ??:??:?? 0 Using analog input 35 for battery level
INFO  | ??:??:?? 0 ADCmod: ADC characterization based on reference voltage stored in eFuse
INFO  | ??:??:?? 0 Scanning for i2c devices...
[   574][W][Wire.cpp:301] begin(): Bus already started in Master Mode.
DEBUG | ??:??:?? 0 Scanning for i2c devices on port 1
DEBUG | ??:??:?? 0 I2C device found at address 0x3c
INFO  | ??:??:?? 0 ssd1306 display found
INFO  | ??:??:?? 0 ssd1306 display found
DEBUG | ??:??:?? 0 0x7 subtype probed in 2 tries
INFO  | ??:??:?? 0 1 I2C devices found
DEBUG | ??:??:?? 0 acc_info = 0
INFO  | ??:??:?? 0 Meshtastic hwvendor=3, swver=2.3.9.f06c56a
DEBUG | ??:??:?? 0 Setting random seed 1146956295
DEBUG | ??:??:?? 0 Total heap: 197596
DEBUG | ??:??:?? 0 Free heap: 163068
DEBUG | ??:??:?? 0 Total PSRAM: 0
DEBUG | ??:??:?? 0 Free PSRAM: 0
DEBUG | ??:??:?? 0 NVS: UsedEntries 90, FreeEntries 540, AllEntries 630, NameSpaces 3
DEBUG | ??:??:?? 0 Setup Preferences in Flash Storage
DEBUG | ??:??:?? 0 Number of Device Reboots: 4
DEBUG | ??:??:?? 0 OTA firmware version 0.2.1.ceca52c
INFO  | ??:??:?? 0 Initializing NodeDB
INFO  | ??:??:?? 0 Loading /prefs/db.proto
INFO  | ??:??:?? 0 Loaded /prefs/db.proto successfully
INFO  | ??:??:?? 0 Loaded saved devicestate version 22, with nodecount: 2
INFO  | ??:??:?? 0 Loading /prefs/config.proto
INFO  | ??:??:?? 0 Loaded /prefs/config.proto successfully
INFO  | ??:??:?? 0 Loaded saved config version 22
INFO  | ??:??:?? 0 Loading /prefs/module.proto
INFO  | ??:??:?? 0 Loaded /prefs/module.proto successfully
INFO  | ??:??:?? 0 Loaded saved moduleConfig version 22
INFO  | ??:??:?? 0 Loading /prefs/channels.proto
INFO  | ??:??:?? 0 Loaded /prefs/channels.proto successfully
INFO  | ??:??:?? 0 Loaded saved channelFile version 22
[   945][E][vfs_api.cpp:105] open(): /littlefs/oem/oem.proto does not exist, no permits for creation
INFO  | ??:??:?? 0 File /oem/oem.proto not found
DEBUG | ??:??:?? 0 cleanupMeshDB purged 0 entries
DEBUG | ??:??:?? 0 Using nodenum 0x6d00f2f8
DEBUG | ??:??:?? 0 Number of Device Reboots: 4
DEBUG | ??:??:?? 0 Expanding short PSK #1
INFO  | ??:??:?? 0 Wanted region 3, using EU_868
INFO  | ??:??:?? 1 Saving /prefs/db.proto
DEBUG | ??:??:?? 1 Using GPIO12 for button
DEBUG | ??:??:?? 1 SPI.begin(SCK=5, MISO=19, MOSI=27, NSS=18)
DEBUG | ??:??:?? 1 Set Timezone to GMT0
DEBUG | ??:??:?? 1 Read RTC time as 61
DEBUG | ??:??:?? 1 NeighborInfoModule is disabled
INFO  | ??:??:?? 1 External Notification Module Disabled
INFO  | ??:??:?? 1 Turning on screen
DEBUG | ??:??:?? 1 Module wants a UI Frame
DEBUG | ??:??:?? 1 RF95Interface(cs=18, irq=26, rst=23, busy=33)
INFO  | ??:??:?? 1 Starting meshradio init...
DEBUG | ??:??:?? 1 (bw=250, sf=11, cr=4/5) packet symLen=8 ms, payloadSize=0, time 231 ms
DEBUG | ??:??:?? 1 (bw=250, sf=11, cr=4/5) packet symLen=8 ms, payloadSize=253, time 2115 ms
INFO  | ??:??:?? 1 Radio freq=869.525, config.lora.frequency_offset=0.000
INFO  | ??:??:?? 1 Set radio: region=EU_868, name=LongFast, config=0, ch=0, power=27
INFO  | ??:??:?? 1 Radio myRegion->freqStart -> myRegion->freqEnd: 869.400024 -> 869.650024 (0.250000 mhz)
INFO  | ??:??:?? 1 Radio myRegion->numChannels: 1 x 250.000kHz
INFO  | ??:??:?? 1 Radio channel_num: 1
INFO  | ??:??:?? 1 Radio frequency: 869.525024
INFO  | ??:??:?? 1 Slot time: 42 msec
INFO  | ??:??:?? 1 Set radio: final power level=20
DEBUG | ??:??:?? 1 Current limit set to 100.000000
DEBUG | ??:??:?? 1 Current limit set result 0
INFO  | ??:??:?? 1 RF95 init result 0
INFO  | ??:??:?? 1 Frequency set to 869.525024
INFO  | ??:??:?? 1 Bandwidth set to 250.000000
INFO  | ??:??:?? 1 Power output set to 20
INFO  | ??:??:?? 1 RF95 Radio init succeeded, using RF95 radio
INFO  | ??:??:?? 1 Not using WIFI
DEBUG | ??:??:?? 1 (bw=250, sf=11, cr=4/5) packet symLen=8 ms, payloadSize=237, time 2000 ms
DEBUG | ??:??:?? 1 ▒~I▒bitrate = 118.500000 bytes / sec
INFO  | ??:??:?? 1 PowerFSM init, USB power=1
DEBUG | ??:??:?? 1 Enter state: BOOT
[  1547][D][esp32-hal-cpu.c:244] setCpuFrequencyMhz(): PLL: 320 / 4 = 80 Mhz, APB: 80000000 Hz
DEBUG | ??:??:?? 1 [Power] Battery: usbPower=0, isCharging=0, batMv=3100, batPct=0
DEBUG | ??:??:?? 1 [Screen] Screen: Started...
INFO  | ??:??:?? 1 [RangeTestModule] Range Test Module - Disabled
INFO  | ??:??:?? 4 [PowerFSM] Loss of power in Powered
INFO  | ??:??:?? 4 [PowerFSM] Loss of power in Powered
INFO  | ??:??:?? 4 [PowerFSM] Initialise the NimBLE bluetooth module
DEBUG | ??:??:?? 5 [PowerFSM] Enter state: ON
INFO  | ??:??:?? 6 BLE authentication complete
INFO  | ??:??:?? 7 [Screen] Done with boot screen...
DEBUG | ??:??:?? 7 [Screen] showing standard frames
DEBUG | ??:??:?? 7 [Screen] Showing 0 module frames
DEBUG | ??:??:?? 7 [Screen] Total frame count: 103
DEBUG | ??:??:?? 7 [Screen] Added modules.  numframes: 0
DEBUG | ??:??:?? 7 [Screen] Finished building frames. numframes: 3
INFO  | ??:??:?? 8 From Radio onread
INFO  | ??:??:?? 8 To Radio onwrite
INFO  | ??:??:?? 8 Client wants config, nonce=3
INFO  | ??:??:?? 8 Starting API client config
INFO  | ??:??:?? 8 From Radio onread
INFO  | ??:??:?? 8 getFromRadio=STATE_SEND_MY_INFO
DEBUG | ??:??:?? 8 encoding toPhone packet to phone variant=3, 14 bytes
INFO  | ??:??:?? 8 From Radio onread
INFO  | ??:??:?? 8 getFromRadio=STATE_SEND_METADATA
DEBUG | ??:??:?? 8 encoding toPhone packet to phone variant=13, 30 bytes
INFO  | ??:??:?? 9 From Radio onread
INFO  | ??:??:?? 9 getFromRadio=STATE_SEND_NODEINFO
INFO  | ??:??:?? 9 nodeinfo: num=0x6d00f2f8, lastseen=0, id=!6d00f2f8, name=Meshtastic f2f8
DEBUG | ??:??:?? 9 encoding toPhone packet to phone variant=4, 79 bytes
INFO  | ??:??:?? 9 From Radio onread
INFO  | ??:??:?? 9 getFromRadio=STATE_SEND_NODEINFO
INFO  | ??:??:?? 9 nodeinfo: num=0xe0d392b0, lastseen=0, id=!e0d392b0, name=bit.ly/mesh_dRH5#Robert@Home
DEBUG | ??:??:?? 9 encoding toPhone packet to phone variant=4, 74 bytes
INFO  | ??:??:?? 9 From Radio onread
INFO  | ??:??:?? 9 getFromRadio=STATE_SEND_NODEINFO
INFO  | ??:??:?? 9 Done sending nodeinfos
INFO  | ??:??:?? 9 getFromRadio=STATE_SEND_CHANNELS
DEBUG | ??:??:?? 9 encoding toPhone packet to phone variant=10, 13 bytes
INFO  | ??:??:?? 9 From Radio onread
INFO  | ??:??:?? 9 getFromRadio=STATE_SEND_CHANNELS
DEBUG | ??:??:?? 9 encoding toPhone packet to phone variant=10, 6 bytes
INFO  | ??:??:?? 9 From Radio onread
INFO  | ??:??:?? 9 getFromRadio=STATE_SEND_CHANNELS
DEBUG | ??:??:?? 9 encoding toPhone packet to phone variant=10, 6 bytes
INFO  | ??:??:?? 10 [Screen] From Radio onread
INFO  | ??:??:?? 10 [Screen] getFromRadio=STATE_SEND_CHANNELS
DEBUG | ??:??:?? 10 encoding toPhone packet to phone variant=10, 6 bytes
INFO  | ??:??:?? 10 From Radio onread
INFO  | ??:??:?? 10 getFromRadio=STATE_SEND_CHANNELS
DEBUG | ??:??:?? 10 encoding toPhone packet to phone variant=10, 6 bytes
INFO  | ??:??:?? 10 From Radio onread
INFO  | ??:??:?? 10 getFromRadio=STATE_SEND_CHANNELS
DEBUG | ??:??:?? 10 encoding toPhone packet to phone variant=10, 6 bytes
INFO  | ??:??:?? 11 From Radio onread
INFO  | ??:??:?? 11 getFromRadio=STATE_SEND_CHANNELS
DEBUG | ??:??:?? 11 encoding toPhone packet to phone variant=10, 6 bytes
INFO  | ??:??:?? 11 From Radio onread
INFO  | ??:??:?? 11 getFromRadio=STATE_SEND_CHANNELS
DEBUG | ??:??:?? 11 encoding toPhone packet to phone variant=10, 6 bytes
INFO  | ??:??:?? 11 From Radio onread
INFO  | ??:??:?? 11 getFromRadio=STATE_SEND_CONFIG
DEBUG | ??:??:?? 11 encoding toPhone packet to phone variant=5, 9 bytes
INFO  | ??:??:?? 11 From Radio onread
INFO  | ??:??:?? 11 getFromRadio=STATE_SEND_CONFIG
DEBUG | ??:??:?? 11 encoding toPhone packet to phone variant=5, 20 bytes
INFO  | ??:??:?? 12 From Radio onread
INFO  | ??:??:?? 12 getFromRadio=STATE_SEND_CONFIG
DEBUG | ??:??:?? 12 encoding toPhone packet to phone variant=5, 17 bytes
INFO  | ??:??:?? 12 From Radio onread
INFO  | ??:??:?? 12 getFromRadio=STATE_SEND_CONFIG
DEBUG | ??:??:?? 12 encoding toPhone packet to phone variant=5, 29 bytes
INFO  | ??:??:?? 13 From Radio onread
INFO  | ??:??:?? 13 getFromRadio=STATE_SEND_CONFIG
DEBUG | ??:??:?? 13 encoding toPhone packet to phone variant=5, 7 bytes
INFO  | ??:??:?? 13 From Radio onread
INFO  | ??:??:?? 13 getFromRadio=STATE_SEND_CONFIG
DEBUG | ??:??:?? 13 encoding toPhone packet to phone variant=5, 19 bytes
INFO  | ??:??:?? 13 From Radio onread
INFO  | ??:??:?? 13 getFromRadio=STATE_SEND_CONFIG
DEBUG | ??:??:?? 13 encoding toPhone packet to phone variant=5, 10 bytes
INFO  | ??:??:?? 13 From Radio onread
INFO  | ??:??:?? 13 getFromRadio=STATE_SEND_MODULECONFIG
DEBUG | ??:??:?? 13 encoding toPhone packet to phone variant=9, 60 bytes
INFO  | ??:??:?? 13 From Radio onread
INFO  | ??:??:?? 13 getFromRadio=STATE_SEND_MODULECONFIG
DEBUG | ??:??:?? 13 encoding toPhone packet to phone variant=9, 4 bytes
INFO  | ??:??:?? 14 From Radio onread
INFO  | ??:??:?? 14 getFromRadio=STATE_SEND_MODULECONFIG
DEBUG | ??:??:?? 14 encoding toPhone packet to phone variant=9, 4 bytes
INFO  | ??:??:?? 14 From Radio onread
INFO  | ??:??:?? 14 getFromRadio=STATE_SEND_MODULECONFIG
DEBUG | ??:??:?? 14 encoding toPhone packet to phone variant=9, 4 bytes
INFO  | ??:??:?? 14 From Radio onread
INFO  | ??:??:?? 14 getFromRadio=STATE_SEND_MODULECONFIG
DEBUG | ??:??:?? 14 encoding toPhone packet to phone variant=9, 4 bytes
INFO  | ??:??:?? 14 From Radio onread
INFO  | ??:??:?? 14 getFromRadio=STATE_SEND_MODULECONFIG
DEBUG | ??:??:?? 14 encoding toPhone packet to phone variant=9, 13 bytes
INFO  | ??:??:?? 14 From Radio onread
INFO  | ??:??:?? 14 getFromRadio=STATE_SEND_MODULECONFIG
DEBUG | ??:??:?? 14 encoding toPhone packet to phone variant=9, 4 bytes
INFO  | ??:??:?? 15 From Radio onread
INFO  | ??:??:?? 15 getFromRadio=STATE_SEND_MODULECONFIG
DEBUG | ??:??:?? 15 encoding toPhone packet to phone variant=9, 4 bytes
INFO  | ??:??:?? 15 From Radio onread
INFO  | ??:??:?? 15 getFromRadio=STATE_SEND_MODULECONFIG
DEBUG | ??:??:?? 15 encoding toPhone packet to phone variant=9, 4 bytes
INFO  | ??:??:?? 15 From Radio onread
INFO  | ??:??:?? 15 getFromRadio=STATE_SEND_MODULECONFIG
DEBUG | ??:??:?? 15 encoding toPhone packet to phone variant=9, 7 bytes
INFO  | ??:??:?? 15 From Radio onread
INFO  | ??:??:?? 15 getFromRadio=STATE_SEND_MODULECONFIG
DEBUG | ??:??:?? 15 encoding toPhone packet to phone variant=9, 12 bytes
@roha-github roha-github added the bug Something isn't working label May 13, 2024
@todd-herbert
Copy link
Contributor

todd-herbert commented May 13, 2024

I remember speaking about this on the discourse server, was hoping to hear back from you! I don't have the hardware myself, but I've reached out to hopefully do some testing with someone who does. Still suspecting it's either due to the defined PIN_BUTTON (GPIO12) for this variant requesting an internal pull-up, or maybe due to its role as a bootstrapping pin (fails if pulled HIGH at boot). If all else fails, there may be a case to disable the PIN_BUTTON wake interrupt for this variant, and rely on the reset instead.

@meshtastic-bot
Copy link

This issue has been mentioned on Meshtastic. There might be relevant details there:

https://meshtastic.discourse.group/t/dodeepsleep-with-msectowake/12338/13

@todd-herbert
Copy link
Contributor

The shutdown worked up to version 2.3.4

If you're wondering what the change was, the EXT0 interrupt was reassigned to listen for interrupts from the radio during sleep, which meant the button interrupt was shifted to EXT1. I think we've caught most of the edge cases at this point, but seems there's still one or two out there!

@roha-github
Copy link
Author

roha-github commented May 13, 2024

@todd-herbert sorry for the late response. I didn't have access to a computer with VS Code this past week to try out your hint. Today I tested the shutdown on different hardware variants on firmware: 2.3.9.f06c56a.

The T-Lora 2.1-1.6 device don't have a push button, only one reset button and power switch.
https://github.com/LilyGO/TTGO-LORA32/blob/master/schematic1in6.pdf

I picked up project environment "env:tlora-v2-1-1_6" and Line 235 to 238 of sleep.cpp are grayed out.
Line 267 is enabled, meaning BUTTON_PIN is defined in variant.h (BUTTON_PIN 35).

Try comment out line 267 and 268 solve the issue for TLORA_2_1_1P6

#ifdef BUTTON_PIN
    // Avoid leakage through button pin
    if (GPIO_IS_VALID_OUTPUT_GPIO(BUTTON_PIN)) {
        // pinMode(BUTTON_PIN, INPUT);
        // gpio_hold_en((gpio_num_t)BUTTON_PIN);
    }
#endif

Perhaps a precompiler variable HAS_NO_BUTTON in variant.h would help to exclude all devices without a push button from such operations in future. There may also be (repeater) devices without PUSH_BUTTON in the future.

@todd-herbert
Copy link
Contributor

todd-herbert commented May 13, 2024

The T-Lora 2.1-1.6 device don't have a push button, only one reset button and power switch.

Perhaps a precompiler variable HAS_NO_BUTTON in variant.h would help to exclude all devices without a push button from such operations in future. There may also be (repeater) devices without PUSH_BUTTON in the future.

I believe it is already acceptable to simply omit the PIN_BUTTON definition when defining a variant without a button. I'm not actually sure what the original reason for adding a button definition for this model was, and I'd hate to break someone else's device by removing it. My concern is that some T-Lora users may have DIY builds which do use a button connected to GPIO12.

Try comment out line 267 and 268 solve the issue for TLORA_2_1_1P6

Just to make sure I understand correctly: you tested commenting out line 267 and 268, and it resolved the issue? If so, that's very helpful information.

If commenting out 267 and 268 worked for you, could I possibly bother you for a tiny bit more testing? I would be interested to know if it is specifically line 267 or 268 which is the issue. My hope is that changing line 267 to INPUT_PULLUP will resolve the issue both on your device (without a button) and on any other user's builds (which may have a button connected to GPIO12?)

#ifdef BUTTON_PIN
    // Avoid leakage through button pin
    if (GPIO_IS_VALID_OUTPUT_GPIO(BUTTON_PIN)) {
        pinMode(BUTTON_PIN, INPUT_PULLUP);
        gpio_hold_en((gpio_num_t)BUTTON_PIN);
    }
#endif

I'm also suspicious that lines 212-214 of src/platform/esp32/main-esp32.cpp may need to be removed..

I'm sorry to ask this of you. Ideally I wouldn't bother you, except that I'm not able to test on my own.

@caveman99
Copy link
Sponsor Member

I believe it is already acceptable to simply omit the PIN_BUTTON definition when defining a variant without a button. I'm not actually sure what the original reason for adding a button definition for this model was, and I'd hate to break someone else's device by removing it. My concern is that some T-Lora users may have DIY builds which do use a button connected to GPIO12.

The best practise to tell the firmware the board does not habe a user button is to not define that macro. This is one of the oldest parts of the code and comes from the idea, to have fixed pins assigned for optional peripherals. we have since removed the fixed GPS pins from those devices, and in theory the user button can be removed and replaced by the protobuf setting. We just need to put this into release nodes. Fixed definitions shold only exist if a button is physically present on the shipped devices.

@todd-herbert
Copy link
Contributor

Makes sense to me. I would still quite like to figure out what changes would resolve this particular situation though if possible, because I notice there are a few other variants which also have BUTTON_NEED_PULLUP, and I'm suspicious that they might be affected by the same bug right now.

@roha-github
Copy link
Author

Yes, I tested commenting out line 267 and 268, and it resolved the issue.

I repeated the test with pinMode INPUT_PULLUP, and it also resolved the issue.
I don't change the main-esp32.cpp and think gpio_pullup_en alone has no impact,
because pinMode is a Arduino wrapper around gpio_set_direction and gpio_set_pull_mode.
https://docs.espressif.com/projects/esp-idf/en/stable/esp32/api-reference/peripherals/gpio.html

@roha-github
Copy link
Author

roha-github commented May 13, 2024

there are a few other variants which also have BUTTON_NEED_PULLUP

There may be models whose push button is connected to GND.

@todd-herbert
Copy link
Contributor

todd-herbert commented May 13, 2024

Thank you very much for confirming that!

I don't change the main-esp32.cpp and think gpio_pullup_en alone has no impact,
because pinMode is a Arduino wrapper around gpio_set_direction and gpio_set_pull_mode.

I had a slight suspicion that maybe there was something odd happening when calling gpio_pullup_en after gpio_hold_en, but from the results of your testing, it seems that it's no problem!

There may be models whose push button is connected to GND.

Specifically the ones without external pull-ups, too.

Just to absolutely confirm: replacing like 267 of sleep.cpp with pinMode(BUTTON_PIN, INPUT_PULLUP); resolves the issue for you; no extra changes required? I ask because I'll make that change for any devices with BUTTON_NEED_PULLUP (hopefully saving them from this same bug), unless of course @caveman99 is happy to make the changes? I can also go through and purge any PIN_BUTTON definitions for devices without an onboard user button, if that helps tidy things up.

@roha-github
Copy link
Author

replacing like 267 of sleep.cpp with pinMode(BUTTON_PIN, INPUT_PULLUP); resolves the issue for me; no extra changes required

#ifdef BUTTON_PIN
    // Avoid leakage through button pin
    if (GPIO_IS_VALID_OUTPUT_GPIO(BUTTON_PIN)) {
        pinMode(BUTTON_PIN, INPUT_PULLUP); // bugfix #3875 pinMode(BUTTON_PIN, INPUT);
        gpio_hold_en((gpio_num_t)BUTTON_PIN);
    }
#endif

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
bug Something isn't working
Projects
None yet
Development

No branches or pull requests

4 participants