search

meshtastic / firmware

Meshtastic device firmware
https://meshtastic.org
GNU General Public License v3.0
3.35k stars 816 forks source link

[Bug]: [RadioIf] Can not send yet, busyRx #4054

Closed pepefrog1234 closed 3 months ago

pepefrog1234 commented 3 months ago

Category

Hardware Compatibility, Other

Hardware

CDEBYTE_EORA_S3

Firmware Version

2.3.10

Description

My test environment has three devices which are: 1. v3 mqtt network node 2. v3 portable device 3. CDEBYTE_EORA_S3 problematic device. The channel is idle, I send a message but the app application shows that the message cannot be sent and the message [Can not send yet, busyRx] appears in the log. I tried to send the same message with device #2 (v3) and it sent successfully.

Relevant log output

INFO | ??:??:?? 5 Booted, wake cause 0 (boot count 1), reset_reason=reset

DEBUG | ??:??:?? 5 Filesystem files (495616/1048576 Bytes):

DEBUG | ??:??:?? 5 /prefs/channels.proto (216 Bytes)

DEBUG | ??:??:?? 5 /prefs/config.proto (98 Bytes)

DEBUG | ??:??:?? 5 /prefs/db.proto (1938 Bytes)

DEBUG | ??:??:?? 5 /prefs/module.proto (104 Bytes)

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

DEBUG | ??:??:?? 5 /static/Logo_Black.svg.gz (602 Bytes)

DEBUG | ??:??:?? 5 /static/Logo_White.svg.gz (610 Bytes)

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

DEBUG | ??:??:?? 5 /static/favicon.ico.gz (2270 Bytes)

DEBUG | ??:??:?? 5 /static/icon.svg.gz (852 Bytes)

DEBUG | ??:??:?? 5 /static/index-BMhLjTmL.css.gz (16270 Bytes)

DEBUG | ??:??:?? 5 /static/index-Bt2MYVVB.js.gz (203021 Bytes)

DEBUG | ??:??:?? 5 /static/index-CGqDWSD_.js.gz (348 Bytes)

DEBUG | ??:??:?? 6 /static/index.html.gz (548 Bytes)

DEBUG | ??:??:?? 6 /static/maplibre-gl-BXZZhNda.js.gz (210451 Bytes)

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

DEBUG | ??:??:?? 6 /static/site.webmanifest.gz (197 Bytes)

DEBUG | ??:??:?? 6 Using analog input 1 for battery level

INFO | ??:??:?? 6 ADCmod: ADC Characterization based on Two Point values and fitting curve coefficients stored in eFuse

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

DEBUG | ??:??:?? 6 Scanning for i2c devices on port 2

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

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

INFO | ??:??:?? 6 ssd1306 display found

INFO | ??:??:?? 6 ssd1306 display found

DEBUG | ??:??:?? 6 0x4 subtype probed in 2 tries

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

DEBUG | ??:??:?? 6 acc_info = 0

DEBUG | ??:??:?? 6 No SD_MMC card detected

INFO | ??:??:?? 6 Meshtastic hwvendor=61, swver=2.3.10.d19607b

DEBUG | ??:??:?? 6 Setting random seed 1209665992

DEBUG | ??:??:?? 6 Total heap: 281664

DEBUG | ??:??:?? 6 Free heap: 238760

DEBUG | ??:??:?? 6 Total PSRAM: 2095103

DEBUG | ??:??:?? 6 Free PSRAM: 2095103

DEBUG | ??:??:?? 6 NVS: UsedEntries 102, FreeEntries 528, AllEntries 630, NameSpaces 3

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

DEBUG | ??:??:?? 6 Number of Device Reboots: 7

DEBUG | ??:??:?? 6 OTA firmware version 0.2.1.ceca52c

INFO | ??:??:?? 6 Initializing NodeDB

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

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

INFO | ??:??:?? 6 Loaded saved devicestate version 22, with nodecount: 25

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

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

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

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

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

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

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

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

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

INFO | ??:??:?? 6 File /oem/oem.proto not found

DEBUG | ??:??:?? 6 cleanupMeshDB purged 0 entries

DEBUG | ??:??:?? 6 Using nodenum 0x75f17b80

DEBUG | ??:??:?? 6 Number of Device Reboots: 7

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

INFO | ??:??:?? 6 Wanted region 8, using TW

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

DEBUG | ??:??:?? 7 Using GPIO00 for button

DEBUG | ??:??:?? 7 SPI.begin(SCK=5, MISO=3, MOSI=6, NSS=7)

DEBUG | ??:??:?? 7 Set Timezone to GMT0

DEBUG | ??:??:?? 7 Read RTC time as 6

DEBUG | ??:??:?? 7 NeighborInfoModule is disabled

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

INFO | ??:??:?? 7 Turning on screen

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

DEBUG | ??:??:?? 7 SX126xInterface(cs=7, irq=33, rst=8, busy=34)

DEBUG | ??:??:?? 7 SX126X_DIO3_TCXO_VOLTAGE not defined, not using DIO3 as TCXO reference voltage

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

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

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

INFO | ??:??:?? 7 Radio freq=923.875, config.lora.frequency_offset=0.000

INFO | ??:??:?? 7 Set radio: region=TW, name=LongFast, config=0, ch=15, power=17

INFO | ??:??:?? 7 Radio myRegion->freqStart -> myRegion->freqEnd: 920.000000 -> 925.000000 (5.000000 mhz)

INFO | ??:??:?? 7 Radio myRegion->numChannels: 20 x 250.000kHz

INFO | ??:??:?? 7 Radio channel_num: 16

INFO | ??:??:?? 7 Radio frequency: 923.875000

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

INFO | ??:??:?? 7 Set radio: final power level=17

INFO | ??:??:?? 7 SX126x init result 0

INFO | ??:??:?? 7 Frequency set to 923.875000

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

INFO | ??:??:?? 7 Power output set to 17

DEBUG | ??:??:?? 7 Current limit set to 140.000000

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

DEBUG | ??:??:?? 7 Setting DIO2 as RF switch

DEBUG | ??:??:?? 7 SX126X_RXEN not defined, defaulting to RADIOLIB_NC

DEBUG | ??:??:?? 7 SX126X_TXEN not defined, defaulting to RADIOLIB_NC

DEBUG | ??:??:?? 7 Using MCU pin -1 as RXEN and pin -1 as TXEN to control RF switching

INFO | ??:??:?? 7 Set RX gain to power saving mode (boosted mode off); result: 0

INFO | ??:??:?? 7 SX1262 Radio init succeeded, using SX1262 radio

INFO | ??:??:?? 7 Not using WIFI

DEBUG | ??:??:?? 7 (bw=250, sf=11, cr=4/5) packet symLen=8 ms, payloadSize=237, time 2000 ms

DEBUG | ??:??:?? 7 LoRA bitrate = 118.500000 bytes / sec

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

DEBUG | ??:??:?? 7 Enter state: BOOT

DEBUG | ??:??:?? 7 [Power] Battery: usbPower=1, isCharging=1, batMv=4787, batPct=100

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

INFO | ??:??:?? 7 [RangeTestModule] Range Test Module - Disabled

INFO | ??:??:?? 10 [PowerFSM] Initialise the NimBLE bluetooth module

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

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

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

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

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

DEBUG | ??:??:?? 12 [Screen] Finished building frames. numframes: 7

DEBUG | ??:??:?? 27 [Power] Battery: usbPower=1, isCharging=1, batMv=4729, batPct=100

INFO | ??:??:?? 28 BLE authentication complete

INFO | ??:??:?? 30 From Radio onread

INFO | ??:??:?? 30 To Radio onwrite

INFO | ??:??:?? 30 Client wants config, nonce=12

INFO | ??:??:?? 30 Starting API client config

INFO | ??:??:?? 30 From Radio onread

INFO | ??:??:?? 30 getFromRadio=STATE_SEND_MY_INFO

DEBUG | ??:??:?? 30 encoding toPhone packet to phone variant=3, 14 bytes

INFO | ??:??:?? 30 From Radio onread

INFO | ??:??:?? 30 getFromRadio=STATE_SEND_METADATA

DEBUG | ??:??:?? 30 encoding toPhone packet to phone variant=13, 31 bytes

INFO | ??:??:?? 30 From Radio onread

INFO | ??:??:?? 30 getFromRadio=STATE_SEND_NODEINFO

INFO | ??:??:?? 30 nodeinfo: num=0x75f17b80, lastseen=0, id=!75f17b80, name=Meshtastic 7b80

DEBUG | ??:??:?? 30 encoding toPhone packet to phone variant=4, 80 bytes

INFO | ??:??:?? 30 From Radio onread

INFO | ??:??:?? 30 getFromRadio=STATE_SEND_NODEINFO

INFO | ??:??:?? 30 nodeinfo: num=0x33698c90, lastseen=1717841680, id=!33698c90, name=BX4ACP

DEBUG | ??:??:?? 30 encoding toPhone packet to phone variant=4, 92 bytes

INFO | ??:??:?? 30 From Radio onread

INFO | ??:??:?? 30 getFromRadio=STATE_SEND_NODEINFO

INFO | ??:??:?? 30 nodeinfo: num=0x33644194, lastseen=1717841735, id=!33644194, name=蜜蜂🐝

DEBUG | ??:??:?? 30 encoding toPhone packet to phone variant=4, 96 bytes

INFO | ??:??:?? 31 From Radio onread

INFO | ??:??:?? 31 getFromRadio=STATE_SEND_NODEINFO

INFO | ??:??:?? 31 nodeinfo: num=0x3369177c, lastseen=0, id=!3369177c, name=BX2AMN GW_Main

DEBUG | ??:??:?? 31 encoding toPhone packet to phone variant=4, 65 bytes

INFO | ??:??:?? 31 From Radio onread

INFO | ??:??:?? 31 getFromRadio=STATE_SEND_NODEINFO

INFO | ??:??:?? 31 nodeinfo: num=0x336978ac, lastseen=0, id=!336978ac, name=Prajna 78ac

DEBUG | ??:??:?? 31 encoding toPhone packet to phone variant=4, 61 bytes

INFO | ??:??:?? 31 From Radio onread

INFO | ??:??:?? 31 getFromRadio=STATE_SEND_NODEINFO

