Post by Jason on Mar 9, 2022 5:52:59 GMT -8
Here's a couple of snippets from logs for 13 hours of runtime.
Setup:
First BT sensor reading cycle
Error and reset
Next up I'll try and capture snippets from trying to read all sixteen sensors, however, my suspicion is that the error will be similar.
Thanks,
Jason
Setup:
10:48:07.908 -> Tick - 15:48:07 Tuesday 8 March 2022
10:48:07.908 -> LocalIP=192.168.50.117
10:48:19.886 -> ---------->REST: assignBluetoothSensors
10:48:19.886 -> Command =admin,C4:7C:8D:6E:01:3D,C4:7C:8D:6E:03:7A
10:48:19.886 -> Bluetooth Sensors Assigned
10:48:19.886 -> BTAdd 0 C4:7C:8D:6E:01:3D
10:48:19.886 -> BTAdd 1 C4:7C:8D:6E:03:7A
10:48:19.961 -> ----Writing Preferences---
10:48:19.961 -> SSID=########
10:48:19.961 -> psk=##########
10:48:19.961 -> stationName=
10:48:19.961 -> COffsetToUTC=0
10:48:19.961 -> EnglishOrMetric:0
10:48:19.961 -> Admin Password:********
10:48:19.961 -> SolarMAXLA Enabled=0
10:48:19.961 -> SolarMAXLiPo Enabled=0
10:48:19.961 -> MQTT_IP=192.168.0.60
10:48:19.961 -> MQTT_PORT=31869
10:48:19.961 -> SensorCycle=600
10:48:19.961 -> HydroponicsMode=0
10:48:19.961 -> HydroponicsLevelMode=0
10:48:19.961 -> BluetoothAddresses[0]=C4:7C:8D:6E:01:3D
10:48:19.961 -> BluetoothAddresses[1]=C4:7C:8D:6E:03:7A
10:48:19.999 -> --------------------------
10:48:19.999 -> countBlueToothSensors=2
10:48:19.999 -> Starting up Bluetooth
10:48:19.999 -> Initialize BLE client...
10:48:37.910 -> Tick - 15:48:37 Tuesday 8 March 2022
10:48:37.910 -> LocalIP=192.168.50.117
First BT sensor reading cycle
10:55:07.797 -> bluetoothPeriod
10:55:07.797 -> >>>>>>>>>>>>>>>>>>>>>>>>>>> Processing Bluetooth: 0
10:55:07.797 -> >>>>>>>>>>>>>>>>>>>>>>>>>>> Bluetooth Try: 1
10:55:07.797 -> Processing Flora device at c4:7c:8d:6e:01:3d (try 1)
10:55:08.573 -> - Connection successful
10:55:09.060 -> - Found data service
10:55:09.060 -> - Force device in data mode
10:55:10.548 -> - Access characteristic from device
10:55:11.401 -> - Read value from characteristic
10:55:11.623 -> Hex: F2 0 69 3B 2 0 0 B 30 0 2 3C 0 FB 34 9B
10:55:11.623 -> -- Temperature: 242
10:55:11.623 -> -- Moisture: 11
10:55:11.623 -> -- Light: 571
10:55:11.623 -> -- Conductivity: 48
10:55:11.623 -> - Access battery characteristic from device
10:55:12.574 -> - Read value from characteristic
10:55:12.981 -> -- Battery: 81
10:55:12.981 -> ReadCount==1
10:55:12.981 -> connected
10:55:12.981 -> Sending Bluetooth MQTT Packet
10:55:12.981 -> {"id": "1879", "messagetype": "5", "timestamp": "2022-03-08 15:55:12", "macaddress": "C4:7C:8D:6E:01:3D", "temperature": "242", "brightness": "571", "moisture": "11", "conductivity": "48", "battery": "81", "readCount": "1", "sensorType": "BT1"}
10:55:12.981 -> Topic=SGS/1879
10:55:12.981 -> MQTT publish result=1
10:55:14.490 -> >>>>>>>>>>>>>>>>>>>>>>>>>>> Processing Bluetooth: 1
10:55:14.490 -> >>>>>>>>>>>>>>>>>>>>>>>>>>> Bluetooth Try: 1
10:55:14.490 -> Processing Flora device at c4:7c:8d:6e:03:7a (try 1)
10:55:22.151 -> - Connection successful
10:55:22.664 -> - Found data service
10:55:22.664 -> - Force device in data mode
10:55:24.498 -> - Access characteristic from device
10:55:25.857 -> - Read value from characteristic
10:55:26.082 -> Hex: EA 0 69 75 3 0 0 0 6 0 2 3C 0 FB 34 9B
10:55:26.082 -> -- Temperature: 234
10:55:26.082 -> -- Moisture: 0
10:55:26.082 -> -- Light: 885
10:55:26.082 -> -- Conductivity: 6
10:55:26.082 -> - Access battery characteristic from device
10:55:26.711 -> - Read value from characteristic
10:55:26.967 -> -- Battery: 46
10:55:26.967 -> ReadCount==2
10:55:26.967 -> connected
10:55:26.967 -> Sending Bluetooth MQTT Packet
10:55:26.967 -> {"id": "1879", "messagetype": "5", "timestamp": "2022-03-08 15:55:26", "macaddress": "C4:7C:8D:6E:03:7A", "temperature": "234", "brightness": "885", "moisture": "0", "conductivity": "6", "battery": "46", "readCount": "2", "sensorType": "BT1"}
10:55:27.001 -> Topic=SGS/1879
10:55:27.001 -> MQTT publish result=1
10:55:30.410 -> lld_pdu_get_tx_flush_nb HCI packet count mismatch (0, 1)
10:55:34.499 -> After FreeHeap=155600
10:55:34.499 -> -------------->>>>>>Heap Difference:-1564
10:55:34.499 -> Tick - 15:55:33 Tuesday 8 March 2022
10:55:34.499 -> LocalIP=192.168.50.117
Error and reset
23:45:09.489 -> bluetoothPeriod
23:45:09.489 -> >>>>>>>>>>>>>>>>>>>>>>>>>>> Processing Bluetooth: 0
23:45:09.489 -> >>>>>>>>>>>>>>>>>>>>>>>>>>> Bluetooth Try: 1
23:45:09.489 -> Processing Flora device at c4:7c:8d:6e:01:3d (try 1)
23:45:24.489 -> lld_pdu_get_tx_flush_nb HCI packet count mismatch (1, 2)
23:45:24.489 -> - Connection failed, skipping
23:45:24.489 -> >>>>>>>>>>>>>>>>>>>>>>>>>>> RETRYING Bluetooth:
23:45:24.489 -> >>>>>>>>>>>>>>>>>>>>>>>>>>> Bluetooth Try: 2
23:45:24.489 -> Processing Flora device at c4:7c:8d:6e:01:3d (try 2)
23:45:33.287 -> - Connection successful
23:45:33.287 -> - Found data service
23:45:33.287 -> - Force device in data mode
23:45:33.287 -> - Access characteristic from device
23:45:38.539 -> - Read value from characteristic
23:45:38.539 -> Hex: EB 0 69 19 1 0 0 B 25 0 2 3C 0 FB 34 9B
23:45:38.539 -> -- Temperature: 235
23:45:38.539 -> -- Moisture: 11
23:45:38.539 -> -- Light: 281
23:45:38.539 -> -- Conductivity: 37
23:45:38.539 -> - Access battery characteristic from device
23:45:38.539 -> - Read value from characteristic
23:45:38.539 -> -- Battery: 72
23:45:38.539 -> ReadCount==152
23:45:38.539 -> connected
23:45:38.539 -> Sending Bluetooth MQTT Packet
23:45:38.539 -> {"id": "1879", "messagetype": "5", "timestamp": "2022-03-09 04:45:30", "macaddress": "C4:7C:8D:6E:01:3D", "temperature": "235", "brightness": "281", "moisture": "11", "conductivity": "37", "battery": "72", "readCount": "152", "sensorType": "BT1"}
23:45:38.539 -> Topic=SGS/1879
23:45:38.539 -> MQTT publish result=1
23:45:38.539 -> >>>>>>>>>>>>>>>>>>>>>>>>>>> Processing Bluetooth: 1
23:45:38.539 -> >>>>>>>>>>>>>>>>>>>>>>>>>>> Bluetooth Try: 1
23:45:38.539 -> Processing Flora device at c4:7c:8d:6e:03:7a (try 1)
23:45:39.499 -> - Connection successful
23:45:39.499 -> - Found data service
23:45:39.499 -> - Force device in data mode
23:45:54.689 -> - Access characteristic from device
23:45:54.689 -> abort() was called at PC 0x40197db3 on core 0
23:45:54.689 ->
23:45:54.689 -> ELF file SHA256: 0000000000000000
23:45:54.689 ->
23:45:54.689 -> Backtrace: 0x4008f614:0x3ffe35b0 0x4008f891:0x3ffe35d0 0x40197db3:0x3ffe35f0 0x40197dfa:0x3ffe3610 0x40197725:0x3ffe3630 0x401977fc:0x3ffe3650 0x400e5d0f:0x3ffe3670 0x400ec16d:0x3ffe36a0 0x400ec8c3:0x3ffe36c0 0x400ed602:0x3ffe3700 0x400e8db8:0x3ffe3730 0x400e8975:0x3ffe3760 0x400efdc5:0x3ffe3790 0x400ee7f5:0x3ffe37c0 0x400ee807:0x3ffe37e0 0x400f5ace:0x3ffe3800 0x400e511b:0x3ffe3820 0x400908a2:0x3ffe3840
23:45:54.689 ->
23:45:54.689 -> Rebooting...
23:45:54.689 -> ets Jun 8 2016 00:22:57
23:45:54.689 ->
23:45:54.689 -> rst:0xc (SW_CPU_RESET),boot:0x13 (SPI_FAST_FLASH_BOOT)
23:45:54.689 -> configsip: 0, SPIWP:0xee
23:45:54.689 -> clk_drv:0x00,q_drv:0x00,d_drv:0x00,cs0_drv:0x00,hd_drv:0x00,wp_drv:0x00
23:45:54.689 -> mode:DIO, clock div:1
23:45:54.689 -> load:0x3fff0018,len:4
23:45:54.689 -> load:0x3fff001c,len:1044
23:45:54.689 -> load:0x40078000,len:10124
23:45:54.689 -> load:0x40080400,len:5856
23:45:54.689 -> entry 0x400806a8
23:45:54.689 ->
23:45:54.689 -> ##############################
23:45:54.689 -> SmartGarden3 Wireless Extender
23:45:54.689 -> ##############################
23:45:54.689 -> Software Version = 060
23:45:54.689 -> preferencesfreeentries=423
23:45:54.689 -> ----Reading Preferences---
23:45:54.689 -> SSID=##########
23:46:03.540 -> psk=##########
23:46:03.540 -> stationName=
23:46:03.540 -> COffsetToUTC=0
23:46:03.540 -> EnglishOrMetric:0
23:46:03.540 -> Admin Password:ad******
23:46:03.540 -> SolarMAXLA Enabled=0
23:46:03.540 -> SolarMAXLiPo Enabled=0
23:46:03.540 -> MQTT_IP=192.168.0.60
23:46:03.540 -> MQTT_PORT=31869
23:46:03.540 -> SensorCycle=600
23:46:03.540 -> HydroponicsMode=0
23:46:03.540 -> HydroponicsLevelMode=0
23:46:03.540 -> BluetoothAddresses[0]=C4:7C:8D:6E:01:3D
23:46:03.540 -> BluetoothAddresses[1]=C4:7C:8D:6E:03:7A
23:46:03.540 -> --------------------------
23:46:03.540 -> ----Clearing Preferences---
23:46:03.540 -> preferencesfreeentries=472
23:46:03.540 -> ----Reading Preferences---
23:46:03.540 -> SSID=
23:46:03.540 -> psk=
23:46:03.540 -> stationName=
23:46:03.540 -> COffsetToUTC=0
23:46:03.540 -> EnglishOrMetric:0
23:46:03.540 -> Admin Password:ad******
23:46:03.540 -> SolarMAXLA Enabled=0
23:46:03.540 -> SolarMAXLiPo Enabled=0
23:46:03.540 -> MQTT_IP=
23:46:03.540 -> MQTT_PORT=1883
23:46:03.540 -> SensorCycle=600
23:46:03.540 -> HydroponicsMode=0
23:46:03.540 -> HydroponicsLevelMode=0
23:46:03.540 -> --------------------------
23:46:03.540 -> Preferences Reset by AUX Button
23:46:03.540 -> aux switch=0
23:46:03.540 -> HydroponicsMode - xReadSensor=0
23:46:03.540 -> *WM: SET AP STA
23:46:03.540 -> *WM:
23:46:03.540 -> *WM: Configuring access point...
23:46:03.540 -> *WM: SG3WExt-1879
23:46:03.540 -> *WM: AP IP address:
23:46:03.540 -> *WM: 192.168.4.1
23:46:03.540 -> *WM: HTTP server started
23:50:44.970 -> failed to connect and hit timeout
23:50:45.073 -> ets Jun 8 2016 00:22:57
Next up I'll try and capture snippets from trying to read all sixteen sensors, however, my suspicion is that the error will be similar.
Thanks,
Jason