Aktualizacja v2.2.0 -> v2.3.0

Moderator: fracz

ODPOWIEDZ
Awatar użytkownika
lesny8
Posty: 2814
Rejestracja: pn gru 11, 2017 9:43 pm

kris.sg pisze: ↑śr lut 13, 2019 7:21 pm
kris.sg pisze: ↑śr lut 13, 2019 4:25 pm przelogowałem moduł na oficjalny cloud i nie zrywa połączenia, nie ma tego błędu:

Kod: Zaznacz cały

client's data invalid protocol
Error: SSL error 3
devconn_disconnect_cb
To oznacza, że serwer go odcina z jakiegoś powodu.
Trzeba debugować po stronie serwera.

[EDIT]

Zrób eksperyment.
1. Ustal urządzenie, które się rozłącza.
2. Odłącz wszystkie
3. Włącz tylko to co się rozłączało i obserwuj czy problem nadal występuje.
wyłączyłem wszystkie moduły które się rozłączały, zostawiłem jeden moduł podłączony, ale też się rozłącza.
wszystkie problematyczne moduły mają soft kompilowany z źródeł 2.7.3, z tym, że na cloudzie 2.2 działały poprawnie.
mam też podpięte sonoff touch 3 sztuki z softem 2.5.3 Zybiego i te się nie rozłączają.
W razie czego mogę dać dostęp do maliny gdzie mam clouda
U mnie problem mają moduły oparte o wersje softu 2.7.2.
Przeprowadziłem ten test.
Wyłączyłem wszystkie moduły. Został aktywny tylko jeden, który mam na biurku, który do wczoraj działał, a teraz się rozłącza.
Wynik testu identyczny. Czas pomiędzy reconnectami ~25s

Kod: Zaznacz cały

connected with stefan, channel 7
dhcp client start...
ip:192.168.100.211,mask:255.255.255.0,gw:192.168.100.1
WiFi Status: 5
IP Received
client handshake start.
client handshake ok!
devconn_connect_cb
Server connected
Registered and ready.
Free heap size: 22584
pm open,type:2 0
Activity timeout 28489591, 7489218, 21, 20
state: 5 -> 0 (0)
rm 0
pm close 7
Disconnected
devconn_disconnect_cb
WiFi Status: 1
scandone
WiFi Status: 1
scandone
state: 0 -> 2 (b0)
state: 2 -> 3 (0)
state: 3 -> 5 (10)
add 0
aid 14
cnt 

connected with stefan, channel 7
dhcp client start...
ip:192.168.100.211,mask:255.255.255.0,gw:192.168.100.1
WiFi Status: 5
IP Received
client handshake start.
client handshake ok!
devconn_connect_cb
Server connected
Registered and ready.
Free heap size: 22464
pm open,type:2 0
Activity timeout 55497712, 34507256, 20, 20
state: 5 -> 0 (0)
rm 0
pm close 7
Disconnected
devconn_disconnect_cb
WiFi Status: 1
scandone
WiFi Status: 1
scandone
state: 0 -> 2 (b0)
state: 2 -> 3 (0)
state: 3 -> 5 (10)
add 0
aid 14
cnt
Czekam na kolejne Supla Offline Party 👍
Na górę
Awatar użytkownika
lesny8
Posty: 2814
Rejestracja: pn gru 11, 2017 9:43 pm

PuteR pisze: ↑śr lut 13, 2019 7:46 pm Czy u innych problemy również występują no softach w wersji 2.7.3 jeśli się nie mylę to chyba multiboard. Chcę się właśnie zabrać za aktualizację ale połowa modułów działa właśnie na multiboard i trochę lipa by była gdyby to nie działało jak należy.
Może wstrzymaj się na razie, chyba że masz duplikat karty...
Czekam na kolejne Supla Offline Party 👍
Na górę
Awatar użytkownika
PuteR
Posty: 1421
Rejestracja: śr gru 06, 2017 10:07 am

