lotus failed to connect to bootstrap peer #9580

uranuschen365 · 2022-11-01T06:40:50Z

uranuschen365
Nov 1, 2022

The lotus daemon fails to sync with the Calibration network.

2022-11-01T14:23:43.362+0800	INFO	paramfetch	[email protected]/paramfetch.go:209	Parameter file /mnt/ssda/work/filecoin-proof-parameters/v28-fil-inner-product-v1.srs is ok
2022-11-01T14:23:43.362+0800	INFO	paramfetch	[email protected]/paramfetch.go:233	parameter and key-fetching complete
2022-11-01T14:23:43.368+0800	INFO	modules	modules/core.go:64	memory limits initialized	{"max_mem_heap": 0, "total_system_mem": 540991397888, "effective_mem_limit": 540991397888}
2022-11-01T14:23:43.369+0800	WARN	modules	modules/core.go:124	failed to initialize cgroup-driven watchdog; err: failed to load memory cgroup stats: open /sys/fs/cgroup/memory/system.slice/supervisor.service/memory.memsw.usage_in_bytes: no such file or directory
2022-11-01T14:23:43.369+0800	WARN	modules	modules/core.go:125	trying a system-driven watchdog
2022-11-01T14:23:43.369+0800	INFO	watchdog	[email protected]/watermarks.go:16	initialized watermark watchdog policy; watermarks: []; thresholds: [270495698944 324594838732 378693978521 459842688204 486892258099 500417043046 513941827993]
2022-11-01T14:23:43.369+0800	INFO	watchdog	[email protected]/watchdog.go:472	initialized heap profile capture; threshold: 0.900000; max captures: 10; dir: /mnt/ssda/.lotus/heapprof
2022-11-01T14:23:43.369+0800	INFO	modules	modules/core.go:130	initialized system-driven watchdog
2022-11-01T14:23:43.370+0800	INFO	p2pnode	lp2p/rcmgr.go:38	libp2p resource manager is enabled
2022-11-01T14:23:43.370+0800	DEBUG	rcmgr	resource-manager/limit.go:84	initializing new limiter with config	{"limits": {"System":{"Streams":131030,"StreamsInbound":65515,"StreamsOutbound":131030,"Conns":8189,"ConnsInbound":4094,"ConnsOutbound":8189,"FD":524288,"Memory":4294967296},"Transient":{"Streams":16378,"StreamsInbound":8189,"StreamsOutbound":16378,"Conns":2079,"ConnsInbound":1039,"ConnsOutbound":2079,"FD":131072,"Memory":8486518784},"AllowlistedSystem":{"Streams":131030,"StreamsInbound":65515,"StreamsOutbound":131030,"Conns":8189,"ConnsInbound":4094,"ConnsOutbound":8189,"FD":524288,"Memory":67757932544},"AllowlistedTransient":{"Streams":16378,"StreamsInbound":8189,"StreamsOutbound":16378,"Conns":2079,"ConnsInbound":1039,"ConnsOutbound":2079,"FD":131072,"Memory":8486518784},"ServiceDefault":{"Streams":133078,"StreamsInbound":33269,"StreamsOutbound":133078,"Conns":0,"ConnsInbound":0,"ConnsOutbound":0,"FD":0,"Memory":8520073216},"Service":{"libp2p.autonat":{"Streams":315,"StreamsInbound":315,"StreamsOutbound":315,"Conns":0,"ConnsInbound":0,"ConnsOutbound":0,"FD":0,"Memory":136271872},"libp2p.holepunch":{"Streams":1071,"StreamsInbound":535,"StreamsOutbound":535,"Conns":0,"ConnsInbound":0,"ConnsOutbound":0,"FD":0,"Memory":268349440},"libp2p.identify":{"Streams":8189,"StreamsInbound":4094,"StreamsOutbound":4094,"Conns":0,"ConnsInbound":0,"ConnsOutbound":0,"FD":0,"Memory":268349440},"libp2p.ping":{"Streams":4094,"StreamsInbound":4094,"StreamsOutbound":4094,"Conns":0,"ConnsInbound":0,"ConnsOutbound":0,"FD":0,"Memory":268349440},"libp2p.relay/v1":{"Streams":16378,"StreamsInbound":16378,"StreamsOutbound":16378,"Conns":0,"ConnsInbound":0,"ConnsOutbound":0,"FD":0,"Memory":1073397760},"libp2p.relay/v2":{"Streams":16378,"StreamsInbound":16378,"StreamsOutbound":16378,"Conns":0,"ConnsInbound":0,"ConnsOutbound":0,"FD":0,"Memory":1073397760}},"ServicePeerDefault":{"Streams":759,"StreamsInbound":379,"StreamsOutbound":759,"Conns":0,"ConnsInbound":0,"ConnsOutbound":0,"FD":0,"Memory":280932352},"ServicePeer":{"libp2p.autonat":{"Streams":2,"StreamsInbound":2,"StreamsOutbound":2,"Conns":0,"ConnsInbound":0,"ConnsOutbound":0,"FD":0,"Memory":1048576},"libp2p.holepunch":{"Streams":2,"StreamsInbound":2,"StreamsOutbound":2,"Conns":0,"ConnsInbound":0,"ConnsOutbound":0,"FD":0,"Memory":1048576},"libp2p.identify":{"Streams":32,"StreamsInbound":16,"StreamsOutbound":16,"Conns":0,"ConnsInbound":0,"ConnsOutbound":0,"FD":0,"Memory":1048576},"libp2p.ping":{"Streams":4,"StreamsInbound":2,"StreamsOutbound":3,"Conns":0,"ConnsInbound":0,"ConnsOutbound":0,"FD":0,"Memory":8590458880},"libp2p.relay/v1":{"Streams":64,"StreamsInbound":64,"StreamsOutbound":64,"Conns":0,"ConnsInbound":0,"ConnsOutbound":0,"FD":0,"Memory":1048576},"libp2p.relay/v2":{"Streams":64,"StreamsInbound":64,"StreamsOutbound":64,"Conns":0,"ConnsInbound":0,"ConnsOutbound":0,"FD":0,"Memory":1048576}},"ProtocolDefault":{"Streams":34293,"StreamsInbound":16634,"StreamsOutbound":34293,"Conns":0,"ConnsInbound":0,"ConnsOutbound":0,"FD":0,"Memory":10897469440},"Protocol":{"/ipfs/id/1.0.0":{"Streams":8189,"StreamsInbound":4094,"StreamsOutbound":4094,"Conns":0,"ConnsInbound":0,"ConnsOutbound":0,"FD":0,"Memory":268349440},"/ipfs/id/push/1.0.0":{"Streams":8189,"StreamsInbound":4094,"StreamsOutbound":4094,"Conns":0,"ConnsInbound":0,"ConnsOutbound":0,"FD":0,"Memory":268349440},"/ipfs/ping/1.0.0":{"Streams":4094,"StreamsInbound":4094,"StreamsOutbound":4094,"Conns":0,"ConnsInbound":0,"ConnsOutbound":0,"FD":0,"Memory":268349440},"/libp2p/autonat/1.0.0":{"Streams":315,"StreamsInbound":315,"StreamsOutbound":315,"Conns":0,"ConnsInbound":0,"ConnsOutbound":0,"FD":0,"Memory":136271872},"/libp2p/circuit/relay/0.1.0":{"Streams":40946,"StreamsInbound":40946,"StreamsOutbound":40946,"Conns":0,"ConnsInbound":0,"ConnsOutbound":0,"FD":0,"Memory":1073397760},"/libp2p/circuit/relay/0.2.0/hop":{"Streams":40946,"StreamsInbound":40946,"StreamsOutbound":40946,"Conns":0,"ConnsInbound":0,"ConnsOutbound":0,"FD":0,"Memory":1073397760},"/libp2p/circuit/relay/0.2.0/stop":{"Streams":40946,"StreamsInbound":40946,"StreamsOutbound":40946,"Conns":0,"ConnsInbound":0,"ConnsOutbound":0,"FD":0,"Memory":1073397760},"/libp2p/dcutr":{"Streams":1071,"StreamsInbound":535,"StreamsOutbound":535,"Conns":0,"ConnsInbound":0,"ConnsOutbound":0,"FD":0,"Memory":268349440},"/p2p/id/delta/1.0.0":{"Streams":8189,"StreamsInbound":4094,"StreamsOutbound":4094,"Conns":0,"ConnsInbound":0,"ConnsOutbound":0,"FD":0,"Memory":268349440}},"ProtocolPeerDefault":{"Streams":1263,"StreamsInbound":315,"StreamsOutbound":631,"Conns":0,"ConnsInbound":0,"ConnsOutbound":0,"FD":0,"Memory":16777467},"ProtocolPeer":{"/ipfs/id/1.0.0":{"Streams":32,"StreamsInbound":16,"StreamsOutbound":16,"Conns":0,"ConnsInbound":0,"ConnsOutbound":0,"FD":0,"Memory":8590458880},"/ipfs/id/push/1.0.0":{"Streams":32,"StreamsInbound":16,"StreamsOutbound":16,"Conns":0,"ConnsInbound":0,"ConnsOutbound":0,"FD":0,"Memory":8590458880},"/ipfs/ping/1.0.0":{"Streams":4,"StreamsInbound":2,"StreamsOutbound":3,"Conns":0,"ConnsInbound":0,"ConnsOutbound":0,"FD":0,"Memory":8590458880},"/libp2p/autonat/1.0.0":{"Streams":2,"StreamsInbound":2,"StreamsOutbound":2,"Conns":0,"ConnsInbound":0,"ConnsOutbound":0,"FD":0,"Memory":1048576},"/libp2p/circuit/relay/0.1.0":{"Streams":128,"StreamsInbound":128,"StreamsOutbound":128,"Conns":0,"ConnsInbound":0,"ConnsOutbound":0,"FD":0,"Memory":33554432},"/libp2p/circuit/relay/0.2.0/hop":{"Streams":128,"StreamsInbound":128,"StreamsOutbound":128,"Conns":0,"ConnsInbound":0,"ConnsOutbound":0,"FD":0,"Memory":33554432},"/libp2p/circuit/relay/0.2.0/stop":{"Streams":128,"StreamsInbound":128,"StreamsOutbound":128,"Conns":0,"ConnsInbound":0,"ConnsOutbound":0,"FD":0,"Memory":33554432},"/libp2p/dcutr":{"Streams":2,"StreamsInbound":2,"StreamsOutbound":2,"Conns":0,"ConnsInbound":0,"ConnsOutbound":0,"FD":0,"Memory":1048576},"/p2p/id/delta/1.0.0":{"Streams":32,"StreamsInbound":16,"StreamsOutbound":16,"Conns":0,"ConnsInbound":0,"ConnsOutbound":0,"FD":0,"Memory":8590458880}},"PeerDefault":{"Streams":16634,"StreamsInbound":8317,"StreamsOutbound":16634,"Conns":8,"ConnsInbound":4,"ConnsOutbound":8,"FD":8192,"Memory":8520073216},"Conn":{"Streams":0,"StreamsInbound":0,"StreamsOutbound":0,"Conns":1,"ConnsInbound":1,"ConnsOutbound":1,"FD":1,"Memory":1048576},"Stream":{"Streams":1,"StreamsInbound":1,"StreamsOutbound":1,"Conns":0,"ConnsInbound":0,"ConnsOutbound":0,"FD":0,"Memory":16777216}}}
2022-11-01T14:23:43.396+0800	INFO	badger	[email protected]/levels.go:183	All 0 tables opened in 0s

