Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Invalid WHOAREYOU(no matching call error) #190

Open
thinkAfCod opened this issue Oct 10, 2024 · 3 comments
Open

Invalid WHOAREYOU(no matching call error) #190

thinkAfCod opened this issue Oct 10, 2024 · 3 comments
Labels
bug Something isn't working

Comments

@thinkAfCod
Copy link

Sometimes, packets of type WHOAREYOU sent by other nodes are received and deemed Invalid.

The reason is that the nonce recorded in the packet header has not been previously recorded in activeCallByAuth.

@GrapeBaBa GrapeBaBa added the bug Something isn't working label Oct 10, 2024
@GrapeBaBa GrapeBaBa changed the title UTP may use sendFromAnotherThread, and it is necessary to record the nonce of the sent packet Invalid WHOAREYOU(no matching call error) Oct 12, 2024
@GrapeBaBa
Copy link
Member

DEBUG[10-13|15:16:03.477] Unsolicited/late <nil>, TALKRESP/v5 response protocol=discV5 id=9bd5f31dcb19bac1 addr=68.183.19.213:9009
DEBUG[10-13|15:16:03.478] Unsolicited/late <nil>, TALKRESP/v5 response protocol=discV5 id=9bd5f31dcb19bac1 addr=68.183.19.213:9009
DEBUG[10-13|15:16:03.478] Unsolicited/late <nil>, TALKRESP/v5 response protocol=discV5 id=9bd5f31dcb19bac1 addr=68.183.19.213:9009
DEBUG[10-13|15:16:03.478] Unsolicited/late <nil>, TALKRESP/v5 response protocol=discV5 id=9bd5f31dcb19bac1 addr=68.183.19.213:9009
DEBUG[10-13|15:16:03.482] Unsolicited/late <nil>, TALKRESP/v5 response protocol=discV5 id=9bd5f31dcb19bac1 addr=68.183.19.213:9009
DEBUG[10-13|15:16:03.489] Unsolicited/late <nil>, TALKRESP/v5 response protocol=discV5 id=9bd5f31dcb19bac1 addr=68.183.19.213:9009
DEBUG[10-13|15:16:03.489] Unsolicited/late <nil>, TALKRESP/v5 response protocol=discV5 id=9bd5f31dcb19bac1 addr=68.183.19.213:9009
DEBUG[10-13|15:16:03.489] Unsolicited/late <nil>, TALKRESP/v5 response protocol=discV5 id=9bd5f31dcb19bac1 addr=68.183.19.213:9009
DEBUG[10-13|15:16:03.489] Unsolicited/late <nil>, TALKRESP/v5 response protocol=discV5 id=9bd5f31dcb19bac1 addr=68.183.19.213:9009
DEBUG[10-13|15:16:03.489] Unsolicited/late <nil>, TALKRESP/v5 response protocol=discV5 id=9bd5f31dcb19bac1 addr=68.183.19.213:9009

@GrapeBaBa
Copy link
Member

2024-10-13T15:03:25.999+0200    DEBUG   :9009.mx        libutp/utp.go:2815      Killing socket  {"conn_send_id": 32635, "conn_recv_id": 32634,
 "addr": "65.109.69.98:10007", "conn_ack": 0, "conn_seq": 19638}
ERROR[10-13|15:03:25.999] failed to dial utp connection            protocol=history err="dial utp [::]:9009->65.109.69.98:10007: connection ti
med out"

@GrapeBaBa
Copy link
Member

