firmware icon indicating copy to clipboard operation
firmware copied to clipboard

[Bug]: T-beam v1.1 cannot setup CN470 directly

Open SteveHawk opened this issue 1 year ago • 5 comments

Category

Other

Hardware

T-Beam

Firmware Version

2.3.8

Description

After freshly flashed and reset a T-beam v1.1 board (the 433/470 MHz variant with SX1268 chip), directly setting region to CN470 would fail, with critical fault # 3 showing on screen. Error logs are attached down below.

Some probably important messages:

  1. It reports critical error 7 before rebooting;
  2. After booting, it fails to init either RF95, SX1262 or SX1268, then reporting error 3;
  3. It also reports error 4 in NodeInfoModule and DeviceTelemetryModule.

However, if I set it to EU433 first (which works fine), then set it back to CN470, it instead works. None of the above error messages showed up, and in both cases (setting to EU433 or CN470), log reports RF95 init success.

Relevant log output

INFO | ??:??:?? 626 [Router] Client is setting the config

INFO | ??:??:?? 626 [Router] Setting config: LoRa

INFO | ??:??:?? 626 [Router] Wanted region 4, using CN

INFO | ??:??:?? 626 [Router] Saving changes to disk

DEBUG | ??:??:?? 626 [Router] Expanding short PSK #1

INFO | ??:??:?? 626 [Router] Wanted region 4, using CN

DEBUG | ??:??:?? 626 [Router] (bw=250, sf=11, cr=4/5) packet symLen=8 ms, payloadSize=0, time 231 ms

DEBUG | ??:??:?? 626 [Router] (bw=250, sf=11, cr=4/5) packet symLen=8 ms, payloadSize=253, time 2115 ms

INFO | ??:??:?? 626 [Router] Radio freq=478.875, config.lora.frequency_offset=0.000

INFO | ??:??:?? 626 [Router] Set radio: region=CN, name=LongFast, config=0, ch=35, power=19

INFO | ??:??:?? 626 [Router] Radio myRegion->freqStart -> myRegion->freqEnd: 470.000000 -> 510.000000 (40.000000 mhz)

INFO | ??:??:?? 626 [Router] Radio myRegion->numChannels: 160 x 250.000kHz

INFO | ??:??:?? 626 [Router] Radio channel_num: 36

INFO | ??:??:?? 626 [Router] Radio frequency: 478.875000

INFO | ??:??:?? 626 [Router] Slot time: 42 msec

ERROR | ??:??:?? 626 [Router] NOTE! Recording critical error 7 at src/mesh/RF95Interface.cpp:154

INFO | ??:??:?? 626 [Router] Saving /prefs/config.proto

INFO | ??:??:?? 627 [Router] Saving /prefs/module.proto

INFO | ??:??:?? 627 [Router] Rebooting in 7 seconds

DEBUG | ??:??:?? 627 [Router] Module 'Admin' considered

DEBUG | ??:??:?? 627 [Router] Module 'routing' wantsPacket=1

INFO | ??:??:?? 627 [Router] Received routing from=0x0, id=0x78875a0b, portnum=6, payloadlen=17

DEBUG | ??:??:?? 627 [Router] Routing sniffing (id=0x78875a0b fr=0x00 to=0xec, WantAck=1, HopLim=3 Ch=0x0 Portnum=6 priority=70)

DEBUG | ??:??:?? 627 [Router] Enqueued local (id=0x19b41c2c fr=0xec to=0xec, WantAck=0, HopLim=3 Ch=0x0 Portnum=5 requestId=78875a0b priority=120)

DEBUG | ??:??:?? 627 [Router] Module 'routing' considered

DEBUG | ??:??:?? 627 [Router] Rx someone rebroadcasting for us (id=0x19b41c2c fr=0xec to=0xec, WantAck=0, HopLim=3 Ch=0x0 Portnum=5 requestId=78875a0b priority=120)

DEBUG | ??:??:?? 627 [Router] didn't find pending packet

DEBUG | ??:??:?? 627 [Router] Add packet record (id=0x19b41c2c fr=0xec to=0xec, WantAck=0, HopLim=3 Ch=0x0 Portnum=5 requestId=78875a0b priority=120)

DEBUG | ??:??:?? 627 [Router] handleReceived(REMOTE) (id=0x19b41c2c fr=0xec to=0xec, WantAck=0, HopLim=3 Ch=0x0 Portnum=5 requestId=78875a0b priority=120)

DEBUG | ??:??:?? 627 [Router] Module 'canned' wantsPacket=1

DEBUG | ??:??:?? 627 [Router] showing standard frames

DEBUG | ??:??:?? 627 [Router] Showing 0 module frames

DEBUG | ??:??:?? 627 [Router] Total frame count: 103

DEBUG | ??:??:?? 627 [Router] Added modules. numframes: 0

DEBUG | ??:??:?? 627 [Router] Finished building frames. numframes: 4

DEBUG | ??:??:?? 627 [Router] Module 'canned' considered

DEBUG | ??:??:?? 627 [Router] Module 'routing' wantsPacket=1

INFO | ??:??:?? 627 [Router] Received routing from=0xa3f991ec, id=0x19b41c2c, portnum=5, payloadlen=2

DEBUG | ??:??:?? 627 [Router] Routing sniffing (id=0x19b41c2c fr=0xec to=0xec, WantAck=0, HopLim=3 Ch=0x0 Portnum=5 requestId=78875a0b priority=120)

DEBUG | ??:??:?? 627 [Router] Received an ack for 0x78875a0b, stopping retransmissions

DEBUG | ??:??:?? 627 [Router] Delivering rx packet (id=0x19b41c2c fr=0xec to=0xec, WantAck=0, HopLim=3 Ch=0x0 Portnum=5 requestId=78875a0b priority=120)

DEBUG | ??:??:?? 627 [Router] Update DB node 0xa3f991ec, rx_time=0

DEBUG | ??:??:?? 627 [Router] Forwarding to phone (id=0x19b41c2c fr=0xec to=0xec, WantAck=0, HopLim=3 Ch=0x0 Portnum=5 requestId=78875a0b priority=120)

DEBUG | ??:??:?? 627 [Router] Module 'routing' considered

INFO | ??:??:?? 627 [Screen] Turning on screen

DEBUG | ??:??:?? 627 [Screen] Screen: Critical error 7!

DEBUG | ??:??:?? 627 [Screen] showing reboot screen

DEBUG | ??:??:?? 627 (Client not yet interested in packets)

INFO | ??:??:?? 634 Rebooting

ets Jul 29 2019 12:21:46



