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

Duplicate dialog create NATS event #1616

Closed
anjmao opened this issue Feb 3, 2020 · 1 comment · Fixed by #1794
Closed

Duplicate dialog create NATS event #1616

anjmao opened this issue Feb 3, 2020 · 1 comment · Fixed by #1794
Assignees
Labels

Comments

@anjmao
Copy link
Contributor

anjmao commented Feb 3, 2020

Reading user report logs I see that there is duplicate dialog-create NATS message

2020-02-01T00:28:48.009 DBG communication/nats/receiver.go:112       > Request "0xd44ab795334471bb7775e5ac19e53d88e36599b6.openvpn.dialog-create" received: {"payload":{"peer_id":"0x26ccd5530fb06e7cb0e406b2053259b9cc6f310f","version":"v1"},"signature":"paseX/bnKKSsevu3sEoKTbu6rPMgcMd/VYYzTQokpMkc0RhFJPyf0YuwtVysn0Sj/EWfh/MbXkR9tWhp2k8pRgA="}
2020-02-01T00:28:48.009 DBG communication/nats/receiver.go:112       > Request "0xd44ab795334471bb7775e5ac19e53d88e36599b6.openvpn.dialog-create" received: {"payload":{"peer_id":"0x26ccd5530fb06e7cb0e406b2053259b9cc6f310f","version":"v1"},"signature":"paseX/bnKKSsevu3sEoKTbu6rPMgcMd/VYYzTQokpMkc0RhFJPyf0YuwtVysn0Sj/EWfh/MbXkR9tWhp2k8pRgA="}

@Waldz Do you know if this is normal? Looks like a bug in consumer side.

@anjmao anjmao added the bug label Feb 3, 2020
@anjmao anjmao added this to Icebox in Engineering via automation Feb 3, 2020
@soffokl
Copy link
Member

soffokl commented Feb 10, 2020

This could be a reason of failed connection from consumer. It generats a lot of events at the same moment.