INFO | ??:??:?? 31 nodeinfo: num=0x3368d3fc, lastseen=0, id=!3368d3fc, name=Meshtastic d3fc

DEBUG | ??:??:?? 31 encoding toPhone packet to phone variant=4, 69 bytes

INFO | ??:??:?? 31 From Radio onread

INFO | ??:??:?? 31 getFromRadio=STATE_SEND_NODEINFO

INFO | ??:??:?? 31 nodeinfo: num=0x336a839c, lastseen=0, id=!336a839c, name=BM4LHG_1

DEBUG | ??:??:?? 31 encoding toPhone packet to phone variant=4, 60 bytes

INFO | ??:??:?? 31 [Screen] From Radio onread

INFO | ??:??:?? 31 [Screen] getFromRadio=STATE_SEND_NODEINFO

INFO | ??:??:?? 31 nodeinfo: num=0x43587c78, lastseen=0, id=!43587c78, name=TnyAT_7c78

DEBUG | ??:??:?? 31 encoding toPhone packet to phone variant=4, 60 bytes

INFO | ??:??:?? 31 From Radio onread

INFO | ??:??:?? 31 getFromRadio=STATE_SEND_NODEINFO

INFO | ??:??:?? 31 nodeinfo: num=0x1f76e6b0, lastseen=0, id=!1f76e6b0, name=PBT

DEBUG | ??:??:?? 31 encoding toPhone packet to phone variant=4, 55 bytes

INFO | ??:??:?? 31 From Radio onread

INFO | ??:??:?? 31 getFromRadio=STATE_SEND_NODEINFO

INFO | ??:??:?? 31 nodeinfo: num=0x33682cf0, lastseen=0, id=!33682cf0, name=Meshtastic 2cf0

DEBUG | ??:??:?? 31 encoding toPhone packet to phone variant=4, 65 bytes

INFO | ??:??:?? 31 From Radio onread

INFO | ??:??:?? 31 getFromRadio=STATE_SEND_NODEINFO

INFO | ??:??:?? 31 nodeinfo: num=0x335d8fa0, lastseen=0, id=!335d8fa0, name=Buddy 2

DEBUG | ??:??:?? 31 encoding toPhone packet to phone variant=4, 55 bytes

INFO | ??:??:?? 32 From Radio onread

INFO | ??:??:?? 32 getFromRadio=STATE_SEND_NODEINFO

INFO | ??:??:?? 32 nodeinfo: num=0x75ceb94c, lastseen=0, id=!75ceb94c, name=Katto

DEBUG | ??:??:?? 32 encoding toPhone packet to phone variant=4, 57 bytes

INFO | ??:??:?? 32 From Radio onread

INFO | ??:??:?? 32 getFromRadio=STATE_SEND_NODEINFO

INFO | ??:??:?? 32 nodeinfo: num=0x43594f2c, lastseen=0, id=!43594f2c, name=Meshtastic 4f2c

DEBUG | ??:??:?? 32 encoding toPhone packet to phone variant=4, 65 bytes

INFO | ??:??:?? 32 From Radio onread

INFO | ??:??:?? 32 getFromRadio=STATE_SEND_NODEINFO

INFO | ??:??:?? 32 nodeinfo: num=0x43587e98, lastseen=0, id=!43587e98, name=Meshtastic 7e98

DEBUG | ??:??:?? 32 encoding toPhone packet to phone variant=4, 65 bytes

INFO | ??:??:?? 32 From Radio onread

INFO | ??:??:?? 32 getFromRadio=STATE_SEND_NODEINFO

INFO | ??:??:?? 32 nodeinfo: num=0xda5ed9d4, lastseen=0, id=!da5ed9d4, name=Dryden Wifi 📶

DEBUG | ??:??:?? 32 encoding toPhone packet to phone variant=4, 68 bytes

INFO | ??:??:?? 32 From Radio onread

INFO | ??:??:?? 32 getFromRadio=STATE_SEND_NODEINFO

INFO | ??:??:?? 32 nodeinfo: num=0x335d8f1c, lastseen=0, id=!335d8f1c, name=155TW829-0

DEBUG | ??:??:?? 32 encoding toPhone packet to phone variant=4, 62 bytes

INFO | ??:??:?? 32 From Radio onread

INFO | ??:??:?? 32 getFromRadio=STATE_SEND_NODEINFO

INFO | ??:??:?? 32 nodeinfo: num=0x75f17b54, lastseen=0, id=!75f17b54, name=EORA-Pi

DEBUG | ??:??:?? 32 encoding toPhone packet to phone variant=4, 59 bytes

INFO | ??:??:?? 32 From Radio onread

INFO | ??:??:?? 32 getFromRadio=STATE_SEND_NODEINFO

INFO | ??:??:?? 32 nodeinfo: num=0x7c5cbf84, lastseen=0, id=!7c5cbf84, name=SYU-MA02(台中)

DEBUG | ??:??:?? 32 encoding toPhone packet to phone variant=4, 68 bytes

INFO | ??:??:?? 33 From Radio onread

INFO | ??:??:?? 33 getFromRadio=STATE_SEND_NODEINFO

INFO | ??:??:?? 33 nodeinfo: num=0xe2e2d9c4, lastseen=0, id=!e2e2d9c4, name=Dryden.S1☀️

DEBUG | ??:??:?? 33 encoding toPhone packet to phone variant=4, 67 bytes

INFO | ??:??:?? 33 From Radio onread

INFO | ??:??:?? 33 getFromRadio=STATE_SEND_NODEINFO

INFO | ??:??:?? 33 nodeinfo: num=0x4358751c, lastseen=0, id=!4358751c, name=MilkywayMQTT

DEBUG | ??:??:?? 33 encoding toPhone packet to phone variant=4, 64 bytes

INFO | ??:??:?? 33 From Radio onread

INFO | ??:??:?? 33 getFromRadio=STATE_SEND_NODEINFO

INFO | ??:??:?? 33 nodeinfo: num=0x43593f5c, lastseen=0, id=!43593f5c, name=Meshtastic 3f5c

DEBUG | ??:??:?? 33 encoding toPhone packet to phone variant=4, 69 bytes

INFO | ??:??:?? 33 From Radio onread

INFO | ??:??:?? 33 getFromRadio=STATE_SEND_NODEINFO

INFO | ??:??:?? 33 nodeinfo: num=0x55c5f668, lastseen=0, id=!55c5f668, name=Victor.H1

DEBUG | ??:??:?? 33 encoding toPhone packet to phone variant=4, 59 bytes

INFO | ??:??:?? 33 From Radio onread

INFO | ??:??:?? 33 getFromRadio=STATE_SEND_NODEINFO

INFO | ??:??:?? 33 nodeinfo: num=0x33644434, lastseen=0, id=!33644434, name=BM7IFH-meshv3

DEBUG | ??:??:?? 33 encoding toPhone packet to phone variant=4, 58 bytes

INFO | ??:??:?? 33 From Radio onread

INFO | ??:??:?? 33 getFromRadio=STATE_SEND_NODEINFO

INFO | ??:??:?? 33 nodeinfo: num=0x4358d324, lastseen=0, id=!4358d324, name=AlexaBot.wp⚡

DEBUG | ??:??:?? 33 encoding toPhone packet to phone variant=4, 57 bytes

INFO | ??:??:?? 33 From Radio onread

INFO | ??:??:?? 33 getFromRadio=STATE_SEND_NODEINFO

INFO | ??:??:?? 33 nodeinfo: num=0xa1df30c4, lastseen=0, id=!a1df30c4, name=Beitun.S1☀️

DEBUG | ??:??:?? 33 encoding toPhone packet to phone variant=4, 56 bytes

INFO | ??:??:?? 34 From Radio onread

INFO | ??:??:?? 34 getFromRadio=STATE_SEND_NODEINFO

INFO | ??:??:?? 34 Done sending nodeinfos

INFO | ??:??:?? 34 getFromRadio=STATE_SEND_CHANNELS

DEBUG | ??:??:?? 34 encoding toPhone packet to phone variant=10, 9 bytes

INFO | ??:??:?? 34 From Radio onread

INFO | ??:??:?? 34 getFromRadio=STATE_SEND_CHANNELS

DEBUG | ??:??:?? 34 encoding toPhone packet to phone variant=10, 61 bytes

INFO | ??:??:?? 34 From Radio onread

INFO | ??:??:?? 34 getFromRadio=STATE_SEND_CHANNELS

DEBUG | ??:??:?? 34 encoding toPhone packet to phone variant=10, 60 bytes

INFO | ??:??:?? 34 From Radio onread

INFO | ??:??:?? 34 getFromRadio=STATE_SEND_CHANNELS

DEBUG | ??:??:?? 34 encoding toPhone packet to phone variant=10, 60 bytes

INFO | ??:??:?? 34 From Radio onread

INFO | ??:??:?? 34 getFromRadio=STATE_SEND_CHANNELS

DEBUG | ??:??:?? 34 encoding toPhone packet to phone variant=10, 6 bytes

INFO | ??:??:?? 34 From Radio onread

INFO | ??:??:?? 34 getFromRadio=STATE_SEND_CHANNELS

DEBUG | ??:??:?? 34 encoding toPhone packet to phone variant=10, 6 bytes

INFO | ??:??:?? 34 From Radio onread

INFO | ??:??:?? 34 getFromRadio=STATE_SEND_CHANNELS

DEBUG | ??:??:?? 34 encoding toPhone packet to phone variant=10, 6 bytes

INFO | ??:??:?? 34 From Radio onread

INFO | ??:??:?? 34 getFromRadio=STATE_SEND_CHANNELS

DEBUG | ??:??:?? 34 encoding toPhone packet to phone variant=10, 6 bytes

INFO | ??:??:?? 34 From Radio onread

INFO | ??:??:?? 34 getFromRadio=STATE_SEND_CONFIG

DEBUG | ??:??:?? 34 encoding toPhone packet to phone variant=5, 9 bytes

INFO | ??:??:?? 34 From Radio onread

INFO | ??:??:?? 34 getFromRadio=STATE_SEND_CONFIG

DEBUG | ??:??:?? 34 encoding toPhone packet to phone variant=5, 20 bytes

INFO | ??:??:?? 34 From Radio onread

INFO | ??:??:?? 34 getFromRadio=STATE_SEND_CONFIG