2022-11-01T14:23:43.396+0800	INFO	badger	[email protected]/value.go:1158	Replaying file id: 0 at offset: 0

2022-11-01T14:23:43.396+0800	INFO	badger	[email protected]/value.go:1178	Replay took: 2.37µs

2022-11-01T14:23:43.398+0800	INFO	badger	[email protected]/levels.go:183	All 0 tables opened in 0s

2022-11-01T14:23:43.398+0800	INFO	badger	[email protected]/value.go:1158	Replaying file id: 0 at offset: 0

2022-11-01T14:23:43.398+0800	INFO	badger	[email protected]/value.go:1178	Replay took: 2µs

2022-11-01T14:23:43.403+0800	INFO	backupds	backupds/log.go:126	opening log	{"file": "/mnt/ssda/.lotus/kvlog/metadata/1667209037.log.cbor"}
2022-11-01T14:23:45.487+0800	INFO	backupds	backupds/log.go:206	log opened	{"file": "/mnt/ssda/.lotus/kvlog/metadata/1667209037.log.cbor", "openCount": 13, "baseValues": 693404, "logValues": 65}
2022-11-01T14:23:45.487+0800	INFO	backupds	backupds/log.go:232	new log head	{"loghead": "1667209037.log.cbor;f35002fb-4eb9-4c76-b753-06320ab6eac6;1667283825"}
2022-11-01T14:23:45.647+0800	INFO	badgerbs	[email protected]/levels.go:183	All 32 tables opened in 150ms