Mam zrobioną kopią karty, ale skoro u wszystkich jest taki problem, więc trochę bez sensu liczyć że u mnie będzie inaczej :] a czy mogę zaktualizować tylko supla scripts do wersji 3.0, czy z tym nie ma problemu??
Na górę
Awatar użytkownika
lesny8
Posty: 2814
Rejestracja: pn gru 11, 2017 9:43 pm

Możesz ;)
Czekam na kolejne Supla Offline Party 👍
Na górę
Awatar użytkownika
lesny8
Posty: 2814
Rejestracja: pn gru 11, 2017 9:43 pm

pzygmunt pisze: ↑śr lut 13, 2019 3:06 pm No to nie wiem. Musisz sobie skompilować wersję z włączonym debugiem
Skompilowałem taką wersję, logi poniżej

Kod: Zaznacz cały

connected with stefan, channel 3
dhcp client start...
ip:192.168.100.211,mask:255.255.255.0,gw:192.168.100.1
WiFi Status: 5
IP Received
supla_esp_devconn_resolvandconnect
Disconnect 0
supla_esp_devconn_dns_found_cb
Disconnect 0
Free heap size: 34520
client handshake start.
client handshake ok!
devconn_connect_cb
sproto send count: 704 result: 0
sproto recv 30 bytes, heap_size: 24480
call_received
Server connected
Registered and ready.
Free heap size: 22360
sproto send count: 24 result: 0
sproto recv 26 bytes, heap_size: 22392
call_received
Free heap size: 22392
sproto send count: 32 result: 0
Free heap size: 23960
supla_esp_channel_value_changed(0, 0)
supla_esp_channel_value_changed(1, 0)
sproto send count: 32 result: 0
sproto send count: 32 result: 0
Free heap size: 22392
sproto send count: 32 result: 0
Free heap size: 20824
sproto send count: 32 result: 0
sproto send count: 32 result: 0
Free heap size: 22392
sproto send count: 32 result: 0
Free heap size: 23960
PING 7,488382
data  : 0x3ffe8000 ~ 0x3ffe83e0, len: 992
rodata: 0x3ffe83e0 ~ 0x3ffebb34, len: 14164
bss   : 0x3ffebb38 ~ 0x3fff2820, len: 27880
heap  : 0x3fff2820 ~ 0x3fffc000, len: 38880
sproto send count: 39 result: 0
Free heap size: 22392
PING 8,488829
data  : 0x3ffe8000 ~ 0x3ffe83e0, len: 992
rodata: 0x3ffe83e0 ~ 0x3ffebb34, len: 14164
bss   : 0x3ffebb38 ~ 0x3fff2820, len: 27880
heap  : 0x3fff2820 ~ 0x3fffc000, len: 38880
sproto send count: 32 result: 0
sproto send count: 39 result: 0
Free heap size: 23960
PING 9,488379
data  : 0x3ffe8000 ~ 0x3ffe83e0, len: 992
rodata: 0x3ffe83e0 ~ 0x3ffebb34, len: 14164
bss   : 0x3ffebb38 ~ 0x3fff2820, len: 27880
heap  : 0x3fff2820 ~ 0x3fffc000, len: 38880
sproto send count: 39 result: 0
sproto send count: 32 result: 0
pm open,type:2 0
sproto send count: 32 result: 0
Free heap size: 21624
PING 10,488377
data  : 0x3ffe8000 ~ 0x3ffe83e0, len: 992
rodata: 0x3ffe83e0 ~ 0x3ffebb34, len: 14164
bss   : 0x3ffebb38 ~ 0x3fff2820, len: 27880
heap  : 0x3fff2820 ~ 0x3fffc000, len: 38880
sproto send count: 32 result: 0
sproto send count: 39 result: 0
Free heap size: 23192
PING 11,488458
data  : 0x3ffe8000 ~ 0x3ffe83e0, len: 992
rodata: 0x3ffe83e0 ~ 0x3ffebb34, len: 14164
bss   : 0x3ffebb38 ~ 0x3fff2820, len: 27880
heap  : 0x3fff2820 ~ 0x3fffc000, len: 38880
sproto send count: 39 result: 0
Free heap size: 21624
PING 12,488761
data  : 0x3ffe8000 ~ 0x3ffe83e0, len: 992
rodata: 0x3ffe83e0 ~ 0x3ffebb34, len: 14164
bss   : 0x3ffebb38 ~ 0x3fff2820, len: 27880
heap  : 0x3fff2820 ~ 0x3fffc000, len: 38880
sproto send count: 32 result: 0
sproto send count: 39 result: 0
Free heap size: 23192
Free heap size: 21624
sproto send count: 32 result: 0
sproto send count: 32 result: 0
sproto send count: 32 result: 0
Free heap size: 23192
Free heap size: 21624
sproto send count: 32 result: 0
Free heap size: 23192
Free heap size: 21624
sproto send count: 32 result: 0
Free heap size: 23192
sproto send count: 32 result: 0
sproto send count: 32 result: 0
Free heap size: 21624
sproto send count: 32 result: 0
Free heap size: 23192
Free heap size: 21624
Activity timeout 22491992, 2136829, 20, 20
Disconnect 3
Disconnected
devconn_disconnect_cb
Disconnect 6
WiFi Status: 5
IP Received
supla_esp_devconn_resolvandconnect
Disconnect 6
supla_esp_devconn_dns_found_cb
Disconnect 6
Disconnected
client handshake start.
state: 5 -> 0 (0)
rm 0
pm close 7
scandone
WiFi Status: 1
Free heap size: 35456
Free heap size: 35456
scandone
state: 0 -> 2 (b0)
state: 2 -> 3 (0)
state: 3 -> 5 (10)
add 0
aid 7
cnt 