DEBUG | ??:??:?? 34 encoding toPhone packet to phone variant=5, 17 bytes

INFO | ??:??:?? 35 From Radio onread

INFO | ??:??:?? 35 getFromRadio=STATE_SEND_CONFIG

DEBUG | ??:??:?? 35 encoding toPhone packet to phone variant=5, 29 bytes

INFO | ??:??:?? 35 From Radio onread

INFO | ??:??:?? 35 getFromRadio=STATE_SEND_CONFIG

DEBUG | ??:??:?? 35 encoding toPhone packet to phone variant=5, 7 bytes

INFO | ??:??:?? 35 From Radio onread

INFO | ??:??:?? 35 getFromRadio=STATE_SEND_CONFIG

DEBUG | ??:??:?? 35 encoding toPhone packet to phone variant=5, 18 bytes

INFO | ??:??:?? 35 From Radio onread

INFO | ??:??:?? 35 getFromRadio=STATE_SEND_CONFIG

DEBUG | ??:??:?? 35 encoding toPhone packet to phone variant=5, 10 bytes

INFO | ??:??:?? 35 From Radio onread

INFO | ??:??:?? 35 getFromRadio=STATE_SEND_MODULECONFIG

DEBUG | ??:??:?? 35 encoding toPhone packet to phone variant=9, 56 bytes

INFO | ??:??:?? 35 From Radio onread

INFO | ??:??:?? 35 getFromRadio=STATE_SEND_MODULECONFIG

DEBUG | ??:??:?? 35 encoding toPhone packet to phone variant=9, 4 bytes

INFO | ??:??:?? 35 From Radio onread

INFO | ??:??:?? 35 getFromRadio=STATE_SEND_MODULECONFIG

DEBUG | ??:??:?? 35 encoding toPhone packet to phone variant=9, 4 bytes

INFO | ??:??:?? 35 From Radio onread

INFO | ??:??:?? 35 getFromRadio=STATE_SEND_MODULECONFIG

DEBUG | ??:??:?? 35 encoding toPhone packet to phone variant=9, 4 bytes

INFO | ??:??:?? 35 From Radio onread

INFO | ??:??:?? 35 getFromRadio=STATE_SEND_MODULECONFIG

DEBUG | ??:??:?? 35 encoding toPhone packet to phone variant=9, 4 bytes

INFO | ??:??:?? 35 From Radio onread

INFO | ??:??:?? 35 getFromRadio=STATE_SEND_MODULECONFIG

DEBUG | ??:??:?? 35 encoding toPhone packet to phone variant=9, 13 bytes

INFO | ??:??:?? 35 From Radio onread

INFO | ??:??:?? 35 getFromRadio=STATE_SEND_MODULECONFIG

DEBUG | ??:??:?? 35 encoding toPhone packet to phone variant=9, 4 bytes

INFO | ??:??:?? 36 From Radio onread

INFO | ??:??:?? 36 getFromRadio=STATE_SEND_MODULECONFIG

DEBUG | ??:??:?? 36 encoding toPhone packet to phone variant=9, 4 bytes

INFO | ??:??:?? 36 From Radio onread

INFO | ??:??:?? 36 getFromRadio=STATE_SEND_MODULECONFIG

DEBUG | ??:??:?? 36 encoding toPhone packet to phone variant=9, 4 bytes

INFO | ??:??:?? 36 From Radio onread

INFO | ??:??:?? 36 getFromRadio=STATE_SEND_MODULECONFIG

DEBUG | ??:??:?? 36 encoding toPhone packet to phone variant=9, 7 bytes

INFO | ??:??:?? 36 From Radio onread

INFO | ??:??:?? 36 getFromRadio=STATE_SEND_MODULECONFIG

DEBUG | ??:??:?? 36 encoding toPhone packet to phone variant=9, 14 bytes

INFO | ??:??:?? 36 From Radio onread

INFO | ??:??:?? 36 getFromRadio=STATE_SEND_MODULECONFIG

DEBUG | ??:??:?? 36 encoding toPhone packet to phone variant=9, 8 bytes

INFO | ??:??:?? 36 From Radio onread

INFO | ??:??:?? 36 getFromRadio=STATE_SEND_MODULECONFIG

DEBUG | ??:??:?? 36 encoding toPhone packet to phone variant=9, 4 bytes

INFO | ??:??:?? 36 [Screen] From Radio onread

INFO | ??:??:?? 36 [Screen] getFromRadio=STATE_SEND_COMPLETE_ID

DEBUG | ??:??:?? 36 [Screen] encoding toPhone packet to phone variant=7, 2 bytes

INFO | ??:??:?? 36 From Radio onread

INFO | ??:??:?? 36 getFromRadio=STATE_SEND_PACKETS

DEBUG | ??:??:?? 36 encoding toPhone packet to phone variant=11, 6 bytes

INFO | ??:??:?? 36 From Radio onread

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

INFO | ??:??:?? 37 [NodeInfoModule] sending owner !75f17b80/Meshtastic 7b80/7b80

DEBUG | ??:??:?? 37 [NodeInfoModule] Initial packet id 95146658, numPacketId 4294967295

DEBUG | ??:??:?? 37 [NodeInfoModule] Update DB node 0x75f17b80, rx_time=0

DEBUG | ??:??:?? 37 [NodeInfoModule] handleReceived(LOCAL) (id=0x05abd2a4 fr=0x80 to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=4 WANTRESP priority=10)

DEBUG | ??:??:?? 37 [NodeInfoModule] No modules interested in portnum=4, src=LOCAL

DEBUG | ??:??:?? 37 [NodeInfoModule] localSend to channel 0

DEBUG | ??:??:?? 37 [NodeInfoModule] Add packet record (id=0x05abd2a4 fr=0x80 to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=4 WANTRESP priority=10)

DEBUG | ??:??:?? 37 [NodeInfoModule] Expanding short PSK #1

DEBUG | ??:??:?? 37 [NodeInfoModule] Using AES128 key!

DEBUG | ??:??:?? 37 [NodeInfoModule] ESP32 crypt fr=75f17b80, num=5abd2a4, numBytes=50!

DEBUG | ??:??:?? 37 [NodeInfoModule] enqueuing for send (id=0x05abd2a4 fr=0x80 to=0xff, WantAck=0, HopLim=3 Ch=0x8 encrypted hopStart=3 priority=10)

DEBUG | ??:??:?? 37 [NodeInfoModule] txGood=0,rxGood=0,rxBad=0

INFO | ??:??:?? 37 Telling client we have new packets 2

INFO | ??:??:?? 37 BLE notify fromNum

WARN | ??:??:?? 37 [RadioIf] Can not send yet, busyRx

INFO | ??:??:?? 37 From Radio onread

INFO | ??:??:?? 37 getFromRadio=STATE_SEND_PACKETS

DEBUG | ??:??:?? 37 encoding toPhone packet to phone variant=11, 11 bytes

WARN | ??:??:?? 37 [RadioIf] Can not send yet, busyRx

INFO | ??:??:?? 37 From Radio onread

WARN | ??:??:?? 37 [RadioIf] Can not send yet, busyRx

WARN | ??:??:?? 37 [RadioIf] Can not send yet, busyRx

WARN | ??:??:?? 37 [RadioIf] Can not send yet, busyRx

WARN | ??:??:?? 37 [RadioIf] Can not send yet, busyRx

DEBUG | ??:??:?? 37 [RadioIf] Ignore false preamble detection.

DEBUG | ??:??:?? 37 [RadioIf] Starting low level send (id=0x05abd2a4 fr=0x80 to=0xff, WantAck=0, HopLim=3 Ch=0x8 encrypted hopStart=3 priority=10)

DEBUG | ??:??:?? 37 [RadioIf] (bw=250, sf=11, cr=4/5) packet symLen=8 ms, payloadSize=66, time 722 ms

DEBUG | ??:??:?? 37 [RadioIf] AirTime - Packet transmitted : 722ms

DEBUG | ??:??:?? 38 [RadioIf] Completed sending (id=0x05abd2a4 fr=0x80 to=0xff, WantAck=0, HopLim=3 Ch=0x8 encrypted hopStart=3 priority=10)

DEBUG | ??:??:?? 40 [RadioIf] (bw=250, sf=11, cr=4/5) packet symLen=8 ms, payloadSize=66, time 722 ms

DEBUG | ??:??:?? 40 [RadioIf] Lora RX (id=0x05abd2a4 fr=0x80 to=0xff, WantAck=0, HopLim=2 Ch=0x8 encrypted rxSNR=4.25 rxRSSI=-83 hopStart=3)

DEBUG | ??:??:?? 40 [RadioIf] AirTime - Packet received : 722ms

DEBUG | ??:??:?? 40 [Router] Rx someone rebroadcasting for us (id=0x05abd2a4 fr=0x80 to=0xff, WantAck=0, HopLim=2 Ch=0x8 encrypted rxSNR=4.25 rxRSSI=-83 hopStart=3)

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

DEBUG | ??:??:?? 40 [Router] Found existing packet record for fr=0x75f17b80,to=0xffffffff,id=0x5abd2a4

DEBUG | ??:??:?? 40 [Router] Found existing packet record for fr=0x75f17b80,to=0xffffffff,id=0x5abd2a4

DEBUG | ??:??:?? 40 [Router] Add packet record (id=0x05abd2a4 fr=0x80 to=0xff, WantAck=0, HopLim=2 Ch=0x8 encrypted rxSNR=4.25 rxRSSI=-83 hopStart=3)

DEBUG | ??:??:?? 40 [Router] Ignoring incoming msg, because we've already seen it (id=0x05abd2a4 fr=0x80 to=0xff, WantAck=0, HopLim=2 Ch=0x8 encrypted rxSNR=4.25 rxRSSI=-83 hopStart=3)

DEBUG | ??:??:?? 40 [Router] cancelSending id=0x5abd2a4, removed=0

DEBUG | ??:??:?? 40 [Router] Incoming message was filtered 0x75f17b80

DEBUG | ??:??:?? 47 [Power] Battery: usbPower=1, isCharging=1, batMv=4711, batPct=100

INFO | ??:??:?? 52 [DeviceTelemetryModule] (Sending): air_util_tx=0.020056, channel_utilization=2.406667, battery_level=101, voltage=4.711000, uptime=52

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