2022-11-01T14:23:45.648+0800	INFO	badgerbs	[email protected]/value.go:1158	Replaying file id: 42 at offset: 490029879

2022-11-01T14:23:45.650+0800	INFO	badgerbs	[email protected]/value.go:1178	Replay took: 2.570021ms

2022-11-01T14:23:45.670+0800	INFO	p2pnode	lp2p/addrs.go:101	Swarm listening at: [/ip4/127.0.0.1/tcp/41253 /ip4/192.168.10.100/tcp/41253 /ip6/::1/tcp/40391]
2022-11-01T14:23:45.700+0800	INFO	modules	modules/services.go:162	subscribing to pubsub topic /fil/blocks/calibrationnet
2022-11-01T14:23:45.700+0800	INFO	modules	modules/services.go:227	relaying messages for pubsub topic /indexer/ingest/calibrationnet
2022-11-01T14:23:45.701+0800	INFO	importmgr	imports/manager.go:61	sanity checking imports
2022/11/01 14:23:45 storess: Query
2022/11/01 14:23:45 storess: q.Prefix: 
2022/11/01 14:23:45 storess: q.KeysOnly: true
2022/11/01 14:23:45 storess: q.Filters: 0
2022/11/01 14:23:45 storess: q.Orders: 0
2022/11/01 14:23:45 storess: q.Offset: 0
2022-11-01T14:23:45.701+0800	INFO	importmgr	imports/manager.go:98	sanity check completed	{"broken": 0, "total": 0}
2022-11-01T14:23:45.703+0800	INFO	dt-impl	impl/impl.go:145	start data-transfer module
2022-11-01T14:23:45.703+0800	INFO	markets	loggers/loggers.go:65	module ready	{"module": "client data transfer"}
[flexi_logger][ERRCODE::Time] flexi_logger has to work with UTC rather than with local time, caused by IndeterminateOffset
    See https://docs.rs/flexi_logger/latest/flexi_logger/error_info/index.html#time