2020-02-10T10:20:38.219 DBG communication/nats/receiver.go:119       > Request "0x50096db4d913bad63cc7264097f9f1960f669c10.openvpn.dialog-create" received: {"payload":{"peer_id":"0x454cbe2b5bde371b7a403f0ada1438ab6546c955","version":"v1"},"signature":"itRox2yp5V7nfYx37gLWqAF3LP6h5QQlHYckvQmNljp6LrOnpzM8LsIUAM4vU2LNVNZQ5v1z4vV9eZVKr8FMCQA="}
2020-02-10T10:20:38.219 DBG communication/nats/receiver.go:119       > Request "0x50096db4d913bad63cc7264097f9f1960f669c10.openvpn.dialog-create" received: {"payload":{"peer_id":"0x454cbe2b5bde371b7a403f0ada1438ab6546c955","version":"v1"},"signature":"itRox2yp5V7nfYx37gLWqAF3LP6h5QQlHYckvQmNljp6LrOnpzM8LsIUAM4vU2LNVNZQ5v1z4vV9eZVKr8FMCQA="}
2020-02-10T10:20:38.221 DBG communication/nats/receiver.go:119       > Request "0x50096db4d913bad63cc7264097f9f1960f669c10.openvpn.dialog-create" received: {"payload":{"peer_id":"0x454cbe2b5bde371b7a403f0ada1438ab6546c955","version":"v1"},"signature":"itRox2yp5V7nfYx37gLWqAF3LP6h5QQlHYckvQmNljp6LrOnpzM8LsIUAM4vU2LNVNZQ5v1z4vV9eZVKr8FMCQA="}
2020-02-10T10:20:38.220 DBG communication/nats/receiver.go:119       > Request "0x50096db4d913bad63cc7264097f9f1960f669c10.openvpn.dialog-create" received: {"payload":{"peer_id":"0x454cbe2b5bde371b7a403f0ada1438ab6546c955","version":"v1"},"signature":"itRox2yp5V7nfYx37gLWqAF3LP6h5QQlHYckvQmNljp6LrOnpzM8LsIUAM4vU2LNVNZQ5v1z4vV9eZVKr8FMCQA="}
2020-02-10T10:20:38.227 DBG communication/nats/receiver.go:159       > Request "d110efda-6775-46db-aabf-c8ef707d07e4.session-create" topic has been subscribed to
2020-02-10T10:20:38.227 DBG communication/nats/receiver.go:159       > Request "937f8ae1-b00c-4068-b8d2-86168cccdd53.session-create" topic has been subscribed to
2020-02-10T10:20:38.228 DBG communication/nats/receiver.go:159       > Request "d110efda-6775-46db-aabf-c8ef707d07e4.session-destroy" topic has been subscribed to
2020-02-10T10:20:38.228 INF communication/nats/dialog/dialog_waiter.go:118 > Accepted dialog from: "0x454cbe2b5bde371b7a403f0ada1438ab6546c955"
2020-02-10T10:20:38.228 DBG communication/nats/receiver.go:159       > Request "937f8ae1-b00c-4068-b8d2-86168cccdd53.session-destroy" topic has been subscribed to
2020-02-10T10:20:38.230 INF communication/nats/dialog/dialog_waiter.go:118 > Accepted dialog from: "0x454cbe2b5bde371b7a403f0ada1438ab6546c955"
2020-02-10T10:20:38.231 DBG communication/nats/receiver.go:142       > Request "0x50096db4d913bad63cc7264097f9f1960f669c10.openvpn.dialog-create" response: {"payload":{"reason":200,"reasonMessage":"OK","topic":"d110efda-6775-46db-aabf-c8ef707d07e4"},"signature":"dwGlOReY4BS2uTMZBbQPY/ia9rRvSYGYGx+ZuvvA2coMdFzeY8E3R1ocQpFeqlnR6jAfXAiyoLQdMTWgQ0aeEAE="}
2020-02-10T10:20:38.234 DBG communication/nats/receiver.go:159       > Request "9f8891f1-3645-4337-aac4-dfb86555d3b1.session-create" topic has been subscribed to
2020-02-10T10:20:38.233 DBG communication/nats/receiver.go:142       > Request "0x50096db4d913bad63cc7264097f9f1960f669c10.openvpn.dialog-create" response: {"payload":{"reason":200,"reasonMessage":"OK","topic":"937f8ae1-b00c-4068-b8d2-86168cccdd53"},"signature":"XPFpaC7TkYfLg31xIByWbwnUk0Fmvj7leZEAKxg0mb5qdn5EJ2rKT2dEfw5WIT984Uz4zRgd/OZGqUaDm2xUCQA="}
2020-02-10T10:20:38.235 DBG communication/nats/receiver.go:159       > Request "9f8891f1-3645-4337-aac4-dfb86555d3b1.session-destroy" topic has been subscribed to
2020-02-10T10:20:38.236 DBG communication/nats/receiver.go:159       > Request "4a9ce422-0b43-4748-ba4a-6ded0b405c35.session-create" topic has been subscribed to
2020-02-10T10:20:38.236 INF communication/nats/dialog/dialog_waiter.go:118 > Accepted dialog from: "0x454cbe2b5bde371b7a403f0ada1438ab6546c955"
2020-02-10T10:20:38.237 DBG communication/nats/receiver.go:159       > Request "4a9ce422-0b43-4748-ba4a-6ded0b405c35.session-destroy" topic has been subscribed to
2020-02-10T10:20:38.238 INF communication/nats/dialog/dialog_waiter.go:118 > Accepted dialog from: "0x454cbe2b5bde371b7a403f0ada1438ab6546c955"
2020-02-10T10:20:38.241 DBG communication/nats/receiver.go:142       > Request "0x50096db4d913bad63cc7264097f9f1960f669c10.openvpn.dialog-create" response: {"payload":{"reason":200,"reasonMessage":"OK","topic":"4a9ce422-0b43-4748-ba4a-6ded0b405c35"},"signature":"DzAxxAiPbQ1SgDZS2LFbERGhrqgYbTBP1iWIWVsOMDdPT26tYcnHxsbsD5NoRfMwwuFJbKfM6Ln8emBB5BT8+QA="}
2020-02-10T10:20:38.241 DBG communication/nats/receiver.go:142       > Request "0x50096db4d913bad63cc7264097f9f1960f669c10.openvpn.dialog-create" response: {"payload":{"reason":200,"reasonMessage":"OK","topic":"9f8891f1-3645-4337-aac4-dfb86555d3b1"},"signature":"Kz06NYtSZldkH/J8amh9p7XgvNSOpq0OznzbfrhDNZZDGv1CgbeMo9p0y4emoZKQgNPshS576MjHc+UzZGXBqAA="}
2020-02-10T10:20:38.300 DBG communication/nats/receiver.go:119       > Request "d110efda-6775-46db-aabf-c8ef707d07e4.session-create" received: {"payload":{"proposal_id":1,"config":{"Ip":"82.196.7.213"},"consumer_info":{"issuerID":{"address":"0x454cbe2b5bde371b7a403f0ada1438ab6546c955"},"accountantID":{"address":"0x0214281cf15c1a66b51990e2e65e1f7b7c363318"},"paymentVersion":"v3"}},"signature":"ReTqQy+psTBe/JigiMtQxgbKPRaUyvMQOjoML1D+cnRYiFxhfNbza7Arz/wMLbjPCBV5XCFSHOU2l0LaLStaCAA="}
2020-02-10T10:20:38.306 INF nat/event/tracker.go:63                  > Getting last NAT event event={"error":{},"stage":"hole_punching","successful":false}
2020-02-10T10:20:38.308 INF core/port/pool.go:67                     > Supplying port 47433
2020-02-10T10:20:38.310 INF core/port/pool.go:67                     > Supplying port 49985
2020-02-10T10:20:38.312 INF session/manager.go:157                   > Using new payments

@soffokl soffokl moved this from Icebox to Todo in Engineering Feb 10, 2020
@chompomonim chompomonim removed this from Todo in Engineering Feb 20, 2020
@soffokl soffokl self-assigned this Feb 26, 2020
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
Projects
None yet
Development

Successfully merging a pull request may close this issue.

2 participants