DEBUG | ??:??:?? 52 [DeviceTelemetryModule] Node status update: 25 online, 25 total

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

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

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

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

DEBUG | ??:??:?? 52 [DeviceTelemetryModule] Finished building frames. numframes: 7

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

DEBUG | ??:??:?? 52 [DeviceTelemetryModule] Update DB node 0x75f17b80, rx_time=0

DEBUG | ??:??:?? 52 [DeviceTelemetryModule] handleReceived(LOCAL) (id=0x05abd2a5 fr=0x80 to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=67 priority=10)

DEBUG | ??:??:?? 52 [DeviceTelemetryModule] No modules interested in portnum=67, src=LOCAL

DEBUG | ??:??:?? 52 [DeviceTelemetryModule] localSend to channel 0

DEBUG | ??:??:?? 52 [DeviceTelemetryModule] Add packet record (id=0x05abd2a5 fr=0x80 to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=67 priority=10)

DEBUG | ??:??:?? 52 [DeviceTelemetryModule] Expanding short PSK #1

DEBUG | ??:??:?? 52 [DeviceTelemetryModule] Using AES128 key!

DEBUG | ??:??:?? 52 [DeviceTelemetryModule] ESP32 crypt fr=75f17b80, num=5abd2a5, numBytes=30!

DEBUG | ??:??:?? 52 [DeviceTelemetryModule] enqueuing for send (id=0x05abd2a5 fr=0x80 to=0xff, WantAck=0, HopLim=3 Ch=0x8 encrypted hopStart=3 priority=10)

DEBUG | ??:??:?? 52 [DeviceTelemetryModule] txGood=1,rxGood=1,rxBad=0

DEBUG | ??:??:?? 52 [DeviceTelemetryModule] Using channel 0 (hash 0x8)

DEBUG | ??:??:?? 52 [DeviceTelemetryModule] Expanding short PSK #1

DEBUG | ??:??:?? 52 [DeviceTelemetryModule] Using AES128 key!

DEBUG | ??:??:?? 52 [DeviceTelemetryModule] ESP32 crypt fr=75f17b80, num=5abd2a5, numBytes=30!

DEBUG | ??:??:?? 52 [DeviceTelemetryModule] decoded message (id=0x05abd2a5 fr=0x80 to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=67 hopStart=3 priority=10)

INFO | ??:??:?? 52 Telling client we have new packets 4

INFO | ??:??:?? 52 BLE notify fromNum

WARN | ??:??:?? 52 [RadioIf] Can not send yet, busyRx

WARN | ??:??:?? 52 [RadioIf] Can not send yet, busyRx

INFO | ??:??:?? 52 From Radio onread

INFO | ??:??:?? 52 [RadioIf] getFromRadio=STATE_SEND_PACKETS

WARN | ??:??:?? 52 [RadioIf] Can not send yet, busyRx

DEBUG | ??:??:?? 52 encoding toPhone packet to phone variant=11, 11 bytes

INFO | ??:??:?? 52 From Radio onread

INFO | ??:??:?? 52 getFromRadio=STATE_SEND_PACKETS

DEBUG | ??:??:?? 52 phone downloaded packet (id=0x05abd2a5 fr=0x80 to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=67 hopStart=3 priority=10)

DEBUG | ??:??:?? 52 encoding toPhone packet to phone variant=2, 55 bytes

WARN | ??:??:?? 52 [RadioIf] Can not send yet, busyRx

INFO | ??:??:?? 52 From Radio onread

WARN | ??:??:?? 52 [RadioIf] Can not send yet, busyRx

WARN | ??:??:?? 52 [RadioIf] Can not send yet, busyRx

DEBUG | ??:??:?? 52 [RadioIf] Ignore false preamble detection.

DEBUG | ??:??:?? 52 [RadioIf] Starting low level send (id=0x05abd2a5 fr=0x80 to=0xff, WantAck=0, HopLim=3 Ch=0x8 encrypted hopStart=3 priority=10)

DEBUG | ??:??:?? 52 [RadioIf] (bw=250, sf=11, cr=4/5) packet symLen=8 ms, payloadSize=46, time 575 ms

DEBUG | ??:??:?? 52 [RadioIf] AirTime - Packet transmitted : 575ms

DEBUG | ??:??:?? 53 [RadioIf] Completed sending (id=0x05abd2a5 fr=0x80 to=0xff, WantAck=0, HopLim=3 Ch=0x8 encrypted hopStart=3 priority=10)

DEBUG | ??:??:?? 67 [Power] Battery: usbPower=1, isCharging=1, batMv=4743, batPct=100

DEBUG | ??:??:?? 87 [Power] Battery: usbPower=1, isCharging=1, batMv=4749, batPct=100

DEBUG | ??:??:?? 107 [Power] Battery: usbPower=1, isCharging=1, batMv=4743, batPct=100

INFO | ??:??:?? 112 [DeviceTelemetryModule] (Sending): air_util_tx=0.036028, channel_utilization=0.000000, battery_level=101, voltage=4.743000, uptime=112

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

DEBUG | ??:??:?? 112 [DeviceTelemetryModule] Node status update: 25 online, 25 total

INFO | ??:??:?? 112 [DeviceTelemetryModule] Sending packet to phone

INFO | ??:??:?? 112 Telling client we have new packets 5

INFO | ??:??:?? 112 BLE notify fromNum

INFO | ??:??:?? 112 From Radio onread

INFO | ??:??:?? 112 getFromRadio=STATE_SEND_PACKETS

DEBUG | ??:??:?? 112 phone downloaded packet (id=0x05abd2a6 fr=0x80 to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=67 priority=10)

DEBUG | ??:??:?? 112 encoding toPhone packet to phone variant=2, 48 bytes

INFO | ??:??:?? 112 From Radio onread

DEBUG | ??:??:?? 123 [RadioIf] (bw=250, sf=11, cr=4/5) packet symLen=8 ms, payloadSize=66, time 722 ms

DEBUG | ??:??:?? 123 [RadioIf] Lora RX (id=0x60b4203d fr=0x3c to=0xff, WantAck=0, HopLim=5 Ch=0x8 encrypted rxSNR=3.75 rxRSSI=-82 hopStart=5)

DEBUG | ??:??:?? 123 [RadioIf] AirTime - Packet received : 722ms

DEBUG | ??:??:?? 123 [Router] Add packet record (id=0x60b4203d fr=0x3c to=0xff, WantAck=0, HopLim=5 Ch=0x8 encrypted rxSNR=3.75 rxRSSI=-82 hopStart=5)

DEBUG | ??:??:?? 123 [Router] Using channel 0 (hash 0x8)

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

DEBUG | ??:??:?? 123 [Router] Using AES128 key!

DEBUG | ??:??:?? 123 [Router] ESP32 crypt fr=33698e3c, num=60b4203d, numBytes=50!

DEBUG | ??:??:?? 123 [Router] decoded message (id=0x60b4203d fr=0x3c to=0xff, WantAck=0, HopLim=5 Ch=0x0 Portnum=4 rxSNR=3.75 rxRSSI=-82 hopStart=5)

DEBUG | ??:??:?? 123 [Router] handleReceived(REMOTE) (id=0x60b4203d fr=0x3c to=0xff, WantAck=0, HopLim=5 Ch=0x0 Portnum=4 rxSNR=3.75 rxRSSI=-82 hopStart=5)

DEBUG | ??:??:?? 123 [Router] Module 'nodeinfo' wantsPacket=1

INFO | ??:??:?? 123 [Router] Received nodeinfo from=0x33698e3c, id=0x60b4203d, portnum=4, payloadlen=46

DEBUG | ??:??:?? 123 [Router] old user //, channel=0

DEBUG | ??:??:?? 123 [Router] updating changed=1 user !33698e3c/Meshtastic 8e3c/8e3c, channel=0

DEBUG | ??:??:?? 123 [Router] Node status update: 26 online, 26 total

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

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

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

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

DEBUG | ??:??:?? 123 [Router] Finished building frames. numframes: 7

INFO | ??:??:?? 123 [Router] Saving /prefs/db.proto

DEBUG | ??:??:?? 123 [Router] Module 'nodeinfo' considered

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

INFO | ??:??:?? 123 [Router] Received routing from=0x33698e3c, id=0x60b4203d, portnum=4, payloadlen=46

DEBUG | ??:??:?? 123 [Router] Routing sniffing (id=0x60b4203d fr=0x3c to=0xff, WantAck=0, HopLim=5 Ch=0x0 Portnum=4 rxSNR=3.75 rxRSSI=-82 hopStart=5)

INFO | ??:??:?? 123 [Router] Rebroadcasting received floodmsg to neighbors

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

DEBUG | ??:??:?? 123 [Router] Using AES128 key!

DEBUG | ??:??:?? 123 [Router] ESP32 crypt fr=33698e3c, num=60b4203d, numBytes=50!

DEBUG | ??:??:?? 123 [Router] enqueuing for send (id=0x60b4203d fr=0x3c to=0xff, WantAck=0, HopLim=4 Ch=0x8 encrypted rxSNR=3.75 rxRSSI=-82 hopStart=5)

DEBUG | ??:??:?? 123 [Router] txGood=2,rxGood=2,rxBad=0

DEBUG | ??:??:?? 123 [Router] rx_snr found. hop_limit:4 rx_snr:3.750000

DEBUG | ??:??:?? 123 [Router] rx_snr found in packet. Setting tx delay:1764

DEBUG | ??:??:?? 123 [Router] Delivering rx packet (id=0x60b4203d fr=0x3c to=0xff, WantAck=0, HopLim=5 Ch=0x0 Portnum=4 rxSNR=3.75 rxRSSI=-82 hopStart=5)

DEBUG | ??:??:?? 123 [Router] Update DB node 0x33698e3c, rx_time=0

DEBUG | ??:??:?? 123 [Router] Forwarding to phone (id=0x60b4203d fr=0x3c to=0xff, WantAck=0, HopLim=5 Ch=0x0 Portnum=4 rxSNR=3.75 rxRSSI=-82 hopStart=5)

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

DEBUG | ??:??:?? 123 [Screen] Screen: Joined: Meshtastic 8e3c

INFO | ??:??:?? 123 Telling client we have new packets 6