2022-11-01T06:23:45.703 INFO filcrypto::util::types > create_fvm_machine: start
2022-11-01T06:23:45.703 INFO filcrypto::fvm::machine > using FVM V1
2022-11-01T14:23:45.703+0800	INFO	markets	loggers/loggers.go:65	module ready	{"module": "discovery"}
2022-11-01T14:23:45.703+0800	INFO	markets	loggers/loggers.go:65	module ready	{"module": "storage client"}
2022-11-01T14:23:45.703+0800	INFO	markets	loggers/loggers.go:65	module ready	{"module": "retrieval client"}
2022-11-01T14:23:45.707+0800	INFO	healthcheck	node/health.go:57	waiting 1s before starting ChainNotify channel
2022-11-01T14:23:46.707+0800	INFO	healthcheck	node/health.go:70	started ChainNotify channel
2022-11-01T06:23:47.244 INFO filcrypto::util::types > create_fvm_machine: end
2022-11-01T06:23:47.245 INFO filcrypto::util::types > fvm_machine_execute_message: start
2022-11-01T06:23:47.249 INFO filcrypto::util::types > fvm_machine_execute_message: end
2022-11-01T06:23:47.249 INFO filcrypto::util::types > fvm_machine_execute_message: start
2022-11-01T06:23:47.255 INFO filcrypto::util::types > fvm_machine_execute_message: end
2022-11-01T06:23:47.255 INFO filcrypto::util::types > fvm_machine_execute_message: start
2022-11-01T06:23:47.261 INFO filcrypto::util::types > fvm_machine_execute_message: end
2022-11-01T06:23:47.261 INFO filcrypto::util::types > fvm_machine_execute_message: start
2022-11-01T06:23:47.264 INFO filcrypto::util::types > fvm_machine_execute_message: end
2022-11-01T06:23:47.264 INFO filcrypto::util::types > fvm_machine_execute_message: start
2022-11-01T06:23:47.268 INFO filcrypto::util::types > fvm_machine_execute_message: end
2022-11-01T06:23:47.268 INFO filcrypto::util::types > fvm_machine_execute_message: start
2022-11-01T06:23:47.274 INFO filcrypto::util::types > fvm_machine_execute_message: end
2022-11-01T06:23:47.274 INFO filcrypto::util::types > fvm_machine_execute_message: start
2022-11-01T06:23:47.295 INFO filcrypto::util::types > fvm_machine_execute_message: end
2022-11-01T06:23:47.295 INFO filcrypto::util::types > fvm_machine_flush: start
2022-11-01T06:23:47.297 INFO filcrypto::util::types > fvm_machine_flush: end
2022-11-01T14:23:47.307+0800	INFO	messagepool	messagepool/messagepool.go:436	mpool ready
2022-11-01T14:23:50.494+0800	INFO	basichost	basic/natmgr.go:93	DiscoverNAT error:no NAT found
2022-11-01T14:23:50.699+0800	INFO	peermgr	peermgr/peermgr.go:209	connecting to bootstrap peers
2022-11-01T14:23:51.267+0800	WARN	peermgr	peermgr/peermgr.go:216	failed to connect to bootstrap peer: failed to dial 12D3KooWMHDi3LVTFG8Szqogt7RkNXvonbQYqSazxBx41A5aeuVz:
  * [/ip4/52.26.5.18/tcp/1347] failed to negotiate security protocol: peer id mismatch: expected 12D3KooWMHDi3LVTFG8Szqogt7RkNXvonbQYqSazxBx41A5aeuVz, but remote key matches 12D3KooWLCMrG31NApq9SycYPEaFRX3EFpVMcisD1N46aouKj7re