rst:0xc (SW_CPU_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:1184

load:0x40078000,len:13192

load:0x40080400,len:3028

entry 0x400805e4

E (847) esp_core_dump_flash: No core dump��ѥѥ���found!

E (847) 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

[ 464][I][esp32-hal-psram.c:96] psramInit(): PSRAM enabled

��@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 (479232/1048576 Bytes):

DEBUG | ??:??:?? 0 /prefs/channels.proto (57 Bytes)

DEBUG | ??:??:?? 0 /prefs/config.proto (87 Bytes)

DEBUG | ??:??:?? 0 /prefs/db.proto (368 Bytes)

DEBUG | ??:??:?? 0 /prefs/module.proto (105 Bytes)

DEBUG | ??:??:?? 0 /static/.gitkeep (0 Bytes)

DEBUG | ??:??:?? 0 /static/Logo_Black.svg.gz (592 Bytes)

DEBUG | ??:??:?? 0 /static/Logo_White.svg.gz (600 Bytes)

DEBUG | ??:??:?? 0 /static/apple-touch-icon.png.gz (3162 Bytes)

DEBUG | ??:??:?? 0 /static/favicon.ico.gz (2221 Bytes)

DEBUG | ??:??:?? 0 /static/icon.svg.gz (842 Bytes)

DEBUG | ??:??:?? 0 /static/index-9602d760.css.gz (17494 Bytes)

DEBUG | ??:??:?? 0 /static/index-a445ed47.js.gz (399957 Bytes)

DEBUG | ??:??:?? 0 /static/index-fc2e9253.js.gz (347 Bytes)

DEBUG | ??:??:?? 0 /static/index.html.gz (547 Bytes)

DEBUG | ??:??:?? 0 /static/robots.txt.gz (42 Bytes)

DEBUG | ??:??:?? 0 /static/site.webmanifest.gz (196 Bytes)

[ 994][I][esp32-hal-i2c.c:75] i2cInit(): Initialising I2C Master: sda=21 scl=22 freq=100000

[ 995][I][XPowersCommon.tpp:248] begin(): SDA:21 SCL:22

[ 999][W][Wire.cpp:301] begin(): Bus already started in Master Mode.

WARN | ??:??:?? 1 Failed to find AXP2101 power management

[ 1018][I][XPowersAXP2101.tpp:230] ~XPowersAXP2101(): ~XPowersAXP2101

[ 1018][I][XPowersCommon.tpp:248] begin(): SDA:21 SCL:22

[ 1022][I][esp32-hal-i2c.c:75] i2cInit(): Initialising I2C Master: sda=21 scl=22 freq=100000

INFO | ??:??:?? 1 AXP192 PMU init succeeded, using AXP192 PMU

[ 1048][D][XPowersAXP192.tpp:2010] setInterruptImpl(): setInterruptImpl DISABLE - 0xffffffffff



DEBUG | ??:??:?? 1 =======================================================================

DEBUG | ??:??:?? 1 DC1 : + Voltage:3300 mV

DEBUG | ??:??:?? 1 DC2 : - Voltage:1800 mV

DEBUG | ??:??:?? 1 DC3 : + Voltage:3300 mV

DEBUG | ??:??:?? 1 LDO2 : + Voltage:3300 mV

DEBUG | ??:??:?? 1 LDO3 : + Voltage:3300 mV

DEBUG | ??:??:?? 1 =======================================================================

DEBUG | ??:??:?? 1 Battery: usbPower=1, isCharging=0, batMv=4142, batPct=99

INFO | ??:??:?? 1 Scanning for i2c devices...

[ 1103][W][Wire.cpp:301] begin(): Bus already started in Master Mode.

DEBUG | ??:??:?? 1 Scanning for i2c devices on port 1

DEBUG | ??:??:?? 1 I2C device found at address 0x34

INFO | ??:??:?? 1 axp192/axp2101 PMU found

DEBUG | ??:??:?? 1 I2C device found at address 0x3c

INFO | ??:??:?? 1 ssd1306 display found

INFO | ??:??:?? 1 ssd1306 display found

DEBUG | ??:??:?? 1 0x7 subtype probed in 2 tries

INFO | ??:??:?? 1 2 I2C devices found

DEBUG | ??:??:?? 1 acc_info = 0

INFO | ??:??:?? 1 Meshtastic hwvendor=4, swver=2.3.8.d490a33

DEBUG | ??:??:?? 1 Setting random seed 3482936810

DEBUG | ??:??:?? 1 Total heap: 192944

DEBUG | ??:??:?? 1 Free heap: 158644

DEBUG | ??:??:?? 1 Total PSRAM: 4192107

DEBUG | ??:??:?? 1 Free PSRAM: 4191847

DEBUG | ??:??:?? 1 NVS: UsedEntries 93, FreeEntries 537, AllEntries 630, NameSpaces 3

DEBUG | ??:??:?? 1 Setup Preferences in Flash Storage

DEBUG | ??:??:?? 1 Number of Device Reboots: 2

DEBUG | ??:??:?? 1 OTA firmware version 0.2.1.2fbe7fc

INFO | ??:??:?? 1 Initializing NodeDB

INFO | ??:??:?? 1 Loading /prefs/db.proto

INFO | ??:??:?? 1 Loaded /prefs/db.proto successfully

INFO | ??:??:?? 1 Loaded saved devicestate version 22, with nodecount: 2

INFO | ??:??:?? 1 Loading /prefs/config.proto

INFO | ??:??:?? 1 Loaded /prefs/config.proto successfully

INFO | ??:??:?? 1 Loaded saved config version 22

INFO | ??:??:?? 1 Loading /prefs/module.proto

INFO | ??:??:?? 1 Loaded /prefs/module.proto successfully

INFO | ??:??:?? 1 Loaded saved moduleConfig version 22

INFO | ??:??:?? 1 Loading /prefs/channels.proto

INFO | ??:??:?? 1 Loaded /prefs/channels.proto successfully

INFO | ??:??:?? 1 Loaded saved channelFile version 22

[ 1542][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 0xa3f991ec

DEBUG | ??:??:?? 1 Number of Device Reboots: 2

DEBUG | ??:??:?? 1 Expanding short PSK #1

INFO | ??:??:?? 1 Wanted region 4, using CN

INFO | ??:??:?? 1 Saving /prefs/db.proto

DEBUG | ??:??:?? 1 Using GPIO38 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 637

INFO | ??:??:?? 1 Setting GPS power=1

DEBUG | ??:??:?? 1 WANT GPS=1

INFO | ??:??:?? 1 Setting GPS power=1

DEBUG | ??:??:?? 1 Using GPIO34 for GPS RX

DEBUG | ??:??:?? 1 Using GPIO12 for GPS TX

DEBUG | ??:??:?? 1 NeighborInfoModule is disabled

INFO | ??:??:?? 1 External Notification Module Disabled

INFO | ??:??:?? 1 Turning on screen

DEBUG | ??:??:?? 2 Module wants a UI Frame

DEBUG | ??:??:?? 2 RF95Interface(cs=18, irq=26, rst=23, busy=33)

INFO | ??:??:?? 2 Starting meshradio init...

DEBUG | ??:??:?? 2 (bw=250, sf=11, cr=4/5) packet symLen=8 ms, payloadSize=0, time 231 ms

DEBUG | ??:??:?? 2 (bw=250, sf=11, cr=4/5) packet symLen=8 ms, payloadSize=253, time 2115 ms

INFO | ??:??:?? 2 Radio freq=478.875, config.lora.frequency_offset=0.000

INFO | ??:??:?? 2 Set radio: region=CN, name=LongFast, config=0, ch=35, power=19

INFO | ??:??:?? 2 Radio myRegion->freqStart -> myRegion->freqEnd: 470.000000 -> 510.000000 (40.000000 mhz)

INFO | ??:??:?? 2 Radio myRegion->numChannels: 160 x 250.000kHz

INFO | ??:??:?? 2 Radio channel_num: 36

INFO | ??:??:?? 2 Radio frequency: 478.875000

INFO | ??:??:?? 2 Slot time: 42 msec

INFO | ??:??:?? 2 Set radio: final power level=19

DEBUG | ??:??:?? 2 Current limit set to 100.000000

DEBUG | ??:??:?? 2 Current limit set result 0

INFO | ??:??:?? 2 RF95 init result -13

INFO | ??:??:?? 2 Frequency set to 478.875000

INFO | ??:??:?? 2 Bandwidth set to 250.000000

INFO | ??:??:?? 2 Power output set to 19

WARN | ??:??:?? 2 Failed to find RF95 radio

DEBUG | ??:??:?? 2 SX126xInterface(cs=18, irq=33, rst=23, busy=32)

DEBUG | ??:??:?? 2 SX126X_DIO3_TCXO_VOLTAGE defined, using DIO3 as TCXO reference voltage at 1.800000 V

INFO | ??:??:?? 2 Starting meshradio init...

DEBUG | ??:??:?? 2 (bw=250, sf=11, cr=4/5) packet symLen=8 ms, payloadSize=0, time 231 ms

DEBUG | ??:??:?? 2 (bw=250, sf=11, cr=4/5) packet symLen=8 ms, payloadSize=253, time 2115 ms

INFO | ??:??:?? 2 Radio freq=478.875, config.lora.frequency_offset=0.000

INFO | ??:??:?? 2 Set radio: region=CN, name=LongFast, config=0, ch=35, power=19

INFO | ??:??:?? 2 Radio myRegion->freqStart -> myRegion->freqEnd: 470.000000 -> 510.000000 (40.000000 mhz)

INFO | ??:??:?? 2 Radio myRegion->numChannels: 160 x 250.000kHz

INFO | ??:??:?? 2 Radio channel_num: 36

INFO | ??:??:?? 2 Radio frequency: 478.875000

INFO | ??:??:?? 2 Slot time: 42 msec

INFO | ??:??:?? 2 Set radio: final power level=19

INFO | ??:??:?? 28 SX126x init result -2

WARN | ??:??:?? 28 Failed to find SX1262 radio

DEBUG | ??:??:?? 28 SX126xInterface(cs=18, irq=33, rst=23, busy=32)

DEBUG | ??:??:?? 28 SX126X_DIO3_TCXO_VOLTAGE defined, using DIO3 as TCXO reference voltage at 1.800000 V

INFO | ??:??:?? 28 Starting meshradio init...

DEBUG | ??:??:?? 28 (bw=250, sf=11, cr=4/5) packet symLen=8 ms, payloadSize=0, time 231 ms

DEBUG | ??:??:?? 28 (bw=250, sf=11, cr=4/5) packet symLen=8 ms, payloadSize=253, time 2115 ms

INFO | ??:??:?? 28 Radio freq=478.875, config.lora.frequency_offset=0.000

INFO | ??:??:?? 28 Set radio: region=CN, name=LongFast, config=0, ch=35, power=19

INFO | ??:??:?? 28 Radio myRegion->freqStart -> myRegion->freqEnd: 470.000000 -> 510.000000 (40.000000 mhz)

INFO | ??:??:?? 28 Radio myRegion->numChannels: 160 x 250.000kHz

INFO | ??:??:?? 28 Radio channel_num: 36

INFO | ??:??:?? 28 Radio frequency: 478.875000

INFO | ??:??:?? 28 Slot time: 42 msec

INFO | ??:??:?? 28 Set radio: final power level=19

INFO | ??:??:?? 55 SX126x init result -2

WARN | ??:??:?? 55 Failed to find SX1268 radio

INFO | ??:??:?? 55 Not using WIFI

ERROR | ??:??:?? 55 NOTE! Recording critical error 3 at src/main.cpp:y`Xj

INFO | ??:??:?? 55 PowerFSM init, USB power=1

DEBUG | ??:??:?? 55 Enter state: BOOT

[ 55112][D][esp32-hal-cpu.c:244] setCpuFrequencyMhz(): PLL: 320 / 4 = 80 Mhz, APB: 80000000 Hz

DEBUG | ??:??:?? 55 [Power] Battery: usbPower=1, isCharging=0, batMv=4141, batPct=99

INFO | ??:??:?? 55 [Screen] Done with boot screen...

DEBUG | ??:??:?? 55 [Screen] Screen: Started...

DEBUG | ??:??:?? 55 [Screen] Screen: Critical error 3!

DEBUG | ??:??:?? 55 [Screen] showing standard frames

DEBUG | ??:??:?? 55 [Screen] Showing 0 module frames

DEBUG | ??:??:?? 55 [Screen] Total frame count: 103

DEBUG | ??:??:?? 55 [Screen] Added modules. numframes: 0

DEBUG | ??:??:?? 55 [Screen] Finished building frames. numframes: 4

DEBUG | ??:??:?? 55 [GPS] Probing for GPS at 9600

INFO | ??:??:?? 57 [GPS] Found a UBlox Module using baudrate 9600

DEBUG | ??:??:?? 57 [GPS] Module Info :

DEBUG | ??:??:?? 57 [GPS] Soft version: 7.03 (45969)

DEBUG | ??:??:?? 57 [GPS] Hard version: 00040007

DEBUG | ??:??:?? 57 [GPS] Extensions:0

INFO | ??:??:?? 59 [GPS] GNSS module configuration saved!

INFO | ??:??:?? 59 [NodeInfoModule] Sending our nodeinfo to mesh (wantReplies=1)

INFO | ??:??:?? 59 [NodeInfoModule] sending owner !a3f991ec/Meshtastic 91ec/91ec

DEBUG | ??:??:?? 59 [NodeInfoModule] Initial packet id 1714302537, numPacketId 4294967295

DEBUG | ??:??:?? 59 [NodeInfoModule] Update DB node 0xa3f991ec, rx_time=0

ERROR | ??:??:?? 59 [NodeInfoModule] Error=4, returning NAK and dropping packet.

ERROR | ??:??:?? 59 [NodeInfoModule] Alloc an err=4,to=0xa3f991ec,idFrom=0x662e2e4b,id=0x662e2e4c

DEBUG | ??:??:?? 59 [NodeInfoModule] Enqueued local (id=0x662e2e4c fr=0xec to=0xec, WantAck=0, HopLim=3 Ch=0x0 Portnum=5 requestId=662e2e4b priority=120)

INFO | ??:??:?? 59 [DeviceTelemetryModule] (Sending): air_util_tx=0.000000, channel_utilization=0.000000, battery_level=99, voltage=4.141000, uptime=59

DEBUG | ??:??:?? 59 [DeviceTelemetryModule] updateTelemetry LOCAL

DEBUG | ??:??:?? 59 [DeviceTelemetryModule] Node status update: 2 online, 2 total

DEBUG | ??:??:?? 59 [DeviceTelemetryModule] showing standard frames

DEBUG | ??:??:?? 59 [DeviceTelemetryModule] Showing 0 module frames

DEBUG | ??:??:?? 59 [DeviceTelemetryModule] Total frame count: 103

DEBUG | ??:??:?? 59 [DeviceTelemetryModule] Added modules. numframes: 0

DEBUG | ??:??:?? 59 [DeviceTelemetryModule] Finished building frames. numframes: 4

INFO | ??:??:?? 59 [DeviceTelemetryModule] Sending packet to mesh

DEBUG | ??:??:?? 59 [DeviceTelemetryModule] Update DB node 0xa3f991ec, rx_time=0

ERROR | ??:??:?? 59 [DeviceTelemetryModule] Error=4, returning NAK and dropping packet.

ERROR | ??:??:?? 59 [DeviceTelemetryModule] Alloc an err=4,to=0xa3f991ec,idFrom=0x662e2e4d,id=0x662e2e4e

DEBUG | ??:??:?? 59 [DeviceTelemetryModule] Enqueued local (id=0x662e2e4e fr=0xec to=0xec, WantAck=0, HopLim=3 Ch=0x0 Portnum=5 requestId=662e2e4d priority=120)

SteveHawk avatar May 05 '24 16:05 SteveHawk

Hi @SteveHawk , is the latest firmware better?

fifieldt avatar Sep 16 '24 12:09 fifieldt

@fifieldt Hi, haven't checked in a while, so I try flashed both 2.5.1 and 2.4.2 onto my T-Beam, and the described issue still exists.

SteveHawk avatar Sep 22 '24 16:09 SteveHawk

One guess: it's trying to initialise a radio that doesn't speak that frequency. Will need to check the logic in the code.

fifieldt avatar Sep 23 '24 01:09 fifieldt

OK, looking at the line in the original reported bug, it failed setting the power:

    err = lora->setOutputPower(power);
    if (err != RADIOLIB_ERR_NONE)
        RECORD_CRITICALERROR(meshtastic_CriticalErrorCode_INVALID_RADIO_SETTING);

We would need logs on a currently supported firmware to confirm this is still valid, given power settings have changed since 2.3.8.

fifieldt avatar Jun 16 '25 00:06 fifieldt

I did a full erase and re-flashed my T-Beam to 2.6.11, and the issue still occurs. 2.6.12 the same. The old workaround still works.

Here's the setup log on 2.6.11:

DEBUG | 16:24:56 102 To Radio onwrite
DEBUG | 16:24:56 102 New ToRadio packet
DEBUG | 16:24:56 102 PACKET FROM PHONE (id=0x9c147936 fr=0x00000000 to=0xa3f9d738, WantAck=1, HopLim=3 Ch=0x0 Portnum=6 priority=70)
DEBUG | 16:24:56 102 Enqueued local (id=0x9c147936 fr=0x00000000 to=0xa3f9d738, WantAck=1, HopLim=3 Ch=0x0 Portnum=6 rxtime=1750263896 priority=70)
DEBUG | 16:24:56 102 [Router] Add packet record fr=0x0, id=0x9c147936
DEBUG | 16:24:56 102 [Router] handleReceived(REMOTE) (id=0x9c147936 fr=0x00000000 to=0xa3f9d738, WantAck=1, HopLim=3 Ch=0x0 Portnum=6 rxtime=1750263896 priority=70)
DEBUG | 16:24:56 102 [Router] Module 'Admin' wantsPacket=1
INFO  | 16:24:56 102 [Router] Received Admin from=0x0, id=0x9c147936, portnum=6, payloadlen=28
INFO  | 16:24:56 102 [Router] Handle admin payload 34
DEBUG | 16:24:56 102 [Router] Client set config
INFO  | 16:24:56 102 [Router] Set config: LoRa
INFO  | 16:24:56 102 [Router] Generate new PKI keys
DEBUG | 16:24:56 102 [Router] Generate Curve25519 keypair
INFO  | 16:24:56 102 [Router] Wanted region 4, using CN
INFO  | 16:24:56 102 [Router] Disable bluetooth until reboot
INFO  | 16:24:56 103 [Router] BLE disconnect
DEBUG | 16:24:56 103 [Router] BluetoothStatus DISCONNECTED
DEBUG | 16:24:56 103 [Router] PhoneAPI::close()
E NimBLEAdvertising: Error enabling advertising; rc=30, 
INFO  | 16:24:56 103 [Router] Save changes to disk
DEBUG | 16:24:56 103 [Router] Expand short PSK #1
INFO  | 16:24:56 103 [Router] Wanted region 4, using CN
INFO  | 16:24:56 103 [Router] Radio freq=478.875, config.lora.frequency_offset=0.000
INFO  | 16:24:56 103 [Router] Set radio: region=CN, name=LongFast, config=0, ch=35, power=19
INFO  | 16:24:56 103 [Router] myRegion->freqStart -> myRegion->freqEnd: 470.000000 -> 510.000000 (40.000000 MHz)
INFO  | 16:24:56 103 [Router] numChannels: 160 x 250.000kHz
INFO  | 16:24:56 103 [Router] channel_num: 36
INFO  | 16:24:56 103 [Router] frequency: 478.875000
INFO  | 16:24:56 103 [Router] Slot time: 28 msec
ERROR | 16:24:56 103 [Router] NOTE! Record critical error 7 at src/mesh/RF95Interface.cpp:252
DEBUG | 16:24:56 103 [Router] Save to disk 3
DEBUG | 16:24:56 103 [Router] Opening /prefs/config.proto, fullAtomic=1
INFO  | 16:24:56 103 [Router] Save /prefs/config.proto
[103309][E][vfs_api.cpp:105] open(): /littlefs/prefs/config.proto does not exist, no permits for creation
DEBUG | 16:24:56 103 [Router] Opening /prefs/module.proto, fullAtomic=1
INFO  | 16:24:56 103 [Router] Save /prefs/module.proto
[103529][E][vfs_api.cpp:105] open(): /littlefs/prefs/module.proto does not exist, no permits for creation
INFO  | 16:24:56 103 [Router] Reboot in 7 seconds
DEBUG | 16:24:56 103 [Router] Module 'Admin' considered
DEBUG | 16:24:56 103 [Router] Module 'routing' wantsPacket=1
INFO  | 16:24:56 103 [Router] Received routing from=0x0, id=0x9c147936, portnum=6, payloadlen=28
DEBUG | 16:24:56 103 [Router] Routing sniffing (id=0x9c147936 fr=0x00000000 to=0xa3f9d738, WantAck=1, HopLim=3 Ch=0x0 Portnum=6 rxtime=1750263896 priority=70)
DEBUG | 16:24:56 103 [Router] Partially randomized packet id 3046008907
DEBUG | 16:24:56 103 [Router] Enqueued local (id=0xb58e684b fr=0xa3f9d738 to=0xa3f9d738, WantAck=0, HopLim=3 Ch=0x0 Portnum=5 requestId=9c147936 rxtime=1750263896 priority=120)
DEBUG | 16:24:56 103 [Router] Module 'routing' considered
DEBUG | 16:24:56 103 [Router] Rx someone rebroadcasting for us (id=0xb58e684b fr=0xa3f9d738 to=0xa3f9d738, WantAck=0, HopLim=3 Ch=0x0 Portnum=5 requestId=9c147936 rxtime=1750263896 priorit
DEBUG | 16:24:57 103 [Router] Didn't find pending packet
DEBUG | 16:24:57 103 [Router] Add packet record fr=0xa3f9d738, id=0xb58e684b
DEBUG | 16:24:57 103 [Router] handleReceived(REMOTE) (id=0xb58e684b fr=0xa3f9d738 to=0xa3f9d738, WantAck=0, HopLim=3 Ch=0x0 Portnum=5 requestId=9c147936 rxtime=1750263897 priority=120)
DEBUG | 16:24:57 103 [Router] Module 'routing' wantsPacket=1
INFO  | 16:24:57 103 [Router] Received routing from=0xa3f9d738, id=0xb58e684b, portnum=5, payloadlen=2
DEBUG | 16:24:57 103 [Router] Routing sniffing (id=0xb58e684b fr=0xa3f9d738 to=0xa3f9d738, WantAck=0, HopLim=3 Ch=0x0 Portnum=5 requestId=9c147936 rxtime=1750263897 priority=120)
DEBUG | 16:24:57 103 [Router] Received a ACK for 0x9c147936, stopping retransmissions
DEBUG | 16:24:57 103 [Router] Delivering rx packet (id=0xb58e684b fr=0xa3f9d738 to=0xa3f9d738, WantAck=0, HopLim=3 Ch=0x0 Portnum=5 requestId=9c147936 rxtime=1750263897 priority=120)
DEBUG | 16:24:57 103 [Router] Update DB node 0xa3f9d738, rx_time=1750263897
DEBUG | 16:24:57 103 [Router] Forwarding to phone (id=0xb58e684b fr=0xa3f9d738 to=0xa3f9d738, WantAck=0, HopLim=3 Ch=0x0 Portnum=5 requestId=9c147936 rxtime=1750263897 priority=120)
DEBUG | 16:24:57 103 [Router] Module 'routing' considered
DEBUG | 16:24:57 103 [Screen] Screen: Critical error 7!
INFO  | 16:25:03 110 Rebooting
ets Jul 29 2019 12:21:46

rst:0xc (SW_CPU_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:1184
load:0x40078000,len:13232
load:0x40080400,len:3028
entry 0x400805e4
E (871) esp_core_dump_flash: No core dump��ѥѥ���found!
E (871) 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
[   475][I][esp32-hal-psram.c:96] psramInit(): PSRAM enabled
��@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 (16384/1048576 Bytes):
DEBUG | ??:??:?? 0  /prefs/channels.proto (57 Bytes)
DEBUG | ??:??:?? 0  /prefs/config.proto (168 Bytes)
DEBUG | ??:??:?? 0  /prefs/device.proto (64 Bytes)
DEBUG | ??:??:?? 0  /prefs/module.proto (92 Bytes)
DEBUG | ??:??:?? 0  /prefs/nodes.proto (245 Bytes)
[   651][I][esp32-hal-i2c.c:75] i2cInit(): Initialising I2C Master: sda=21 scl=22 freq=100000
[   664][I][XPowersCommon.tpp:453] begin(): SDA:21 SCL:22
[   669][W][Wire.cpp:301] begin(): Bus already started in Master Mode.
WARN  | ??:??:?? 0 No AXP2101 power management
[   678][I][XPowersAXP2101.tpp:216] ~XPowersAXP2101(): ~XPowersAXP2101
[   689][I][XPowersCommon.tpp:453] begin(): SDA:21 SCL:22
[   694][I][esp32-hal-i2c.c:75] i2cInit(): Initialising I2C Master: sda=21 scl=22 freq=100000
INFO  | ??:??:?? 0 AXP192 PMU init succeeded
[   710][D][XPowersAXP192.tpp:2026] setInterruptImpl(): setInterruptImpl DISABLE - 0xffffffffff

DEBUG | ??:??:?? 0 DC1  : +   Voltage:3300 mV 
DEBUG | ??:??:?? 0 DC2  : -   Voltage:1800 mV 
DEBUG | ??:??:?? 0 DC3  : +   Voltage:3300 mV 
DEBUG | ??:??:?? 0 LDO2 : +   Voltage:3300 mV 
DEBUG | ??:??:?? 0 LDO3 : +   Voltage:3300 mV 
DEBUG | ??:??:?? 0 Battery: usbPower=1, isCharging=1, batMv=3875, batPct=65
INFO  | ??:??:?? 0 Scan for i2c devices
[   763][W][Wire.cpp:301] begin(): Bus already started in Master Mode.
DEBUG | ??:??:?? 0 Scan for I2C devices on port 1
INFO  | ??:??:?? 0 AXP192/AXP2101 found at address 0x34
INFO  | ??:??:?? 0 SSD1306 found at address 0x3c
INFO  | ??:??:?? 0 SSD1306 found at address 0x3c
DEBUG | ??:??:?? 0 0x7 subtype probed in 2 tries 
INFO  | ??:??:?? 0 2 I2C devices found
DEBUG | ??:??:?? 0 acc_info = 0
INFO  | ??:??:?? 0 S:B:4,2.6.11.60ec05e
INFO  | ??:??:?? 0 Build timestamp: 1749659846
DEBUG | ??:??:?? 0 Total heap: 190928
DEBUG | ??:??:?? 0 Free heap: 156592
DEBUG | ??:??:?? 0 Total PSRAM: 4192107
DEBUG | ??:??:?? 0 Free PSRAM: 4191863
DEBUG | ??:??:?? 0 NVS: UsedEntries 99, FreeEntries 531, AllEntries 630, NameSpaces 5
DEBUG | ??:??:?? 0 Setup Preferences in Flash Storage
DEBUG | ??:??:?? 0 Number of Device Reboots: 2
INFO  | ??:??:?? 0 BLE OTA firmware version 0.3.0.47c250a
INFO  | ??:??:?? 0 No WiFi OTA firmware available
INFO  | ??:??:?? 0 Init NodeDB
[   911][E][vfs_api.cpp:105] open(): /littlefs/static/static does not exist, no permits for creation
[   930][E][vfs_api.cpp:105] open(): /littlefs/prefs/db.proto does not exist, no permits for creation
INFO  | ??:??:?? 0 Load /prefs/nodes.proto
INFO  | ??:??:?? 0 Loaded /prefs/nodes.proto successfully
INFO  | ??:??:?? 0 Loaded saved nodedatabase version 24, with nodes count: 1
INFO  | ??:??:?? 1 Load /prefs/device.proto
INFO  | ??:??:?? 1 Loaded /prefs/device.proto successfully
INFO  | ??:??:?? 1 Loaded saved devicestate version 24
INFO  | ??:??:?? 1 Load /prefs/config.proto
INFO  | ??:??:?? 1 Loaded /prefs/config.proto successfully
INFO  | ??:??:?? 1 Loaded saved config version 24
INFO  | ??:??:?? 1 Load /prefs/module.proto
INFO  | ??:??:?? 1 Loaded /prefs/module.proto successfully
INFO  | ??:??:?? 1 Loaded saved moduleConfig version 24
INFO  | ??:??:?? 1 Load /prefs/channels.proto
INFO  | ??:??:?? 1 Loaded /prefs/channels.proto successfully
INFO  | ??:??:?? 1 Loaded saved channelFile version 24
[  1151][E][vfs_api.cpp:105] open(): /littlefs/prefs/uiconfig.proto does not exist, no permits for creation
ERROR | ??:??:?? 1 Could not open / read /prefs/uiconfig.proto
DEBUG | ??:??:?? 1 cleanupMeshDB purged 0 entries
DEBUG | ??:??:?? 1 Use nodenum 0xa3f9d738 
DEBUG | ??:??:?? 1 Number of Device Reboots: 2
DEBUG | ??:??:?? 1 Expand short PSK #1
INFO  | ??:??:?? 1 Wanted region 4, using CN
DEBUG | ??:??:?? 1 Coerce telemetry to min of 30 minutes on defaults
DEBUG | ??:??:?? 1 Save to disk 4
DEBUG | ??:??:?? 1 Opening /prefs/device.proto, fullAtomic=1
INFO  | ??:??:?? 1 Save /prefs/device.proto
DEBUG | ??:??:?? 1 Use GPIO38 for button
DEBUG | ??:??:?? 1 SPI.begin(SCK=5, MISO=19, MOSI=27, NSS=18)
DEBUG | ??:??:?? 1 Use compiled/slipstreamed tzplaceholder                                         
DEBUG | ??:??:?? 1 Set Timezone to GMT0
DEBUG | ??:??:?? 1 Read RTC time as 1750263906
INFO  | ??:??:?? 1 GPS power state move from OFF to ACTIVE
DEBUG | ??:??:?? 1 Use GPIO34 for GPS RX
DEBUG | ??:??:?? 1 Use GPIO12 for GPS TX
DEBUG | ??:??:?? 1 Start multicast thread
DEBUG | ??:??:?? 1 NeighborInfoModule is disabled
DEBUG | ??:??:?? 1 Rescan for I2C keyboard
DEBUG | ??:??:?? 1 Scan for I2C devices on port 1
DEBUG | ??:??:?? 1 Scan address 0x1f
DEBUG | ??:??:?? 1 Scan address 0x34
INFO  | ??:??:?? 1 AXP192/AXP2101 found at address 0x34
DEBUG | ??:??:?? 1 Scan address 0x55
DEBUG | ??:??:?? 1 Scan address 0x5a
DEBUG | ??:??:?? 1 Scan address 0x5f
INFO  | ??:??:?? 1 External Notification Module Disabled
INFO  | ??:??:?? 1 Turn on screen
DEBUG | ??:??:?? 1 haveGlyphs=1
DEBUG | ??:??:?? 1 waypoint wants a UI Frame
DEBUG | ??:??:?? 1 canned wants a UI Frame
DEBUG | ??:??:?? 1 RF95Interface(cs=18, irq=26, rst=23, busy=33)
INFO  | ??:??:?? 1 Start meshradio init
INFO  | ??:??:?? 1 Radio freq=478.875, config.lora.frequency_offset=0.000
INFO  | ??:??:?? 1 Set radio: region=CN, name=LongFast, config=0, ch=35, power=19
INFO  | ??:??:?? 1 myRegion->freqStart -> myRegion->freqEnd: 470.000000 -> 510.000000 (40.000000 MHz)
INFO  | ??:??:?? 1 numChannels: 160 x 250.000kHz
INFO  | ??:??:?? 1 channel_num: 36
INFO  | ??:??:?? 1 frequency: 478.875000
INFO  | ??:??:?? 1 Slot time: 28 msec
INFO  | ??:??:?? 1 Final Tx power: 19 dBm
DEBUG | ??:??:?? 1 Current limit set to 100.000000
DEBUG | ??:??:?? 1 Current limit set result 0
INFO  | ??:??:?? 1 RF95 init result -13
INFO  | ??:??:?? 1 Frequency set to 478.875000
INFO  | ??:??:?? 1 Bandwidth set to 250.000000
INFO  | ??:??:?? 1 Power output set to 19
WARN  | ??:??:?? 1 No RF95 radio
DEBUG | ??:??:?? 1 SX126xInterface(cs=18, irq=33, rst=23, busy=32)
DEBUG | ??:??:?? 1 SX126X_DIO3_TCXO_VOLTAGE defined, using DIO3 as TCXO reference voltage at 1.800000 V
INFO  | ??:??:?? 1 Start meshradio init
INFO  | ??:??:?? 1 Radio freq=478.875, config.lora.frequency_offset=0.000
INFO  | ??:??:?? 1 Set radio: region=CN, name=LongFast, config=0, ch=35, power=19
INFO  | ??:??:?? 1 myRegion->freqStart -> myRegion->freqEnd: 470.000000 -> 510.000000 (40.000000 MHz)
INFO  | ??:??:?? 1 numChannels: 160 x 250.000kHz
INFO  | ??:??:?? 1 channel_num: 36
INFO  | ??:??:?? 1 frequency: 478.875000
INFO  | ??:??:?? 1 Slot time: 28 msec
INFO  | ??:??:?? 1 Final Tx power: 19 dBm
INFO  | ??:??:?? 28 SX126x init result -2
WARN  | ??:??:?? 28 No SX1262 radio
DEBUG | ??:??:?? 28 SX126xInterface(cs=18, irq=33, rst=23, busy=32)
DEBUG | ??:??:?? 28 SX126X_DIO3_TCXO_VOLTAGE not defined, not using DIO3 as TCXO reference voltage
INFO  | ??:??:?? 28 Start meshradio init
INFO  | ??:??:?? 28 Radio freq=478.875, config.lora.frequency_offset=0.000
INFO  | ??:??:?? 28 Set radio: region=CN, name=LongFast, config=0, ch=35, power=19
INFO  | ??:??:?? 28 myRegion->freqStart -> myRegion->freqEnd: 470.000000 -> 510.000000 (40.000000 MHz)
INFO  | ??:??:?? 28 numChannels: 160 x 250.000kHz
INFO  | ??:??:?? 28 channel_num: 36
INFO  | ??:??:?? 28 frequency: 478.875000
INFO  | ??:??:?? 28 Slot time: 28 msec
INFO  | ??:??:?? 28 Final Tx power: 19 dBm
INFO  | ??:??:?? 54 SX126x init result -2
WARN  | ??:??:?? 54 No SX1268 radio
INFO  | ??:??:?? 54 Not using WIFI
ERROR | ??:??:?? 54 NOTE! Record critical error 3 at src/main.cpp:1279
INFO  | ??:??:?? 54 PowerFSM init, USB power=1
DEBUG &j�??:??:?? 54 State: BOOT
[ 54818][D][esp32-hal-cpu.c:244] setCpuFrequencyMhz(): PLL: 320 / 4 = 80 Mhz, APB: 80000000 Hz
DEBUG | ??:??:?? 54 Free heap  :  128384 bytes
DEBUG | ??:??:?? 54 Free PSRAM : 4171651 bytes
DEBUG | ??:??:?? 54 [Power] Battery: usbPower=1, isCharging=1, batMv=3876, batPct=66
INFO  | ??:??:?? 54 [Screen] Done with boot screen
DEBUG | ??:??:?? 54 [Screen] Screen: Started...
DEBUG | ??:??:?? 54 [Screen] Screen: Critical error 3!
DEBUG | ??:??:?? 54 [Screen] Show standard frames
DEBUG | ??:??:?? 54 [Screen] Show 0 module frames
DEBUG | ??:??:?? 54 [Screen] Total frame count: 103
DEBUG | ??:??:?? 54 [Screen] Added modules.  numframes: 0
DEBUG | ??:??:?? 54 [Screen] Finished build frames. numframes: 3
DEBUG | ??:??:?? 54 [GPS] Probe for GPS at 9600
DEBUG | ??:??:?? 55 [GPS] Trying $PDTINFO (Unicore Family)...
DEBUG | ??:??:?? 55 [GPS] Trying $PCAS06,1*1A (ATGM33xx Family)...
DEBUG | ??:??:?? 56 [GPS] Trying $PAIR021*39 (Airoha Family)...
DEBUG | ??:??:?? 57 [GPS] Trying $PQTMVERNO*58 (LC86)...
DEBUG | ??:??:?? 57 [GPS] Trying $PCAS06,0*1B (L76K)...
DEBUG | ??:??:?? 58 [GPS] Trying $PMTK605*31 (MTK Family)...
DEBUG | ??:??:?? 58 [GPS] Module Info : 
DEBUG | ??:??:?? 58 [GPS] Soft version: 7.03 (45969)
DEBUG | ??:??:?? 58 [GPS] Hard version: 00040007
DEBUG | ??:??:?? 58 [GPS] Extensions:0
INFO  | ??:??:?? 58 [GPS] U-blox 6 detected
INFO  | ??:??:?? 61 [GPS] GNSS module config saved!
DEBUG | ??:??:?? 61 [GPS] Publish pos@0:2, hasVal=0, Sats=0, GPSlock=0
DEBUG | ??:??:?? 61 [GPS] No GPS lock
DEBUG | ??:??:?? 61 [GPS] onGPSChanged() pos@0 time=0 lat=0 lon=0 alt=0
INFO  | ??:??:?? 61 [GPS] updatePosition LOCAL pos@0 time=0 lat=0 lon=0 alt=0
DEBUG | ??:??:?? 61 [GPS] Set local position: lat=0 lon=0 time=0 timestamp=0
DEBUG | ??:??:?? 61 [GPS] Node status update: 0 online, 1 total
DEBUG | ??:??:?? 61 [GPS] Show standard frames
DEBUG | ??:??:?? 61 [GPS] Show 0 module frames
DEBUG | ??:??:?? 61 [GPS] Total frame count: 103
DEBUG | ??:??:?? 61 [GPS] Added modules.  numframes: 0
DEBUG | ??:??:?? 61 [GPS] Finished build frames. numframes: 3
INFO  | ??:??:?? 61 [NodeInfo] Send our nodeinfo to mesh (wantReplies=0)
INFO  | ??:??:?? 61 [NodeInfo] Send owner !a3f9d738/Meshtastic d738/d738
DEBUG | ??:??:?? 61 [NodeInfo] Initial packet id 1875146343
DEBUG | ??:??:?? 61 [NodeInfo] Partially randomized packet id 65400424
DEBUG | ??:??:?? 61 [NodeInfo] Update DB node 0xa3f9d738, rx_time=0
ERROR | ??:??:?? 61 [NodeInfo] Error=4, return NAK and drop packet
DEBUG | ??:??:?? 61 [NodeInfo] Partially randomized packet id 1734834793
WARN  | ??:??:?? 61 [NodeInfo] Alloc an err=4,to=0xa3f9d738,idFrom=0x3e5ee68,id=0x67677a69
DEBUG | ??:??:?? 61 [NodeInfo] Enqueued local (id=0x67677a69 fr=0xa3f9d738 to=0xa3f9d738, WantAck=0, HopLim=0 Ch=0x0 Portnum=5 requestId=3e5ee68 priority=120)
INFO  | ??:??:?? 61 [RangeTest] Range Test Module - Disabled
INFO  | ??:??:?? 61 [PowerFSM] Init the NimBLE bluetooth module
DEBUG | ??:??:?? 61 [Router] Rx someone rebroadcasting for us (id=0x67677a69 fr=0xa3f9d738 to=0xa3f9d738, WantAck=0, HopLim=0 Ch=0x0 Portnum=5 requestId=3e5ee68 priority=120)
DEBUG | ??:??:?? 62 [Router] Didn't find pending packet
DEBUG | ??:??:?? 62 [Router] Add packet record fr=0xa3f9d738, id=0x67677a69
DEBUG | ??:??:?? 62 [Router] handleReceived(REMOTE) (id=0x67677a69 fr=0xa3f9d738 to=0xa3f9d738, WantAck=0, HopLim=0 Ch=0x0 Portnum=5 requestId=3e5ee68 priority=120)
DEBUG | ??:??:?? 62 [Router] Module 'routing' wantsPacket=1
INFO  | ??:??:?? 62 [Router] Received routing from=0xa3f9d738, id=0x67677a69, portnum=5, payloadlen=2
DEBUG | ??:??:?? 62 [Router] Routing sniffing (id=0x67677a69 fr=0xa3f9d738 to=0xa3f9d738, WantAck=0, HopLim=0 Ch=0x0 Portnum=5 requestId=3e5ee68 priority=120)
DEBUG | ??:??:?? 62 [Router] Received a NAK for 0x0, stopping retransmissions
DEBUG | ??:??:?? 62 [Router] Delivering rx packet (id=0x67677a69 fr=0xa3f9d738 to=0xa3f9d738, WantAck=0, HopLim=0 Ch=0x0 Portnum=5 requestId=3e5ee68 priority=120)
DEBUG | ??:??:?? 62 [Router] Update DB node 0xa3f9d738, rx_time=0
DEBUG | ??:??:?? 62 [Router] Forwarding to phone (id=0x67677a69 fr=0xa3f9d738 to=0xa3f9d738, WantAck=0, HopLim=0 Ch=0x0 Portnum=5 requestId=3e5ee68 priority=120)
DEBUG | ??:??:?? 62 [Router] Module 'routing' considered
DEBUG | ??:??:?? 62 [GPS] NMEA GPS time 2025-06-18 16:26:07 age 3
DEBUG | ??:??:?? 62 [GPS] Upgrade time to quality GPS
DEBUG | 16:26:07 62 [GPS] Read RTC time as 1750263967
DEBUG | 16:26:07 62 [GPS] Publish pos@0:2, hasVal=0, Sats=0, GPSlock=0
DEBUG | 16:26:07 62 [GPS] onGPSChanged() pos@0 time=1750263967 lat=0 lon=0 alt=0
INFO  | 16:26:07 62 [GPS] updatePosition LOCAL pos@0 time=1750263967 lat=0 lon=0 alt=0
DEBUG | 16:26:07 62 [GPS] Set local position: lat=0 lon=0 time=1750263967 timestamp=0
DEBUG | 16:26:07 62 [GPS] Node status update: 1 online, 1 total
INFO  | 16:26:07 62 [DeviceTelemetry] Send: air_util_tx=0.000000, channel_utilization=0.000000, battery_level=101, voltage=3.876000, uptime=62
DEBUG | 16:26:07 62 [DeviceTelemetry] Partially randomized packet id 1320098410
DEBUG | 16:26:07 62 [DeviceTelemetry] updateTelemetry LOCAL
DEBUG | 16:26:07 62 [DeviceTelemetry] Node status update: 1 online, 1 total
INFO  | 16:26:07 62 [DeviceTelemetry] Send packet to mesh
DEBUG | 16:26:07 62 [DeviceTelemetry] Update DB node 0xa3f9d738, rx_time=1750263967
ERROR | 16:26:07 62 [DeviceTelemetry] Error=4, return NAK and drop packet
DEBUG | 16:26:07 62 [DeviceTelemetry] Partially randomized packet id 1109566059
WARN  | 16:26:07 62 [DeviceTelemetry] Alloc an err=4,to=0xa3f9d738,idFrom=0x4eaf1a6a,id=0x4222a26b
DEBUG | 16:26:07 62 [DeviceTelemetry] Enqueued local (id=0x4222a26b fr=0xa3f9d738 to=0xa3f9d738, WantAck=0, HopLim=0 Ch=0x0 Portnum=5 requestId=4eaf1a6a rxtime=1750263967 priority=120)
DEBUG | 16:26:07 62 [Router] Rx someone rebroadcasting for us (id=0x4222a26b fr=0xa3f9d738 to=0xa3f9d738, WantAck=0, HopLim=0 Ch=0x0 Portnum=5 requestId=4eaf1a6a rxtime=1750263967 priorit
DEBUG | 16:26:07 62 [Router] Didn't find pending packet
DEBUG | 16:26:07 62 [Router] Add packet record fr=0xa3f9d738, id=0x4222a26b
DEBUG | 16:26:07 62 [Router] handleReceived(REMOTE) (id=0x4222a26b fr=0xa3f9d738 to=0xa3f9d738, WantAck=0, HopLim=0 Ch=0x0 Portnum=5 requestId=4eaf1a6a rxtime=1750263967 priority=120)
DEBUG | 16:26:07 62 [Router] Module 'routing' wantsPacket=1
INFO  | 16:26:07 62 [Router] Received routing from=0xa3f9d738, id=0x4222a26b, portnum=5, payloadlen=2
DEBUG | 16:26:07 62 [Router] Routing sniffing (id=0x4222a26b fr=0xa3f9d738 to=0xa3f9d738, WantAck=0, HopLim=0 Ch=0x0 Portnum=5 requestId=4eaf1a6a rxtime=1750263967 priority=120)
DEBUG | 16:26:07 62 [Router] Received a NAK for 0x0, stopping retransmissions
DEBUG | 16:26:07 62 [Router] Delivering rx packet (id=0x4222a26b fr=0xa3f9d738 to=0xa3f9d738, WantAck=0, HopLim=0 Ch=0x0 Portnum=5 requestId=4eaf1a6a rxtime=1750263967 priority=120)
DEBUG | 16:26:07 62 [Router] Update DB node 0xa3f9d738, rx_time=1750263967
DEBUG | 16:26:07 62 [Router] Forwarding to phone (id=0x4222a26b fr=0xa3f9d738 to=0xa3f9d738, WantAck=0, HopLim=0 Ch=0x0 Portnum=5 requestId=4eaf1a6a rxtime=1750263967 priority=120)
DEBUG | 16:26:07 62 [Router] Module 'routing' considered
INFO  | 16:26:08 63 BLE authentication complete
DEBUG | 16:26:08 63 BluetoothStatus CONNECTED
INFO  | 16:26:10 65 BLE disconnect
DEBUG | 16:26:10 65 BluetoothStatus DISCONNECTED
DEBUG | 16:26:10 65 PhoneAPI::close()
DEBUG | 16:26:19 74 [Power] Battery: usbPower=1, isCharging=1, batMv=3878, batPct=66
DEBUG | 16:26:39 94 [Power] Battery: usbPower=1, isCharging=1, batMv=3878, batPct=66

SteveHawk avatar Jun 18 '25 17:06 SteveHawk

Thanks! Looking up

src/mesh/RF95Interface.cpp:252

fifieldt avatar Jun 19 '25 09:06 fifieldt

Confirmed. It failed setting the power.

@SteveHawk , could you try setting the power much lower and see if it works?

fifieldt avatar Jun 19 '25 09:06 fifieldt

Yes, it does appear to be the power issue.

The default power value is 30dBm, and I tried to set it as 3dBm CN470, this time it successfully initialized. Then I tried to set it back to 30dBm, and it got back into the critical error # 3 state.

Then I tried the workaround, and apparently somewhere down the chain the power is automatically reduced to 12dBm when using EU433, even when I have it set to 30dBm. So changing it to CN470 is no problem afterwards.

So what's the limitation here? Is this a sx1278 chip limitation?

SteveHawk avatar Jun 19 '25 14:06 SteveHawk

Tried a few power values around, the upper limit appears to be 17dBm. Which seems to align with some info about ax1278 on the internet.

SteveHawk avatar Jun 19 '25 15:06 SteveHawk

Thanks for the testing, @SteveHawk . Looking into whether this is in our code or RadioLib.

fifieldt avatar Jun 19 '25 22:06 fifieldt

OK, so, tracing power settings ....

what's happening is that the power is being limited to the max power in the region(19), rather than the max power of the radio (17) ? It looks like we assumed that the region always allows less than the radio, which is not true in this case?

Notes:

bool RF95Interface::init()
    RadioLibInterface::init(); --> didn't check yet.

    limitPower(); ---> 

void RadioInterface::limitPower()
{
    uint8_t maxPower = 255; // No limit

    if (myRegion->powerLimit)  ---> == 19
        maxPower = myRegion->powerLimit; == 19

    if ((power > maxPower) && !devicestate.owner.is_licensed) { --> trying for 30 here means power is set to 19
        LOG_INFO("Lower transmit power because of regulatory limits");
        power = maxPower;
    }

    if (TX_GAIN_LORA > 0) { ---> 0, does not run.
        LOG_INFO("Requested Tx power: %d dBm; Device LoRa Tx gain: %d dB", power, TX_GAIN_LORA);
        power -= TX_GAIN_LORA;
    }

    LOG_INFO("Final Tx power: %d dBm", power);
}

Now back to init:

if (power > RF95_MAX_POWER) // This chip has lower power limits than some --> 19 > 
    power = RF95_MAX_POWER;

fifieldt avatar Jun 19 '25 23:06 fifieldt

@fifieldt how close did you get to a resolution?

@ndoo you've done a lot of work with power level setting. Do the notes above make sense?

NomDeTom avatar Aug 12 '25 11:08 NomDeTom

This should have been fixed since https://github.com/meshtastic/firmware/pull/7065 - specifically this commit and line: https://github.com/meshtastic/firmware/pull/7065/commits/9ba4d8068934c5ad219ab0b3c1577802523b48bb#diff-ad55475cca6c487a8b020a5f8036f160cd1fc92cce2ea887bfe83f284a6fc855

ndoo avatar Aug 12 '25 16:08 ndoo

@SteveHawk could you test and see if it's fixed, please?

NomDeTom avatar Aug 12 '25 16:08 NomDeTom

Tested 2.7.3 and 2.7.4, still not working. Firmware still tries to limit power to 19dbm when upper limit is 17dbm.

Relevant Log (2.7.4):

INFO  | ??:??:?? 1 Start meshradio init
INFO  | ??:??:?? 1 Radio freq=478.875, config.lora.frequency_offset=0.000
INFO  | ??:??:?? 1 Set radio: region=CN, name=LongFast, config=0, ch=35, power=19
INFO  | ??:??:?? 1 myRegion->freqStart -> myRegion->freqEnd: 470.000000 -> 510.000000 (40.000000 MHz)
INFO  | ??:??:?? 1 numChannels: 160 x 250.000kHz
INFO  | ??:??:?? 1 channel_num: 36
INFO  | ??:??:?? 1 frequency: 478.875000
INFO  | ??:??:?? 1 Slot time: 28 msec
INFO  | ??:??:?? 1 Final Tx power: 19 dBm
DEBUG | ??:??:?? 1 Current limit set to 100.000000
DEBUG | ??:??:?? 1 Current limit set result 0
INFO  | ??:??:?? 1 RF95 init result -13
INFO  | ??:??:?? 1 Frequency set to 478.875000
INFO  | ??:??:?? 1 Bandwidth set to 250.000000
INFO  | ??:??:?? 1 Power output set to 19
WARN  | ??:??:?? 1 No RF95 radio
DEBUG | ??:??:?? 1 SX126xInterface(cs=18, irq=33, rst=23, busy=32)
DEBUG | ??:??:?? 1 SX126X_DIO3_TCXO_VOLTAGE defined, using DIO3 as TCXO reference voltage at 1.800000 V
INFO  | ??:??:?? 1 Start meshradio init
INFO  | ??:??:?? 1 Radio freq=478.875, config.lora.frequency_offset=0.000
INFO  | ??:??:?? 1 Set radio: region=CN, name=LongFast, config=0, ch=35, power=19
INFO  | ??:??:?? 1 myRegion->freqStart -> myRegion->freqEnd: 470.000000 -> 510.000000 (40.000000 MHz)
INFO  | ??:??:?? 1 numChannels: 160 x 250.000kHz
INFO  | ??:??:?? 1 channel_num: 36
INFO  | ??:??:?? 1 frequency: 478.875000
INFO  | ??:??:?? 1 Slot time: 28 msec
INFO  | ??:??:?? 1 Final Tx power: 19 dBm
INFO  | ??:??:?? 28 SX126x init result -2
WARN  | ??:??:?? 28 No SX1262 radio
DEBUG | ??:??:?? 28 SX126xInterface(cs=18, irq=33, rst=23, busy=32)
DEBUG | ??:??:?? 28 SX126X_DIO3_TCXO_VOLTAGE not defined, not using DIO3 as TCXO reference voltage
INFO  | ??:??:?? 28 Start meshradio init
INFO  | ??:??:?? 28 Radio freq=478.875, config.lora.frequency_offset=0.000
INFO  | ??:??:?? 28 Set radio: region=CN, name=LongFast, config=0, ch=35, power=19
INFO  | ??:??:?? 28 myRegion->freqStart -> myRegion->freqEnd: 470.000000 -> 510.000000 (40.000000 MHz)
INFO  | ??:??:?? 28 numChannels: 160 x 250.000kHz
INFO  | ??:??:?? 28 channel_num: 36
INFO  | ??:??:?? 28 frequency: 478.875000
INFO  | ??:??:?? 28 Slot time: 28 msec
INFO  | ??:??:?? 28 Final Tx power: 19 dBm
INFO  | ??:??:?? 54 SX126x init result -2
WARN  | ??:??:?? 54 No SX1268 radio
INFO  | ??:??:?? 54 Not using WIFI
ERROR | ??:??:?? 54 NOTE! Record critical error 3 at src/main.cpp:1431

SteveHawk avatar Aug 12 '25 18:08 SteveHawk

Tested 2.7.3 and 2.7.4, still not working. Firmware still tries to limit power to 19dbm when upper limit is 17dbm.

Relevant Log (2.7.4):

INFO  | ??:??:?? 1 Start meshradio init
INFO  | ??:??:?? 1 Radio freq=478.875, config.lora.frequency_offset=0.000
INFO  | ??:??:?? 1 Set radio: region=CN, name=LongFast, config=0, ch=35, power=19
INFO  | ??:??:?? 1 myRegion->freqStart -> myRegion->freqEnd: 470.000000 -> 510.000000 (40.000000 MHz)
INFO  | ??:??:?? 1 numChannels: 160 x 250.000kHz
INFO  | ??:??:?? 1 channel_num: 36
INFO  | ??:??:?? 1 frequency: 478.875000
INFO  | ??:??:?? 1 Slot time: 28 msec
INFO  | ??:??:?? 1 Final Tx power: 19 dBm
DEBUG | ??:??:?? 1 Current limit set to 100.000000
DEBUG | ??:??:?? 1 Current limit set result 0
INFO  | ??:??:?? 1 RF95 init result -13
INFO  | ??:??:?? 1 Frequency set to 478.875000
INFO  | ??:??:?? 1 Bandwidth set to 250.000000
INFO  | ??:??:?? 1 Power output set to 19
WARN  | ??:??:?? 1 No RF95 radio
DEBUG | ??:??:?? 1 SX126xInterface(cs=18, irq=33, rst=23, busy=32)
DEBUG | ??:??:?? 1 SX126X_DIO3_TCXO_VOLTAGE defined, using DIO3 as TCXO reference voltage at 1.800000 V
INFO  | ??:??:?? 1 Start meshradio init
INFO  | ??:??:?? 1 Radio freq=478.875, config.lora.frequency_offset=0.000
INFO  | ??:??:?? 1 Set radio: region=CN, name=LongFast, config=0, ch=35, power=19
INFO  | ??:??:?? 1 myRegion->freqStart -> myRegion->freqEnd: 470.000000 -> 510.000000 (40.000000 MHz)
INFO  | ??:??:?? 1 numChannels: 160 x 250.000kHz
INFO  | ??:??:?? 1 channel_num: 36
INFO  | ??:??:?? 1 frequency: 478.875000
INFO  | ??:??:?? 1 Slot time: 28 msec
INFO  | ??:??:?? 1 Final Tx power: 19 dBm
INFO  | ??:??:?? 28 SX126x init result -2
WARN  | ??:??:?? 28 No SX1262 radio
DEBUG | ??:??:?? 28 SX126xInterface(cs=18, irq=33, rst=23, busy=32)
DEBUG | ??:??:?? 28 SX126X_DIO3_TCXO_VOLTAGE not defined, not using DIO3 as TCXO reference voltage
INFO  | ??:??:?? 28 Start meshradio init
INFO  | ??:??:?? 28 Radio freq=478.875, config.lora.frequency_offset=0.000
INFO  | ??:??:?? 28 Set radio: region=CN, name=LongFast, config=0, ch=35, power=19
INFO  | ??:??:?? 28 myRegion->freqStart -> myRegion->freqEnd: 470.000000 -> 510.000000 (40.000000 MHz)
INFO  | ??:??:?? 28 numChannels: 160 x 250.000kHz
INFO  | ??:??:?? 28 channel_num: 36
INFO  | ??:??:?? 28 frequency: 478.875000
INFO  | ??:??:?? 28 Slot time: 28 msec
INFO  | ??:??:?? 28 Final Tx power: 19 dBm
INFO  | ??:??:?? 54 SX126x init result -2
WARN  | ??:??:?? 54 No SX1268 radio
INFO  | ??:??:?? 54 Not using WIFI
ERROR | ??:??:?? 54 NOTE! Record critical error 3 at src/main.cpp:1431

Just to confirm, it is a SX1268?

ndoo avatar Aug 12 '25 18:08 ndoo

Is it an SX1268, SX1278, etc.? I can't find any information about it being limited to 17dBm.

At any rate, it seems like it can't be autodetected well unless #define RF95_MAX_POWER is set lower at 17dBm in the variant, but then this will affect the HF RFM95 modules.

ndoo avatar Aug 12 '25 18:08 ndoo

I believe my unit is sx1278.

SteveHawk avatar Aug 12 '25 18:08 SteveHawk

I found this discussion thread mentioning sx1278 being limited to 17dbm and such: https://github.com/jgromes/RadioLib/discussions/308

I'm not sure, but could this relate to current limit? I checked the datasheet on semtech's website here: https://www.semtech.com/products/wireless-rf/lora-connect/sx1278, and it says sx1278 can do 20dbm but current is going to be 120mA, and below that is 17dbm@87mA.

Image

SteveHawk avatar Aug 12 '25 18:08 SteveHawk

Weird, I would like to hear from other RFM95 SX1278 users if they have such issues. I never heard of the chip only supporting 17dBm.

Does it initialize correctly once a lower txpower is set?

On Wed, Aug 13, 2025 at 2:52 AM Steve @.***> wrote:

SteveHawk left a comment (meshtastic/firmware#3791) https://github.com/meshtastic/firmware/issues/3791#issuecomment-3180584626

I found this discussion thread mentioning sx1278 being limited to 17dbm and such: jgromes/RadioLib#308 https://github.com/jgromes/RadioLib/discussions/308

I'm not sure, but could this relate to current limit? I checked the datasheet on semtech's website here: https://www.semtech.com/products/wireless-rf/lora-connect/sx1278, and it says sx1278 can do 20dbm but current is going to be 120mA, and below that is @.*** image.png (view on web) https://github.com/user-attachments/assets/1650d0a3-2c10-4b61-bef0-4850d65a2bae

— Reply to this email directly, view it on GitHub https://github.com/meshtastic/firmware/issues/3791#issuecomment-3180584626, or unsubscribe https://github.com/notifications/unsubscribe-auth/AACAFACCHJPEDXFWXBJOQS33NIZYHAVCNFSM6AAAAAB7L4JMRCVHI2DSMVQWIX3LMV43OSLTON2WKQ3PNVWWK3TUHMZTCOBQGU4DINRSGY . You are receiving this because you were mentioned.Message ID: @.***>

ndoo avatar Aug 12 '25 18:08 ndoo

Does it initialize correctly once a lower txpower is set?

Yes, manually set it to 17dbm and it initializes correctly. Any value above that (18/19) it's not happy and errors out.

And it does seem to relate to power limit somehow, I see this line in boot log: Current limit set to 100.000000 (And also in all previous logs)

INFO  | ??:??:?? 1 Start meshradio init
INFO  | ??:??:?? 1 Radio freq=478.875, config.lora.frequency_offset=0.000
INFO  | ??:??:?? 1 Set radio: region=CN, name=LongFast, config=0, ch=35, power=17
INFO  | ??:??:?? 1 myRegion->freqStart -> myRegion->freqEnd: 470.000000 -> 510.000000 (40.000000 MHz)
INFO  | ??:??:?? 1 numChannels: 160 x 250.000kHz
INFO  | ??:??:?? 1 channel_num: 36
INFO  | ??:??:?? 1 frequency: 478.875000
INFO  | ??:??:?? 1 Slot time: 28 msec
INFO  | ??:??:?? 1 Final Tx power: 17 dBm
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 478.875000
INFO  | ??:??:?? 1 Bandwidth set to 250.000000
INFO  | ??:??:?? 1 Power output set to 17
INFO  | ??:??:?? 1 RF95 init success

But I do remember way back in the early days when I bought the unit, everything works. I might try to flash a very old firmware (2.2 maybe?) and check the log see what's the difference.

SteveHawk avatar Aug 12 '25 19:08 SteveHawk

Does it initialize correctly once a lower txpower is set?

Yes, manually set it to 17dbm and it initializes correctly. Any value above that (18/19) it's not happy and errors out.

And it does seem to relate to power limit somehow, I see this line in boot log: Current limit set to 100.000000 (And also in all previous logs)

INFO  | ??:??:?? 1 Start meshradio init
INFO  | ??:??:?? 1 Radio freq=478.875, config.lora.frequency_offset=0.000
INFO  | ??:??:?? 1 Set radio: region=CN, name=LongFast, config=0, ch=35, power=17
INFO  | ??:??:?? 1 myRegion->freqStart -> myRegion->freqEnd: 470.000000 -> 510.000000 (40.000000 MHz)
INFO  | ??:??:?? 1 numChannels: 160 x 250.000kHz
INFO  | ??:??:?? 1 channel_num: 36
INFO  | ??:??:?? 1 frequency: 478.875000
INFO  | ??:??:?? 1 Slot time: 28 msec
INFO  | ??:??:?? 1 Final Tx power: 17 dBm
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 478.875000
INFO  | ??:??:?? 1 Bandwidth set to 250.000000
INFO  | ??:??:?? 1 Power output set to 17
INFO  | ??:??:?? 1 RF95 init success

But I do remember way back in the early days when I bought the unit, everything works. I might try to flash a very old firmware (2.2 maybe?) and check the log see what's the difference.

If you are set up to build firmware, try changing this line to something else, e.g. 120: https://github.com/meshtastic/firmware/blob/e3dd8164a423a12d88232475a529233144d775b0/src/mesh/RadioLibRF95.h#L71

This was changed in 2021 so even a relatively old version of Meshtastic from after will probably show the same symptoms you are experiencing now. https://github.com/meshtastic/firmware/commit/1a671f287753e9140b72dd8bd83676766059fa0b

ndoo avatar Aug 12 '25 19:08 ndoo

This happens too https://github.com/meshtastic/firmware/issues/6668

garthvh avatar Aug 12 '25 20:08 garthvh

I went all the way back to firmware v2.0.2 (Nov 10, 2022), that's around the time I purchased this unit (it worked back then). And it works! It successfully initialized with 19dbm and 100mA settings.

??:??:?? 1 Starting meshradio init...
??:??:?? 1 Set radio: region=CN, name=LongFast, config=0, ch=35, power=19
??:??:?? 1 Radio myRegion->freqStart / myRegion->freqEnd: 470.000000 -> 510.000000 (40.000000 mhz)
??:??:?? 1 Radio myRegion->numChannels: 160
??:??:?? 1 Radio channel_num: 35
??:??:?? 1 Radio frequency: 478.875000
??:??:?? 1 Slot time: 42 msec
??:??:?? 1 Set radio: final power level=19
??:??:?? 1 Current limit set to 100.000000
??:??:?? 1 Current limit set result 0
??:??:?? 1 RF95 init result -13
??:??:?? 1 Frequency set to 478.875000
??:??:?? 1 Bandwidth set to 250.000000
??:??:?? 1 Power output set to 19
??:??:?? 1 Current limit set to 100.000000
??:??:?? 1 Current limit set result 0
??:??:?? 1 RF95 Radio init succeeded, using RF95 radio

Guess I can try to bisect and find the version when it breaks...

SteveHawk avatar Aug 12 '25 21:08 SteveHawk

2.0.20 works

INFO  | ??:??:?? 1 Starting meshradio init...
INFO  | ??:??:?? 1 Radio freq=478.875, config.lora.frequency_offset=0.000
INFO  | ??:??:?? 1 Set radio: region=CN, name=LongFast, config=0, ch=35, power=19
INFO  | ??:??:?? 1 Radio myRegion->freqStart -> myRegion->freqEnd: 470.000000 -> 510.000000 (40.000000 mhz)
INFO  | ??:??:?? 1 Radio myRegion->numChannels: 160 x 250.000kHz
INFO  | ??:??:?? 1 Radio channel_num: 35
INFO  | ??:??:?? 1 Radio frequency: 478.875000
INFO  | ??:??:?? 1 Slot time: 42 msec
INFO  | ??:??:?? 1 Set radio: final power level=19
DEBUG | ??:??:?? 1 Current limit set to 100.000000
DEBUG | ??:??:?? 1 Current limit set result 0
INFO  | ??:??:?? 1 RF95 init result -13
INFO  | ??:??:?? 1 Frequency set to 478.875000
INFO  | ??:??:?? 1 Bandwidth set to 250.000000
INFO  | ??:??:?? 1 Power output set to 19
DEBUG | ??:??:?? 1 Current limit set to 100.000000
DEBUG | ??:??:?? 1 Current limit set result 0
INFO  | ??:??:?? 1 RF95 Radio init succeeded, using RF95 radio

2.0.21 doesn't work

INFO  | ??:??:?? 1 Starting meshradio init...
INFO  | ??:??:?? 1 Radio freq=478.875, config.lora.frequency_offset=0.000
INFO  | ??:??:?? 1 Set radio: region=CN, name=LongFast, config=0, ch=35, power=19
INFO  | ??:??:?? 1 Radio myRegion->freqStart -> myRegion->freqEnd: 470.000000 -> 510.000000 (40.000000 mhz)
INFO  | ??:??:?? 1 Radio myRegion->numChannels: 160 x 250.000kHz
INFO  | ??:??:?? 1 Radio channel_num: 35
INFO  | ??:??:?? 1 Radio frequency: 478.875000
INFO  | ??:??:?? 1 Slot time: 42 msec
INFO  | ??:??:?? 1 Set radio: final power level=19
INFO  | ??:??:?? 7 SX126x init result -705
INFO  | ??:??:?? 7 Frequency set to 478.875000
INFO  | ??:??:?? 7 Bandwidth set to 250.000000
INFO  | ??:??:?? 7 Power output set to 19
DEBUG | ??:??:?? 12 Current limit set to 140.000000
DEBUG | ??:??:?? 12 Current limit set result -705
INFO  | ??:??:?? 17 Set Rx Power Saving Gain mode; result: 64831
WARN  | ??:??:?? 17 Failed to find SX1262 radio
WARN  | ??:??:?? 17 SX126xInterface(cs=18, irq=33, rst=23, busy=32)
INFO  | ??:??:?? 17 Starting meshradio init...
INFO  | ??:??:?? 17 Radio freq=478.875, config.lora.frequency_offset=0.000
INFO  | ??:??:?? 17 Set radio: region=CN, name=LongFast, config=0, ch=35, power=19
INFO  | ??:??:?? 17 Radio myRegion->freqStart -> myRegion->freqEnd: 470.000000 -> 510.000000 (40.000000 mhz)
INFO  | ??:??:?? 17 Radio myRegion->numChannels: 160 x 250.000kHz
INFO  | ??:??:?? 17 Radio channel_num: 35
INFO  | ??:??:?? 17 Radio frequency: 433.174988
INFO  | ??:??:?? 17 Slot time: 42 msec
INFO  | ??:??:?? 17 Set radio: final power level=19
INFO  | ??:??:?? 23 SX126x init result -705
INFO  | ??:??:?? 23 Frequency set to 433.174988
INFO  | ??:??:?? 23 Bandwidth set to 250.000000
INFO  | ??:??:?? 23 Power output set to 19
DEBUG | ??:??:?? 28 Current limit set to 140.000000
DEBUG | ??:??:?? 28 Current limit set result -705
INFO  | ??:??:?? 33 Set Rx Power Saving Gain mode; result: 64831
WARN  | ??:??:?? 33 Failed to find SX1268 radio
INFO  | ??:??:?? 33 Not using WIFI
ERROR | ??:??:?? 33 NOTE! Recording critical erro?
??src/main.cpp:513
INFO  | ??:??:?? 33 PowerFSM init, USB power=1
INFO  | ??:??:?? 33 Enter state: BOOT
[ 33292][D][esp32-hal-cpu.c:244] setCpuFrequencyMhz(): PLL: 320 / 4 = 80 Mhz, APB: 80000000 Hz
Guru Meditation Error: Core  1 panic'ed (LoadProhibited). Exception was unhandled.

So something in between probably breaks it. https://github.com/meshtastic/firmware/compare/v2.0.20.7100416...v2.0.21.83e6cea

SteveHawk avatar Aug 12 '25 22:08 SteveHawk

Another related report: https://github.com/meshtastic/firmware/issues/6261

TTGO Lora32 v2.1-1.6.1 is also using sx1276/1278.

SteveHawk avatar Aug 13 '25 07:08 SteveHawk

Spent some time digging into the codes, and realized something very weird.

The 17dbm limit actually comes from radiolib (RadioLib/src/modules/SX127x/SX1278.cpp, setOutputPower/checkOutputPower), it only allows output power of <=17dbm or 20dbm, not in between (ie 18/19dbm). This behavior has been there since many years ago. After temporarily lifting the 19dbm regulatory limit in firmware, I successfully init'd tbeam's sx1278 at 17dbm and 20dbm.

And the above "success" logs @19dbm all show this line: RF95 init result -13, which means RADIOLIB_ERR_INVALID_OUTPUT_POWER. However, somehow versions before v2.0.20 ignores this and thinks it inits successfully, and also is somehow able to actually use the radio in that way.

Not entirely sure yet, but my gut feeling is that it worked before v2.0.20 because of some kind of bug+bug=works magic, and v2.0.21 fixed one of the bug and breaks it.

SteveHawk avatar Sep 03 '25 08:09 SteveHawk

So, would simply adding a check to set 17dBm if 17 < txpower < 20 is requested, resolve the issue?

ndoo avatar Sep 03 '25 08:09 ndoo

For sx1278 yes, I think so. (maybe also sx1276 too, seems like codes in radiolib inherits from 1278)

SteveHawk avatar Sep 03 '25 08:09 SteveHawk