connected with stefan, channel 3
dhcp client start...
Free heap size: 35000
ip:192.168.100.211,mask:255.255.255.0,gw:192.168.100.1
WiFi Status: 5
IP Received
supla_esp_devconn_resolvandconnect
Disconnect 6
supla_esp_devconn_dns_found_cb
Disconnect 6
Free heap size: 34520
client handshake start.
client handshake ok!
devconn_connect_cb
sproto send count: 704 result: 0
sproto recv 30 bytes, heap_size: 24480
call_received
Server connected
Registered and ready.
Free heap size: 22360
sproto send count: 24 result: 0
sproto recv 26 bytes, heap_size: 22392
call_received
Free heap size: 22392
supla_esp_channel_value_changed(0, 0)
supla_esp_channel_value_changed(1, 0)
sproto send count: 32 result: 0
sproto send count: 32 result: 0
Free heap size: 22392
sproto send count: 32 result: 0
Free heap size: 23960
Free heap size: 20824
sproto send count: 32 result: 0
sproto send count: 32 result: 0
Free heap size: 23960
Free heap size: 22392
PING 32,511363
data  : 0x3ffe8000 ~ 0x3ffe83e0, len: 992
rodata: 0x3ffe83e0 ~ 0x3ffebb34, len: 14164
bss   : 0x3ffebb38 ~ 0x3fff2820, len: 27880
heap  : 0x3fff2820 ~ 0x3fffc000, len: 38880
sproto send count: 32 result: 0
sproto send count: 39 result: 0
Free heap size: 23960
PING 33,511382
data  : 0x3ffe8000 ~ 0x3ffe83e0, len: 992
rodata: 0x3ffe83e0 ~ 0x3ffebb34, len: 14164
bss   : 0x3ffebb38 ~ 0x3fff2820, len: 27880
heap  : 0x3fff2820 ~ 0x3fffc000, len: 38880
sproto send count: 39 result: 0
Free heap size: 22392
PING 34,511367
data  : 0x3ffe8000 ~ 0x3ffe83e0, len: 992
rodata: 0x3ffe83e0 ~ 0x3ffebb34, len: 14164
bss   : 0x3ffebb38 ~ 0x3fff2820, len: 27880
heap  : 0x3fff2820 ~ 0x3fffc000, len: 38880
sproto send count: 32 result: 0
sproto send count: 39 result: 0
pm open,type:2 0
Free heap size: 20056
PING 35,511454
data  : 0x3ffe8000 ~ 0x3ffe83e0, len: 992
rodata: 0x3ffe83e0 ~ 0x3ffebb34, len: 14164
bss   : 0x3ffebb38 ~ 0x3fff2820, len: 27880
heap  : 0x3fff2820 ~ 0x3fffc000, len: 38880
sproto send count: 32 result: 0
sproto send count: 32 result: 0
Free heap size: 21624
PING 36,511466
data  : 0x3ffe8000 ~ 0x3ffe83e0, len: 992
rodata: 0x3ffe83e0 ~ 0x3ffebb34, len: 14164
bss   : 0x3ffebb38 ~ 0x3fff2820, len: 27880
heap  : 0x3fff2820 ~ 0x3fffc000, len: 38880
sproto send count: 32 result: 0
sproto send count: 39 result: 0
Free heap size: 23192
PING 37,511469
data  : 0x3ffe8000 ~ 0x3ffe83e0, len: 992
rodata: 0x3ffe83e0 ~ 0x3ffebb34, len: 14164
bss   : 0x3ffebb38 ~ 0x3fff2820, len: 27880
heap  : 0x3fff2820 ~ 0x3fffc000, len: 38880
sproto send count: 39 result: 0
Free heap size: 21624
sproto send count: 32 result: 0
Free heap size: 23192
Free heap size: 20056
sproto send count: 32 result: 0
sproto send count: 32 result: 0
Free heap size: 23192
Free heap size: 21624
sproto send count: 32 result: 0
Free heap size: 23192
Free heap size: 21624
sproto send count: 32 result: 0
Free heap size: 20056
sproto send count: 32 result: 0
sproto send count: 32 result: 0
Free heap size: 21624
sproto send count: 32 result: 0
Free heap size: 23192
Activity timeout 47511477, 26852472, 20, 20
Disconnect 3
Disconnected
devconn_disconnect_cb
Disconnect 6
WiFi Status: 5
IP Received
supla_esp_devconn_resolvandconnect
Disconnect 6
supla_esp_devconn_dns_found_cb
Disconnect 6
Disconnected
client handshake start.
state: 5 -> 0 (0)
rm 0
pm close 7
scandone
WiFi Status: 1
Free heap size: 35456
Free heap size: 35456
scandone
state: 0 -> 2 (b0)
state: 2 -> 3 (0)
state: 3 -> 5 (10)
add 0
aid 7
cnt
Masz jakieś pomysły?