2022-11-01T14:23:51.268+0800	WARN	peermgr	peermgr/peermgr.go:216	failed to connect to bootstrap peer: failed to dial 12D3KooWJkikQQkxS58spo76BYzFt4fotaT5NpV2zngvrqm4u5ow:
  * [/ip4/34.222.9.215/tcp/1347] failed to negotiate security protocol: peer id mismatch: expected 12D3KooWJkikQQkxS58spo76BYzFt4fotaT5NpV2zngvrqm4u5ow, but remote key matches 12D3KooWLEzKRUNuoUJu2XqvVBpEEWTedaeiAF3HzTYgYCgthDzG
2022-11-01T14:23:51.272+0800	WARN	peermgr	peermgr/peermgr.go:216	failed to connect to bootstrap peer: failed to dial 12D3KooWLce5FDHR4EX4CrYavphA5xS3uDsX6aoowXh5tzDUxJav:
  * [/ip4/34.216.239.197/tcp/1347] failed to negotiate security protocol: peer id mismatch: expected 12D3KooWLce5FDHR4EX4CrYavphA5xS3uDsX6aoowXh5tzDUxJav, but remote key matches 12D3KooW9yJHDL2U7zNRcanxueJTBNA6RigSjZSYNPbBQUPKqy7W
2022-11-01T14:23:51.318+0800	WARN	peermgr	peermgr/peermgr.go:216	failed to connect to bootstrap peer: failed to dial 12D3KooWA9hFfQG9GjP6bHeuQQbMD3FDtZLdW1NayxKXUT26PQZu:
  * [/ip4/35.163.7.162/tcp/1347] failed to negotiate security protocol: peer id mismatch: expected 12D3KooWA9hFfQG9GjP6bHeuQQbMD3FDtZLdW1NayxKXUT26PQZu, but remote key matches 12D3KooWFQNqG18XwpHojZKah6WNESnuuCPx4UComwZHEyQjUy8B
2022-11-01T14:23:55.700+0800	INFO	peermgr	peermgr/peermgr.go:209	connecting to bootstrap peers
2022-11-01T14:23:55.701+0800	WARN	peermgr	peermgr/peermgr.go:216	failed to connect to bootstrap peer: failed to dial 12D3KooWLce5FDHR4EX4CrYavphA5xS3uDsX6aoowXh5tzDUxJav:
  * [/ip4/34.216.239.197/tcp/1347] dial backoff
2022-11-01T14:23:55.701+0800	WARN	peermgr	peermgr/peermgr.go:216	failed to connect to bootstrap peer: failed to dial 12D3KooWA9hFfQG9GjP6bHeuQQbMD3FDtZLdW1NayxKXUT26PQZu:
  * [/ip4/35.163.7.162/tcp/1347] dial backoff
2022-11-01T14:23:55.701+0800	WARN	peermgr	peermgr/peermgr.go:216	failed to connect to bootstrap peer: failed to dial 12D3KooWJkikQQkxS58spo76BYzFt4fotaT5NpV2zngvrqm4u5ow:
  * [/ip4/34.222.9.215/tcp/1347] dial backoff
2022-11-01T14:23:55.702+0800	WARN	peermgr	peermgr/peermgr.go:216	failed to connect to bootstrap peer: failed to dial 12D3KooWMHDi3LVTFG8Szqogt7RkNXvonbQYqSazxBx41A5aeuVz:
  * [/ip4/52.26.5.18/tcp/1347] dial backoff

I am using the latest version of lotus:
lotus version 1.18.0-rc4+calibnet+git.c4875d475.dirty

Provide feedback

Saved searches

Use saved searches to filter your results more quickly

lotus failed to connect to bootstrap peer #9580

{{title}}

Replies: 0 comments

Select a reply

lotus failed to connect to bootstrap peer #9580

uranuschen365 Nov 1, 2022

Replies: 0 comments

uranuschen365
Nov 1, 2022