INFO | ??:??:?? 123 BLE notify fromNum

INFO | ??:??:?? 123 From Radio onread

INFO | ??:??:?? 123 getFromRadio=STATE_SEND_PACKETS

DEBUG | ??:??:?? 123 phone downloaded packet (id=0x60b4203d fr=0x3c to=0xff, WantAck=0, HopLim=5 Ch=0x0 Portnum=4 rxSNR=3.75 rxRSSI=-82 hopStart=5)

DEBUG | ??:??:?? 123 encoding toPhone packet to phone variant=2, 89 bytes

INFO | ??:??:?? 123 From Radio onread

WARN | ??:??:?? 125 [RadioIf] Can not send yet, busyRx

DEBUG | ??:??:?? 125 [RadioIf] rx_snr found. hop_limit:4 rx_snr:3.750000

DEBUG | ??:??:?? 125 [RadioIf] rx_snr found in packet. Setting tx delay:1386

DEBUG | ??:??:?? 126 [RadioIf] Ignore false preamble detection.

DEBUG | ??:??:?? 126 [RadioIf] rx_snr found. hop_limit:4 rx_snr:3.750000

DEBUG | ??:??:?? 126 [RadioIf] rx_snr found in packet. Setting tx delay:1848

DEBUG | ??:??:?? 127 [Power] Battery: usbPower=1, isCharging=1, batMv=4732, batPct=100

WARN | ??:??:?? 128 [RadioIf] Can not send yet, busyRx

DEBUG | ??:??:?? 128 [RadioIf] rx_snr found. hop_limit:4 rx_snr:3.750000

DEBUG | ??:??:?? 128 [RadioIf] rx_snr found in packet. Setting tx delay:882

DEBUG | ??:??:?? 129 [RadioIf] Ignore false preamble detection.

DEBUG | ??:??:?? 129 [RadioIf] Starting low level send (id=0x60b4203d fr=0x3c to=0xff, WantAck=0, HopLim=4 Ch=0x8 encrypted rxSNR=3.75 rxRSSI=-82 hopStart=5 priority=64)

DEBUG | ??:??:?? 129 [RadioIf] (bw=250, sf=11, cr=4/5) packet symLen=8 ms, payloadSize=66, time 722 ms

DEBUG | ??:??:?? 129 [RadioIf] AirTime - Packet transmitted : 722ms

DEBUG | ??:??:?? 130 [RadioIf] Completed sending (id=0x60b4203d fr=0x3c to=0xff, WantAck=0, HopLim=4 Ch=0x8 encrypted rxSNR=3.75 rxRSSI=-82 hopStart=5 priority=64)

DEBUG | ??:??:?? 147 [Power] Battery: usbPower=1, isCharging=1, batMv=4766, batPct=100

DEBUG | ??:??:?? 148 [RadioIf] (bw=250, sf=11, cr=4/5) packet symLen=8 ms, payloadSize=48, time 591 ms

DEBUG | ??:??:?? 148 [RadioIf] Lora RX (id=0x48baddb0 fr=0xc4 to=0xff, WantAck=0, HopLim=4 Ch=0x8 encrypted rxSNR=4.25 rxRSSI=-83 hopStart=5)

DEBUG | ??:??:?? 148 [RadioIf] AirTime - Packet received : 591ms

DEBUG | ??:??:?? 148 [Router] Add packet record (id=0x48baddb0 fr=0xc4 to=0xff, WantAck=0, HopLim=4 Ch=0x8 encrypted rxSNR=4.25 rxRSSI=-83 hopStart=5)

DEBUG | ??:??:?? 148 [Router] Using channel 0 (hash 0x8)

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

DEBUG | ??:??:?? 148 [Router] Using AES128 key!

DEBUG | ??:??:?? 148 [Router] ESP32 crypt fr=a1df30c4, num=48baddb0, numBytes=32!

DEBUG | ??:??:?? 148 [Router] decoded message (id=0x48baddb0 fr=0xc4 to=0xff, WantAck=0, HopLim=4 Ch=0x0 Portnum=67 rxSNR=4.25 rxRSSI=-83 hopStart=5)

DEBUG | ??:??:?? 148 [Router] handleReceived(REMOTE) (id=0x48baddb0 fr=0xc4 to=0xff, WantAck=0, HopLim=4 Ch=0x0 Portnum=67 rxSNR=4.25 rxRSSI=-83 hopStart=5)

DEBUG | ??:??:?? 148 [Router] Module 'DeviceTelemetry' wantsPacket=1

INFO | ??:??:?? 148 [Router] Received DeviceTelemetry from=0xa1df30c4, id=0x48baddb0, portnum=67, payloadlen=28

INFO | ??:??:?? 148 [Router] (Received from BeS1): air_util_tx=0.124083, channel_utilization=0.821667, battery_level=95, voltage=4.120000

DEBUG | ??:??:?? 148 [Router] updateTelemetry REMOTE node=0xa1df30c4

DEBUG | ??:??:?? 148 [Router] Node status update: 26 online, 26 total

DEBUG | ??:??:?? 148 [Router] Module 'DeviceTelemetry' considered

DEBUG | ??:??:?? 148 [Router] Module 'EnvironmentTelemetry' wantsPacket=1

INFO | ??:??:?? 148 [Router] Received EnvironmentTelemetry from=0xa1df30c4, id=0x48baddb0, portnum=67, payloadlen=28

DEBUG | ??:??:?? 148 [Router] Module 'EnvironmentTelemetry' considered

DEBUG | ??:??:?? 148 [Router] Module 'PowerTelemetry' wantsPacket=1

INFO | ??:??:?? 148 [Router] Received PowerTelemetry from=0xa1df30c4, id=0x48baddb0, portnum=67, payloadlen=28

DEBUG | ??:??:?? 148 [Router] Module 'PowerTelemetry' considered

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

INFO | ??:??:?? 148 [Router] Received routing from=0xa1df30c4, id=0x48baddb0, portnum=67, payloadlen=28

DEBUG | ??:??:?? 148 [Router] Routing sniffing (id=0x48baddb0 fr=0xc4 to=0xff, WantAck=0, HopLim=4 Ch=0x0 Portnum=67 rxSNR=4.25 rxRSSI=-83 hopStart=5)

INFO | ??:??:?? 148 [Router] Rebroadcasting received floodmsg to neighbors

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

DEBUG | ??:??:?? 148 [Router] Using AES128 key!

DEBUG | ??:??:?? 148 [Router] ESP32 crypt fr=a1df30c4, num=48baddb0, numBytes=32!

DEBUG | ??:??:?? 148 [Router] enqueuing for send (id=0x48baddb0 fr=0xc4 to=0xff, WantAck=0, HopLim=3 Ch=0x8 encrypted rxSNR=4.25 rxRSSI=-83 hopStart=5)

DEBUG | ??:??:?? 148 [Router] txGood=3,rxGood=3,rxBad=0

DEBUG | ??:??:?? 148 [Router] rx_snr found. hop_limit:3 rx_snr:4.250000

DEBUG | ??:??:?? 148 [Router] rx_snr found in packet. Setting tx delay:1680

DEBUG | ??:??:?? 148 [Router] Delivering rx packet (id=0x48baddb0 fr=0xc4 to=0xff, WantAck=0, HopLim=4 Ch=0x0 Portnum=67 rxSNR=4.25 rxRSSI=-83 hopStart=5)

DEBUG | ??:??:?? 148 [Router] Update DB node 0xa1df30c4, rx_time=0

DEBUG | ??:??:?? 148 [Router] Forwarding to phone (id=0x48baddb0 fr=0xc4 to=0xff, WantAck=0, HopLim=4 Ch=0x0 Portnum=67 rxSNR=4.25 rxRSSI=-83 hopStart=5)

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

INFO | ??:??:?? 148 Telling client we have new packets 7

INFO | ??:??:?? 148 BLE notify fromNum

INFO | ??:??:?? 149 From Radio onread

INFO | ??:??:?? 149 getFromRadio=STATE_SEND_PACKETS

DEBUG | ??:??:?? 149 phone downloaded packet (id=0x48baddb0 fr=0xc4 to=0xff, WantAck=0, HopLim=4 Ch=0x0 Portnum=67 rxSNR=4.25 rxRSSI=-83 hopStart=5)

DEBUG | ??:??:?? 149 encoding toPhone packet to phone variant=2, 71 bytes

INFO | ??:??:?? 149 From Radio onread

WARN | ??:??:?? 150 [RadioIf] Can not send yet, busyRx

DEBUG | ??:??:?? 150 [RadioIf] rx_snr found. hop_limit:3 rx_snr:4.250000

DEBUG | ??:??:?? 150 [RadioIf] rx_snr found in packet. Setting tx delay:2016

DEBUG | ??:??:?? 152 [RadioIf] Ignore false preamble detection.

DEBUG | ??:??:?? 152 [RadioIf] rx_snr found. hop_limit:3 rx_snr:4.250000

DEBUG | ??:??:?? 152 [RadioIf] rx_snr found in packet. Setting tx delay:1302

DEBUG | ??:??:?? 153 [RadioIf] rx_snr found. hop_limit:3 rx_snr:4.250000

DEBUG | ??:??:?? 153 [RadioIf] rx_snr found in packet. Setting tx delay:3234

WARN | ??:??:?? 157 [RadioIf] Can not send yet, busyRx

DEBUG | ??:??:?? 157 [RadioIf] rx_snr found. hop_limit:3 rx_snr:4.250000

DEBUG | ??:??:?? 157 [RadioIf] rx_snr found in packet. Setting tx delay:2226

DEBUG | ??:??:?? 159 [RadioIf] Ignore false preamble detection.

DEBUG | ??:??:?? 159 [RadioIf] rx_snr found. hop_limit:3 rx_snr:4.250000

DEBUG | ??:??:?? 159 [RadioIf] rx_snr found in packet. Setting tx delay:2520

DEBUG | ??:??:?? 162 [RadioIf] rx_snr found. hop_limit:3 rx_snr:4.250000

DEBUG | ??:??:?? 162 [RadioIf] rx_snr found in packet. Setting tx delay:3150

WARN | ??:??:?? 165 [RadioIf] Can not send yet, busyRx

