Parent Log:
http://ci.aztec-labs.com/939d73b2eac1b0f7
Command: 0ed4365a8999eaca:ISOLATE=1:NAME=p2p/src/testbench/port_change.test.ts:CPUS=10:MEM=16g yarn-project/scripts/run_test.sh p2p/src/testbench/port_change.test.ts
Commit:
https://github.com/AztecProtocol/aztec-packages/commit/df2a427a93a9a196bfa701a613a512e66b2d9ad7
Env: REF_NAME=gh-readonly-queue/master/pr-14254-2c9d3c871bc457a07752c86b208f630736f4d4c0 CURRENT_VERSION=0.86.0 CI_FULL=1
Date: Tue May 13 21:27:44 UTC 2025
System: ARCH=amd64 CPUS=128 MEM=493Gi HOSTNAME=gh-readonly-queue_master_pr-14254-2c9d3c871bc457a0_amd64
Resources: CPU_LIST=0-127 CPUS=10 MEM=16g TIMEOUT=600s
History:
http://ci.aztec-labs.com/list/9b8c9f29fec539b7
21:27:44 +++ id -u
21:27:44 +++ id -g
21:27:44 ++ docker run -d --name p2p_src_testbench_port_change.test.ts --net=none --cpuset-cpus=0-127 --cpus=10 --memory=16g --user 1000:1000 -v/home/aztec-dev:/home/aztec-dev --mount type=tmpfs,target=/tmp,tmpfs-size=1g --workdir /home/aztec-dev/aztec-packages -e HOME -e VERBOSE -e GIT_CONFIG_GLOBAL=/home/aztec-dev/aztec-packages/build-images/src/home/.gitconfig -e FORCE_COLOR=true -e CPUS -e MEM aztecprotocol/build:3.0 /bin/bash -c 'timeout -v 600s bash -c '\''yarn-project/scripts/run_test.sh p2p/src/testbench/port_change.test.ts'\'''
21:27:45 + cid=c3253a4374781881d21bed7acd15967ee6552c07f61d19aaa6bf363f87ec9d02
21:27:45 + set +x
21:27:50 [21:27:50.959]
INFO:
testbench-ports Starting test setup
21:27:50 [21:27:50.973]
INFO:
testbench-ports Loading config from /home/aztec-dev/aztec-packages/yarn-project/p2p/testbench/configurations/normal-degree-5-nodes.json
21:27:50 [21:27:50.976]
INFO:
testbench-ports Creating 5 clients
21:27:51 [21:27:51.324]
INFO:
testbench-ports Creating client 0
21:27:51 [21:27:51.340]
INFO:
testbench-ports Creating client 1
21:27:51 [21:27:51.356]
INFO:
testbench-ports Creating client 2
21:27:51 [21:27:51.369]
INFO:
testbench-ports Creating client 3
21:27:51 [21:27:51.388]
INFO:
testbench-ports Creating client 4
21:27:58 {"level":30,"time":1747171678985,"pid":34,"hostname":"c3253a437478","module":"kv-store:lmdb-v2:test-1","msg":"Starting data store with maxReaders 16"}
21:27:58 {"level":30,"time":1747171678995,"pid":34,"hostname":"c3253a437478","module":"p2p-archive:lmdb-v2","msg":"Starting data store with maxReaders 16"}
21:27:58 {"level":30,"time":1747171678999,"pid":34,"hostname":"c3253a437478","module":"p2p-peer:lmdb-v2","msg":"Starting data store with maxReaders 16"}
21:27:59 {"level":30,"time":1747171679207,"pid":34,"hostname":"c3253a437478","module":"p2p:1:libp2p_service","msg":"Started libp2p service with protocol version 00-31337-0f77840f-1-222c1769-0c338ec5"}
21:27:59 {"level":30,"time":1747171679211,"pid":34,"hostname":"c3253a437478","module":"p2p:1","msg":"Started libp2p service with protocol version 00-31337-0f77840f-1-222c1769-0c338ec5"}
21:27:59 {"level":30,"time":1747171679232,"pid":34,"hostname":"c3253a437478","module":"p2p:1:discv5_service","nodeId":"81538eb6d5fb37d9bfeaa8acb60df6d4d56919ca9101bf6cadce47ed66c48d91","peerId":"16Uiu2HAmMYfNyMXPoeAVVtTPwpURk3f7BGZ44vBiXKP7Ms7rdjra","enrUdp":"/ip4/127.0.0.1/udp/40697/p2p/16Uiu2HAmMYfNyMXPoeAVVtTPwpURk3f7BGZ44vBiXKP7Ms7rdjra","enrTcp":"/ip4/127.0.0.1/tcp/40697/p2p/16Uiu2HAmMYfNyMXPoeAVVtTPwpURk3f7BGZ44vBiXKP7Ms7rdjra","versions":{"l1ChainId":31337,"l1RollupAddress":"0x0f77840fbda715503b72dcccd27595919c798e26","rollupVersion":1,"l2ProtocolContractsTreeRoot":"0x222c1769b46f1011cb7cba1ce72c83378facaf357af384ed6c7bbff04c0a1f32","l2CircuitsVkTreeRoot":"0x0c338ec5c1e5cd2813aaa04c2b70b1ba09e6765e78d816fc9ca742d9144eb996"},"msg":"DiscV5 service started"}
21:27:59 {"level":30,"time":1747171679234,"pid":34,"hostname":"c3253a437478","module":"p2p:1:discv5_service","msg":"Adding 1 bootstrap nodes ENRs: enr:-Le4QKSc0yLbxLGe420DB1a_ABkHkPPD1EO50R2UEfJ6EQRvCdlzWw707765Vxxdn34X58SryNJlePGHqKZmI28yhu8GhWF6dGVjpTAwLTMxMzM3LTBmNzc4NDBmLTEtMjIyYzE3NjktMGMzMzhlYzWCaWSCdjSCaXCEfwAAAYlzZWNwMjU2azGhA60_9Muc0Fu3FdQA-Y5mfQFWouzNQ7hw7TjvRewM_VxTg3RjcIKAU4N1ZHCCgFM"}
21:27:59 {"level":30,"time":1747171679277,"pid":35,"hostname":"c3253a437478","module":"kv-store:lmdb-v2:test-2","msg":"Starting data store with maxReaders 16"}
21:27:59 {"level":30,"time":1747171679290,"pid":35,"hostname":"c3253a437478","module":"p2p-archive:lmdb-v2","msg":"Starting data store with maxReaders 16"}
21:27:59 {"level":30,"time":1747171679302,"pid":33,"hostname":"c3253a437478","module":"kv-store:lmdb-v2:test-0","msg":"Starting data store with maxReaders 16"}
21:27:59 {"level":30,"time":1747171679293,"pid":35,"hostname":"c3253a437478","module":"p2p-peer:lmdb-v2","msg":"Starting data store with maxReaders 16"}
21:27:59 {"level":30,"time":1747171679309,"pid":33,"hostname":"c3253a437478","module":"p2p-archive:lmdb-v2","msg":"Starting data store with maxReaders 16"}
21:27:59 {"level":30,"time":1747171679312,"pid":33,"hostname":"c3253a437478","module":"p2p-peer:lmdb-v2","msg":"Starting data store with maxReaders 16"}
21:27:59 {"level":30,"time":1747171679368,"pid":34,"hostname":"c3253a437478","module":"p2p:1","listen":"127.0.0.1","port":40697,"announce":"/ip4/127.0.0.1/tcp/40697","peerId":"16Uiu2HAmMYfNyMXPoeAVVtTPwpURk3f7BGZ44vBiXKP7Ms7rdjra","msg":"Started P2P service"}
21:27:59 {"level":30,"time":1747171679368,"pid":34,"hostname":"c3253a437478","module":"p2p","syncedLatestBlock":1,"syncedProvenBlock":1,"syncedFinalizedBlock":1,"msg":"Starting P2P client synced to 1"}
21:27:59 {"level":30,"time":1747171679405,"pid":41,"hostname":"c3253a437478","module":"kv-store:lmdb-v2:test-3","msg":"Starting data store with maxReaders 16"}
21:27:59 {"level":30,"time":1747171679414,"pid":41,"hostname":"c3253a437478","module":"p2p-archive:lmdb-v2","msg":"Starting data store with maxReaders 16"}
21:27:59 {"level":30,"time":1747171679416,"pid":41,"hostname":"c3253a437478","module":"p2p-peer:lmdb-v2","msg":"Starting data store with maxReaders 16"}
21:27:59 {"level":30,"time":1747171679519,"pid":35,"hostname":"c3253a437478","module":"p2p:2:libp2p_service","msg":"Started libp2p service with protocol version 00-31337-0f77840f-1-222c1769-0c338ec5"}
21:27:59 {"level":30,"time":1747171679525,"pid":33,"hostname":"c3253a437478","module":"p2p:0:libp2p_service","msg":"Started libp2p service with protocol version 00-31337-0f77840f-1-222c1769-0c338ec5"}
21:27:59 {"level":30,"time":1747171679523,"pid":35,"hostname":"c3253a437478","module":"p2p:2","msg":"Started libp2p service with protocol version 00-31337-0f77840f-1-222c1769-0c338ec5"}
21:27:59 {"level":30,"time":1747171679529,"pid":33,"hostname":"c3253a437478","module":"p2p:0","msg":"Started libp2p service with protocol version 00-31337-0f77840f-1-222c1769-0c338ec5"}
21:27:59 {"level":30,"time":1747171679546,"pid":35,"hostname":"c3253a437478","module":"p2p:2:discv5_service","nodeId":"c46d37cc250cdebb123eabd32fc89a2ab65207829e6b2203ed0c8577cceb7d65","peerId":"16Uiu2HAmK9A7dkMudXGxLAs563nzZhASJkkYGTBbhdG8yE3etFRc","enrUdp":"/ip4/127.0.0.1/udp/45353/p2p/16Uiu2HAmK9A7dkMudXGxLAs563nzZhASJkkYGTBbhdG8yE3etFRc","enrTcp":"/ip4/127.0.0.1/tcp/45353/p2p/16Uiu2HAmK9A7dkMudXGxLAs563nzZhASJkkYGTBbhdG8yE3etFRc","versions":{"l1ChainId":31337,"l1RollupAddress":"0x0f77840fbda715503b72dcccd27595919c798e26","rollupVersion":1,"l2ProtocolContractsTreeRoot":"0x222c1769b46f1011cb7cba1ce72c83378facaf357af384ed6c7bbff04c0a1f32","l2CircuitsVkTreeRoot":"0x0c338ec5c1e5cd2813aaa04c2b70b1ba09e6765e78d816fc9ca742d9144eb996"},"msg":"DiscV5 service started"}
21:27:59 {"level":30,"time":1747171679553,"pid":33,"hostname":"c3253a437478","module":"p2p:0:discv5_service","nodeId":"07196ac408e90d03e2c34882cb40ac8b83c83ced214675bca3efef911150972b","peerId":"16Uiu2HAmQKHSo74kVjZnNw32GKuuhMebnWHfdYvffXG21Z79Y9YN","enrUdp":"/ip4/127.0.0.1/udp/32851/p2p/16Uiu2HAmQKHSo74kVjZnNw32GKuuhMebnWHfdYvffXG21Z79Y9YN","enrTcp":"/ip4/127.0.0.1/tcp/32851/p2p/16Uiu2HAmQKHSo74kVjZnNw32GKuuhMebnWHfdYvffXG21Z79Y9YN","versions":{"l1ChainId":31337,"l1RollupAddress":"0x0f77840fbda715503b72dcccd27595919c798e26","rollupVersion":1,"l2ProtocolContractsTreeRoot":"0x222c1769b46f1011cb7cba1ce72c83378facaf357af384ed6c7bbff04c0a1f32","l2CircuitsVkTreeRoot":"0x0c338ec5c1e5cd2813aaa04c2b70b1ba09e6765e78d816fc9ca742d9144eb996"},"msg":"DiscV5 service started"}
21:27:59 {"level":30,"time":1747171679548,"pid":35,"hostname":"c3253a437478","module":"p2p:2:discv5_service","msg":"Adding 2 bootstrap nodes ENRs: enr:-Le4QKSc0yLbxLGe420DB1a_ABkHkPPD1EO50R2UEfJ6EQRvCdlzWw707765Vxxdn34X58SryNJlePGHqKZmI28yhu8GhWF6dGVjpTAwLTMxMzM3LTBmNzc4NDBmLTEtMjIyYzE3NjktMGMzMzhlYzWCaWSCdjSCaXCEfwAAAYlzZWNwMjU2azGhA60_9Muc0Fu3FdQA-Y5mfQFWouzNQ7hw7TjvRewM_VxTg3RjcIKAU4N1ZHCCgFM, enr:-Le4QCHALcv99I74YsvsW2zst7SQYOnZJO7o4WGNPEDv3vX2QOJFvQCXYdNpkjtdfiyRP7MOfqCPVJwVvwnUnbq7WZEGhWF6dGVjpTAwLTMxMzM3LTBmNzc4NDBmLTEtMjIyYzE3NjktMGMzMzhlYzWCaWSCdjSCaXCEfwAAAYlzZWNwMjU2azGhA4QaPzXbIO4HfcqhwfgCdfYFE_WKT00ayM_hc3IeNJcTg3RjcIKe-YN1ZHCCnvk"}
21:27:59 {"level":30,"time":1747171679616,"pid":41,"hostname":"c3253a437478","module":"p2p:3:libp2p_service","msg":"Started libp2p service with protocol version 00-31337-0f77840f-1-222c1769-0c338ec5"}
21:27:59 {"level":30,"time":1747171679621,"pid":41,"hostname":"c3253a437478","module":"p2p:3","msg":"Started libp2p service with protocol version 00-31337-0f77840f-1-222c1769-0c338ec5"}
21:27:59 {"level":30,"time":1747171679629,"pid":43,"hostname":"c3253a437478","module":"kv-store:lmdb-v2:test-4","msg":"Starting data store with maxReaders 16"}
21:27:59 {"level":30,"time":1747171679635,"pid":41,"hostname":"c3253a437478","module":"p2p:3:discv5_service","nodeId":"3e3748776fb2f61bf22ccdeaadbca0468a5b3767c2bb6b5c92fa03d7c71c2929","peerId":"16Uiu2HAmBCmvyFtkYmrLdthDSmwTQEDF7DKuzBNwo8tvR3GeKGrV","enrUdp":"/ip4/127.0.0.1/udp/43913/p2p/16Uiu2HAmBCmvyFtkYmrLdthDSmwTQEDF7DKuzBNwo8tvR3GeKGrV","enrTcp":"/ip4/127.0.0.1/tcp/43913/p2p/16Uiu2HAmBCmvyFtkYmrLdthDSmwTQEDF7DKuzBNwo8tvR3GeKGrV","versions":{"l1ChainId":31337,"l1RollupAddress":"0x0f77840fbda715503b72dcccd27595919c798e26","rollupVersion":1,"l2ProtocolContractsTreeRoot":"0x222c1769b46f1011cb7cba1ce72c83378facaf357af384ed6c7bbff04c0a1f32","l2CircuitsVkTreeRoot":"0x0c338ec5c1e5cd2813aaa04c2b70b1ba09e6765e78d816fc9ca742d9144eb996"},"msg":"DiscV5 service started"}
21:27:59 {"level":30,"time":1747171679637,"pid":43,"hostname":"c3253a437478","module":"p2p-archive:lmdb-v2","msg":"Starting data store with maxReaders 16"}
21:27:59 {"level":30,"time":1747171679639,"pid":43,"hostname":"c3253a437478","module":"p2p-peer:lmdb-v2","msg":"Starting data store with maxReaders 16"}
21:27:59 {"level":30,"time":1747171679638,"pid":41,"hostname":"c3253a437478","module":"p2p:3:discv5_service","msg":"Adding 3 bootstrap nodes ENRs: enr:-Le4QKSc0yLbxLGe420DB1a_ABkHkPPD1EO50R2UEfJ6EQRvCdlzWw707765Vxxdn34X58SryNJlePGHqKZmI28yhu8GhWF6dGVjpTAwLTMxMzM3LTBmNzc4NDBmLTEtMjIyYzE3NjktMGMzMzhlYzWCaWSCdjSCaXCEfwAAAYlzZWNwMjU2azGhA60_9Muc0Fu3FdQA-Y5mfQFWouzNQ7hw7TjvRewM_VxTg3RjcIKAU4N1ZHCCgFM, enr:-Le4QCHALcv99I74YsvsW2zst7SQYOnZJO7o4WGNPEDv3vX2QOJFvQCXYdNpkjtdfiyRP7MOfqCPVJwVvwnUnbq7WZEGhWF6dGVjpTAwLTMxMzM3LTBmNzc4NDBmLTEtMjIyYzE3NjktMGMzMzhlYzWCaWSCdjSCaXCEfwAAAYlzZWNwMjU2azGhA4QaPzXbIO4HfcqhwfgCdfYFE_WKT00ayM_hc3IeNJcTg3RjcIKe-YN1ZHCCnvk, enr:-Le4QMrzwLlWrrqGvKEFhA9Ki-fxSEhEFnwGdrz9I37BVHtOW3-XqjtI83cyYglVxIry2SxVjJgRTbyUXOkDSbOfYekGhWF6dGVjpTAwLTMxMzM3LTBmNzc4NDBmLTEtMjIyYzE3NjktMGMzMzhlYzWCaWSCdjSCaXCEfwAAAYlzZWNwMjU2azGhA2BdbM8rwkNrc9Ne0PuS53bBkKi-3xDL0RKo_6LjnXxTg3RjcIKxKYN1ZHCCsSk"}
21:27:59 {"level":30,"time":1747171679684,"pid":33,"hostname":"c3253a437478","module":"p2p:0","listen":"127.0.0.1","port":32851,"announce":"/ip4/127.0.0.1/tcp/32851","peerId":"16Uiu2HAmQKHSo74kVjZnNw32GKuuhMebnWHfdYvffXG21Z79Y9YN","msg":"Started P2P service"}
21:27:59 {"level":30,"time":1747171679684,"pid":33,"hostname":"c3253a437478","module":"p2p","syncedLatestBlock":1,"syncedProvenBlock":1,"syncedFinalizedBlock":1,"msg":"Starting P2P client synced to 1"}
21:27:59 {"level":30,"time":1747171679751,"pid":35,"hostname":"c3253a437478","module":"p2p:2","listen":"127.0.0.1","port":45353,"announce":"/ip4/127.0.0.1/tcp/45353","peerId":"16Uiu2HAmK9A7dkMudXGxLAs563nzZhASJkkYGTBbhdG8yE3etFRc","msg":"Started P2P service"}
21:27:59 {"level":30,"time":1747171679751,"pid":35,"hostname":"c3253a437478","module":"p2p","syncedLatestBlock":1,"syncedProvenBlock":1,"syncedFinalizedBlock":1,"msg":"Starting P2P client synced to 1"}
21:27:59 {"level":30,"time":1747171679820,"pid":43,"hostname":"c3253a437478","module":"p2p:4:libp2p_service","msg":"Started libp2p service with protocol version 00-31337-0f77840f-1-222c1769-0c338ec5"}
21:27:59 {"level":30,"time":1747171679824,"pid":43,"hostname":"c3253a437478","module":"p2p:4","msg":"Started libp2p service with protocol version 00-31337-0f77840f-1-222c1769-0c338ec5"}
21:27:59 {"level":30,"time":1747171679842,"pid":43,"hostname":"c3253a437478","module":"p2p:4:discv5_service","nodeId":"1c25ad266210be5670db15a179aa74d32a0c918f5160407e90c6492868e80d1e","peerId":"16Uiu2HAm9e2nrkyVCXt1JUTWw4HY1vSu2fgjxVv54buSsuSVoRFL","enrUdp":"/ip4/127.0.0.1/udp/33101/p2p/16Uiu2HAm9e2nrkyVCXt1JUTWw4HY1vSu2fgjxVv54buSsuSVoRFL","enrTcp":"/ip4/127.0.0.1/tcp/33101/p2p/16Uiu2HAm9e2nrkyVCXt1JUTWw4HY1vSu2fgjxVv54buSsuSVoRFL","versions":{"l1ChainId":31337,"l1RollupAddress":"0x0f77840fbda715503b72dcccd27595919c798e26","rollupVersion":1,"l2ProtocolContractsTreeRoot":"0x222c1769b46f1011cb7cba1ce72c83378facaf357af384ed6c7bbff04c0a1f32","l2CircuitsVkTreeRoot":"0x0c338ec5c1e5cd2813aaa04c2b70b1ba09e6765e78d816fc9ca742d9144eb996"},"msg":"DiscV5 service started"}
21:27:59 {"level":30,"time":1747171679845,"pid":43,"hostname":"c3253a437478","module":"p2p:4:discv5_service","msg":"Adding 4 bootstrap nodes ENRs: enr:-Le4QKSc0yLbxLGe420DB1a_ABkHkPPD1EO50R2UEfJ6EQRvCdlzWw707765Vxxdn34X58SryNJlePGHqKZmI28yhu8GhWF6dGVjpTAwLTMxMzM3LTBmNzc4NDBmLTEtMjIyYzE3NjktMGMzMzhlYzWCaWSCdjSCaXCEfwAAAYlzZWNwMjU2azGhA60_9Muc0Fu3FdQA-Y5mfQFWouzNQ7hw7TjvRewM_VxTg3RjcIKAU4N1ZHCCgFM, enr:-Le4QCHALcv99I74YsvsW2zst7SQYOnZJO7o4WGNPEDv3vX2QOJFvQCXYdNpkjtdfiyRP7MOfqCPVJwVvwnUnbq7WZEGhWF6dGVjpTAwLTMxMzM3LTBmNzc4NDBmLTEtMjIyYzE3NjktMGMzMzhlYzWCaWSCdjSCaXCEfwAAAYlzZWNwMjU2azGhA4QaPzXbIO4HfcqhwfgCdfYFE_WKT00ayM_hc3IeNJcTg3RjcIKe-YN1ZHCCnvk, enr:-Le4QMrzwLlWrrqGvKEFhA9Ki-fxSEhEFnwGdrz9I37BVHtOW3-XqjtI83cyYglVxIry2SxVjJgRTbyUXOkDSbOfYekGhWF6dGVjpTAwLTMxMzM3LTBmNzc4NDBmLTEtMjIyYzE3NjktMGMzMzhlYzWCaWSCdjSCaXCEfwAAAYlzZWNwMjU2azGhA2BdbM8rwkNrc9Ne0PuS53bBkKi-3xDL0RKo_6LjnXxTg3RjcIKxKYN1ZHCCsSk, enr:-Le4QIXzfg2qAzIS310gRZXUhUnulw5A6mXa8631lZZcpI2SF3exRTCDwD5-8HtrNmJd-60FLGfPYBjqpUppYpTG-wsGhWF6dGVjpTAwLTMxMzM3LTBmNzc4NDBmLTEtMjIyYzE3NjktMGMzMzhlYzWCaWSCdjSCaXCEfwAAAYlzZWNwMjU2azGhAuptK2QWbFQoNpC_kNTx9MqAQHurR9tjU7QxJP_a2qZSg3RjcIKriYN1ZHCCq4k"}
21:27:59 {"level":30,"time":1747171679925,"pid":41,"hostname":"c3253a437478","module":"p2p:3","listen":"127.0.0.1","port":43913,"announce":"/ip4/127.0.0.1/tcp/43913","peerId":"16Uiu2HAmBCmvyFtkYmrLdthDSmwTQEDF7DKuzBNwo8tvR3GeKGrV","msg":"Started P2P service"}
21:27:59 {"level":30,"time":1747171679926,"pid":41,"hostname":"c3253a437478","module":"p2p","syncedLatestBlock":1,"syncedProvenBlock":1,"syncedFinalizedBlock":1,"msg":"Starting P2P client synced to 1"}
21:28:00 {"level":30,"time":1747171680244,"pid":43,"hostname":"c3253a437478","module":"p2p:4","listen":"127.0.0.1","port":33101,"announce":"/ip4/127.0.0.1/tcp/33101","peerId":"16Uiu2HAm9e2nrkyVCXt1JUTWw4HY1vSu2fgjxVv54buSsuSVoRFL","msg":"Started P2P service"}
21:28:00 {"level":30,"time":1747171680244,"pid":43,"hostname":"c3253a437478","module":"p2p","syncedLatestBlock":1,"syncedProvenBlock":1,"syncedFinalizedBlock":1,"msg":"Starting P2P client synced to 1"}
21:28:11 [21:28:11.418]
INFO:
testbench-ports Workers Ready
21:28:11 [21:28:11.883]
INFO:
testbench-ports Transaction sent from client 2
21:28:12 {"level":50,"time":1747171692498,"pid":43,"hostname":"c3253a437478","module":"p2p:4","msg":"Error handling gossipsub message: Error: Attempted to read beyond buffer length. Start index: 0, Num bytes to read: 1, Buffer length: 0"}
21:28:12 {"level":50,"time":1747171692499,"pid":33,"hostname":"c3253a437478","module":"p2p:0","msg":"Error handling gossipsub message: Error: Attempted to read beyond buffer length. Start index: 0, Num bytes to read: 1, Buffer length: 0"}
21:28:12 {"level":50,"time":1747171692500,"pid":41,"hostname":"c3253a437478","module":"p2p:3","msg":"Error handling gossipsub message: Error: Attempted to read beyond buffer length. Start index: 0, Num bytes to read: 1, Buffer length: 0"}
21:28:12 {"level":50,"time":1747171692500,"pid":34,"hostname":"c3253a437478","module":"p2p:1","msg":"Error handling gossipsub message: Error: Attempted to read beyond buffer length. Start index: 0, Num bytes to read: 1, Buffer length: 0"}
21:28:21 [21:28:21.889]
INFO:
testbench-ports Checking message propagation results
21:28:21 [21:28:21.894]
INFO:
testbench-ports Number of clients that received message: 4
21:28:21 [21:28:21.898]
INFO:
testbench-ports All clients received message
21:28:21 [21:28:21.899]
INFO:
testbench-ports Iteration 1 done
21:28:21 [21:28:21.899]
INFO:
testbench-ports Changing port for client 2
21:28:22 {"level":30,"time":1747171702046,"pid":35,"hostname":"c3253a437478","module":"p2p:reqresp:connection-sampler","msg":"Stopping connection sampler"}
21:28:22 {"level":30,"time":1747171702047,"pid":35,"hostname":"c3253a437478","module":"p2p:reqresp:connection-sampler","msg":"Connection sampler stopped"}
21:28:22 {"level":30,"time":1747171702067,"pid":35,"hostname":"c3253a437478","module":"p2p:2","msg":"LibP2P service stopped"}
21:28:22 {"level":30,"time":1747171702067,"pid":35,"hostname":"c3253a437478","module":"p2p","msg":"P2P client stopped."}
21:28:22 [21:28:22.320]
WARN:
testbench-ports Worker 2 exited unexpectedly with code null and signal SIGSEGV
21:28:31 [21:28:31.902]
INFO:
testbench-ports Changing port for client 2 to 41791
21:28:31 [21:28:31.914]
INFO:
testbench-ports Changing port for client 2 to 41791 with other nodes
{"0":"enr:-Le4QKSc0yLbxLGe420DB1a_ABkHkPPD1EO50R2UEfJ6EQRvCdlzWw707765Vxxdn34X58SryNJlePGHqKZmI28yhu8GhWF6dGVjpTAwLTMxMzM3LTBmNzc4NDBmLTEtMjIyYzE3NjktMGMzMzhlYzWCaWSCdjSCaXCEfwAAAYlzZWNwMjU2azGhA60_9Muc0Fu3FdQA-Y5mfQFWouzNQ7hw7TjvRewM_VxTg3RjcIKAU4N1ZHCCgFM","1":"enr:-Le4QCHALcv99I74YsvsW2zst7SQYOnZJO7o4WGNPEDv3vX2QOJFvQCXYdNpkjtdfiyRP7MOfqCPVJwVvwnUnbq7WZEGhWF6dGVjpTAwLTMxMzM3LTBmNzc4NDBmLTEtMjIyYzE3NjktMGMzMzhlYzWCaWSCdjSCaXCEfwAAAYlzZWNwMjU2azGhA4QaPzXbIO4HfcqhwfgCdfYFE_WKT00ayM_hc3IeNJcTg3RjcIKe-YN1ZHCCnvk","2":"enr:-Le4QIXzfg2qAzIS310gRZXUhUnulw5A6mXa8631lZZcpI2SF3exRTCDwD5-8HtrNmJd-60FLGfPYBjqpUppYpTG-wsGhWF6dGVjpTAwLTMxMzM3LTBmNzc4NDBmLTEtMjIyYzE3NjktMGMzMzhlYzWCaWSCdjSCaXCEfwAAAYlzZWNwMjU2azGhAuptK2QWbFQoNpC_kNTx9MqAQHurR9tjU7QxJP_a2qZSg3RjcIKriYN1ZHCCq4k","3":"enr:-Le4QJWsyI74g6Fqmo0xXyyANJ_na9otdo3gQ7ObEZXenhNiRYdu3veVXoYREafoTWahRITXApOiXCFEy6mlb-bLexkGhWF6dGVjpTAwLTMxMzM3LTBmNzc4NDBmLTEtMjIyYzE3NjktMGMzMzhlYzWCaWSCdjSCaXCEfwAAAYlzZWNwMjU2azGhAtMuHkemYOPbTkSEwc9LnDlTlOMzHqUhTbPeidjZOQ8vg3RjcIKBTYN1ZHCCgU0"}
21:28:37 {"level":30,"time":1747171717201,"pid":93,"hostname":"c3253a437478","module":"kv-store:lmdb-v2:test-2","msg":"Starting data store with maxReaders 16"}
21:28:37 {"level":30,"time":1747171717208,"pid":93,"hostname":"c3253a437478","module":"p2p-archive:lmdb-v2","msg":"Starting data store with maxReaders 16"}
21:28:37 {"level":30,"time":1747171717209,"pid":93,"hostname":"c3253a437478","module":"p2p-peer:lmdb-v2","msg":"Starting data store with maxReaders 16"}
21:28:37 {"level":30,"time":1747171717390,"pid":93,"hostname":"c3253a437478","module":"p2p:2:libp2p_service","msg":"Started libp2p service with protocol version 00-31337-0f77840f-1-222c1769-0c338ec5"}
21:28:37 {"level":30,"time":1747171717392,"pid":93,"hostname":"c3253a437478","module":"p2p:2","msg":"Started libp2p service with protocol version 00-31337-0f77840f-1-222c1769-0c338ec5"}
21:28:37 {"level":30,"time":1747171717405,"pid":93,"hostname":"c3253a437478","module":"p2p:2:discv5_service","nodeId":"c46d37cc250cdebb123eabd32fc89a2ab65207829e6b2203ed0c8577cceb7d65","peerId":"16Uiu2HAmK9A7dkMudXGxLAs563nzZhASJkkYGTBbhdG8yE3etFRc","enrUdp":"/ip4/127.0.0.1/udp/41791/p2p/16Uiu2HAmK9A7dkMudXGxLAs563nzZhASJkkYGTBbhdG8yE3etFRc","enrTcp":"/ip4/127.0.0.1/tcp/41791/p2p/16Uiu2HAmK9A7dkMudXGxLAs563nzZhASJkkYGTBbhdG8yE3etFRc","versions":{"l1ChainId":31337,"l1RollupAddress":"0x0f77840fbda715503b72dcccd27595919c798e26","rollupVersion":1,"l2ProtocolContractsTreeRoot":"0x222c1769b46f1011cb7cba1ce72c83378facaf357af384ed6c7bbff04c0a1f32","l2CircuitsVkTreeRoot":"0x0c338ec5c1e5cd2813aaa04c2b70b1ba09e6765e78d816fc9ca742d9144eb996"},"msg":"DiscV5 service started"}
21:28:37 {"level":30,"time":1747171717407,"pid":93,"hostname":"c3253a437478","module":"p2p:2:discv5_service","msg":"Adding 4 bootstrap nodes ENRs: enr:-Le4QKSc0yLbxLGe420DB1a_ABkHkPPD1EO50R2UEfJ6EQRvCdlzWw707765Vxxdn34X58SryNJlePGHqKZmI28yhu8GhWF6dGVjpTAwLTMxMzM3LTBmNzc4NDBmLTEtMjIyYzE3NjktMGMzMzhlYzWCaWSCdjSCaXCEfwAAAYlzZWNwMjU2azGhA60_9Muc0Fu3FdQA-Y5mfQFWouzNQ7hw7TjvRewM_VxTg3RjcIKAU4N1ZHCCgFM, enr:-Le4QCHALcv99I74YsvsW2zst7SQYOnZJO7o4WGNPEDv3vX2QOJFvQCXYdNpkjtdfiyRP7MOfqCPVJwVvwnUnbq7WZEGhWF6dGVjpTAwLTMxMzM3LTBmNzc4NDBmLTEtMjIyYzE3NjktMGMzMzhlYzWCaWSCdjSCaXCEfwAAAYlzZWNwMjU2azGhA4QaPzXbIO4HfcqhwfgCdfYFE_WKT00ayM_hc3IeNJcTg3RjcIKe-YN1ZHCCnvk, enr:-Le4QIXzfg2qAzIS310gRZXUhUnulw5A6mXa8631lZZcpI2SF3exRTCDwD5-8HtrNmJd-60FLGfPYBjqpUppYpTG-wsGhWF6dGVjpTAwLTMxMzM3LTBmNzc4NDBmLTEtMjIyYzE3NjktMGMzMzhlYzWCaWSCdjSCaXCEfwAAAYlzZWNwMjU2azGhAuptK2QWbFQoNpC_kNTx9MqAQHurR9tjU7QxJP_a2qZSg3RjcIKriYN1ZHCCq4k, enr:-Le4QJWsyI74g6Fqmo0xXyyANJ_na9otdo3gQ7ObEZXenhNiRYdu3veVXoYREafoTWahRITXApOiXCFEy6mlb-bLexkGhWF6dGVjpTAwLTMxMzM3LTBmNzc4NDBmLTEtMjIyYzE3NjktMGMzMzhlYzWCaWSCdjSCaXCEfwAAAYlzZWNwMjU2azGhAtMuHkemYOPbTkSEwc9LnDlTlOMzHqUhTbPeidjZOQ8vg3RjcIKBTYN1ZHCCgU0"}
21:28:37 {"level":30,"time":1747171717696,"pid":93,"hostname":"c3253a437478","module":"p2p:2","listen":"127.0.0.1","port":41791,"announce":"/ip4/127.0.0.1/tcp/41791","peerId":"16Uiu2HAmK9A7dkMudXGxLAs563nzZhASJkkYGTBbhdG8yE3etFRc","msg":"Started P2P service"}
21:28:37 {"level":30,"time":1747171717696,"pid":93,"hostname":"c3253a437478","module":"p2p","syncedLatestBlock":1,"syncedProvenBlock":1,"syncedFinalizedBlock":1,"msg":"Starting P2P client synced to 1"}
21:28:41 [21:28:41.816]
INFO:
testbench-ports Transaction sent from client 4
21:28:42 {"level":50,"time":1747171722795,"pid":41,"hostname":"c3253a437478","module":"p2p:3","msg":"Error handling gossipsub message: Error: Attempted to read beyond buffer length. Start index: 0, Num bytes to read: 1, Buffer length: 0"}
21:28:42 {"level":50,"time":1747171722800,"pid":34,"hostname":"c3253a437478","module":"p2p:1","msg":"Error handling gossipsub message: Error: Attempted to read beyond buffer length. Start index: 0, Num bytes to read: 1, Buffer length: 0"}
21:28:42 {"level":50,"time":1747171722806,"pid":93,"hostname":"c3253a437478","module":"p2p:2","msg":"Error handling gossipsub message: Error: Attempted to read beyond buffer length. Start index: 0, Num bytes to read: 1, Buffer length: 0"}
21:28:51 [21:28:51.818]
INFO:
testbench-ports Checking message propagation results
21:28:51 [21:28:51.819]
INFO:
testbench-ports Number of clients that received message: 3
21:28:51 [21:28:51.824]
INFO:
testbench-ports Cleaning up
21:28:51 [21:28:51.825]
INFO:
testbench-ports Cleaning up 5 worker processes
21:28:51 {"level":30,"time":1747171731934,"pid":43,"hostname":"c3253a437478","module":"p2p:reqresp:connection-sampler","msg":"Stopping connection sampler"}
21:28:51 {"level":30,"time":1747171731936,"pid":41,"hostname":"c3253a437478","module":"p2p:reqresp:connection-sampler","msg":"Stopping connection sampler"}
21:28:51 {"level":30,"time":1747171731937,"pid":41,"hostname":"c3253a437478","module":"p2p:reqresp:connection-sampler","msg":"Connection sampler stopped"}
21:28:51 {"level":30,"time":1747171731949,"pid":93,"hostname":"c3253a437478","module":"p2p:reqresp:connection-sampler","msg":"Stopping connection sampler"}
21:28:51 {"level":30,"time":1747171731935,"pid":43,"hostname":"c3253a437478","module":"p2p:reqresp:connection-sampler","msg":"Connection sampler stopped"}
21:28:51 {"level":30,"time":1747171731971,"pid":41,"hostname":"c3253a437478","module":"p2p:3","msg":"LibP2P service stopped"}
21:28:51 {"level":30,"time":1747171731972,"pid":41,"hostname":"c3253a437478","module":"p2p","msg":"P2P client stopped."}
21:28:51 {"level":30,"time":1747171731950,"pid":93,"hostname":"c3253a437478","module":"p2p:reqresp:connection-sampler","msg":"Connection sampler stopped"}
21:28:51 {"level":30,"time":1747171731989,"pid":43,"hostname":"c3253a437478","module":"p2p:4","msg":"LibP2P service stopped"}
21:28:51 {"level":30,"time":1747171731989,"pid":43,"hostname":"c3253a437478","module":"p2p","msg":"P2P client stopped."}
21:28:51 {"level":30,"time":1747171731996,"pid":93,"hostname":"c3253a437478","module":"p2p:2","msg":"LibP2P service stopped"}
21:28:51 {"level":30,"time":1747171731997,"pid":93,"hostname":"c3253a437478","module":"p2p","msg":"P2P client stopped."}
21:28:52 {"level":30,"time":1747171732010,"pid":33,"hostname":"c3253a437478","module":"p2p:reqresp:connection-sampler","msg":"Stopping connection sampler"}
21:28:52 {"level":30,"time":1747171732011,"pid":33,"hostname":"c3253a437478","module":"p2p:reqresp:connection-sampler","msg":"Connection sampler stopped"}
21:28:52 {"level":30,"time":1747171732060,"pid":33,"hostname":"c3253a437478","module":"p2p:0","msg":"LibP2P service stopped"}
21:28:52 {"level":30,"time":1747171732060,"pid":33,"hostname":"c3253a437478","module":"p2p","msg":"P2P client stopped."}
21:28:52 {"level":30,"time":1747171732066,"pid":34,"hostname":"c3253a437478","module":"p2p:reqresp:connection-sampler","msg":"Stopping connection sampler"}
21:28:52 {"level":30,"time":1747171732070,"pid":34,"hostname":"c3253a437478","module":"p2p:reqresp:connection-sampler","msg":"Connection sampler stopped"}
21:28:52 {"level":30,"time":1747171732098,"pid":34,"hostname":"c3253a437478","module":"p2p:1","msg":"LibP2P service stopped"}
21:28:52 {"level":30,"time":1747171732099,"pid":34,"hostname":"c3253a437478","module":"p2p","msg":"P2P client stopped."}
21:28:52 [21:28:52.229]
WARN:
testbench-ports Worker 3 exited unexpectedly with code null and signal SIGSEGV
21:28:52 [21:28:52.260]
WARN:
testbench-ports Worker 4 exited unexpectedly with code null and signal SIGSEGV
21:28:52 [21:28:52.365]
WARN:
testbench-ports Worker 0 exited unexpectedly with code null and signal SIGSEGV
21:28:52 [21:28:52.370]
WARN:
testbench-ports Worker 1 exited unexpectedly with code null and signal SIGSEGV
21:28:52 [21:28:52.372]
WARN:
testbench-ports Worker 2 exited unexpectedly with code null and signal SIGSEGV
21:28:52 [21:28:52.375]
INFO:
testbench-ports All worker processes cleaned up
21:28:52
FAIL src/testbench/port_change.test.ts (
64.172 s)
21:28:52 Port Change
21:28:52
✕ should change port and propagate the gossip message correctly (61419 ms)
21:28:52
21:28:52
● Port Change › should change port and propagate the gossip message correctly
21:28:52
21:28:52
expect(received).toBe
(expected) // Object.is equality
21:28:52
21:28:52 Expected:
4
21:28:52 Received:
3
21:28:52
21:28:52
75 | logger
.info(
`Number of clients that received message: ${numberOfClientsThatReceivedMessage}`)
;
21:28:52
76 |
21:28:52
> 77 | expect(numberOfClientsThatReceivedMessage).toBe(numberOfClients - 1);
21:28:52
| ^
21:28:52
78 | logger.info('All clients received message');
21:28:52
79 |
21:28:52
80 | workerClientManager.purgeMessageReceivedByClient();
21:28:52
21:28:52
at Object.toBe (testbench/port_change.test.ts:77:52)
21:28:52
21:28:52
Test Suites: 1 failed, 1 total
21:28:52
Tests: 1 failed, 1 total
21:28:52
Snapshots: 0 total
21:28:52
Time: 64.243 s
21:28:52
Ran all test suites matching /p2p\/src\/testbench\/port_change.test.ts/i
.
21:28:52
Force exiting Jest: Have you considered using `--detectOpenHandles` to detect async operations that kept running after all tests finished?