[Bug]: RX problem sx1276 with 2.5.4

[lagunacomputer]

Category

Other

Hardware

T-Beam, DIY, Other

Firmware Version

2.5.4 or 2.5.3a70d5ee

Description

Hope to save someone some time if they don't get any external node/RX activity after the latest alpha is flashed, and they have preset the Region in userPrefs.h.

Environment: Build your own firmware, download latest github code (git clone https://github.com/meshtastic/firmware.git), tbeam clone DIY hardware w/ RMF95

Bug Description: If you pre-configure userPrefs.h with "#define CONFIG_LORA_REGION_USERPREFS meshtastic_Config_LoRaConfig_RegionCode_US", then upon flashing and rebooting the node, RX does not work.

Workaround: After flashing, goto Lora Config, temporarily change Region to something else (from US to Malaysia in my case). Save. Reboot. Go back to Lora Config, and change the Region back to US. Save. Reboot. Now RX works.

Note: the problem did not appear on my other node that has a E22 SX1262 radio. The problem seems to only happen on the node with the RMF95/SX1276 radio

*UPDATE:

1. Reverting back to 2.5.2.fa1cc598 from 2.5.4 definitely fixes the issue on the problem node.
2. Under 2.5.4, the SX1276 node will only receive 1-2 node discoveries and then all new node discoveries seem to stop.
3. Under 2.5.4, the node( node1, 2.5.4, SX1276) will send a node discovery to the other node ( node2, running 2.5.4 without issue, SX1262), and the discovery is shown on the other node2, however the return discovery back to node#2 does not show or populate in the node list of the problem node1 with 2.5.4 firmware (node discovery is one-sided).

Relevant log output

No response

[lagunacomputer]

DEBUG | ??:??:?? 25 [Router] Initial packet id 722601885
DEBUG | ??:??:?? 25 [Router] Partially randomized packet id 367991710
DEBUG | ??:??:?? 25 [Router] Enqueued local (id=0x15ef1b9e fr=0x68 to=0x68, WantAck=0, HopLim=3 Ch=0x0 Portnum=5 requestId=d404bacd priority=120)
DEBUG | ??:??:?? 25 [Router] Module 'routing' considered
DEBUG | ??:??:?? 25 [Router] Rx someone rebroadcasting for us (id=0x15ef1b9e fr=0x68 to=0x68, WantAck=0, HopLim=3 Ch=0x0 Portnum=5 requestId=d404bacd priority=120)
DEBUG | ??:??:?? 25 [Router] didn't find pending packet
DEBUG | ??:??:?? 25 [Router] Add packet record (id=0x15ef1b9e fr=0x68 to=0x68, WantAck=0, HopLim=3 Ch=0x0 Portnum=5 requestId=d404bacd priority=120)
DEBUG | ??:??:?? 25 [Router] handleReceived(REMOTE) (id=0x15ef1b9e fr=0x68 to=0x68, WantAck=0, HopLim=3 Ch=0x0 Portnum=5 requestId=d404bacd priority=120)
DEBUG | ??:??:?? 25 [Router] Module 'routing' wantsPacket=1
INFO | ??:??:?? 25 [Router] Received routing from=0xe3a93468, id=0x15ef1b9e, portnum=5, payloadlen=2
DEBUG | ??:??:?? 25 [Router] Routing sniffing (id=0x15ef1b9e fr=0x68 to=0x68, WantAck=0, HopLim=3 Ch=0x0 Portnum=5 requestId=d404bacd priority=120)
DEBUG | ??:??:?? 25 [Router] Received an ack for 0xd404bacd, stopping retransmissions
DEBUG | ??:??:?? 25 [Router] Delivering rx packet (id=0x15ef1b9e fr=0x68 to=0x68, WantAck=0, HopLim=3 Ch=0x0 Portnum=5 requestId=d404bacd priority=120)
DEBUG | ??:??:?? 25 [Router] Update DB node 0xe3a93468, rx_time=0
DEBUG | ??:??:?? 25 [Router] Forwarding to phone (id=0x15ef1b9e fr=0x68 to=0x68, WantAck=0, HopLim=3 Ch=0x0 Portnum=5 requestId=d404bacd priority=120)
DEBUG | ??:??:?? 25 [Router] Module 'routing' considered
INFO | ??:??:?? 30 [NodeInfoModule] Sending our nodeinfo to mesh (wantReplies=1)
INFO | ??:??:?? 30 [NodeInfoModule] sending owner !e3a93468/KM6HHP_base/KM6H
DEBUG | ??:??:?? 30 [NodeInfoModule] Partially randomized packet id 158296991
DEBUG | ??:??:?? 30 [NodeInfoModule] Update DB node 0xe3a93468, rx_time=0
DEBUG | ??:??:?? 30 [NodeInfoModule] handleReceived(LOCAL) (id=0x096f6b9f fr=0x68 to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=4 WANTRESP priority=10)
DEBUG | ??:??:?? 30 [NodeInfoModule] No modules interested in portnum=4, src=LOCAL
DEBUG | ??:??:?? 30 [NodeInfoModule] Add packet record (id=0x096f6b9f fr=0x68 to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=4 WANTRESP priority=10)
DEBUG | ??:??:?? 30 [NodeInfoModule] Expanding short PSK #1
DEBUG | ??:??:?? 30 [NodeInfoModule] Using AES128 key!
DEBUG | ??:??:?? 30 [NodeInfoModule] enqueuing for send (id=0x096f6b9f fr=0x68 to=0xff, WantAck=0, HopLim=3 Ch=0x8 encrypted hopStart=3 priority=10)
DEBUG | ??:??:?? 30 [NodeInfoModule] txGood=0,rxGood=0,rxBad=0
DEBUG | ??:??:?? 30 [RadioIf] Starting low level send (id=0x096f6b9f fr=0x68 to=0xff, WantAck=0, HopLim=3 Ch=0x8 encrypted hopStart=3 priority=10)
DEBUG | ??:??:?? 30 [RadioIf] (bw=250, sf=11, cr=4/5) packet symLen=8 ms, payloadSize=99, time 968 ms
DEBUG | ??:??:?? 30 [RadioIf] AirTime - Packet transmitted : 968ms
DEBUG | ??:??:?? 31 [RadioIf] Completed sending (id=0x096f6b9f fr=0x68 to=0xff, WantAck=0, HopLim=3 Ch=0x8 encrypted hopStart=3 priority=10)
INFO | ??:??:?? 32 Rebooting
ets Jun 8 2016 00:22:57

rst:0xc (SW_CPU_RESET),boot:0x17 (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 (850) esp_core_dump_flash: No core dump▒▒ѥѥ▒▒▒found!
E (850) esp_core_dump_flash: No core dump partition found!
[ 11][D][esp32-hal-cpu.c:244] setCpuFrequencyMhz(): PLL: 480 / 2 = 240 Mhz, APB: 80000000 Hz
E (601) psram: PSRAM ID read error: 0xffffffff
[ 27][W][esp32-hal-psram.c:71] psramInit(): PSRAM init failed!
▒▒@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 (166 Bytes)
DEBUG | ??:??:?? 0 /prefs/db.proto (389 Bytes)
[ 115][I][esp32-hal-i2c.c:75] i2cInit(): Initialising I2C Master: sda=5 scl=4 freq=100000
DEBUG | ??:??:?? 0 Power::lipoInit lipo sensor is not ready yet
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...
[ 155][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 0x6 subtype probed in 2 tries
INFO | ??:??:?? 0 1 I2C devices found
DEBUG | ??:??:?? 0 acc_info = 0
INFO | ??:??:?? 0 S:B:255,2.5.4.d73cbf14
INFO | ??:??:?? 0 Build timestamp: 1727633396
DEBUG | ??:??:?? 0 Total heap: 187640
DEBUG | ??:??:?? 0 Free heap: 152728
DEBUG | ??:??:?? 0 Total PSRAM: 0
DEBUG | ??:??:?? 0 Free PSRAM: 0
DEBUG | ??:??:?? 0 NVS: UsedEntries 98, FreeEntries 532, AllEntries 630, NameSpaces 4
DEBUG | ??:??:?? 0 Setup Preferences in Flash Storage
DEBUG | ??:??:?? 0 Number of Device Reboots: 3
ESP_ERROR_CHECK_WITHOUT_ABORT failed: esp_err_t 0x105 (ESP_ERR_NOT_FOUND) at 0x4009894b
file: "src/platform/esp32/BleOta.cpp" line 16
func: static const esp_partition_t* BleOta::findEspOtaAppPartition()
expression: esp_ota_get_partition_description(part, &app_desc)
ESP_ERROR_CHECK_WITHOUT_ABORT failed: esp_err_t 0x102 (ESP_ERR_INVALID_ARG) at 0x4009894b
file: "src/platform/esp32/BleOta.cpp" line 30
func: static String BleOta::getOtaAppVersion()
expression: esp_ota_get_partition_description(part, &app_desc)
INFO | ??:??:?? 0 No OTA firmware available
INFO | ??:??:?? 0 Initializing NodeDB
INFO | ??:??:?? 0 Loading /prefs/db.proto
INFO | ??:??:?? 0 Loaded /prefs/db.proto successfully
INFO | ??:??:?? 0 Loaded saved devicestate version 23, with nodecount: 1
INFO | ??:??:?? 0 Loading /prefs/config.proto
INFO | ??:??:?? 0 Loaded /prefs/config.proto successfully
INFO | ??:??:?? 0 Loaded saved config version 23
[ 371][E][vfs_api.cpp:105] open(): /littlefs/prefs/module.proto does not exist, no permits for creation
ERROR | ??:??:?? 0 Could not open / read /prefs/module.proto
INFO | ??:??:?? 0 Installing default ModuleConfig
INFO | ??:??:?? 0 Loading /prefs/channels.proto
INFO | ??:??:?? 0 Loaded /prefs/channels.proto successfully
INFO | ??:??:?? 0 Loaded saved channelFile version 23
[ 415][E][vfs_api.cpp:105] open(): /littlefs/oem/oem.proto does not exist, no permits for creation
ERROR | ??:??:?? 0 Could not open / read /oem/oem.proto
DEBUG | ??:??:?? 0 cleanupMeshDB purged 0 entries
DEBUG | ??:??:?? 0 Using nodenum 0xe3a93468
DEBUG | ??:??:?? 0 Old Pubkey 3e 7e 65 38 26 8e 8c 35 70 7e 91 7c 2e 4d 83 57 6a 0b 20 4e 7c 00 4b 90 cb 1d 2a ac 10 1c 0d 58
INFO | ??:??:?? 0 Using saved PKI keys
DEBUG | ??:??:?? 0 Number of Device Reboots: 3
DEBUG | ??:??:?? 0 Expanding short PSK #1
INFO | ??:??:?? 0 Wanted region 1, using US
INFO | ??:??:?? 0 Saving /prefs/db.proto
DEBUG | ??:??:?? 0 Using GPIO16 for button
DEBUG | ??:??:?? 0 SPI.begin(SCK=18, MISO=19, MOSI=23, NSS=17)
DEBUG | ??:??:?? 0 Set Timezone to GMT0
DEBUG | ??:??:?? 0 Read RTC time as 375
DEBUG | ??:??:?? 0 NeighborInfoModule is disabled
DEBUG | ??:??:?? 0 Rescanning for I2C keyboard
DEBUG | ??:??:?? 0 Scanning for I2C devices on port 1
DEBUG | ??:??:?? 0 Scanning address 0x1f
DEBUG | ??:??:?? 0 Scanning address 0x55
DEBUG | ??:??:?? 0 Scanning address 0x5f
INFO | ??:??:?? 0 External Notification Module Disabled
INFO | ??:??:?? 0 Turning on screen
DEBUG | ??:??:?? 0 haveGlyphs=1
DEBUG | ??:??:?? 0 Module wants a UI Frame
DEBUG | ??:??:?? 0 Module wants a UI Frame
DEBUG | ??:??:?? 0 RF95Interface(cs=17, irq=26, rst=22, busy=33)
INFO | ??:??:?? 0 Starting meshradio init...
DEBUG | ??:??:?? 0 (bw=250, sf=11, cr=4/5) packet symLen=8 ms, payloadSize=0, time 231 ms
DEBUG | ??:??:?? 0 (bw=250, sf=11, cr=4/5) packet symLen=8 ms, payloadSize=253, time 2115 ms
INFO | ??:??:?? 0 Radio freq=906.875, config.lora.frequency_offset=0.000
INFO | ??:??:?? 0 Set radio: region=US, name=LongFast, config=0, ch=19, power=30
INFO | ??:??:?? 0 Radio myRegion->freqStart -> myRegion->freqEnd: 902.000000 -> 928.000000 (26.000000 MHz)
INFO | ??:??:?? 0 Radio myRegion->numChannels: 104 x 250.000kHz
INFO | ??:??:?? 0 Radio channel_num: 20
INFO | ??:??:?? 0 Radio frequency: 906.875000
INFO | ??:??:?? 0 Slot time: 77 msec
INFO | ??:??:?? 0 Set radio: final power level=20
DEBUG | ??:??:?? 0 Current limit set to 100.000000
DEBUG | ??:??:?? 0 Current limit set result 0
INFO | ??:??:?? 0 RF95 init result 0
INFO | ??:??:?? 0 Frequency set to 906.875000
INFO | ??:??:?? 0 Bandwidth set to 250.000000
INFO | ??:??:?? 0 Power output set to 20
INFO | ??:??:?? 0 RF95 Radio init succeeded, using RF95 radio
INFO | ??:??:?? 0 Not using WIFI
DEBUG | ??:??:?? 0 (bw=250, sf=11, cr=4/5) packet symLen=8 ms, payloadSize=237, time 2000 ms
DEBUG | ??:??:?? 0 LoRA bitrate = 118.500000 bytes / sec
INFO ▒▒▒▒▒▒▒▒▒▒▒[32mPowerFSM init, USB power=1
DEBUG | ??:??:?? 0 Enter state: BOOT
[ 848][D][esp32-hal-cpu.c:244] setCpuFrequencyMhz(): PLL: 320 / 4 = 80 Mhz, APB: 80000000 Hz
DEBUG | ??:??:?? 0 [Power] Battery: usbPower=0, isCharging=0, batMv=3100, batPct=0
DEBUG | ??:??:?? 0 [Screen] Screen: Started...
DEBUG | ??:??:?? 0 [Screen] haveGlyphs=1
INFO | ??:??:?? 0 [RangeTestModule] Range Test Module - Disabled
DEBUG | ??:??:?? 1 [Screen] haveGlyphs=1
DEBUG | ??:??:?? 2 [Screen] haveGlyphs=1
DEBUG | ??:??:?? 3 [Screen] haveGlyphs=1
INFO | ??:??:?? 3 [PowerFSM] Loss of power in Powered
INFO | ??:??:?? 4 [PowerFSM] Loss of power in Powered
INFO | ??:??:?? 4 [PowerFSM] Initialise the NimBLE bluetooth module
DEBUG | ??:??:?? 4 [PowerFSM] Enter state: ON
DEBUG | ??:??:?? 4 [Screen] haveGlyphs=1
INFO | ??:??:?? 5 [Screen] Done with boot screen...
DEBUG | ??:??:?? 5 [Screen] showing standard frames
DEBUG | ??:??:?? 5 [Screen] Showing 0 module frames
DEBUG | ??:??:?? 5 [Screen] Total frame count: 103
DEBUG | ??:??:?? 5 [Screen] Added modules. numframes: 0
DEBUG | ??:??:?? 5 [Screen] Finished building frames. numframes: 2
INFO | ??:??:?? 5 [Screen] BLE authentication complete
DEBUG | ??:??:?? 7 [RadioIf] (bw=250, sf=11, cr=4/5) packet symLen=8 ms, payloadSize=81, time 837 ms
DEBUG | ??:??:?? 7 [RadioIf] Lora RX (id=0x3156955f fr=0xac to=0xa9, WantAck=0, HopLim=2 Ch=0x8 encrypted rxSNR=-11.25 rxRSSI=-128 hopStart=5)
DEBUG | ??:??:?? 7 [RadioIf] AirTime - Packet received : 837ms
DEBUG | ??:??:?? 7 [Router] Add packet record (id=0x3156955f fr=0xac to=0xa9, WantAck=0, HopLim=2 Ch=0x8 encrypted rxSNR=-11.25 rxRSSI=-128 hopStart=5)
DEBUG | ??:??:?? 7 [Router] Using channel 0 (hash 0x8)
DEBUG | ??:??:?? 7 [Router] Expanding short PSK #1
DEBUG | ??:??:?? 7 [Router] Using AES128 key!
DEBUG | ??:??:?? 7 [Router] decoded message (id=0x3156955f fr=0xac to=0xa9, WantAck=0, HopLim=2 Ch=0x0 Portnum=4 WANTRESP rxSNR=-11.25 rxRSSI=-128 hopStart=5)
DEBUG | ??:??:?? 7 [Router] handleReceived(REMOTE) (id=0x3156955f fr=0xac to=0xa9, WantAck=0, HopLim=2 Ch=0x0 Portnum=4 WANTRESP rxSNR=-11.25 rxRSSI=-128 hopStart=5)
DEBUG | ??:??:?? 7 [Router] Module 'nodeinfo' wantsPacket=1
INFO | ??:??:?? 7 [Router] Received nodeinfo from=0xf9624cac, id=0x3156955f, portnum=4, payloadlen=59
INFO | ??:??:?? 7 [Router] Adding node to database with 2 nodes and 86308 bytes free!
DEBUG | ??:??:?? 7 [Router] old user /, channel=0
DEBUG | ??:??:?? 7 [Router] updating changed=1 user Coyote Hills - SoCalMesh.org/COYO, channel=0
DEBUG | ??:??:?? 7 [Router] Enter state: ON
DEBUG | ??:??:?? 7 [Router] Node status update: 2 online, 2 total
DEBUG | ??:??:?? 7 [Router] showing standard frames
DEBUG | ??:??:?? 7 [Router] Showing 0 module frames
DEBUG | ??:??:?? 7 [Router] Total frame count: 103
DEBUG | ??:??:?? 7 [Router] Added modules. numframes: 0
DEBUG | ??:??:?? 7 [Router] Finished building frames. numframes: 3
INFO | ??:??:?? 7 [Router] Saving /prefs/db.proto
INFO | ??:??:?? 7 [Router] To Radio onwrite
DEBUG | ??:??:?? 7 [Router] New ToRadio packet
INFO | ??:??:?? 7 [Router] Client wants config, nonce=24
DEBUG | ??:??:?? 8 [Router] Module 'nodeinfo' considered
DEBUG | ??:??:?? 8 [Router] Module 'routing' wantsPacket=1
INFO | ??:??:?? 8 [Router] Received routing from=0xf9624cac, id=0x3156955f, portnum=4, payloadlen=59
DEBUG | ??:??:?? 8 [Router] Routing sniffing (id=0x3156955f fr=0xac to=0xa9, WantAck=0, HopLim=2 Ch=0x0 Portnum=4 WANTRESP rxSNR=-11.25 rxRSSI=-128 hopStart=5)
DEBUG | ??:??:?? 8 [Router] Got 3 files in manifest
INFO | ??:??:?? 8 [Router] Starting API client config
INFO | ??:??:?? 8 [Router] Rebroadcasting received floodmsg to neighbors
DEBUG | ??:??:?? 8 [Router] Expanding short PSK #1
DEBUG | ??:??:?? 8 [Router] Using AES128 key!
DEBUG | ??:??:?? 8 [Router] enqueuing for send (id=0x3156955f fr=0xac to=0xa9, WantAck=0, HopLim=1 Ch=0x8 encrypted rxSNR=-11.25 rxRSSI=-128 hopStart=5 priority=70)
DEBUG | ??:??:?? 8 [Router] txGood=0,rxGood=1,rxBad=0
DEBUG | ??:??:?? 8 [Router] rx_snr found. hop_limit:1 rx_snr:-11.250000
DEBUG | ??:??:?? 8 [Router] rx_snr found in packet. Setting tx delay:1386
DEBUG | ??:??:?? 8 [Router] Module 'routing' considered
DEBUG | ??:??:?? 8 (Client not yet interested in packets)
INFO | ??:??:?? 8 getFromRadio=STATE_SEND_MY_INFO
INFO | ??:??:?? 8 getFromRadio=STATE_SEND_OWN_NODEINFO
INFO | ??:??:?? 8 getFromRadio=STATE_SEND_METADATA
INFO | ??:??:?? 8 getFromRadio=STATE_SEND_CHANNELS
INFO | ??:??:?? 8 getFromRadio=STATE_SEND_CHANNELS
INFO | ??:??:?? 8 getFromRadio=STATE_SEND_CHANNELS
INFO | ??:??:?? 9 getFromRadio=STATE_SEND_CHANNELS
INFO | ??:??:?? 9 getFromRadio=STATE_SEND_CHANNELS
INFO | ??:??:?? 9 getFromRadio=STATE_SEND_CHANNELS
INFO | ??:??:?? 9 getFromRadio=STATE_SEND_CHANNELS
INFO | ??:??:?? 9 getFromRadio=STATE_SEND_CHANNELS
INFO | ??:??:?? 9 getFromRadio=STATE_SEND_CONFIG
INFO | ??:??:?? 9 getFromRadio=STATE_SEND_CONFIG
INFO | ??:??:?? 9 getFromRadio=STATE_SEND_CONFIG
INFO | ??:??:?? 9 getFromRadio=STATE_SEND_CONFIG
DEBUG | ??:??:?? 9 [RadioIf] Starting low level send (id=0x3156955f fr=0xac to=0xa9, WantAck=0, HopLim=1 Ch=0x8 encrypted rxSNR=-11.25 rxRSSI=-128 hopStart=5 priority=70)
DEBUG | ??:??:?? 9 [RadioIf] (bw=250, sf=11, cr=4/5) packet symLen=8 ms, payloadSize=81, time 837 ms
DEBUG | ??:??:?? 9 [RadioIf] AirTime - Packet transmitted : 837ms
INFO | ??:??:?? 9 getFromRadio=STATE_SEND_CONFIG
INFO | ??:??:?? 9 getFromRadio=STATE_SEND_CONFIG
INFO | ??:??:?? 10 getFromRadio=STATE_SEND_CONFIG
INFO | ??:??:?? 10 getFromRadio=STATE_SEND_CONFIG
INFO | ??:??:?? 10 getFromRadio=STATE_SEND_CONFIG
INFO | ??:??:?? 10 getFromRadio=STATE_SEND_MODULECONFIG
INFO | ??:??:?? 10 getFromRadio=STATE_SEND_MODULECONFIG
INFO | ??:??:?? 10 getFromRadio=STATE_SEND_MODULECONFIG
INFO | ??:??:?? 10 getFromRadio=STATE_SEND_MODULECONFIG
DEBUG | ??:??:?? 10 [RadioIf] Completed sending (id=0x3156955f fr=0xac to=0xa9, WantAck=0, HopLim=1 Ch=0x8 encrypted rxSNR=-11.25 rxRSSI=-128 hopStart=5 priority=70)
INFO | ??:??:?? 10 [RadioIf] getFromRadio=STATE_SEND_MODULECONFIG
INFO | ??:??:?? 10 getFromRadio=STATE_SEND_MODULECONFIG
INFO | ??:??:?? 10 getFromRadio=STATE_SEND_MODULECONFIG
INFO | ??:??:?? 11 getFromRadio=STATE_SEND_MODULECONFIG
INFO | ??:??:?? 11 getFromRadio=STATE_SEND_MODULECONFIG
INFO | ??:??:?? 11 getFromRadio=STATE_SEND_MODULECONFIG
INFO | ??:??:?? 11 getFromRadio=STATE_SEND_MODULECONFIG
INFO | ??:??:?? 11 getFromRadio=STATE_SEND_MODULECONFIG
INFO | ??:??:?? 11 getFromRadio=STATE_SEND_MODULECONFIG
INFO | ??:??:?? 11 getFromRadio=STATE_SEND_OTHER_NODEINFOS
INFO | ??:??:?? 11 nodeinfo: num=0xf9624cac, lastseen=0, id=!f9624cac, name=Coyote Hills - SoCalMesh.org
INFO | ??:??:?? 11 getFromRadio=STATE_SEND_OTHER_NODEINFOS
INFO | ??:??:?? 11 Done sending nodeinfos
INFO | ??:??:?? 11 getFromRadio=STATE_SEND_FILEMANIFEST
DEBUG | ??:??:?? 11 File: /prefs/channels.proto (57) bytes
INFO | ??:??:?? 11 getFromRadio=STATE_SEND_FILEMANIFEST
DEBUG | ??:??:?? 11 File: /prefs/config.proto (166) bytes
INFO | ??:??:?? 11 getFromRadio=STATE_SEND_FILEMANIFEST
DEBUG | ??:??:?? 11 File: /prefs/db.proto (445) bytes
INFO | ??:??:?? 11 getFromRadio=STATE_SEND_FILEMANIFEST
INFO | ??:??:?? 11 getFromRadio=STATE_SEND_COMPLETE_ID
INFO | ??:??:?? 11 getFromRadio=STATE_SEND_PACKETS
INFO | ??:??:?? 12 getFromRadio=STATE_SEND_PACKETS
DEBUG | ??:??:?? 12 phone downloaded packet (id=0x3156955f fr=0xac to=0xa9, WantAck=0, HopLim=2 Ch=0x0 Portnum=4 WANTRESP rxSNR=-11.25 rxRSSI=-128 hopStart=5)
DEBUG | ??:??:?? 20 [Power] Battery: usbPower=0, isCharging=0, batMv=4018, batPct=84
INFO | ??:??:?? 30 [NodeInfoModule] Sending our nodeinfo to mesh (wantReplies=1)
INFO | ??:??:?? 30 [NodeInfoModule] sending owner !e3a93468/KM6HHP_base/KM6H
DEBUG | ??:??:?? 30 [NodeInfoModule] Initial packet id 671554495
DEBUG | ??:??:?? 30 [NodeInfoModule] Partially randomized packet id 2926497728
DEBUG | ??:??:?? 30 [NodeInfoModule] Update DB node 0xe3a93468, rx_time=0
DEBUG | ??:??:?? 30 [NodeInfoModule] handleReceived(LOCAL) (id=0xae6ecfc0 fr=0x68 to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=4 WANTRESP priority=10)
DEBUG | ??:??:?? 30 [NodeInfoModule] No modules interested in portnum=4, src=LOCAL
DEBUG | ??:??:?? 30 [NodeInfoModule] Add packet record (id=0xae6ecfc0 fr=0x68 to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=4 WANTRESP priority=10)
DEBUG | ??:??:?? 30 [NodeInfoModule] Expanding short PSK #1
DEBUG | ??:??:?? 30 [NodeInfoModule] Using AES128 key!
DEBUG | ??:??:?? 30 [NodeInfoModule] enqueuing for send (id=0xae6ecfc0 fr=0x68 to=0xff, WantAck=0, HopLim=3 Ch=0x8 encrypted hopStart=3 priority=10)
DEBUG | ??:??:?? 30 [NodeInfoModule] txGood=1,rxGood=1,rxBad=0
INFO | ??:??:?? 30 Telling client we have new packets 3
INFO | ??:??:?? 30 BLE notify fromNum
INFO | ??:??:?? 30 getFromRadio=STATE_SEND_PACKETS
WARN | ??:??:?? 30 [RadioIf] Can not send yet, busyRx
WARN | ??:??:?? 30 [RadioIf] Can not send yet, busyRx
WARN | ??:??:?? 30 [RadioIf] Can not send yet, busyRx
WARN | ??:??:?? 31 [RadioIf] Can not send yet, busyRx
WARN | ??:??:?? 31 [RadioIf] Can not send yet, busyRx
WARN | ??:??:?? 31 [RadioIf] Can not send yet, busyRx
WARN | ??:??:?? 31 [RadioIf] Can not send yet, busyRx
DEBUG | ??:??:?? 31 [RadioIf] Starting low level send (id=0xae6ecfc0 fr=0x68 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=99, time 968 ms
DEBUG | ??:??:?? 31 [RadioIf] AirTime - Packet transmitted : 968ms
DEBUG | ??:??:?? 32 [RadioIf] Completed sending (id=0xae6ecfc0 fr=0x68 to=0xff, WantAck=0, HopLim=3 Ch=0x8 encrypted hopStart=3 priority=10)
DEBUG | ??:??:?? 40 [Power] Battery: usbPower=0, isCharging=0, batMv=4013, batPct=83
INFO | ??:??:?? 45 [DeviceTelemetryModule] (Sending): air_util_tx=0.050139, channel_utilization=4.403333, battery_level=83, voltage=4.013000, uptime=45
DEBUG | ??:??:?? 45 [DeviceTelemetryModule] Partially randomized packet id 2068260801
DEBUG | ??:??:?? 45 [DeviceTelemetryModule] updateTelemetry LOCAL
DEBUG | ??:??:?? 45 [DeviceTelemetryModule] Node status update: 2 online, 2 total
INFO | ??:??:?? 45 [DeviceTelemetryModule] Sending packet to mesh
DEBUG | ??:??:?? 45 [DeviceTelemetryModule] Update DB node 0xe3a93468, rx_time=0
DEBUG | ??:??:?? 45 [DeviceTelemetryModule] handleReceived(LOCAL) (id=0x7b4727c1 fr=0x68 to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=67 priority=10)
DEBUG | ??:??:?? 45 [DeviceTelemetryModule] No modules interested in portnum=67, src=LOCAL
DEBUG | ??:??:?? 45 [DeviceTelemetryModule] Add packet record (id=0x7b4727c1 fr=0x68 to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=67 priority=10)
DEBUG | ??:??:?? 45 [DeviceTelemetryModule] Expanding short PSK #1
DEBUG | ??:??:?? 45 [DeviceTelemetryModule] Using AES128 key!
DEBUG | ??:??:?? 45 [DeviceTelemetryModule] enqueuing for send (id=0x7b4727c1 fr=0x68 to=0xff, WantAck=0, HopLim=3 Ch=0x8 encrypted hopStart=3 priority=10)
DEBUG | ??:??:?? 45 [DeviceTelemetryModule] txGood=2,rxGood=1,rxBad=0
DEBUG | ??:??:?? 45 [DeviceTelemetryModule] Using channel 0 (hash 0x8)
DEBUG | ??:??:?? 45 [DeviceTelemetryModule] Expanding short PSK #1
DEBUG | ??:??:?? 45 [DeviceTelemetryModule] Using AES128 key!
DEBUG | ??:??:?? 45 [DeviceTelemetryModule] decoded message (id=0x7b4727c1 fr=0x68 to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=67 hopStart=3 priority=10)
INFO | ??:??:?? 45 Telling client we have new packets 5
INFO | ??:??:?? 45 BLE notify fromNum
DEBUG | ??:??:?? 45 [RadioIf] Starting low level send (id=0x7b4727c1 fr=0x68 to=0xff, WantAck=0, HopLim=3 Ch=0x8 encrypted hopStart=3 priority=10)
INFO | ??:??:?? 45 [RadioIf] getFromRadio=STATE_SEND_PACKETS
DEBUG | ??:??:?? 45 [RadioIf] (bw=250, sf=11, cr=4/5) packet symLen=8 ms, payloadSize=48, time 591 ms
DEBUG | ??:??:?? 45 [RadioIf] AirTime - Packet transmitted : 591ms
INFO | ??:??:?? 45 getFromRadio=STATE_SEND_PACKETS
DEBUG | ??:??:?? 45 phone downloaded packet (id=0x7b4727c1 fr=0x68 to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=67 hopStart=3 priority=10)
DEBUG | ??:??:?? 46 [RadioIf] Completed sending (id=0x7b4727c1 fr=0x68 to=0xff, WantAck=0, HopLim=3 Ch=0x8 encrypted hopStart=3 priority=10)
DEBUG | ??:??:?? 60 [Power] Battery: usbPower=0, isCharging=0, batMv=4014, batPct=84
DEBUG | ??:??:?? 80 [Power] Battery: usbPower=0, isCharging=0, batMv=4018, batPct=84
DEBUG | ??:??:?? 100 [Power] Battery: usbPower=0, isCharging=0, batMv=4022, batPct=85
INFO | ??:??:?? 105 [DeviceTelemetryModule] (Sending): air_util_tx=0.066556, channel_utilization=0.000000, battery_level=85, voltage=4.022000, uptime=105
DEBUG | ??:??:?? 105 [DeviceTelemetryModule] Partially randomized packet id 1864544194
DEBUG | ??:??:?? 105 [DeviceTelemetryModule] updateTelemetry LOCAL
DEBUG | ??:??:?? 105 [DeviceTelemetryModule] Node status update: 2 online, 2 total
INFO | ??:??:?? 105 [DeviceTelemetryModule] Sending packet to phone
INFO | ??:??:?? 105 [DeviceTelemetryModule] (Sending local stats): uptime=105, channel_utilization=0.000000, air_util_tx=0.066556, num_online_nodes=2, num_total_nodes=2
INFO | ??:??:?? 105 [DeviceTelemetryModule] num_packets_tx=3, num_packets_rx=1, num_packets_rx_bad=0
DEBUG | ??:??:?? 105 [DeviceTelemetryModule] Partially randomized packet id 2556573635
INFO | ??:??:?? 105 Telling client we have new packets 7
INFO | ??:??:?? 105 BLE notify fromNum
INFO | ??:??:?? 106 getFromRadio=STATE_SEND_PACKETS
DEBUG | ??:??:?? 106 phone downloaded packet (id=0x6f22afc2 fr=0x68 to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=67 priority=10)
INFO | ??:??:?? 106 [Screen] getFromRadio=STATE_SEND_PACKETS
DEBUG | ??:??:?? 106 phone downloaded packet (id=0x986237c3 fr=0x68 to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=67 priority=10)
DEBUG | ??:??:?? 120 [Power] Battery: usbPower=0, isCharging=0, batMv=4020, batPct=85
DEBUG | ??:??:?? 140 [Power] Battery: usbPower=0, isCharging=0, batMv=4019, batPct=84
DEBUG | ??:??:?? 160 [Power] Battery: usbPower=0, isCharging=0, batMv=4020, batPct=85
INFO | ??:??:?? 165 [DeviceTelemetryModule] (Sending): air_util_tx=0.066556, channel_utilization=0.000000, battery_level=85, voltage=4.020000, uptime=165
DEBUG | ??:??:?? 165 [DeviceTelemetryModule] Partially randomized packet id 1965272004
DEBUG | ??:??:?? 165 [DeviceTelemetryModule] updateTelemetry LOCAL
DEBUG | ??:??:?? 165 [DeviceTelemetryModule] Node status update: 2 online, 2 total
INFO | ??:??:?? 165 [DeviceTelemetryModule] Sending packet to phone
INFO | ??:??:?? 165 Telling client we have new packets 8
INFO | ??:??:?? 165 BLE notify fromNum
INFO | ??:??:?? 165 getFromRadio=STATE_SEND_PACKETS
DEBUG | ??:??:?? 165 phone downloaded packet (id=0x7523abc4 fr=0x68 to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=67 priority=10)
DEBUG | ??:??:?? 180 [Power] Battery: usbPower=0, isCharging=0, batMv=4023, batPct=85
DEBUG | ??:??:?? 200 [Power] Battery: usbPower=0, isCharging=0, batMv=4022, batPct=85
DEBUG | ??:??:?? 220 [Power] Battery: usbPower=0, isCharging=0, batMv=4022, batPct=85
INFO | ??:??:?? 225 [DeviceTelemetryModule] (Sending): air_util_tx=0.066556, channel_utilization=0.000000, battery_level=85, voltage=4.022000, uptime=225
DEBUG | ??:??:?? 225 [DeviceTelemetryModule] Partially randomized packet id 3070703557
DEBUG | ??:??:?? 225 [DeviceTelemetryModule] updateTelemetry LOCAL
DEBUG | ??:??:?? 225 [DeviceTelemetryModule] Node status update: 2 online, 2 total
INFO | ??:??:?? 225 [DeviceTelemetryModule] Sending packet to phone
INFO | ??:??:?? 225 Telling client we have new packets 9
INFO | ??:??:?? 225 BLE notify fromNum
INFO | ??:??:?? 226 getFromRadio=STATE_SEND_PACKETS
DEBUG | ??:??:?? 226 phone downloaded packet (id=0xb70737c5 fr=0x68 to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=67 priority=10)
DEBUG | ??:??:?? 240 [Power] Battery: usbPower=0, isCharging=0, batMv=4020, batPct=85
DEBUG | ??:??:?? 260 [Power] Battery: usbPower=0, isCharging=0, batMv=4019, batPct=84
DEBUG | ??:??:?? 280 [Power] Battery: usbPower=0, isCharging=0, batMv=4020, batPct=85
INFO | ??:??:?? 285 [DeviceTelemetryModule] (Sending): air_util_tx=0.066556, channel_utilization=0.000000, battery_level=85, voltage=4.020000, uptime=285
DEBUG | ??:??:?? 285 [DeviceTelemetryModule] Partially randomized packet id 44059590
DEBUG | ??:??:?? 285 [DeviceTelemetryModule] updateTelemetry LOCAL
DEBUG | ??:??:?? 285 [DeviceTelemetryModule] Node status update: 2 online, 2 total
INFO | ??:??:?? 286 [DeviceTelemetryModule] Sending packet to phone
INFO | ??:??:?? 286 Telling client we have new packets 10
INFO | ??:??:?? 286 BLE notify fromNum
INFO | ??:??:?? 286 getFromRadio=STATE_SEND_PACKETS
DEBUG | ??:??:?? 286 phone downloaded packet (id=0x02a04bc6 fr=0x68 to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=67 priority=10)
DEBUG | ??:??:?? 300 [Power] Battery: usbPower=0, isCharging=0, batMv=4019, batPct=84
DEBUG | ??:??:?? 320 [Power] Battery: usbPower=0, isCharging=0, batMv=4017, batPct=84
DEBUG | ??:??:?? 340 [Power] Battery: usbPower=0, isCharging=0, batMv=4019, batPct=84
INFO | ??:??:?? 346 [DeviceTelemetryModule] (Sending): air_util_tx=0.066556, channel_utilization=0.000000, battery_level=84, voltage=4.019000, uptime=346
DEBUG | ??:??:?? 346 [DeviceTelemetryModule] Partially randomized packet id 406288327
DEBUG | ??:??:?? 346 [DeviceTelemetryModule] updateTelemetry LOCAL
DEBUG | ??:??:?? 346 [DeviceTelemetryModule] Node status update: 2 online, 2 total
INFO | ??:??:?? 346 [DeviceTelemetryModule] Sending packet to phone
INFO | ??:??:?? 346 Telling client we have new packets 11
INFO | ??:??:?? 346 BLE notify fromNum
INFO | ??:??:?? 346 getFromRadio=STATE_SEND_PACKETS
DEBUG | ??:??:?? 346 phone downloaded packet (id=0x183777c7 fr=0x68 to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=67 priority=10)
DEBUG | ??:??:?? 360 [Power] Battery: usbPower=0, isCharging=0, batMv=4021, batPct=85
DEBUG | ??:??:?? 381 [Power] Battery: usbPower=0, isCharging=0, batMv=4023, batPct=85
DEBUG | ??:??:?? 401 [Power] Battery: usbPower=0, isCharging=0, batMv=4021, batPct=85
INFO | ??:??:?? 406 [DeviceTelemetryModule] (Sending): air_util_tx=0.066556, channel_utilization=0.000000, battery_level=85, voltage=4.021000, uptime=406
DEBUG | ??:??:?? 406 [DeviceTelemetryModule] Partially randomized packet id 3669232584
DEBUG | ??:??:?? 406 [DeviceTelemetryModule] updateTelemetry LOCAL
DEBUG | ??:??:?? 406 [DeviceTelemetryModule] Node status update: 2 online, 2 total
INFO | ??:??:?? 406 [DeviceTelemetryModule] Sending packet to phone
INFO | ??:??:?? 406 Telling client we have new packets 12
INFO | ??:??:?? 406 BLE notify fromNum
INFO | ??:??:?? 406 getFromRadio=STATE_SEND_PACKETS
DEBUG | ??:??:?? 406 phone downloaded packet (id=0xdab40bc8 fr=0x68 to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=67 priority=10)
DEBUG | ??:??:?? 421 [Power] Battery: usbPower=0, isCharging=0, batMv=4021, batPct=85
DEBUG | ??:??:?? 441 [Power] Battery: usbPower=0, isCharging=0, batMv=4020, batPct=85
DEBUG | ??:??:?? 461 [Power] Battery: usbPower=0, isCharging=0, batMv=4021, batPct=85
INFO | ??:??:?? 466 [DeviceTelemetryModule] (Sending): air_util_tx=0.066556, channel_utilization=0.000000, battery_level=85, voltage=4.021000, uptime=466
DEBUG | ??:??:?? 466 [DeviceTelemetryModule] Partially randomized packet id 2039008201
DEBUG | ??:??:?? 466 [DeviceTelemetryModule] updateTelemetry LOCAL
DEBUG | ??:??:?? 466 [DeviceTelemetryModule] Node status update: 2 online, 2 total
INFO | ??:??:?? 466 [DeviceTelemetryModule] Sending packet to phone
INFO | ??:??:?? 466 Telling client we have new packets 13
INFO | ??:??:?? 466 BLE notify fromNum
INFO | ??:??:?? 466 getFromRadio=STATE_SEND_PACKETS
DEBUG | ??:??:?? 466 phone downloaded packet (id=0x7988cbc9 fr=0x68 to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=67 priority=10)
DEBUG | ??:??:?? 481 [Power] Battery: usbPower=0, isCharging=0, batMv=4023, batPct=85
DEBUG | ??:??:?? 501 [Power] Battery: usbPower=0, isCharging=0, batMv=4013, batPct=83
DEBUG | ??:??:?? 521 [Power] Battery: usbPower=0, isCharging=0, batMv=4019, batPct=84
INFO | ??:??:?? 526 [DeviceTelemetryModule] (Sending): air_util_tx=0.066556, channel_utilization=0.000000, battery_level=84, voltage=4.019000, uptime=526
DEBUG | ??:??:?? 526 [DeviceTelemetryModule] Partially randomized packet id 1375912906
DEBUG | ??:??:?? 526 [DeviceTelemetryModule] updateTelemetry LOCAL
DEBUG | ??:??:?? 526 [DeviceTelemetryModule] Node status update: 2 online, 2 total
INFO | ??:??:?? 526 [DeviceTelemetryModule] Sending packet to phone
INFO | ??:??:?? 526 Telling client we have new packets 14
INFO | ??:??:?? 526 BLE notify fromNum
INFO | ??:??:?? 526 getFromRadio=STATE_SEND_PACKETS
DEBUG | ??:??:?? 526 phone downloaded packet (id=0x5202c3ca fr=0x68 to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=67 priority=10)
DEBUG | ??:??:?? 541 [Power] Battery: usbPower=0, isCharging=0, batMv=4017, batPct=84
DEBUG | ??:??:?? 561 [Power] Battery: usbPower=0, isCharging=0, batMv=4019, batPct=84
DEBUG | ??:??:?? 581 [Power] Battery: usbPower=0, isCharging=0, batMv=4017, batPct=84
INFO | ??:??:?? 586 [DeviceTelemetryModule] (Sending): air_util_tx=0.066556, channel_utilization=0.000000, battery_level=84, voltage=4.017000, uptime=586
DEBUG | ??:??:?? 586 [DeviceTelemetryModule] Partially randomized packet id 392144843
DEBUG | ??:??:?? 586 [DeviceTelemetryModule] updateTelemetry LOCAL
DEBUG | ??:??:?? 586 [DeviceTelemetryModule] Node status update: 2 online, 2 total
INFO | ??:??:?? 586 [DeviceTelemetryModule] Sending packet to phone
INFO | ??:??:?? 586 Telling client we have new packets 15
INFO | ??:??:?? 586 BLE notify fromNum
INFO | ??:??:?? 586 getFromRadio=STATE_SEND_PACKETS
DEBUG | ??:??:?? 586 phone downloaded packet (id=0x175fa7cb fr=0x68 to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=67 priority=10)
DEBUG | ??:??:?? 601 [Power] Battery: usbPower=0, isCharging=0, batMv=3992, batPct=80
INFO | ??:??:?? 607 [PowerFSM] Turning off screen
DEBUG | ??:??:?? 621 [Power] Battery: usbPower=0, isCharging=0, batMv=4009, batPct=83
DEBUG | ??:??:?? 641 [Power] Battery: usbPower=0, isCharging=0, batMv=4013, batPct=83
INFO | ??:??:?? 646 [DeviceTelemetryModule] (Sending): air_util_tx=0.066556, channel_utilization=0.000000, battery_level=83, voltage=4.013000, uptime=646
DEBUG | ??:??:?? 646 [DeviceTelemetryModule] Partially randomized packet id 992877516
DEBUG | ??:??:?? 646 [DeviceTelemetryModule] updateTelemetry LOCAL
DEBUG | ??:??:?? 646 [DeviceTelemetryModule] Node status update: 2 online, 2 total
INFO | ??:??:?? 646 [DeviceTelemetryModule] Sending packet to phone
INFO | ??:??:?? 646 Telling client we have new packets 16
INFO | ??:??:?? 646 BLE notify fromNum
INFO | ??:??:?? 646 getFromRadio=STATE_SEND_PACKETS
DEBUG | ??:??:?? 646 phone downloaded packet (id=0x3b2e1bcc fr=0x68 to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=67 priority=10)
DEBUG | ??:??:?? 661 [Power] Battery: usbPower=0, isCharging=0, batMv=4016, batPct=84
INFO | ??:??:?? 679 To Radio onwrite
DEBUG | ??:??:?? 679 New ToRadio packet
DEBUG | ??:??:?? 679 PACKET FROM PHONE (id=0xd404bace fr=0x00 to=0x68, WantAck=1, HopLim=3 Ch=0x0 Portnum=6 priority=70)
DEBUG | ??:??:?? 679 Enqueued local (id=0xd404bace fr=0x00 to=0x68, WantAck=1, HopLim=3 Ch=0x0 Portnum=6 priority=70)
DEBUG | ??:??:?? 679 [Router] Add packet record (id=0xd404bace fr=0x00 to=0x68, WantAck=1, HopLim=3 Ch=0x0 Portnum=6 priority=70)
DEBUG | ??:??:?? 679 [Router] handleReceived(REMOTE) (id=0xd404bace fr=0x00 to=0x68, WantAck=1, HopLim=3 Ch=0x0 Portnum=6 priority=70)
DEBUG | ??:??:?? 679 [Router] Module 'Admin' wantsPacket=1
INFO | ??:??:?? 679 [Router] Received Admin from=0x0, id=0xd404bace, portnum=6, payloadlen=3
INFO | ??:??:?? 679 [Router] Handling admin payload 100
INFO | ??:??:?? 679 [Router] Disable bluetooth until reboot
INFO | ??:??:?? 679 [Router] BLE disconnect
INFO | ??:??:?? 679 [Router] PhoneAPI::close()
E NimBLEAdvertising: Error enabling advertising; rc=30,
INFO | ??:??:?? 679 [Router] Initiating node-db reset
DEBUG | ??:??:?? 679 [Router] Setting local position: lat=0 lon=0 time=0 timestamp=0
INFO | ??:??:?? 679 [Router] Saving /prefs/db.proto
INFO | ??:??:?? 679 [Router] Rebooting in 7 seconds
DEBUG | ??:??:?? 679 [Router] Module 'Admin' considered
DEBUG | ??:??:?? 679 [Router] Module 'routing' wantsPacket=1
INFO | ??:??:?? 679 [Router] Received routing from=0x0, id=0xd404bace, portnum=6, payloadlen=3
DEBUG | ??:??:?? 679 [Router] Routing sniffing (id=0xd404bace fr=0x00 to=0x68, WantAck=1, HopLim=3 Ch=0x0 Portnum=6 priority=70)
DEBUG | ??:??:?? 679 [Router] Partially randomized packet id 3800073165
DEBUG | ??:??:?? 679 [Router] Enqueued local (id=0xe28083cd fr=0x68 to=0x68, WantAck=0, HopLim=3 Ch=0x0 Portnum=5 requestId=d404bace priority=120)
DEBUG | ??:??:?? 679 [Router] Module 'routing' considered
DEBUG | ??:??:?? 679 [Router] Rx someone rebroadcasting for us (id=0xe28083cd fr=0x68 to=0x68, WantAck=0, HopLim=3 Ch=0x0 Portnum=5 requestId=d404bace priority=120)
DEBUG | ??:??:?? 680 [Router] didn't find pending packet
DEBUG | ??:??:?? 680 [Router] Add packet record (id=0xe28083cd fr=0x68 to=0x68, WantAck=0, HopLim=3 Ch=0x0 Portnum=5 requestId=d404bace priority=120)
DEBUG | ??:??:?? 680 [Router] handleReceived(REMOTE) (id=0xe28083cd fr=0x68 to=0x68, WantAck=0, HopLim=3 Ch=0x0 Portnum=5 requestId=d404bace priority=120)
DEBUG | ??:??:?? 680 [Router] Module 'routing' wantsPacket=1
INFO | ??:??:?? 680 [Router] Received routing from=0xe3a93468, id=0xe28083cd, portnum=5, payloadlen=2
DEBUG | ??:??:?? 680 [Router] Routing sniffing (id=0xe28083cd fr=0x68 to=0x68, WantAck=0, HopLim=3 Ch=0x0 Portnum=5 requestId=d404bace priority=120)
DEBUG | ??:??:?? 680 [Router] Received an ack for 0xd404bace, stopping retransmissions
DEBUG | ??:??:?? 680 [Router] Delivering rx packet (id=0xe28083cd fr=0x68 to=0x68, WantAck=0, HopLim=3 Ch=0x0 Portnum=5 requestId=d404bace priority=120)
DEBUG | ??:??:?? 680 [Router] Update DB node 0xe3a93468, rx_time=0
DEBUG | ??:??:?? 680 [Router] Forwarding to phone (id=0xe28083cd fr=0x68 to=0x68, WantAck=0, HopLim=3 Ch=0x0 Portnum=5 requestId=d404bace priority=120)
DEBUG | ??:??:?? 680 [Router] Module 'routing' considered
DEBUG | ??:??:?? 681 [Power] Battery: usbPower=0, isCharging=0, batMv=4021, batPct=85
INFO | ??:??:?? 687 Rebooting
ets Jun 8 2016 00:22:57

rst:0xc (SW_CPU_RESET),boot:0x17 (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 (850) esp_core_dump_flash: No core dump▒▒ѥѥ▒▒▒found!
E (850) esp_core_dump_flash: No core dump partition found!
[ 11][D][esp32-hal-cpu.c:244] setCpuFrequencyMhz(): PLL: 480 / 2 = 240 Mhz, APB: 80000000 Hz
E (601) psram: PSRAM ID read error: 0xffffffff
[ 27][W][esp32-hal-psram.c:71] psramInit(): PSRAM init failed!
▒▒@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 (166 Bytes)
DEBUG | ??:??:?? 0 /prefs/db.proto (389 Bytes)
[ 133][I][esp32-hal-i2c.c:75] i2cInit(): Initialising I2C Master: sda=5 scl=4 freq=100000
DEBUG | ??:??:?? 0 Power::lipoInit lipo sensor is not ready yet
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...
[ 174][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 0x6 subtype probed in 2 tries
INFO | ??:??:?? 0 1 I2C devices found
DEBUG | ??:??:?? 0 acc_info = 0
INFO | ??:??:?? 0 S:B:255,2.5.4.d73cbf14
INFO | ??:??:?? 0 Build timestamp: 1727633396
DEBUG | ??:??:?? 0 Total heap: 187640
DEBUG | ??:??:?? 0 Free heap: 152728
DEBUG | ??:??:?? 0 Total PSRAM: 0
DEBUG | ??:??:?? 0 Free PSRAM: 0
DEBUG | ??:??:?? 0 NVS: UsedEntries 98, FreeEntries 532, AllEntries 630, NameSpaces 4
DEBUG | ??:??:?? 0 Setup Preferences in Flash Storage
DEBUG | ??:??:?? 0 Number of Device Reboots: 4
ESP_ERROR_CHECK_WITHOUT_ABORT failed: esp_err_t 0x105 (ESP_ERR_NOT_FOUND) at 0x4009894b
file: "src/platform/esp32/BleOta.cpp" line 16
func: static const esp_partition_t* BleOta::findEspOtaAppPartition()
expression: esp_ota_get_partition_description(part, &app_desc)
ESP_ERROR_CHECK_WITHOUT_ABORT failed: esp_err_t 0x102 (ESP_ERR_INVALID_ARG) at 0x4009894b
file: "src/platform/esp32/BleOta.cpp" line 30
func: static String BleOta::getOtaAppVersion()
expression: esp_ota_get_partition_description(part, &app_desc)
INFO | ??:??:?? 0 No OTA firmware available
INFO | ??:??:?? 0 Initializing NodeDB
INFO | ??:??:?? 0 Loading /prefs/db.proto
INFO | ??:??:?? 0 Loaded /prefs/db.proto successfully
INFO | ??:??:?? 0 Loaded saved devicestate version 23, with nodecount: 1
INFO | ??:??:?? 0 Loading /prefs/config.proto
INFO | ??:??:?? 0 Loaded /prefs/config.proto successfully
INFO | ??:??:?? 0 Loaded saved config version 23
[ 404][E][vfs_api.cpp:105] open(): /littlefs/prefs/module.proto does not exist, no permits for creation
ERROR | ??:??:?? 0 Could not open / read /prefs/module.proto
INFO | ??:??:?? 0 Installing default ModuleConfig
INFO | ??:??:?? 0 Loading /prefs/channels.proto
INFO | ??:??:?? 0 Loaded /prefs/channels.proto successfully
INFO | ??:??:?? 0 Loaded saved channelFile version 23
[ 455][E][vfs_api.cpp:105] open(): /littlefs/oem/oem.proto does not exist, no permits for creation
ERROR | ??:??:?? 0 Could not open / read /oem/oem.proto
DEBUG | ??:??:?? 0 cleanupMeshDB purged 0 entries
DEBUG | ??:??:?? 0 Using nodenum 0xe3a93468
DEBUG | ??:??:?? 0 Old Pubkey 3e 7e 65 38 26 8e 8c 35 70 7e 91 7c 2e 4d 83 57 6a 0b 20 4e 7c 00 4b 90 cb 1d 2a ac 10 1c 0d 58
INFO | ??:??:?? 0 Using saved PKI keys
DEBUG | ??:??:?? 0 Number of Device Reboots: 4
DEBUG | ??:??:?? 0 Expanding short PSK #1
INFO | ??:??:?? 0 Wanted region 1, using US
INFO | ??:??:?? 0 Saving /prefs/db.proto
DEBUG | ??:??:?? 0 Using GPIO16 for button
DEBUG | ??:??:?? 0 SPI.begin(SCK=18, MISO=19, MOSI=23, NSS=17)
DEBUG | ??:??:?? 0 Set Timezone to GMT0
DEBUG | ??:??:?? 0 Read RTC time as 1063
DEBUG | ??:??:?? 0 NeighborInfoModule is disabled
DEBUG | ??:??:?? 0 Rescanning for I2C keyboard
DEBUG | ??:??:?? 0 Scanning for I2C devices on port 1
DEBUG | ??:??:?? 0 Scanning address 0x1f
DEBUG | ??:??:?? 0 Scanning address 0x55
DEBUG | ??:??:?? 0 Scanning address 0x5f
INFO | ??:??:?? 0 External Notification Module Disabled
INFO | ??:??:?? 0 Turning on screen
DEBUG | ??:??:?? 0 haveGlyphs=1
DEBUG | ??:??:?? 0 Module wants a UI Frame
DEBUG | ??:??:?? 0 Module wants a UI Frame
DEBUG | ??:??:?? 0 RF95Interface(cs=17, irq=26, rst=22, busy=33)
INFO | ??:??:?? 0 Starting meshradio init...
DEBUG | ??:??:?? 0 (bw=250, sf=11, cr=4/5) packet symLen=8 ms, payloadSize=0, time 231 ms
DEBUG | ??:??:?? 0 (bw=250, sf=11, cr=4/5) packet symLen=8 ms, payloadSize=253, time 2115 ms
INFO | ??:??:?? 0 Radio freq=906.875, config.lora.frequency_offset=0.000
INFO | ??:??:?? 0 Set radio: region=US, name=LongFast, config=0, ch=19, power=30
INFO | ??:??:?? 0 Radio myRegion->freqStart -> myRegion->freqEnd: 902.000000 -> 928.000000 (26.000000 MHz)
INFO | ??:??:?? 0 Radio myRegion->numChannels: 104 x 250.000kHz
INFO | ??:??:?? 0 Radio channel_num: 20
INFO | ??:??:?? 0 Radio frequency: 906.875000
INFO | ??:??:?? 0 Slot time: 77 msec
INFO | ??:??:?? 0 Set radio: final power level=20
DEBUG | ??:??:?? 0 Current limit set to 100.000000
DEBUG | ??:??:?? 0 Current limit set result 0
INFO | ??:??:?? 0 RF95 init result 0
INFO | ??:??:?? 0 Frequency set to 906.875000
INFO | ??:??:?? 0 Bandwidth set to 250.000000
INFO | ??:??:?? 0 Power output set to 20
INFO | ??:??:?? 0 RF95 Radio init succeeded, using RF95 radio
INFO | ??:??:?? 0 Not using WIFI
DEBUG | ??:??:?? 0 (bw=250, sf=11, cr=4/5) packet symLen=8 ms, payloadSize=237, time 2000 ms
DEBUG | ??:??:?? 0 LoRA bitrate = 118.500000 bytes / sec
INFO ▒▒▒▒▒▒▒▒▒▒▒[32mPowerFSM init, USB power=1
DEBUG | ??:??:?? 0 Enter state: BOOT
[ 901][D][esp32-hal-cpu.c:244] setCpuFrequencyMhz(): PLL: 320 / 4 = 80 Mhz, APB: 80000000 Hz
DEBUG | ??:??:?? 0 [Power] Battery: usbPower=0, isCharging=0, batMv=3100, batPct=0
DEBUG | ??:??:?? 0 [Screen] Screen: Started...
DEBUG | ??:??:?? 0 [Screen] haveGlyphs=1
INFO | ??:??:?? 0 [RangeTestModule] Range Test Module - Disabled
DEBUG | ??:??:?? 1 [Screen] haveGlyphs=1
DEBUG | ??:??:?? 2 [Screen] haveGlyphs=1
DEBUG | ??:??:?? 3 [Screen] haveGlyphs=1
INFO | ??:??:?? 3 [PowerFSM] Loss of power in Powered
INFO | ??:??:?? 4 [PowerFSM] Loss of power in Powered
INFO | ??:??:?? 4 [PowerFSM] Initialise the NimBLE bluetooth module
DEBUG | ??:??:?? 4 [PowerFSM] Enter state: ON
DEBUG | ??:??:?? 4 [Screen] haveGlyphs=1
INFO | ??:??:?? 5 [Screen] Done with boot screen...
DEBUG | ??:??:?? 5 [Screen] showing standard frames
DEBUG | ??:??:?? 5 [Screen] Showing 0 module frames
DEBUG | ??:??:?? 5 [Screen] Total frame count: 103
DEBUG | ??:??:?? 5 [Screen] Added modules. numframes: 0
DEBUG | ??:??:?? 5 [Screen] Finished building frames. numframes: 2
INFO | ??:??:?? 5 BLE authentication complete
INFO | ??:??:?? 7 To Radio onwrite
DEBUG | ??:??:?? 7 New ToRadio packet
INFO | ??:??:?? 7 Client wants config, nonce=25
DEBUG | ??:??:?? 8 Got 3 files in manifest
INFO | ??:??:?? 8 Starting API client config
INFO | ??:??:?? 8 getFromRadio=STATE_SEND_MY_INFO
INFO | ??:??:?? 8 getFromRadio=STATE_SEND_OWN_NODEINFO
INFO | ??:??:?? 8 getFromRadio=STATE_SEND_METADATA
INFO | ??:??:?? 8 getFromRadio=STATE_SEND_CHANNELS
INFO | ??:??:?? 8 getFromRadio=STATE_SEND_CHANNELS
INFO | ??:??:?? 8 getFromRadio=STATE_SEND_CHANNELS
INFO | ??:??:?? 8 getFromRadio=STATE_SEND_CHANNELS
INFO | ??:??:?? 9 getFromRadio=STATE_SEND_CHANNELS
INFO | ??:??:?? 9 getFromRadio=STATE_SEND_CHANNELS
INFO | ??:??:?? 9 getFromRadio=STATE_SEND_CHANNELS
INFO | ??:??:?? 9 getFromRadio=STATE_SEND_CHANNELS
INFO | ??:??:?? 9 getFromRadio=STATE_SEND_CONFIG
INFO | ??:??:?? 9 getFromRadio=STATE_SEND_CONFIG
INFO | ??:??:?? 9 getFromRadio=STATE_SEND_CONFIG
INFO | ??:??:?? 9 getFromRadio=STATE_SEND_CONFIG
INFO | ??:??:?? 9 getFromRadio=STATE_SEND_CONFIG
INFO | ??:??:?? 9 getFromRadio=STATE_SEND_CONFIG
INFO | ??:??:?? 9 getFromRadio=STATE_SEND_CONFIG
INFO | ??:??:?? 10 getFromRadio=STATE_SEND_CONFIG
INFO | ??:??:?? 10 getFromRadio=STATE_SEND_CONFIG
INFO | ??:??:?? 10 getFromRadio=STATE_SEND_MODULECONFIG
INFO | ??:??:?? 10 getFromRadio=STATE_SEND_MODULECONFIG
INFO | ??:??:?? 10 getFromRadio=STATE_SEND_MODULECONFIG
INFO | ??:??:?? 10 getFromRadio=STATE_SEND_MODULECONFIG
INFO | ??:??:?? 10 getFromRadio=STATE_SEND_MODULECONFIG
INFO | ??:??:?? 10 getFromRadio=STATE_SEND_MODULECONFIG
INFO | ??:??:?? 10 getFromRadio=STATE_SEND_MODULECONFIG
INFO | ??:??:?? 10 getFromRadio=STATE_SEND_MODULECONFIG
INFO | ??:??:?? 10 getFromRadio=STATE_SEND_MODULECONFIG
INFO | ??:??:?? 11 getFromRadio=STATE_SEND_MODULECONFIG
INFO | ??:??:?? 11 getFromRadio=STATE_SEND_MODULECONFIG
INFO | ??:??:?? 11 getFromRadio=STATE_SEND_MODULECONFIG
INFO | ??:??:?? 11 getFromRadio=STATE_SEND_MODULECONFIG
INFO | ??:??:?? 11 getFromRadio=STATE_SEND_OTHER_NODEINFOS
INFO | ??:??:?? 11 Done sending nodeinfos
INFO | ??:??:?? 11 getFromRadio=STATE_SEND_FILEMANIFEST
DEBUG | ??:??:?? 11 File: /prefs/channels.proto (57) bytes
INFO | ??:??:?? 11 getFromRadio=STATE_SEND_FILEMANIFEST
DEBUG | ??:??:?? 11 File: /prefs/config.proto (166) bytes
INFO | ??:??:?? 11 getFromRadio=STATE_SEND_FILEMANIFEST
DEBUG | ??:??:?? 11 File: /prefs/db.proto (389) bytes
INFO | ??:??:?? 11 getFromRadio=STATE_SEND_FILEMANIFEST
INFO | ??:??:?? 11 getFromRadio=STATE_SEND_COMPLETE_ID
INFO | ??:??:?? 11 getFromRadio=STATE_SEND_PACKETS
DEBUG | ??:??:?? 17 [RadioIf] (bw=250, sf=11, cr=4/5) packet symLen=8 ms, payloadSize=95, time 944 ms
DEBUG | ??:??:?? 17 [RadioIf] Lora RX (id=0x5454b5f4 fr=0x48 to=0xc8, WantAck=0, HopLim=3 Ch=0x8 encrypted rxSNR=-13.5 rxRSSI=-132 hopStart=5)
DEBUG | ??:??:?? 17 [RadioIf] AirTime - Packet received : 944ms
DEBUG | ??:??:?? 17 [Router] Add packet record (id=0x5454b5f4 fr=0x48 to=0xc8, WantAck=0, HopLim=3 Ch=0x8 encrypted rxSNR=-13.5 rxRSSI=-132 hopStart=5)
DEBUG | ??:??:?? 17 [Router] Using channel 0 (hash 0x8)
DEBUG | ??:??:?? 17 [Router] Expanding short PSK #1
DEBUG | ??:??:?? 17 [Router] Using AES128 key!
DEBUG | ??:??:?? 17 [Router] decoded message (id=0x5454b5f4 fr=0x48 to=0xc8, WantAck=0, HopLim=3 Ch=0x0 Portnum=4 WANTRESP rxSNR=-13.5 rxRSSI=-132 hopStart=5)
DEBUG | ??:??:?? 17 [Router] handleReceived(REMOTE) (id=0x5454b5f4 fr=0x48 to=0xc8, WantAck=0, HopLim=3 Ch=0x0 Portnum=4 WANTRESP rxSNR=-13.5 rxRSSI=-132 hopStart=5)
DEBUG | ??:??:?? 17 [Router] Module 'nodeinfo' wantsPacket=1
INFO | ??:??:?? 17 [Router] Received nodeinfo from=0x7c5ac348, id=0x5454b5f4, portnum=4, payloadlen=71
INFO | ??:??:?? 17 [Router] Adding node to database with 2 nodes and 85416 bytes free!
DEBUG | ??:??:?? 17 [Router] old user /, channel=0
DEBUG | ??:??:?? 17 [Router] Incoming Pubkey: e8 b5 6a 6a 40 98 a3 c1 d8 a1 d9 48 42 a2 0f c0 80 5f 77 ce 29 c2 9e 2c e8 80 15 4a 5d 93 bf 67
INFO | ??:??:?? 17 [Router] Updating Node Pubkey!
DEBUG | ??:??:?? 17 [Router] Saved Pubkey: e8 b5 6a 6a 40 98 a3 c1 d8 a1 d9 48 42 a2 0f c0 80 5f 77 ce 29 c2 9e 2c e8 80 15 4a 5d 93 bf 67
DEBUG | ??:??:?? 17 [Router] updating changed=1 user 225 BBS/225, channel=0
DEBUG | ??:??:?? 17 [Router] Enter state: ON
DEBUG | ??:??:?? 17 [Router] Node status update: 2 online, 2 total
DEBUG | ??:??:?? 17 [Router] showing standard frames
DEBUG | ??:??:?? 17 [Router] Showing 0 module frames
DEBUG | ??:??:?? 17 [Router] Total frame count: 103
DEBUG | ??:??:?? 17 [Router] Added modules. numframes: 0
DEBUG | ??:??:?? 17 [Router] Finished building frames. numframes: 3
INFO | ??:??:?? 17 [Router] Saving /prefs/db.proto
DEBUG | ??:??:?? 17 [Router] Module 'nodeinfo' considered
DEBUG | ??:??:?? 17 [Router] Module 'routing' wantsPacket=1
INFO | ??:??:?? 17 [Router] Received routing from=0x7c5ac348, id=0x5454b5f4, portnum=4, payloadlen=71
DEBUG | ??:??:?? 17 [Router] Routing sniffing (id=0x5454b5f4 fr=0x48 to=0xc8, WantAck=0, HopLim=3 Ch=0x0 Portnum=4 WANTRESP rxSNR=-13.5 rxRSSI=-132 hopStart=5)
INFO | ??:??:?? 17 [Router] Rebroadcasting received floodmsg to neighbors
DEBUG | ??:??:?? 17 [Router] Expanding short PSK #1
DEBUG | ??:??:?? 17 [Router] Using AES128 key!
DEBUG | ??:??:?? 18 [Router] enqueuing for send (id=0x5454b5f4 fr=0x48 to=0xc8, WantAck=0, HopLim=2 Ch=0x8 encrypted rxSNR=-13.5 rxRSSI=-132 hopStart=5 priority=70)
DEBUG | ??:??:?? 18 [Router] txGood=0,rxGood=1,rxBad=0
DEBUG | ??:??:?? 18 [Router] rx_snr found. hop_limit:2 rx_snr:-13.500000
DEBUG | ??:??:?? 18 [Router] rx_snr found in packet. Setting tx delay:1540
DEBUG | ??:??:?? 18 [Router] Module 'routing' considered
INFO | ??:??:?? 18 Telling client we have new packets 2
INFO | ??:??:?? 18 BLE notify fromNum
INFO | ??:??:?? 18 getFromRadio=STATE_SEND_PACKETS
DEBUG | ??:??:?? 18 phone downloaded packet (id=0x5454b5f4 fr=0x48 to=0xc8, WantAck=0, HopLim=3 Ch=0x0 Portnum=4 WANTRESP rxSNR=-13.5 rxRSSI=-132 hopStart=5)
DEBUG | ??:??:?? 18 [RadioIf] (bw=250, sf=11, cr=4/5) packet symLen=8 ms, payloadSize=57, time 657 ms
DEBUG | ??:??:?? 18 [RadioIf] Lora RX (id=0x3139b661 fr=0x56 to=0x04, WantAck=0, HopLim=0 Ch=0x8 encrypted rxSNR=-13 rxRSSI=-132)
DEBUG | ??:??:?? 18 [RadioIf] AirTime - Packet received : 657ms
DEBUG | ??:??:?? 18 [Router] Add packet record (id=0x3139b661 fr=0x56 to=0x04, WantAck=0, HopLim=0 Ch=0x8 encrypted rxSNR=-13 rxRSSI=-132)
DEBUG | ??:??:?? 18 [Router] Using channel 0 (hash 0x8)
DEBUG | ??:??:?? 18 [Router] Expanding short PSK #1
DEBUG | ??:??:?? 18 [Router] Using AES128 key!
DEBUG | ??:??:?? 18 [Router] decoded message (id=0x3139b661 fr=0x56 to=0x04, WantAck=0, HopLim=0 Ch=0x0 Portnum=4 WANTRESP rxSNR=-13 rxRSSI=-132)
DEBUG | ??:??:?? 18 [Router] handleReceived(REMOTE) (id=0x3139b661 fr=0x56 to=0x04, WantAck=0, HopLim=0 Ch=0x0 Portnum=4 WANTRESP rxSNR=-13 rxRSSI=-132)
DEBUG | ??:??:?? 18 [Router] Module 'nodeinfo' wantsPacket=1
INFO | ??:??:?? 18 [Router] Received nodeinfo from=0x7b765d56, id=0x3139b661, portnum=4, payloadlen=35
INFO | ??:??:?? 18 [Router] Adding node to database with 3 nodes and 84708 bytes free!
DEBUG | ??:??:?? 18 [Router] old user /, channel=0
DEBUG | ??:??:?? 18 [Router] updating changed=1 user hmr2/hmr2, channel=0
DEBUG | ??:??:?? 18 [Router] Enter state: ON
DEBUG | ??:??:?? 18 [Router] Node status update: 3 online, 3 total
DEBUG | ??:??:?? 18 [Router] showing standard frames
DEBUG | ??:??:?? 18 [Router] Showing 0 module frames
DEBUG | ??:??:?? 18 [Router] Total frame count: 103
DEBUG | ??:??:?? 18 [Router] Added modules. numframes: 0
DEBUG | ??:??:?? 18 [Router] Finished building frames. numframes: 4
DEBUG | ??:??:?? 18 [Router] Deferring NodeDB saveToDisk for now, since we saved less than a minute ago
DEBUG | ??:??:?? 18 [Router] Module 'nodeinfo' considered
DEBUG | ??:??:?? 18 [Router] Module 'routing' wantsPacket=1
INFO | ??:??:?? 18 [Router] Received routing from=0x7b765d56, id=0x3139b661, portnum=4, payloadlen=35
DEBUG | ??:??:?? 18 [Router] Routing sniffing (id=0x3139b661 fr=0x56 to=0x04, WantAck=0, HopLim=0 Ch=0x0 Portnum=4 WANTRESP rxSNR=-13 rxRSSI=-132)
DEBUG | ??:??:?? 18 [Router] Module 'routing' considered
INFO | ??:??:?? 18 Telling client we have new packets 3
INFO | ??:??:?? 18 BLE notify fromNum
DEBUG | ??:??:?? 18 [RadioIf] Starting low level send (id=0x5454b5f4 fr=0x48 to=0xc8, WantAck=0, HopLim=2 Ch=0x8 encrypted rxSNR=-13.5 rxRSSI=-132 hopStart=5 priority=70)
DEBUG | ??:??:?? 18 [RadioIf] (bw=250, sf=11, cr=4/5) packet symLen=8 ms, payloadSize=95, time 944 ms
DEBUG | ??:??:?? 18 [RadioIf] AirTime - Packet transmitted : 944ms
INFO | ??:??:?? 18 getFromRadio=STATE_SEND_PACKETS
DEBUG | ??:??:?? 18 phone downloaded packet (id=0x3139b661 fr=0x56 to=0x04, WantAck=0, HopLim=0 Ch=0x0 Portnum=4 WANTRESP rxSNR=-13 rxRSSI=-132)
DEBUG | ??:??:?? 19 [RadioIf] Completed sending (id=0x5454b5f4 fr=0x48 to=0xc8, WantAck=0, HopLim=2 Ch=0x8 encrypted rxSNR=-13.5 rxRSSI=-132 hopStart=5 priority=70)
DEBUG | ??:??:?? 20 [Power] Battery: usbPower=0, isCharging=0, batMv=4018, batPct=84
INFO | ??:??:?? 30 [NodeInfoModule] Sending our nodeinfo to mesh (wantReplies=1)
INFO | ??:??:?? 30 [NodeInfoModule] sending owner !e3a93468/KM6HHP_base/KM6H
DEBUG | ??:??:?? 30 [NodeInfoModule] Initial packet id 217756454
DEBUG | ??:??:?? 30 [NodeInfoModule] Partially randomized packet id 2257950503
DEBUG | ??:??:?? 30 [NodeInfoModule] Update DB node 0xe3a93468, rx_time=0
DEBUG | ??:??:?? 30 [NodeInfoModule] handleReceived(LOCAL) (id=0x86959727 fr=0x68 to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=4 WANTRESP priority=10)
DEBUG | ??:??:?? 30 [NodeInfoModule] No modules interested in portnum=4, src=LOCAL
DEBUG | ??:??:?? 30 [NodeInfoModule] Add packet record (id=0x86959727 fr=0x68 to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=4 WANTRESP priority=10)
DEBUG | ??:??:?? 30 [NodeInfoModule] Expanding short PSK #1
DEBUG | ??:??:?? 30 [NodeInfoModule] Using AES128 key!
DEBUG | ??:??:?? 30 [NodeInfoModule] enqueuing for send (id=0x86959727 fr=0x68 to=0xff, WantAck=0, HopLim=3 Ch=0x8 encrypted hopStart=3 priority=10)
DEBUG | ??:??:?? 30 [NodeInfoModule] txGood=1,rxGood=2,rxBad=0
INFO | ??:??:?? 30 Telling client we have new packets 4
INFO | ??:??:?? 30 BLE notify fromNum
DEBUG | ??:??:?? 30 [RadioIf] Starting low level send (id=0x86959727 fr=0x68 to=0xff, WantAck=0, HopLim=3 Ch=0x8 encrypted hopStart=3 priority=10)
INFO | ??:??:?? 30 [RadioIf] getFromRadio=STATE_SEND_PACKETS
DEBUG | ??:??:?? 30 [RadioIf] (bw=250, sf=11, cr=4/5) packet symLen=8 ms, payloadSize=99, time 968 ms
DEBUG | ??:??:?? 30 [RadioIf] AirTime - Packet transmitted : 968ms
DEBUG | ??:??:?? 31 [RadioIf] Completed sending (id=0x86959727 fr=0x68 to=0xff, WantAck=0, HopLim=3 Ch=0x8 encrypted hopStart=3 priority=10)
DEBUG | ??:??:?? 40 [Power] Battery: usbPower=0, isCharging=0, batMv=3985, batPct=79
INFO | ??:??:?? 45 [DeviceTelemetryModule] (Sending): air_util_tx=0.053111, channel_utilization=5.855000, battery_level=79, voltage=3.985000, uptime=45
DEBUG | ??:??:?? 45 [DeviceTelemetryModule] Partially randomized packet id 4233221928
DEBUG | ??:??:?? 45 [DeviceTelemetryModule] updateTelemetry LOCAL
DEBUG | ??:??:?? 45 [DeviceTelemetryModule] Node status update: 3 online, 3 total
INFO | ??:??:?? 45 [DeviceTelemetryModule] Sending packet to mesh
DEBUG | ??:??:?? 45 [DeviceTelemetryModule] Update DB node 0xe3a93468, rx_time=0
DEBUG | ??:??:?? 45 [DeviceTelemetryModule] handleReceived(LOCAL) (id=0xfc51d728 fr=0x68 to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=67 priority=10)
DEBUG | ??:??:?? 45 [DeviceTelemetryModule] No modules interested in portnum=67, src=LOCAL
DEBUG | ??:??:?? 45 [DeviceTelemetryModule] Add packet record (id=0xfc51d728 fr=0x68 to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=67 priority=10)
DEBUG | ??:??:?? 45 [DeviceTelemetryModule] Expanding short PSK #1
DEBUG | ??:??:?? 45 [DeviceTelemetryModule] Using AES128 key!
DEBUG | ??:??:?? 45 [DeviceTelemetryModule] enqueuing for send (id=0xfc51d728 fr=0x68 to=0xff, WantAck=0, HopLim=3 Ch=0x8 encrypted hopStart=3 priority=10)
DEBUG | ??:??:?? 45 [DeviceTelemetryModule] txGood=2,rxGood=2,rxBad=0
DEBUG | ??:??:?? 45 [DeviceTelemetryModule] Using channel 0 (hash 0x8)
DEBUG | ??:??:?? 45 [DeviceTelemetryModule] Expanding short PSK #1
DEBUG | ??:??:?? 45 [DeviceTelemetryModule] Using AES128 key!
DEBUG | ??:??:?? 45 [DeviceTelemetryModule] decoded message (id=0xfc51d728 fr=0x68 to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=67 hopStart=3 priority=10)
INFO | ??:??:?? 45 Telling client we have new packets 6
INFO | ??:??:?? 45 BLE notify fromNum
INFO | ??:??:?? 45 getFromRadio=STATE_SEND_PACKETS
INFO | ??:??:?? 46 [RadioIf] getFromRadio=STATE_SEND_PACKETS
DEBUG | ??:??:?? 46 [RadioIf] Starting low level send (id=0xfc51d728 fr=0x68 to=0xff, WantAck=0, HopLim=3 Ch=0x8 encrypted hopStart=3 priority=10)
DEBUG | ??:??:?? 46 [RadioIf] phone downloaded packet (id=0xfc51d728 fr=0x68 to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=67 hopStart=3 priority=10)
DEBUG | ??:??:?? 46 [RadioIf] (bw=250, sf=11, cr=4/5) packet symLen=8 ms, payloadSize=48, time 591 ms
DEBUG | ??:??:?? 46 [RadioIf] AirTime - Packet transmitted : 591ms
DEBUG | ??:??:?? 46 [RadioIf] Completed sending (id=0xfc51d728 fr=0x68 to=0xff, WantAck=0, HopLim=3 Ch=0x8 encrypted hopStart=3 priority=10)
DEBUG | ??:??:?? 60 [Power] Battery: usbPower=0, isCharging=0, batMv=3998, batPct=81
DEBUG | ??:??:?? 80 [Power] Battery: usbPower=0, isCharging=0, batMv=4008, batPct=83
DEBUG | ??:??:?? 100 [Power] Battery: usbPower=0, isCharging=0, batMv=4009, batPct=83
INFO | ??:??:?? 105 [DeviceTelemetryModule] (Sending): air_util_tx=0.069528, channel_utilization=0.000000, battery_level=83, voltage=4.009000, uptime=105
DEBUG | ??:??:?? 105 [DeviceTelemetryModule] Partially randomized packet id 23805737
DEBUG | ??:??:?? 105 [DeviceTelemetryModule] updateTelemetry LOCAL
DEBUG | ??:??:?? 105 [DeviceTelemetryModule] Node status update: 3 online, 3 total
INFO | ??:??:?? 105 [DeviceTelemetryModule] Sending packet to phone
INFO | ??:??:?? 105 [DeviceTelemetryModule] (Sending local stats): uptime=105, channel_utilization=0.000000, air_util_tx=0.069528, num_online_nodes=3, num_total_nodes=3
INFO | ??:??:?? 105 [DeviceTelemetryModule] num_packets_tx=3, num_packets_rx=2, num_packets_rx_bad=0
DEBUG | ??:??:?? 105 [DeviceTelemetryModule] Partially randomized packet id 2137234218
INFO | ??:??:?? 105 Telling client we have new packets 8
INFO | ??:??:?? 105 BLE notify fromNum
INFO | ??:??:?? 106 getFromRadio=STATE_SEND_PACKETS
DEBUG | ??:??:?? 106 phone downloaded packet (id=0x016b3f29 fr=0x68 to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=67 priority=10)
INFO | ??:??:?? 106 getFromRadio=STATE_SEND_PACKETS
DEBUG | ??:??:?? 106 phone downloaded packet (id=0x7f639b2a fr=0x68 to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=67 priority=10)
DEBUG | ??:??:?? 120 [Power] Battery: usbPower=0, isCharging=0, batMv=4016, batPct=84
DEBUG | ??:??:?? 140 [Power] Battery: usbPower=0, isCharging=0, batMv=3986, batPct=79
DEBUG | ??:??:?? 160 [Power] Battery: usbPower=0, isCharging=0, batMv=4002, batPct=82
INFO | ??:??:?? 165 [DeviceTelemetryModule] (Sending): air_util_tx=0.069528, channel_utilization=0.000000, battery_level=82, voltage=4.002000, uptime=165
DEBUG | ??:??:?? 165 [DeviceTelemetryModule] Partially randomized packet id 3443959595
DEBUG | ??:??:?? 165 [DeviceTelemetryModule] updateTelemetry LOCAL
DEBUG | ??:??:?? 165 [DeviceTelemetryModule] Node status update: 3 online, 3 total
INFO | ??:??:?? 165 [DeviceTelemetryModule] Sending packet to phone
INFO | ??:??:?? 165 Telling client we have new packets 9
INFO | ??:??:?? 165 BLE notify fromNum
INFO | ??:??:?? 166 getFromRadio=STATE_SEND_PACKETS
DEBUG | ??:??:?? 166 phone downloaded packet (id=0xcd46a72b fr=0x68 to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=67 priority=10)
DEBUG | ??:??:?? 180 [Power] Battery: usbPower=0, isCharging=0, batMv=4010, batPct=83
DEBUG | ??:??:?? 200 [Power] Battery: usbPower=0, isCharging=0, batMv=4014, batPct=84
DEBUG | ??:??:?? 220 [Power] Battery: usbPower=0, isCharging=0, batMv=4012, batPct=83
INFO | ??:??:?? 225 [DeviceTelemetryModule] (Sending): air_util_tx=0.069528, channel_utilization=0.000000, battery_level=83, voltage=4.012000, uptime=225
DEBUG | ??:??:?? 225 [DeviceTelemetryModule] Partially randomized packet id 92412716
DEBUG | ??:??:?? 225 [DeviceTelemetryModule] updateTelemetry LOCAL
DEBUG | ??:??:?? 226 [DeviceTelemetryModule] Node status update: 3 online, 3 total
INFO | ??:??:?? 226 [DeviceTelemetryModule] Sending packet to phone
INFO | ??:??:?? 226 Telling client we have new packets 10
INFO | ??:??:?? 226 BLE notify fromNum
INFO | ??:??:?? 226 getFromRadio=STATE_SEND_PACKETS
DEBUG | ??:??:?? 226 phone downloaded packet (id=0x05821b2c fr=0x68 to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=67 priority=10)
DEBUG | ??:??:?? 241 [Power] Battery: usbPower=0, isCharging=0, batMv=3977, batPct=78
DEBUG | ??:??:?? 261 [Power] Battery: usbPower=0, isCharging=0, batMv=3999, batPct=81
DEBUG | ??:??:?? 281 [Power] Battery: usbPower=0, isCharging=0, batMv=4012, batPct=83
INFO | ??:??:?? 286 [DeviceTelemetryModule] (Sending): air_util_tx=0.069528, channel_utilization=0.000000, battery_level=83, voltage=4.012000, uptime=286
DEBUG | ??:??:?? 286 [DeviceTelemetryModule] Partially randomized packet id 4036513581
DEBUG | ??:??:?? 286 [DeviceTelemetryModule] updateTelemetry LOCAL
DEBUG | ??:??:?? 286 [DeviceTelemetryModule] Node status update: 3 online, 3 total
INFO | ??:??:?? 286 [DeviceTelemetryModule] Sending packet to phone
INFO | ??:??:?? 286 Telling client we have new packets 11
INFO | ??:??:?? 286 BLE notify fromNum
INFO | ??:??:?? 286 getFromRadio=STATE_SEND_PACKETS
DEBUG | ??:??:?? 286 phone downloaded packet (id=0xf0984f2d fr=0x68 to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=67 priority=10)
DEBUG | ??:??:?? 301 [Power] Battery: usbPower=0, isCharging=0, batMv=4015, batPct=84
DEBUG | ??:??:?? 321 [Power] Battery: usbPower=0, isCharging=0, batMv=4019, batPct=84

[caveman99]

This is a red herring and has nothing to do with the patch, or it would have happened on the SX1262 too. Also there would be log entries alerting you to the RX suppression. The SX1276 still seem to have problems with the recent radiolib update, that's why the older versions worked. I'll investigate

[caveman99]

@GUVWAF any idea from the top of your head? we're de facto on radiolib 7.0.1

[GUVWAF]

Strange, it looks like a different DIO mapping is applied now:
jgromes/RadioLib@38fc7a9#diff-00e5593e444a00c69ace49442b07515a9fecbf542ce7288a558bf5a0f3d8ce52R1403
Shouldn’t this be the other way around: (dioNum <= 3)?

[caveman99]

yes, that really looks wrong RADIOLIB_SX127X_REG_DIO_MAPPING_2 is only used for DIO4 and bigger. either <=3 or swap the labels.

[caveman99]

i opened jgromes/RadioLib#1244 - seems like a quick fix.

[caveman99]

I just confirmed that patch fixes the Problem. Waiting for Radiolib 7.0.2 as a hotfix