DEBUG | ??:??:?? 165 [RadioIf] rx_snr found. hop_limit:3 rx_snr:4.250000

DEBUG | ??:??:?? 165 [RadioIf] rx_snr found in packet. Setting tx delay:2016

DEBUG | ??:??:?? 167 [RadioIf] Ignore false preamble detection.

DEBUG | ??:??:?? 167 [RadioIf] Starting low level send (id=0x48baddb0 fr=0xc4 to=0xff, WantAck=0, HopLim=3 Ch=0x8 encrypted rxSNR=4.25 rxRSSI=-83 hopStart=5 priority=64)

DEBUG | ??:??:?? 167 [RadioIf] (bw=250, sf=11, cr=4/5) packet symLen=8 ms, payloadSize=48, time 591 ms

DEBUG | ??:??:?? 167 [RadioIf] AirTime - Packet transmitted : 591ms

DEBUG | ??:??:?? 167 [Power] Battery: usbPower=1, isCharging=1, batMv=4755, batPct=100

DEBUG | ??:??:?? 167 [RadioIf] Completed sending (id=0x48baddb0 fr=0xc4 to=0xff, WantAck=0, HopLim=3 Ch=0x8 encrypted rxSNR=4.25 rxRSSI=-83 hopStart=5 priority=64)

INFO | ??:??:?? 172 [DeviceTelemetryModule] (Sending): air_util_tx=0.072500, channel_utilization=4.376667, battery_level=101, voltage=4.755000, uptime=172

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

DEBUG | ??:??:?? 172 [DeviceTelemetryModule] Node status update: 26 online, 26 total

INFO | ??:??:?? 172 [DeviceTelemetryModule] Sending packet to phone

INFO | ??:??:?? 172 Telling client we have new packets 8

INFO | ??:??:?? 172 BLE notify fromNum

INFO | ??:??:?? 172 From Radio onread

INFO | ??:??:?? 172 getFromRadio=STATE_SEND_PACKETS

DEBUG | ??:??:?? 172 phone downloaded packet (id=0x05abd2a7 fr=0x80 to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=67 priority=10)

DEBUG | ??:??:?? 172 encoding toPhone packet to phone variant=2, 54 bytes

INFO | ??:??:?? 172 From Radio onread

DEBUG | ??:??:?? 187 [Power] Battery: usbPower=1, isCharging=1, batMv=4713, batPct=100

DEBUG | ??:??:?? 207 [Power] Battery: usbPower=1, isCharging=1, batMv=4703, batPct=100

DEBUG | ??:??:?? 227 [Power] Battery: usbPower=1, isCharging=1, batMv=4727, batPct=100

INFO | ??:??:?? 232 [DeviceTelemetryModule] (Sending): air_util_tx=0.072500, channel_utilization=0.000000, battery_level=101, voltage=4.727000, uptime=232

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

DEBUG | ??:??:?? 232 [DeviceTelemetryModule] Node status update: 26 online, 26 total

INFO | ??:??:?? 232 [DeviceTelemetryModule] Sending packet to phone

INFO | ??:??:?? 232 Telling client we have new packets 9

INFO | ??:??:?? 232 BLE notify fromNum

INFO | ??:??:?? 232 From Radio onread

INFO | ??:??:?? 232 getFromRadio=STATE_SEND_PACKETS

DEBUG | ??:??:?? 232 phone downloaded packet (id=0x05abd2a8 fr=0x80 to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=67 priority=10)

DEBUG | ??:??:?? 232 encoding toPhone packet to phone variant=2, 49 bytes

INFO | ??:??:?? 232 From Radio onread

DEBUG | ??:??:?? 247 [Power] Battery: usbPower=1, isCharging=1, batMv=4745, batPct=100

DEBUG | ??:??:?? 267 [Power] Battery: usbPower=1, isCharging=1, batMv=4739, batPct=100

DEBUG | ??:??:?? 287 [Power] Battery: usbPower=1, isCharging=1, batMv=4742, batPct=100

INFO | ??:??:?? 292 [DeviceTelemetryModule] (Sending): air_util_tx=0.072500, channel_utilization=0.000000, battery_level=101, voltage=4.742000, uptime=292

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

DEBUG | ??:??:?? 292 [DeviceTelemetryModule] Node status update: 26 online, 26 total

INFO | ??:??:?? 292 [DeviceTelemetryModule] Sending packet to phone

INFO | ??:??:?? 292 Telling client we have new packets 10

INFO | ??:??:?? 292 BLE notify fromNum

INFO | ??:??:?? 292 From Radio onread

INFO | ??:??:?? 292 getFromRadio=STATE_SEND_PACKETS

DEBUG | ??:??:?? 292 phone downloaded packet (id=0x05abd2a9 fr=0x80 to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=67 priority=10)

DEBUG | ??:??:?? 292 encoding toPhone packet to phone variant=2, 49 bytes

INFO | ??:??:?? 292 From Radio onread

DEBUG | ??:??:?? 307 [Power] Battery: usbPower=1, isCharging=1, batMv=4732, batPct=100

DEBUG | ??:??:?? 327 [Power] Battery: usbPower=1, isCharging=1, batMv=4717, batPct=100

DEBUG | ??:??:?? 347 [Power] Battery: usbPower=1, isCharging=1, batMv=4703, batPct=100

INFO | ??:??:?? 352 [DeviceTelemetryModule] (Sending): air_util_tx=0.072500, channel_utilization=0.000000, battery_level=101, voltage=4.703000, uptime=352

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

DEBUG | ??:??:?? 352 [DeviceTelemetryModule] Node status update: 26 online, 26 total

INFO | ??:??:?? 352 [DeviceTelemetryModule] Sending packet to phone

INFO | ??:??:?? 352 Telling client we have new packets 11

INFO | ??:??:?? 352 BLE notify fromNum

INFO | ??:??:?? 352 From Radio onread

INFO | ??:??:?? 352 getFromRadio=STATE_SEND_PACKETS

DEBUG | ??:??:?? 352 phone downloaded packet (id=0x05abd2aa fr=0x80 to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=67 priority=10)

DEBUG | ??:??:?? 352 encoding toPhone packet to phone variant=2, 49 bytes

INFO | ??:??:?? 352 From Radio onread

INFO | ??:??:?? 367 To Radio onwrite

DEBUG | ??:??:?? 367 PACKET FROM PHONE (id=0xdf1c0a2e fr=0x00 to=0x80, WantAck=1, HopLim=3 Ch=0x0 Portnum=6 WANTRESP priority=70)

DEBUG | ??:??:?? 367 Enqueued local (id=0xdf1c0a2e fr=0x00 to=0x80, WantAck=1, HopLim=3 Ch=0x0 Portnum=6 WANTRESP priority=70)

DEBUG | ??:??:?? 367 [Router] Add packet record (id=0xdf1c0a2e fr=0x00 to=0x80, WantAck=1, HopLim=3 Ch=0x0 Portnum=6 WANTRESP priority=70)

DEBUG | ??:??:?? 367 [Router] handleReceived(REMOTE) (id=0xdf1c0a2e fr=0x00 to=0x80, WantAck=1, HopLim=3 Ch=0x0 Portnum=6 WANTRESP priority=70)

DEBUG | ??:??:?? 367 [Router] Module 'Admin' wantsPacket=1

INFO | ??:??:?? 367 [Router] Received Admin from=0x0, id=0xdf1c0a2e, portnum=6, payloadlen=2

INFO | ??:??:?? 367 [Router] Client is getting owner

INFO | ??:??:?? 367 [Router] Asked module 'Admin' to send a response

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

INFO | ??:??:?? 367 [Router] Received routing from=0x0, id=0xdf1c0a2e, portnum=6, payloadlen=2

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

DEBUG | ??:??:?? 367 [Router] Some other module has replied to this message, no need for a 2nd ack

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

DEBUG | ??:??:?? 367 [Router] Sending response (id=0x05abd2ab fr=0x80 to=0x80, WantAck=0, HopLim=3 Ch=0x0 Portnum=6 requestId=df1c0a2e priority=70)

DEBUG | ??:??:?? 367 [Router] Update DB node 0x75f17b80, rx_time=0

DEBUG | ??:??:?? 367 [Router] Enqueued local (id=0x05abd2ab fr=0x80 to=0x80, WantAck=0, HopLim=3 Ch=0x0 Portnum=6 requestId=df1c0a2e priority=70)

DEBUG | ??:??:?? 367 [Router] Rx someone rebroadcasting for us (id=0x05abd2ab fr=0x80 to=0x80, WantAck=0, HopLim=3 Ch=0x0 Portnum=6 requestId=df1c0a2e priority=70)

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

DEBUG | ??:??:?? 367 [Router] Add packet record (id=0x05abd2ab fr=0x80 to=0x80, WantAck=0, HopLim=3 Ch=0x0 Portnum=6 requestId=df1c0a2e priority=70)

DEBUG | ??:??:?? 367 [Router] handleReceived(REMOTE) (id=0x05abd2ab fr=0x80 to=0x80, WantAck=0, HopLim=3 Ch=0x0 Portnum=6 requestId=df1c0a2e priority=70)

DEBUG | ??:??:?? 367 [Router] Module 'Admin' wantsPacket=1

DEBUG | ??:??:?? 367 [Router] packet on wrong channel, but can't respond (id=0x05abd2ab fr=0x80 to=0x80, WantAck=0, HopLim=3 Ch=0x0 Portnum=6 requestId=df1c0a2e priority=70)

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

INFO | ??:??:?? 367 [Router] Received routing from=0x75f17b80, id=0x5abd2ab, portnum=6, payloadlen=46

DEBUG | ??:??:?? 367 [Router] Routing sniffing (id=0x05abd2ab fr=0x80 to=0x80, WantAck=0, HopLim=3 Ch=0x0 Portnum=6 requestId=df1c0a2e priority=70)

DEBUG | ??:??:?? 367 [Router] Received an ack for 0xdf1c0a2e, stopping retransmissions

DEBUG | ??:??:?? 367 [Router] Delivering rx packet (id=0x05abd2ab fr=0x80 to=0x80, WantAck=0, HopLim=3 Ch=0x0 Portnum=6 requestId=df1c0a2e priority=70)

DEBUG | ??:??:?? 367 [Router] Update DB node 0x75f17b80, rx_time=0