TRACE[10-14|03:57:04.536] << UNKNOWN/v5                            protocol=discV5 id=ecc66bf7b9332d69 addr=198.54.130.39:19004
TRACE[10-14|03:57:04.536] >> WHOAREYOU/v5                          protocol=discV5 id=ecc66bf7b9332d69 addr=198.54.130.39:19004           nonce=dcfad477812775b4a9a09484
DEBUG[10-14|03:57:04.681] the bucket is full and will add in replacement protocol=discV5 id=ecc66bf7b9332d69
TRACE[10-14|03:57:04.681] << FINDNODE/v5                           protocol=discV5 id=ecc66bf7b9332d69 addr=198.54.130.39:19004           req=0x3f36f4ccb2764010
TRACE[10-14|03:57:04.681] >> NODES/v5                              protocol=discV5 id=ecc66bf7b9332d69 addr=198.54.130.39:19004           req=0x3f36f4ccb2764010 tot=4 n=5 nonce=000000012ff5c73a2052f1dd
TRACE[10-14|03:57:04.681] >> NODES/v5                              protocol=discV5 id=ecc66bf7b9332d69 addr=198.54.130.39:19004           req=0x3f36f4ccb2764010 tot=4 n=5 nonce=000000025079ca7ad6a30bb2
TRACE[10-14|03:57:04.681] >> NODES/v5                              protocol=discV5 id=ecc66bf7b9332d69 addr=198.54.130.39:19004           req=0x3f36f4ccb2764010 tot=4 n=5 nonce=000000034a2252b42b4ba1ec
TRACE[10-14|03:57:04.681] >> NODES/v5                              protocol=discV5 id=ecc66bf7b9332d69 addr=198.54.130.39:19004           req=0x3f36f4ccb2764010 tot=4 n=1 nonce=00000004620775f2de10023a
TRACE[10-14|03:57:04.801] << UNKNOWN/v5                            protocol=discV5 id=fd9381bcc9a3e577 addr=34.92.44.223:30303
TRACE[10-14|03:57:04.801] >> WHOAREYOU/v5                          protocol=discV5 id=fd9381bcc9a3e577 addr=34.92.44.223:30303            nonce=972b9d09d64078b12809f5ea
TRACE[10-14|03:57:04.933] << PING/v5                               protocol=discV5 id=e186e56a2db99c13 addr=178.115.246.157:7502          req=0xace2ceca96ba1fb6 enrseq=1,713,299,752,154
TRACE[10-14|03:57:04.933] >> PONG/v5                               protocol=discV5 id=e186e56a2db99c13 addr=178.115.246.157:7502          req=0xace2ceca96ba1fb6 enrseq=2                 nonce=0000000671a95a0ef6b73ce6
DEBUG[10-14|03:57:05.024] the bucket is full and will add in replacement protocol=discV5 id=fd9381bcc9a3e577
TRACE[10-14|03:57:05.024] << FINDNODE/v5                           protocol=discV5 id=fd9381bcc9a3e577 addr=34.92.44.223:30303            req=0x6fd595d862a541bc
**TRACE[10-14|03:57:05.024] >> NODES/v5                              protocol=discV5 id=fd9381bcc9a3e577 addr=34.92.44.223:30303            req=0x6fd595d862a541bc tot=4 n=5 nonce=000000019027d014b4d6ccc4
TRACE[10-14|03:57:05.024] >> NODES/v5                              protocol=discV5 id=fd9381bcc9a3e577 addr=34.92.44.223:30303            req=0x6fd595d862a541bc tot=4 n=5 nonce=000000026457759b034d080a
TRACE[10-14|03:57:05.024] >> NODES/v5                              protocol=discV5 id=fd9381bcc9a3e577 addr=34.92.44.223:30303            req=0x6fd595d862a541bc tot=4 n=5 nonce=00000003d92066a83a2703bc
TRACE[10-14|03:57:05.024] >> NODES/v5                              protocol=discV5 id=fd9381bcc9a3e577 addr=34.92.44.223:30303            req=0x6fd595d862a541bc tot=4 n=1 nonce=00000004642dbba533b6c028**
TRACE[10-14|03:57:05.057] << UNKNOWN/v5                            protocol=discV5 id=e1b5d7c220252e76 addr=136.243.36.74:9222
TRACE[10-14|03:57:05.057] >> WHOAREYOU/v5                          protocol=discV5 id=e1b5d7c220252e76 addr=136.243.36.74:9222            nonce=9368ad94cdfdb17bfe552952
DEBUG[10-14|03:57:05.083] the node is already in table             protocol=discV5 id=e1b5d7c220252e76
TRACE[10-14|03:57:05.083] << PING/v5                               protocol=discV5 id=e1b5d7c220252e76 addr=136.243.36.74:9222            req=0x28170e2bbac92246 enrseq=1,725,524,366,909
TRACE[10-14|03:57:05.083] >> PONG/v5                               protocol=discV5 id=e1b5d7c220252e76 addr=136.243.36.74:9222            req=0x28170e2bbac92246 enrseq=2                 nonce=0000000186adcad1a5555ad2
**DEBUG[10-14|03:57:05.246] Invalid WHOAREYOU/v5                     protocol=discV5 addr=34.92.44.223:30303            nonce=00000004642dbba533b6c028 err="no matching call"
DEBUG[10-14|03:57:05.246] Invalid WHOAREYOU/v5                     protocol=discV5 addr=34.92.44.223:30303            nonce=00000004642dbba533b6c028 err="no matching call"
DEBUG[10-14|03:57:05.246] Invalid WHOAREYOU/v5                     protocol=discV5 addr=34.92.44.223:30303            nonce=00000004642dbba533b6c028 err="no matching call"**

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
bug Something isn't working
Projects
None yet
Development

No branches or pull requests

2 participants