go-libp2p icon indicating copy to clipboard operation
go-libp2p copied to clipboard

identify: TestObsAddrSet is flaky

Open aarshkshah1992 opened this issue 5 years ago • 1 comments

Looks like a regression. Need to investigate.

https://travis-ci.com/github/libp2p/go-libp2p/builds/166912970.

aarshkshah1992 avatar May 19 '20 12:05 aarshkshah1992

https://github.com/libp2p/go-libp2p/runs/7153635906?check_suite_focus=true

 === RUN   TestObsAddrSet
  2022-07-01T16:37:09.347Z	DEBUG	net/identify	identify/obsaddr.go:397	added own observed listen addr	{"observed": "/ip4/1.2.3.4/tcp/1231"}
  2022-07-01T16:37:09.347Z	DEBUG	net/identify	identify/id.go:408	/ipfs/id/1.0.0 sent message to Qmaj3Hr7dGd4MJGqPSFGvXDhvJZzyHdFFDpX3SEvyPHDdF /ip4/127.0.0.1/tcp/10086
  2022-07-01T16:37:09.347Z	DEBUG	net/identify	identify/id.go:440	/ipfs/id/1.0.0 received message from QmXDu4V9B1CD3LqGr7xrMQygeSHPTzu9ZC7rYH96owVPcC /ip4/1.2.3.4/tcp/1234
  2022-07-01T16:37:09.347Z	DEBUG	net/identify	identify/id.go:636	Qmaj3Hr7dGd4MJGqPSFGvXDhvJZzyHdFFDpX3SEvyPHDdF received listen addrs for QmXDu4V9B1CD3LqGr7xrMQygeSHPTzu9ZC7rYH96owVPcC: [/ip4/1.2.3.4/tcp/1234]
  2022-07-01T16:37:09.347Z	DEBUG	net/identify	identify/id.go:408	/ipfs/id/1.0.0 sent message to QmXDu4V9B1CD3LqGr7xrMQygeSHPTzu9ZC7rYH96owVPcC /ip4/1.2.3.4/tcp/1234
  2022-07-01T16:37:09.347Z	DEBUG	net/identify	identify/id.go:440	/ipfs/id/1.0.0 received message from Qmaj3Hr7dGd4MJGqPSFGvXDhvJZzyHdFFDpX3SEvyPHDdF /ip4/127.0.0.1/tcp/10086
  2022-07-01T16:37:09.348Z	DEBUG	net/identify	identify/id.go:636	QmXDu4V9B1CD3LqGr7xrMQygeSHPTzu9ZC7rYH96owVPcC received listen addrs for Qmaj3Hr7dGd4MJGqPSFGvXDhvJZzyHdFFDpX3SEvyPHDdF: [/ip4/127.0.0.1/tcp/10086]
  2022-07-01T16:37:09.348Z	DEBUG	net/identify	identify/obsaddr.go:397	added own observed listen addr	{"observed": "/ip4/1.2.3.4/tcp/1234"}
  2022-07-01T16:37:09.435Z	DEBUG	net/identify	identify/obsaddr.go:397	added own observed listen addr	{"observed": "/ip4/1.2.3.4/tcp/1232"}
  2022-07-01T16:37:09.493Z	DEBUG	net/identify	identify/obsaddr.go:397	added own observed listen addr	{"observed": "/ip4/1.2.3.4/tcp/1233"}
  2022-07-01T16:37:09.563Z	DEBUG	net/identify	identify/obsaddr.go:397	added own observed listen addr	{"observed": "/ip4/1.2.3.4/tcp/1231"}
  2022-07-01T16:37:09.653Z	DEBUG	net/identify	identify/obsaddr.go:397	added own observed listen addr	{"observed": "/ip4/1.2.3.4/tcp/1232"}
  2022-07-01T16:37:09.774Z	DEBUG	net/identify	identify/obsaddr.go:397	added own observed listen addr	{"observed": "/ip4/1.2.3.4/tcp/1233"}
  2022-07-01T16:37:09.898Z	DEBUG	net/identify	identify/obsaddr.go:397	added own observed listen addr	{"observed": "/ip4/1.2.3.4/tcp/1231"}
  2022-07-01T16:37:09.898Z	DEBUG	net/identify	identify/id.go:408	/ipfs/id/1.0.0 sent message to Qmaj3Hr7dGd4MJGqPSFGvXDhvJZzyHdFFDpX3SEvyPHDdF /ip4/127.0.0.1/tcp/10086
  2022-07-01T16:37:09.898Z	DEBUG	net/identify	identify/id.go:408	/ipfs/id/1.0.0 sent message to QmfWYWjh4zem3fvjJtcuAhwbRXpf1zRQxgZ5TqgnyLMVWv /ip4/1.2.3.4/tcp/1235
  2022-07-01T16:37:09.898Z	DEBUG	net/identify	identify/id.go:440	/ipfs/id/1.0.0 received message from QmfWYWjh4zem3fvjJtcuAhwbRXpf1zRQxgZ5TqgnyLMVWv /ip4/1.2.3.4/tcp/1235
  2022-07-01T16:37:09.898Z	DEBUG	net/identify	identify/id.go:636	Qmaj3Hr7dGd4MJGqPSFGvXDhvJZzyHdFFDpX3SEvyPHDdF received listen addrs for QmfWYWjh4zem3fvjJtcuAhwbRXpf1zRQxgZ5TqgnyLMVWv: [/ip4/1.2.3.4/tcp/1235]
  2022-07-01T16:37:09.898Z	DEBUG	net/identify	identify/id.go:440	/ipfs/id/1.0.0 received message from Qmaj3Hr7dGd4MJGqPSFGvXDhvJZzyHdFFDpX3SEvyPHDdF /ip4/127.0.0.1/tcp/10086
  2022-07-01T16:37:09.898Z	DEBUG	net/identify	identify/id.go:636	QmfWYWjh4zem3fvjJtcuAhwbRXpf1zRQxgZ5TqgnyLMVWv received listen addrs for Qmaj3Hr7dGd4MJGqPSFGvXDhvJZzyHdFFDpX3SEvyPHDdF: [/ip4/127.0.0.1/tcp/10086]
  2022-07-01T16:37:09.899Z	DEBUG	net/identify	identify/obsaddr.go:397	added own observed listen addr	{"observed": "/ip4/1.2.3.4/tcp/1235"}
  2022-07-01T16:37:09.954Z	DEBUG	net/identify	identify/obsaddr.go:397	added own observed listen addr	{"observed": "/ip4/1.2.3.4/tcp/1232"}
  2022-07-01T16:37:10.041Z	DEBUG	net/identify	identify/obsaddr.go:397	added own observed listen addr	{"observed": "/ip4/1.2.3.4/tcp/1233"}
  2022-07-01T16:37:10.092Z	DEBUG	net/identify	identify/obsaddr.go:397	added own observed listen addr	{"observed": "/ip4/1.2.3.4/tcp/1231"}
  2022-07-01T16:37:10.092Z	DEBUG	net/identify	identify/id.go:408	/ipfs/id/1.0.0 sent message to Qmaj3Hr7dGd4MJGqPSFGvXDhvJZzyHdFFDpX3SEvyPHDdF /ip4/127.0.0.1/tcp/10086
  2022-07-01T16:37:10.092Z	DEBUG	net/identify	identify/id.go:440	/ipfs/id/1.0.0 received message from QmegvjNzXPrEpubV4bdMdKGfh4bvKX8oSpWXLhrWrXoj2Q /ip4/1.2.3.6/tcp/1236
  2022-07-01T16:37:10.092Z	DEBUG	net/identify	identify/id.go:636	Qmaj3Hr7dGd4MJGqPSFGvXDhvJZzyHdFFDpX3SEvyPHDdF received listen addrs for QmegvjNzXPrEpubV4bdMdKGfh4bvKX8oSpWXLhrWrXoj2Q: [/ip4/1.2.3.6/tcp/1236]
  2022-07-01T16:37:10.093Z	DEBUG	net/identify	identify/id.go:408	/ipfs/id/1.0.0 sent message to QmegvjNzXPrEpubV4bdMdKGfh4bvKX8oSpWXLhrWrXoj2Q /ip4/1.2.3.6/tcp/1236
  2022-07-01T16:37:10.093Z	DEBUG	net/identify	identify/id.go:440	/ipfs/id/1.0.0 received message from Qmaj3Hr7dGd4MJGqPSFGvXDhvJZzyHdFFDpX3SEvyPHDdF /ip4/127.0.0.1/tcp/10086
  2022-07-01T16:37:10.093Z	DEBUG	net/identify	identify/id.go:636	QmegvjNzXPrEpubV4bdMdKGfh4bvKX8oSpWXLhrWrXoj2Q received listen addrs for Qmaj3Hr7dGd4MJGqPSFGvXDhvJZzyHdFFDpX3SEvyPHDdF: [/ip4/127.0.0.1/tcp/10086]
  2022-07-01T16:37:10.093Z	DEBUG	net/identify	identify/obsaddr.go:397	added own observed listen addr	{"observed": "/ip4/1.2.3.6/tcp/1236"}
  2022-07-01T16:37:10.197Z	DEBUG	net/identify	identify/obsaddr.go:397	added own observed listen addr	{"observed": "/ip4/1.2.3.4/tcp/1231"}
  2022-07-01T16:37:10.197Z	DEBUG	net/identify	identify/id.go:408	/ipfs/id/1.0.0 sent message to Qmaj3Hr7dGd4MJGqPSFGvXDhvJZzyHdFFDpX3SEvyPHDdF /ip4/127.0.0.1/tcp/10086
  2022-07-01T16:37:10.197Z	DEBUG	net/identify	identify/id.go:408	/ipfs/id/1.0.0 sent message to QmWzXopEqWyxARSb61onEtmY6pD7MVd72snCUnKuDS61YL /ip4/1.2.3.7/tcp/1237
  2022-07-01T16:37:10.197Z	DEBUG	net/identify	identify/id.go:440	/ipfs/id/1.0.0 received message from Qmaj3Hr7dGd4MJGqPSFGvXDhvJZzyHdFFDpX3SEvyPHDdF /ip4/127.0.0.1/tcp/10086
  2022-07-01T16:37:10.197Z	DEBUG	net/identify	identify/id.go:636	QmWzXopEqWyxARSb61onEtmY6pD7MVd72snCUnKuDS61YL received listen addrs for Qmaj3Hr7dGd4MJGqPSFGvXDhvJZzyHdFFDpX3SEvyPHDdF: [/ip4/127.0.0.1/tcp/10086]
  2022-07-01T16:37:10.197Z	DEBUG	net/identify	identify/id.go:440	/ipfs/id/1.0.0 received message from QmWzXopEqWyxARSb61onEtmY6pD7MVd72snCUnKuDS61YL /ip4/1.2.3.7/tcp/1237
  2022-07-01T16:37:10.197Z	DEBUG	net/identify	identify/id.go:636	Qmaj3Hr7dGd4MJGqPSFGvXDhvJZzyHdFFDpX3SEvyPHDdF received listen addrs for QmWzXopEqWyxARSb61onEtmY6pD7MVd72snCUnKuDS61YL: [/ip4/1.2.3.7/tcp/1237]
  2022-07-01T16:37:10.197Z	DEBUG	net/identify	identify/obsaddr.go:397	added own observed listen addr	{"observed": "/ip4/1.2.3.7/tcp/1237"}
  2022-07-01T16:37:10.273Z	DEBUG	net/identify	identify/obsaddr.go:397	added own observed listen addr	{"observed": "/ip4/1.2.3.4/tcp/1231"}
  2022-07-01T16:37:10.273Z	DEBUG	net/identify	identify/id.go:408	/ipfs/id/1.0.0 sent message to Qmaj3Hr7dGd4MJGqPSFGvXDhvJZzyHdFFDpX3SEvyPHDdF /ip4/127.0.0.1/tcp/10086
  2022-07-01T16:37:10.273Z	DEBUG	net/identify	identify/id.go:408	/ipfs/id/1.0.0 sent message to QmSJmZhSX4BiZqb2L9VYTPXJSxdSPcHQXxTQkJSgUA8BPx /ip4/1.2.3.8/tcp/1237
  2022-07-01T16:37:10.273Z	DEBUG	net/identify	identify/id.go:440	/ipfs/id/1.0.0 received message from Qmaj3Hr7dGd4MJGqPSFGvXDhvJZzyHdFFDpX3SEvyPHDdF /ip4/127.0.0.1/tcp/10086
  2022-07-01T16:37:10.273Z	DEBUG	net/identify	identify/id.go:636	QmSJmZhSX4BiZqb2L9VYTPXJSxdSPcHQXxTQkJSgUA8BPx received listen addrs for Qmaj3Hr7dGd4MJGqPSFGvXDhvJZzyHdFFDpX3SEvyPHDdF: [/ip4/127.0.0.1/tcp/10086]
  2022-07-01T16:37:10.273Z	DEBUG	net/identify	identify/id.go:440	/ipfs/id/1.0.0 received message from QmSJmZhSX4BiZqb2L9VYTPXJSxdSPcHQXxTQkJSgUA8BPx /ip4/1.2.3.8/tcp/1237
  2022-07-01T16:37:10.273Z	DEBUG	net/identify	identify/id.go:636	Qmaj3Hr7dGd4MJGqPSFGvXDhvJZzyHdFFDpX3SEvyPHDdF received listen addrs for QmSJmZhSX4BiZqb2L9VYTPXJSxdSPcHQXxTQkJSgUA8BPx: [/ip4/1.2.3.8/tcp/1237]
  2022-07-01T16:37:10.273Z	DEBUG	net/identify	identify/obsaddr.go:397	added own observed listen addr	{"observed": "/ip4/1.2.3.8/tcp/1237"}
  2022-07-01T16:37:10.378Z	DEBUG	net/identify	identify/obsaddr.go:397	added own observed listen addr	{"observed": "/ip4/1.2.3.4/tcp/1232"}
  2022-07-01T16:37:10.472Z	DEBUG	net/identify	identify/obsaddr.go:397	added own observed listen addr	{"observed": "/ip4/1.2.3.4/tcp/1231"}
  2022-07-01T16:37:10.561Z	DEBUG	net/identify	identify/obsaddr.go:397	added own observed listen addr	{"observed": "/ip4/1.2.3.4/tcp/1231"}
  2022-07-01T16:37:10.648Z	DEBUG	net/identify	identify/obsaddr.go:397	added own observed listen addr	{"observed": "/ip4/1.2.3.4/tcp/1232"}
  2022-07-01T16:37:10.773Z	DEBUG	net/identify	identify/obsaddr.go:397	added own observed listen addr	{"observed": "/ip4/1.2.3.4/tcp/1231"}
  2022-07-01T16:37:10.876Z	DEBUG	net/identify	identify/obsaddr.go:397	added own observed listen addr	{"observed": "/ip4/1.2.3.4/tcp/1231"}
  2022-07-01T16:37:10.926Z	DEBUG	net/identify	identify/obsaddr.go:397	added own observed listen addr	{"observed": "/ip4/1.2.3.4/tcp/1232"}
  2022-07-01T16:37:10.985Z	DEBUG	net/identify	identify/obsaddr.go:397	added own observed listen addr	{"observed": "/ip4/1.2.3.4/tcp/1231"}
  2022-07-01T16:37:11.038Z	DEBUG	net/identify	identify/obsaddr.go:397	added own observed listen addr	{"observed": "/ip4/1.2.3.4/tcp/1231"}
  2022-07-01T16:37:11.092Z	DEBUG	net/identify	identify/obsaddr.go:397	added own observed listen addr	{"observed": "/ip4/1.2.3.4/tcp/1232"}
  2022-07-01T16:37:11.093Z	DEBUG	net/identify	identify/id.go:408	/ipfs/id/1.0.0 sent message to Qmaj3Hr7dGd4MJGqPSFGvXDhvJZzyHdFFDpX3SEvyPHDdF /ip4/127.0.0.1/tcp/10086
  2022-07-01T16:37:11.093Z	DEBUG	net/identify	identify/id.go:440	/ipfs/id/1.0.0 received message from QmVhniwe9hC4P5Xw7AehAupGNYUcwdnx6uNAZsEQSWp5qy /ip4/1.2.3.9/tcp/1237
  2022-07-01T16:37:11.093Z	DEBUG	net/identify	identify/id.go:636	Qmaj3Hr7dGd4MJGqPSFGvXDhvJZzyHdFFDpX3SEvyPHDdF received listen addrs for QmVhniwe9hC4P5Xw7AehAupGNYUcwdnx6uNAZsEQSWp5qy: [/ip4/1.2.3.9/tcp/1237]
  2022-07-01T16:37:11.093Z	DEBUG	net/identify	identify/id.go:408	/ipfs/id/1.0.0 sent message to QmVhniwe9hC4P5Xw7AehAupGNYUcwdnx6uNAZsEQSWp5qy /ip4/1.2.3.9/tcp/1237
  2022-07-01T16:37:11.093Z	DEBUG	net/identify	identify/id.go:440	/ipfs/id/1.0.0 received message from Qmaj3Hr7dGd4MJGqPSFGvXDhvJZzyHdFFDpX3SEvyPHDdF /ip4/127.0.0.1/tcp/10086
  2022-07-01T16:37:11.093Z	DEBUG	net/identify	identify/id.go:636	QmVhniwe9hC4P5Xw7AehAupGNYUcwdnx6uNAZsEQSWp5qy received listen addrs for Qmaj3Hr7dGd4MJGqPSFGvXDhvJZzyHdFFDpX3SEvyPHDdF: [/ip4/127.0.0.1/tcp/10086]
  2022-07-01T16:37:11.093Z	DEBUG	net/identify	identify/obsaddr.go:397	added own observed listen addr	{"observed": "/ip4/1.2.3.9/tcp/1237"}
  2022-07-01T16:37:11.202Z	DEBUG	net/identify	identify/obsaddr.go:397	added own observed listen addr	{"observed": "/ip4/1.2.3.4/tcp/1232"}
  2022-07-01T16:37:11.202Z	DEBUG	net/identify	identify/id.go:408	/ipfs/id/1.0.0 sent message to QmRQ12K5JqoscDFq9EQTHuiPoDNXjcD2wcpMwYD23m885q /ip4/1.2.3.10/tcp/1237
  2022-07-01T16:37:11.202Z	DEBUG	net/identify	identify/id.go:408	/ipfs/id/1.0.0 sent message to Qmaj3Hr7dGd4MJGqPSFGvXDhvJZzyHdFFDpX3SEvyPHDdF /ip4/127.0.0.1/tcp/10086
  2022-07-01T16:37:11.202Z	DEBUG	net/identify	identify/id.go:440	/ipfs/id/1.0.0 received message from QmRQ12K5JqoscDFq9EQTHuiPoDNXjcD2wcpMwYD23m885q /ip4/1.2.3.10/tcp/1237
  2022-07-01T16:37:11.202Z	DEBUG	net/identify	identify/id.go:440	/ipfs/id/1.0.0 received message from Qmaj3Hr7dGd4MJGqPSFGvXDhvJZzyHdFFDpX3SEvyPHDdF /ip4/127.0.0.1/tcp/10086
  2022-07-01T16:37:11.202Z	DEBUG	net/identify	identify/id.go:636	Qmaj3Hr7dGd4MJGqPSFGvXDhvJZzyHdFFDpX3SEvyPHDdF received listen addrs for QmRQ12K5JqoscDFq9EQTHuiPoDNXjcD2wcpMwYD23m885q: [/ip4/1.2.3.10/tcp/1237]
  2022-07-01T16:37:11.202Z	DEBUG	net/identify	identify/id.go:636	QmRQ12K5JqoscDFq9EQTHuiPoDNXjcD2wcpMwYD23m885q received listen addrs for Qmaj3Hr7dGd4MJGqPSFGvXDhvJZzyHdFFDpX3SEvyPHDdF: [/ip4/127.0.0.1/tcp/10086]
  2022-07-01T16:37:11.202Z	DEBUG	net/identify	identify/obsaddr.go:397	added own observed listen addr	{"observed": "/ip4/1.2.3.10/tcp/1237"}
      obsaddr_test.go:228: 
          	Error Trace:	obsaddr_test.go:228
          	Error:      	Condition never satisfied
          	Test:       	TestObsAddrSet
          	Messages:   	addrs should have timed out
  --- FAIL: TestObsAddrSet (2.75s)

marten-seemann avatar Jul 01 '22 17:07 marten-seemann