Edit:
Jeszcze raz skompilowałem z wszystkimi logami jakie były w supla_esp_devconn.c
Czekam na kolejne Supla Offline Party 👍
Na górę
Awatar użytkownika
pzygmunt
Posty: 18333
Rejestracja: wt sty 19, 2016 9:26 am
Lokalizacja: Paczków
Kontakt:
Skontaktuj się z pzygmunt

Debug po stronie supla-server, a nie po stronie ESP
Na górę
Awatar użytkownika
lesny8
Posty: 2814
Rejestracja: pn gru 11, 2017 9:43 pm

:o yyy... a podpowiesz jak to zrobić :?:
Czekam na kolejne Supla Offline Party 👍
Na górę
Awatar użytkownika
pzygmunt
Posty: 18333
Rejestracja: wt sty 19, 2016 9:26 am
Lokalizacja: Paczków
Kontakt:
Skontaktuj się z pzygmunt

Ściągasz z githuba
cd supla-server/Debug
make clean
make all
Na górę
Awatar użytkownika
lesny8
Posty: 2814
Rejestracja: pn gru 11, 2017 9:43 pm

Przełączyłem się na src i zedytowałem Dockerfile dla server-a z Release na Debug zanim odpisałeś. Właśnie się kompiluje.

Ok powstawało, tak to wygląda

Kod: Zaznacz cały

