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

[SteveHawk]

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)