DEBUG | ??:??:?? 367 [Router] Forwarding to phone (id=0x05abd2ab fr=0x80 to=0x80, WantAck=0, HopLim=3 Ch=0x0 Portnum=6 requestId=df1c0a2e priority=70)

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

INFO | ??:??:?? 367 Telling client we have new packets 14

INFO | ??:??:?? 367 BLE notify fromNum

DEBUG | ??:??:?? 367 [Power] Battery: usbPower=1, isCharging=1, batMv=4690, batPct=100

INFO | ??:??:?? 367 From Radio onread

INFO | ??:??:?? 367 getFromRadio=STATE_SEND_PACKETS

DEBUG | ??:??:?? 367 encoding toPhone packet to phone variant=11, 12 bytes

INFO | ??:??:?? 367 From Radio onread

INFO | ??:??:?? 367 getFromRadio=STATE_SEND_PACKETS

DEBUG | ??:??:?? 367 encoding toPhone packet to phone variant=11, 11 bytes

INFO | ??:??:?? 367 From Radio onread

INFO | ??:??:?? 367 getFromRadio=STATE_SEND_PACKETS

DEBUG | ??:??:?? 367 phone downloaded packet (id=0x05abd2ab fr=0x80 to=0x80, WantAck=0, HopLim=3 Ch=0x0 Portnum=6 requestId=df1c0a2e priority=70)

DEBUG | ??:??:?? 367 encoding toPhone packet to phone variant=2, 78 bytes

INFO | ??:??:?? 367 From Radio onread

INFO | ??:??:?? 370 To Radio onwrite

DEBUG | ??:??:?? 370 PACKET FROM PHONE (id=0xdf1c0a2f fr=0x00 to=0x80, WantAck=1, HopLim=3 Ch=0x0 Portnum=6 WANTRESP priority=70)

DEBUG | ??:??:?? 370 Enqueued local (id=0xdf1c0a2f fr=0x00 to=0x80, WantAck=1, HopLim=3 Ch=0x0 Portnum=6 WANTRESP priority=70)

DEBUG | ??:??:?? 370 [Router] Add packet record (id=0xdf1c0a2f fr=0x00 to=0x80, WantAck=1, HopLim=3 Ch=0x0 Portnum=6 WANTRESP priority=70)

DEBUG | ??:??:?? 370 [Router] handleReceived(REMOTE) (id=0xdf1c0a2f fr=0x00 to=0x80, WantAck=1, HopLim=3 Ch=0x0 Portnum=6 WANTRESP priority=70)

DEBUG | ??:??:?? 370 [Router] Module 'Admin' wantsPacket=1

INFO | ??:??:?? 370 [Router] Received Admin from=0x0, id=0xdf1c0a2f, portnum=6, payloadlen=2

INFO | ??:??:?? 370 [Router] Client is getting owner

INFO | ??:??:?? 370 [Router] Asked module 'Admin' to send a response

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

INFO | ??:??:?? 370 [Router] Received routing from=0x0, id=0xdf1c0a2f, portnum=6, payloadlen=2

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

DEBUG | ??:??:?? 370 [Router] Some other module has replied to this message, no need for a 2nd ack

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

DEBUG | ??:??:?? 370 [Router] Sending response (id=0x05abd2ac fr=0x80 to=0x80, WantAck=0, HopLim=3 Ch=0x0 Portnum=6 requestId=df1c0a2f priority=70)

DEBUG | ??:??:?? 370 [Router] Update DB node 0x75f17b80, rx_time=0

DEBUG | ??:??:?? 370 [Router] Enqueued local (id=0x05abd2ac fr=0x80 to=0x80, WantAck=0, HopLim=3 Ch=0x0 Portnum=6 requestId=df1c0a2f priority=70)

DEBUG | ??:??:?? 370 [Router] Rx someone rebroadcasting for us (id=0x05abd2ac fr=0x80 to=0x80, WantAck=0, HopLim=3 Ch=0x0 Portnum=6 requestId=df1c0a2f priority=70)

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

DEBUG | ??:??:?? 370 [Router] Add packet record (id=0x05abd2ac fr=0x80 to=0x80, WantAck=0, HopLim=3 Ch=0x0 Portnum=6 requestId=df1c0a2f priority=70)

DEBUG | ??:??:?? 370 [Router] handleReceived(REMOTE) (id=0x05abd2ac fr=0x80 to=0x80, WantAck=0, HopLim=3 Ch=0x0 Portnum=6 requestId=df1c0a2f priority=70)

DEBUG | ??:??:?? 370 [Router] Module 'Admin' wantsPacket=1

DEBUG | ??:??:?? 370 [Router] packet on wrong channel, but can't respond (id=0x05abd2ac fr=0x80 to=0x80, WantAck=0, HopLim=3 Ch=0x0 Portnum=6 requestId=df1c0a2f priority=70)

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

INFO | ??:??:?? 370 [Router] Received routing from=0x75f17b80, id=0x5abd2ac, portnum=6, payloadlen=46

DEBUG | ??:??:?? 370 [Router] Routing sniffing (id=0x05abd2ac fr=0x80 to=0x80, WantAck=0, HopLim=3 Ch=0x0 Portnum=6 requestId=df1c0a2f priority=70)

DEBUG | ??:??:?? 370 [Router] Received an ack for 0xdf1c0a2f, stopping retransmissions

DEBUG | ??:??:?? 370 [Router] Delivering rx packet (id=0x05abd2ac fr=0x80 to=0x80, WantAck=0, HopLim=3 Ch=0x0 Portnum=6 requestId=df1c0a2f priority=70)

DEBUG | ??:??:?? 370 [Router] Update DB node 0x75f17b80, rx_time=0

DEBUG | ??:??:?? 370 [Router] Forwarding to phone (id=0x05abd2ac fr=0x80 to=0x80, WantAck=0, HopLim=3 Ch=0x0 Portnum=6 requestId=df1c0a2f priority=70)

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

INFO | ??:??:?? 370 Telling client we have new packets 17

INFO | ??:??:?? 370 BLE notify fromNum

INFO | ??:??:?? 371 From Radio onread

INFO | ??:??:?? 371 getFromRadio=STATE_SEND_PACKETS

DEBUG | ??:??:?? 371 encoding toPhone packet to phone variant=11, 12 bytes

INFO | ??:??:?? 371 From Radio onread

INFO | ??:??:?? 371 getFromRadio=STATE_SEND_PACKETS

DEBUG | ??:??:?? 371 encoding toPhone packet to phone variant=11, 11 bytes

INFO | ??:??:?? 371 From Radio onread

INFO | ??:??:?? 371 getFromRadio=STATE_SEND_PACKETS

DEBUG | ??:??:?? 371 phone downloaded packet (id=0x05abd2ac fr=0x80 to=0x80, WantAck=0, HopLim=3 Ch=0x0 Portnum=6 requestId=df1c0a2f priority=70)

DEBUG | ??:??:?? 371 encoding toPhone packet to phone variant=2, 78 bytes

INFO | ??:??:?? 371 From Radio onread

DEBUG | ??:??:?? 387 [Power] Battery: usbPower=1, isCharging=1, batMv=4717, batPct=100

DEBUG | ??:??:?? 407 [Power] Battery: usbPower=1, isCharging=1, batMv=4704, batPct=100

INFO | ??:??:?? 412 [DeviceTelemetryModule] (Sending): air_util_tx=0.072500, channel_utilization=0.000000, battery_level=101, voltage=4.704000, uptime=412

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

DEBUG | ??:??:?? 412 [DeviceTelemetryModule] Node status update: 26 online, 26 total

INFO | ??:??:?? 412 [DeviceTelemetryModule] Sending packet to phone

INFO | ??:??:?? 412 Telling client we have new packets 18

INFO | ??:??:?? 412 BLE notify fromNum

INFO | ??:??:?? 412 From Radio onread

INFO | ??:??:?? 412 getFromRadio=STATE_SEND_PACKETS

DEBUG | ??:??:?? 412 phone downloaded packet (id=0x05abd2ad fr=0x80 to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=67 priority=10)

DEBUG | ??:??:?? 412 encoding toPhone packet to phone variant=2, 49 bytes

INFO | ??:??:?? 412 From Radio onread

DEBUG | ??:??:?? 427 [Power] Battery: usbPower=1, isCharging=1, batMv=4698, batPct=100

DEBUG | ??:??:?? 447 [Power] Battery: usbPower=1, isCharging=1, batMv=4736, batPct=100

DEBUG | ??:??:?? 467 [Power] Battery: usbPower=1, isCharging=1, batMv=4768, batPct=100

INFO | ??:??:?? 472 [DeviceTelemetryModule] (Sending): air_util_tx=0.072500, channel_utilization=0.000000, battery_level=101, voltage=4.768000, uptime=472

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

DEBUG | ??:??:?? 472 [DeviceTelemetryModule] Node status update: 26 online, 26 total

INFO | ??:??:?? 472 [DeviceTelemetryModule] Sending packet to phone

INFO | ??:??:?? 472 Telling client we have new packets 19

INFO | ??:??:?? 472 BLE notify fromNum

INFO | ??:??:?? 472 From Radio onread

INFO | ??:??:?? 472 getFromRadio=STATE_SEND_PACKETS

DEBUG | ??:??:?? 472 phone downloaded packet (id=0x05abd2ae fr=0x80 to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=67 priority=10)

DEBUG | ??:??:?? 472 encoding toPhone packet to phone variant=2, 49 bytes

INFO | ??:??:?? 472 From Radio onread

DEBUG | ??:??:?? 487 [Power] Battery: usbPower=1, isCharging=1, batMv=4766, batPct=100

DEBUG | ??:??:?? 507 [Power] Battery: usbPower=1, isCharging=1, batMv=4728, batPct=100
GUVWAF commented 3 months ago

I'm not sure what the bug is here? That warning is normal, it's either receiving a real packet, so it cannot send yet, or it had a false preamble detection, which it discards after a short while. Then it will send as normal, like you said happened.

pepefrog1234 commented 3 months ago

My test environment has three devices which are: 1. v3 mqtt network node 2. v3 portable device 3. CDEBYTE_EORA_S3 problematic device. The channel is idle, I send a message but the app application shows that the message cannot be sent and the message [Can not send yet, busyRx] appears in the log. I tried to send the same message with device #2 (v3) and it sent successfully.