INFO[1550097832.3179] Device registered. ID: 66, ClientSD: 29 Protocol Version: 8
DEBUG[1550097832.759952] Connection Finished
DEBUG[1550097847.800361] Connection Finished
INFO[1550097850.822883] Connection accepted: 192.168.100.211:25421 ClientSD: 45 Secure: 1
INFO[1550097850.976077] Cipher: AES128-SHA, ClientSD: 45
DEBUG[1550097850.976986] Connection Started 1942112528, secure=1
DEBUG[1550097851.87018] Adjusting protocol version to 8
DEBUG[1550097851.87047] SUPLA_DS_CALL_REGISTER_DEVICE_D
DEBUG[1550097851.87061] SUPLA_DS_CALL_REGISTER_DEVICE_E
INFO[1550097851.211763] Device registered. ID: 68, ClientSD: 45 Protocol Version: 8
DEBUG[1550097870.91575] Activity timeout 1942072320, 30, 1
DEBUG[1550097870.92229] Connection Finished
INFO[1550097875.833865] Connection accepted: 192.168.100.211:36512 ClientSD: 20 Secure: 1
INFO[1550097875.959321] Cipher: AES128-SHA, ClientSD: 20
DEBUG[1550097875.959901] Connection Started 1942112720, secure=1
DEBUG[1550097876.71654] Adjusting protocol version to 8
DEBUG[1550097876.72380] SUPLA_DS_CALL_REGISTER_DEVICE_D
DEBUG[1550097876.72410] SUPLA_DS_CALL_REGISTER_DEVICE_E
INFO[1550097877.191359] Device registered. ID: 68, ClientSD: 20 Protocol Version: 8
DEBUG[1550097877.192552] Connection Finished
DEBUG[1550097879.922486] Activity timeout 1942071720, 30, 1
DEBUG[1550097879.922993] Connection Finished
Edit:
Skompilowałem ponownie soft do modułu ale z wersją proto 10, z nadzieją że coś to zmieni. Dla zasady zaorałem flash, usunąłem z chmury, wypełniłem config zapisałem i zrestartowałęm. Po stronie modułu nic się nie zmieniło, dalej się rozłącza, ale po stronie serwera w logach widać dodatkowo SSL_ERROR_SYSCALL, choć nie wiem jaki to może mieć związek, sam popatrz

Kod: Zaznacz cały

INFO[1550100389.610593] Connection accepted: 192.168.100.211:40145 ClientSD: 14 Secure: 1
INFO[1550100389.754483] Cipher: AES128-SHA, ClientSD: 14
DEBUG[1550100389.755571] Connection Started 1942039144, secure=1
INFO[1550100392.611713] Connection accepted: 192.168.100.211:9222 ClientSD: 13 Secure: 1
INFO[1550100392.765030] Cipher: AES128-SHA, ClientSD: 13
DEBUG[1550100392.766167] Connection Started 1942110760, secure=1
DEBUG[1550100392.877141] SUPLA_DS_CALL_REGISTER_DEVICE_E
DEBUG[1550100393.161042] SSL_ERROR_SYSCALL
DEBUG[1550100393.161449] Connection Finished
INFO[1550100395.964393] Device registered. ID: 69, ClientSD: 13 Protocol Version: 10
DEBUG[1550100416.628362] Connection Finished
INFO[1550100416.631068] Connection accepted: 192.168.100.211:25651 ClientSD: 17 Secure: 1
DEBUG[1550100419.970299] Connection Finished
INFO[1550100420.631850] Connection accepted: 192.168.100.211:11712 ClientSD: 13 Secure: 1
INFO[1550100420.782038] Cipher: AES128-SHA, ClientSD: 13
DEBUG[1550100420.782767] Connection Started 1941974472, secure=1
DEBUG[1550100420.893688] SUPLA_DS_CALL_REGISTER_DEVICE_E
INFO[1550100420.930395] Device registered. ID: 69, ClientSD: 13 Protocol Version: 10
DEBUG[1550100441.648126] Connection Finished
INFO[1550100441.650788] Connection accepted: 192.168.100.211:41284 ClientSD: 14 Secure: 1
INFO[1550100441.796618] Cipher: AES128-SHA, ClientSD: 14
DEBUG[1550100441.796663] Connection Started 1942006920, secure=1
DEBUG[1550100445.240918] SSL_ERROR_SYSCALL
DEBUG[1550100445.241357] Connection Finished
INFO[1550100445.651158] Connection accepted: 192.168.100.211:41848 ClientSD: 13 Secure: 1
INFO[1550100445.799976] Cipher: AES128-SHA, ClientSD: 13
DEBUG[1550100445.800024] Connection Started 1941974440, secure=1
DEBUG[1550100445.912440] SUPLA_DS_CALL_REGISTER_DEVICE_E
INFO[1550100446.41830] Device registered. ID: 69, ClientSD: 13 Protocol Version: 10
INFO[1550100469.666839] Connection accepted: 192.168.100.211:10646 ClientSD: 14 Secure: 1
INFO[1550100469.820374] Cipher: AES128-SHA, ClientSD: 14
DEBUG[1550100469.821707] Connection Started 1942017912, secure=1
DEBUG[1550100469.937114] SUPLA_DS_CALL_REGISTER_DEVICE_E
DEBUG[1550100472.156163] Connection Finished
INFO[1550100472.157226] Device registered. ID: 69, ClientSD: 14 Protocol Version: 10
INFO[1550100492.682487] Connection accepted: 192.168.100.211:14226 ClientSD: 18 Secure: 1
INFO[1550100492.827658] Cipher: AES128-SHA, ClientSD: 18
DEBUG[1550100492.827704] Connection Started 1942017952, secure=1
DEBUG[1550100496.281208] SSL_ERROR_SYSCALL
DEBUG[1550100496.281605] Connection Finished
INFO[1550100496.685297] Connection accepted: 192.168.100.211:25200 ClientSD: 17 Secure: 1
INFO[1550100496.833084] Cipher: AES128-SHA, ClientSD: 17
DEBUG[1550100496.834291] Connection Started 1942039176, secure=1
DEBUG[1550100496.944864] SUPLA_DS_CALL_REGISTER_DEVICE_E
DEBUG[1550100498.72995] Connection Finished
INFO[1550100498.74201] Device registered. ID: 69, ClientSD: 17 Protocol Version: 10
DEBUG[1550100518.700581] Connection Finished
INFO[1550100518.703991] Connection accepted: 192.168.100.211:39732 ClientSD: 13 Secure: 1
DEBUG[1550100521.970392] Connection Finished
INFO[1550100522.703017] Connection accepted: 192.168.100.211:12630 ClientSD: 14 Secure: 1
INFO[1550100522.826201] Cipher: AES128-SHA, ClientSD: 14
DEBUG[1550100522.827194] Connection Started 1941974440, secure=1
DEBUG[1550100522.938993] SUPLA_DS_CALL_REGISTER_DEVICE_E
INFO[1550100523.3723] Device registered. ID: 69, ClientSD: 14 Protocol Version: 10
DEBUG[1550100543.720611] Connection Finished
INFO[1550100543.726934] Connection accepted: 192.168.100.211:16440 ClientSD: 13 Secure: 1
DEBUG[1550100547.10070] Connection Finished
INFO[1550100547.722353] Connection accepted: 192.168.100.211:6899 ClientSD: 14 Secure: 1
INFO[1550100547.840537] Cipher: AES128-SHA, ClientSD: 14
DEBUG[1550100547.840564] Connection Started 1942110720, secure=1
DEBUG[1550100547.952850] SUPLA_DS_CALL_REGISTER_DEVICE_E
INFO[1550100548.589378] Device registered. ID: 69, ClientSD: 14 Protocol Version: 10
DEBUG[1550100568.740398] Connection Finished
Czekam na kolejne Supla Offline Party 👍
Na górę
Awatar użytkownika
pzygmunt
Posty: 18333
Rejestracja: wt sty 19, 2016 9:26 am
Lokalizacja: Paczków
Kontakt:
Skontaktuj się z pzygmunt

Musisz dodać więcej logów.
Dodaj tu
https://github.com/SUPLA/supla-core/blo ... r.cpp#L262
przed terminate

supla_log(LOG_DEBUG, "Duplicate!");
Skompiluj i sprawdź.
Na górę
ODPOWIEDZ

Wróć do „supla-docker”