I apologize for not being clear earlier, I've reworked my issue.

GUVWAF commented 3 months ago

So from which device is the log output? That looks normal.

pepefrog1234 commented 3 months ago

The logs are exported from CDEBYTE_EORA_S3.

GUVWAF commented 3 months ago

But that logs shows it successfully sent the packets and even another node is rebroadcasting for you. Which app are you using that shows it cannot be sent?

pepefrog1234 commented 3 months ago

android app. I have observed that if the message is only one or two characters long, it is more likely to be sent successfully, but if the characters are too long (more than 50 bytes), it will fail to be sent.

GUVWAF commented 3 months ago

How far are the nodes from each other? If they're in the same room, they might get overloaded.

Can you show a log when the message fails? Preferably from both sending and receiving device.

enforcer2000 commented 1 month ago

I am having the same problem - I have 3 nodes - a PICO, and two Heltec V3s. the PICO and one Heltec are performing fine - the firmware was consistent on v2.3.15.deb7c27 between the two Heltec nodes but only one Heltec and the PICO were communicating. With direct messaging between the Heltecs, the working Heltec was saying the message sent successfully but it was never shown as received in the app (android) on the Heltec I am having issues with. I have just re-flashed the firmware on the problematic Heltec with the latest Alpha release 2.4.2.5b45303 and I am still getting swamped with the following logs as you can see from the timestamps. Even the beacons don't seem to be getting through as the node is not showing up on either the PICO (newly reflashed with flash_nuke) or updating the last seen on the working Heltec...

Any help greatly appreciated!

WARN | 17:12:37 34 [RadioIf] Can not send yet, busyRx WARN | 17:12:37 34 [RadioIf] Can not send yet, busyRx WARN | 17:12:37 34 [RadioIf] Can not send yet, busyRx WARN | 17:12:37 34 [RadioIf] Can not send yet, busyRx WARN | 17:12:38 34 [RadioIf] Can not send yet, busyRx WARN | 17:12:38 34 [RadioIf] Can not send yet, busyRx WARN | 17:12:38 34 [RadioIf] Can not send yet, busyRx WARN | 17:12:38 34 [RadioIf] Can not send yet, busyRx WARN | 17:12:38 34 [RadioIf] Can not send yet, busyRx WARN | 17:12:38 34 [RadioIf] Can not send yet, busyRx WARN | 17:12:38 35 [RadioIf] Can not send yet, busyRx WARN | 17:12:38 35 [RadioIf] Can not send yet, busyRx WARN | 17:12:38 35 [RadioIf] Can not send yet, busyRx WARN | 17:12:38 35 [RadioIf] Can not send yet, busyRx WARN | 17:12:38 35 [RadioIf] Can not send yet, busyRx WARN | 17:12:39 35 [RadioIf] Can not send yet, busyRx WARN | 17:12:39 35 [RadioIf] Can not send yet, busyRx WARN | 17:12:39 35 [RadioIf] Can not send yet, busyRx WARN | 17:12:39 35 [RadioIf] Can not send yet, busyRx WARN | 17:12:39 35 [RadioIf] Can not send yet, busyRx WARN | 17:12:39 35 [RadioIf] Can not send yet, busyRx WARN | 17:12:39 35 [RadioIf] Can not send yet, busyRx WARN | 17:12:39 36 [RadioIf] Can not send yet, busyRx WARN | 17:12:39 36 [RadioIf] Can not send yet, busyRx WARN | 17:12:39 36 [RadioIf] Can not send yet, busyRx WARN | 17:12:39 36 [RadioIf] Can not send yet, busyRx WARN | 17:12:39 36 [RadioIf] Can not send yet, busyRx WARN | 17:12:40 36 [RadioIf] Can not send yet, busyRx WARN | 17:12:40 36 [RadioIf] Can not send yet, busyRx WARN | 17:12:40 36 [RadioIf] Can not send yet, busyRx WARN | 17:12:40 36 [RadioIf] Can not send yet, busyRx WARN | 17:12:40 36 [RadioIf] Can not send yet, busyRx WARN | 17:12:40 36 [RadioIf] Can not send yet, busyRx WARN | 17:12:40 36 [RadioIf] Can not send yet, busyRx WARN | 17:12:40 36 [RadioIf] Can not send yet, busyRx WARN | 17:12:40 36 [RadioIf] Can not send yet, busyRx WARN | 17:12:40 37 [RadioIf] Can not send yet, busyRx WARN | 17:12:40 37 [RadioIf] Can not send yet, busyRx WARN | 17:12:40 37 [RadioIf] Can not send yet, busyRx WARN | 17:12:41 37 [RadioIf] Can not send yet, busyRx WARN | 17:12:41 37 [RadioIf] Can not send yet, busyRx WARN | 17:12:41 37 [RadioIf] Can not send yet, busyRx WARN | 17:12:41 37 [RadioIf] Can not send yet, busyRx WARN | 17:12:41 37 [RadioIf] Can not send yet, busyRx WARN | 17:12:41 37 [RadioIf] Can not send yet, busyRx WARN | 17:12:41 37 [RadioIf] Can not send yet, busyRx WARN | 17:12:41 37 [RadioIf] Can not send yet, busyRx WARN | 17:12:41 37 [RadioIf] Can not send yet, busyRx WARN | 17:12:41 37 [RadioIf] Can not send yet, busyRx WARN | 17:12:41 37 [RadioIf] Can not send yet, busyRx WARN | 17:12:41 37 [RadioIf] Can not send yet, busyRx WARN | 17:12:41 38 [RadioIf] Can not send yet, busyRx WARN | 17:12:41 38 [RadioIf] Can not send yet, busyRx WARN | 17:12:41 38 [RadioIf] Can not send yet, busyRx WARN | 17:12:41 38 [RadioIf] Can not send yet, busyRx WARN | 17:12:41 38 [RadioIf] Can not send yet, busyRx WARN | 17:12:42 38 [RadioIf] Can not send yet, busyRx WARN | 17:12:42 38 [RadioIf] Can not send yet, busyRx WARN | 17:12:42 38 [RadioIf] Can not send yet, busyRx WARN | 17:12:42 38 [RadioIf] Can not send yet, busyRx WARN | 17:12:42 38 [RadioIf] Can not send yet, busyRx WARN | 17:12:42 38 [RadioIf] Can not send yet, busyRx WARN | 17:12:42 38 [RadioIf] Can not send yet, busyRx WARN | 17:12:42 38 [RadioIf] Can not send yet, busyRx WARN | 17:12:42 39 [RadioIf] Can not send yet, busyRx WARN | 17:12:42 39 [RadioIf] Can not send yet, busyRx WARN | 17:12:43 39 [RadioIf] Can not send yet, busyRx WARN | 17:12:43 39 [RadioIf] Can not send yet, busyRx WARN | 17:12:43 39 [RadioIf] Can not send yet, busyRx WARN | 17:12:43 39 [RadioIf] Can not send yet, busyRx WARN | 17:12:43 39 [RadioIf] Can not send yet, busyRx WARN | 17:12:43 39 [RadioIf] Can not send yet, busyRx WARN | 17:12:43 39 [RadioIf] Can not send yet, busyRx WARN | 17:12:43 39 [RadioIf] Can not send yet, busyRx WARN | 17:12:43 39 [RadioIf] Can not send yet, busyRx WARN | 17:12:43 39 [RadioIf] Can not send yet, busyRx WARN | 17:12:43 39 [RadioIf] Can not send yet, busyRx WARN | 17:12:43 40 [RadioIf] Can not send yet, busyRx WARN | 17:12:43 40 [RadioIf] Can not send yet, busyRx WARN | 17:12:43 40 [RadioIf] Can not send yet, busyRx WARN | 17:12:43 40 [RadioIf] Can not send yet, busyRx WARN | 17:12:43 40 [RadioIf] Can not send yet, busyRx WARN | 17:12:43 40 [RadioIf] Can not send yet, busyRx WARN | 17:12:44 40 [RadioIf] Can not send yet, busyRx WARN | 17:12:44 40 [RadioIf] Can not send yet, busyRx WARN | 17:12:44 40 [RadioIf] Can not send yet, busyRx WARN | 17:12:44 40 [RadioIf] Can not send yet, busyRx WARN | 17:12:44 40 [RadioIf] Can not send yet, busyRx WARN | 17:12:44 40 [RadioIf] Can not send yet, busyRx WARN | 17:12:44 40 [RadioIf] Can not send yet, busyRx WARN | 17:12:44 40 [RadioIf] Can not send yet, busyRx WARN | 17:12:44 40 [RadioIf] Can not send yet, busyRx WARN | 17:12:44 41 [RadioIf] Can not send yet, busyRx WARN | 17:12:44 41 [RadioIf] Can not send yet, busyRx WARN | 17:12:44 41 [RadioIf] Can not send yet, busyRx WARN | 17:12:44 41 [RadioIf] Can not send yet, busyRx WARN | 17:12:44 41 [RadioIf] Can not send yet, busyRx WARN | 17:12:44 41 [RadioIf] Can not send yet, busyRx WARN | 17:12:44 41 [RadioIf] Can not send yet, busyRx WARN | 17:12:45 41 [RadioIf] Can not send yet, busyRx WARN | 17:12:45 41 [RadioIf] Can not send yet, busyRx WARN | 17:12:45 41 [RadioIf] Can not send yet, busyRx

GerritS76 commented 1 month ago

Same issue here, started from 2.4.0 on two Heltec V3's as well 2 Heltec Wireless Stick Lite v3's.

Note; in non congested area!

garthvh commented 1 month ago

Turn of mqtt and debug, those are normal errors if you are congested

GUVWAF commented 1 month ago

The [RadioIf] Can not send yet, busyRx logs are normal indeed, but should stop being printed after the maximum packet length, which is a couple of seconds or so on LongFast. If it's longer than that, then definitely something is wrong. I haven't encountered this on 2.4 myself.

enforcer2000 commented 1 month ago

So I wanted to drop an update on this from my perspective... After a reflash with " full erase and install" set on the problematic Heltec V3, this seemed to have corrected the issue so there must have been some corrupt configuration somewhere. Unfortunately I lost all my previous beacon info but at least I have it working again.