Parent Log:
http://ci.aztec-labs.com/7ec2c98ba3b5bfe9
Command: 652ae1544075a801: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/69477bdb11901c6961903abd4b7123c2c077393f
Env: REF_NAME=gh-readonly-queue/master/pr-14620-410f89c998d436ce6db0b392b9a7c79ca844f577 CURRENT_VERSION=0.87.6 CI_FULL=0
Date: Mon Jun 2 15:29:52 UTC 2025
System: ARCH=arm64 CPUS=64 MEM=247Gi HOSTNAME=pr-14620_arm64_a1-fast
Resources: CPU_LIST=0-63 CPUS=10 MEM=16g TIMEOUT=600s
History:
http://ci.aztec-labs.com/list/9b8c9f29fec539b7
15:29:52 +++ id -u
15:29:52 +++ id -g
15:29:52 ++ docker run -d --name p2p_src_testbench_port_change.test.ts --net=none --cpuset-cpus=0-63 --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'\'''
15:29:53 + cid=28f63ed03d9c747c82ebb3da98de0b15db680263479780f735fbef889667cc6d
15:29:53 + set +x
15:29:56 [15:29:56.017]
INFO:
testbench-ports Starting test setup
15:29:56 [15:29:56.028]
INFO:
testbench-ports Loading config from /home/aztec-dev/aztec-packages/yarn-project/p2p/testbench/configurations/normal-degree-5-nodes.json
15:29:56 [15:29:56.030]
INFO:
testbench-ports Creating 5 clients
15:29:56 [15:29:56.180]
INFO:
testbench-ports Creating client 0
15:29:56 [15:29:56.191]
INFO:
testbench-ports Creating client 1
15:29:56 [15:29:56.200]
INFO:
testbench-ports Creating client 2
15:29:56 [15:29:56.211]
INFO:
testbench-ports Creating client 3
15:29:56 [15:29:56.230]
INFO:
testbench-ports Creating client 4
15:30:01 {"level":30,"time":1748878201361,"pid":33,"hostname":"28f63ed03d9c","module":"kv-store:lmdb-v2:test-0","msg":"Starting data store with maxReaders 16"}
15:30:01 {"level":30,"time":1748878201366,"pid":33,"hostname":"28f63ed03d9c","module":"p2p-archive:lmdb-v2","msg":"Starting data store with maxReaders 16"}
15:30:01 {"level":30,"time":1748878201366,"pid":33,"hostname":"28f63ed03d9c","module":"p2p-peer:lmdb-v2","msg":"Starting data store with maxReaders 16"}
15:30:01 {"level":30,"time":1748878201434,"pid":35,"hostname":"28f63ed03d9c","module":"kv-store:lmdb-v2:test-1","msg":"Starting data store with maxReaders 16"}
15:30:01 {"level":30,"time":1748878201438,"pid":35,"hostname":"28f63ed03d9c","module":"p2p-archive:lmdb-v2","msg":"Starting data store with maxReaders 16"}
15:30:01 {"level":30,"time":1748878201439,"pid":35,"hostname":"28f63ed03d9c","module":"p2p-peer:lmdb-v2","msg":"Starting data store with maxReaders 16"}
15:30:01 {"level":30,"time":1748878201441,"pid":47,"hostname":"28f63ed03d9c","module":"kv-store:lmdb-v2:test-3","msg":"Starting data store with maxReaders 16"}
15:30:01 {"level":30,"time":1748878201448,"pid":47,"hostname":"28f63ed03d9c","module":"p2p-archive:lmdb-v2","msg":"Starting data store with maxReaders 16"}
15:30:01 {"level":30,"time":1748878201450,"pid":47,"hostname":"28f63ed03d9c","module":"p2p-peer:lmdb-v2","msg":"Starting data store with maxReaders 16"}
15:30:01 {"level":30,"time":1748878201487,"pid":33,"hostname":"28f63ed03d9c","module":"p2p:0:libp2p_service","msg":"Started libp2p service with protocol version 00-31337-fea5891f-1-2efd3fd6-2339da45"}
15:30:01 {"level":30,"time":1748878201490,"pid":33,"hostname":"28f63ed03d9c","module":"p2p:0","msg":"Started libp2p service with protocol version 00-31337-fea5891f-1-2efd3fd6-2339da45"}
15:30:01 {"level":30,"time":1748878201499,"pid":33,"hostname":"28f63ed03d9c","module":"p2p:0:discv5_service","nodeId":"430579d6c64a1e1bc3460d2041680dda49195f562862dedb3cc33e5cf4cd3bc4","peerId":"16Uiu2HAmMaJo2MRDstxPsiP38eWU37cTVGvdPHfynhZ1wxNjmeNB","enrUdp":"/ip4/127.0.0.1/udp/38125/p2p/16Uiu2HAmMaJo2MRDstxPsiP38eWU37cTVGvdPHfynhZ1wxNjmeNB","enrTcp":"/ip4/127.0.0.1/tcp/38125/p2p/16Uiu2HAmMaJo2MRDstxPsiP38eWU37cTVGvdPHfynhZ1wxNjmeNB","versions":{"l1ChainId":31337,"l1RollupAddress":"0xfea5891f79ce4b468ff6b4a5604709ed0efe3abc","rollupVersion":1,"l2ProtocolContractsTreeRoot":"0x2efd3fd6b542f09e9f76c84337f46370f67729ce54c815d35866b4cb2a267203","l2CircuitsVkTreeRoot":"0x2339da454741e2360c4e27c6f68767215a5b6ce150527d32d6b6c95aa92ec8c9"},"msg":"DiscV5 service started"}
15:30:01 {"level":30,"time":1748878201552,"pid":35,"hostname":"28f63ed03d9c","module":"p2p:1:libp2p_service","msg":"Started libp2p service with protocol version 00-31337-fea5891f-1-2efd3fd6-2339da45"}
15:30:01 {"level":30,"time":1748878201555,"pid":35,"hostname":"28f63ed03d9c","module":"p2p:1","msg":"Started libp2p service with protocol version 00-31337-fea5891f-1-2efd3fd6-2339da45"}
15:30:01 {"level":30,"time":1748878201566,"pid":47,"hostname":"28f63ed03d9c","module":"p2p:3:libp2p_service","msg":"Started libp2p service with protocol version 00-31337-fea5891f-1-2efd3fd6-2339da45"}
15:30:01 {"level":30,"time":1748878201567,"pid":35,"hostname":"28f63ed03d9c","module":"p2p:1:discv5_service","nodeId":"19308ab99f938ab3fa83540b8faae5129951c1bd3a8427da913f9a29646105c2","peerId":"16Uiu2HAkzMVzzw28N1iNY4mJp442KoJyAtgXuVr8FNamSq1V1Fk2","enrUdp":"/ip4/127.0.0.1/udp/44845/p2p/16Uiu2HAkzMVzzw28N1iNY4mJp442KoJyAtgXuVr8FNamSq1V1Fk2","enrTcp":"/ip4/127.0.0.1/tcp/44845/p2p/16Uiu2HAkzMVzzw28N1iNY4mJp442KoJyAtgXuVr8FNamSq1V1Fk2","versions":{"l1ChainId":31337,"l1RollupAddress":"0xfea5891f79ce4b468ff6b4a5604709ed0efe3abc","rollupVersion":1,"l2ProtocolContractsTreeRoot":"0x2efd3fd6b542f09e9f76c84337f46370f67729ce54c815d35866b4cb2a267203","l2CircuitsVkTreeRoot":"0x2339da454741e2360c4e27c6f68767215a5b6ce150527d32d6b6c95aa92ec8c9"},"msg":"DiscV5 service started"}
15:30:01 {"level":30,"time":1748878201569,"pid":47,"hostname":"28f63ed03d9c","module":"p2p:3","msg":"Started libp2p service with protocol version 00-31337-fea5891f-1-2efd3fd6-2339da45"}
15:30:01 {"level":30,"time":1748878201578,"pid":47,"hostname":"28f63ed03d9c","module":"p2p:3:discv5_service","nodeId":"c5c4b892117389c95a3c185887dc74c40e3d11347d9da22f018e8de5718c190f","peerId":"16Uiu2HAmP1zSJ58VgTELHHEGYXk3KAdCNWKHmauU6z22gz3xTq5q","enrUdp":"/ip4/127.0.0.1/udp/38549/p2p/16Uiu2HAmP1zSJ58VgTELHHEGYXk3KAdCNWKHmauU6z22gz3xTq5q","enrTcp":"/ip4/127.0.0.1/tcp/38549/p2p/16Uiu2HAmP1zSJ58VgTELHHEGYXk3KAdCNWKHmauU6z22gz3xTq5q","versions":{"l1ChainId":31337,"l1RollupAddress":"0xfea5891f79ce4b468ff6b4a5604709ed0efe3abc","rollupVersion":1,"l2ProtocolContractsTreeRoot":"0x2efd3fd6b542f09e9f76c84337f46370f67729ce54c815d35866b4cb2a267203","l2CircuitsVkTreeRoot":"0x2339da454741e2360c4e27c6f68767215a5b6ce150527d32d6b6c95aa92ec8c9"},"msg":"DiscV5 service started"}
15:30:01 {"level":30,"time":1748878201568,"pid":35,"hostname":"28f63ed03d9c","module":"p2p:1:discv5_service","msg":"Adding 1 bootstrap nodes ENRs: enr:-Le4QALLtyWtVvwYIdNcM0AmLnV-h3V5awFlMVCV2j2ubVG4Z_VDihLn9y8gRgJURpS1caqeaAN941KXyZw0I0qetDQGhWF6dGVjpTAwLTMxMzM3LWZlYTU4OTFmLTEtMmVmZDNmZDYtMjMzOWRhNDWCaWSCdjSCaXCEfwAAAYlzZWNwMjU2azGhA4SGIfTlc13Y7syoC2xkrO5yK9QXNQXtQb5nSzf6cAtgg3RjcIKU7YN1ZHCClO0"}
15:30:01 {"level":30,"time":1748878201580,"pid":47,"hostname":"28f63ed03d9c","module":"p2p:3:discv5_service","msg":"Adding 3 bootstrap nodes ENRs: enr:-Le4QALLtyWtVvwYIdNcM0AmLnV-h3V5awFlMVCV2j2ubVG4Z_VDihLn9y8gRgJURpS1caqeaAN941KXyZw0I0qetDQGhWF6dGVjpTAwLTMxMzM3LWZlYTU4OTFmLTEtMmVmZDNmZDYtMjMzOWRhNDWCaWSCdjSCaXCEfwAAAYlzZWNwMjU2azGhA4SGIfTlc13Y7syoC2xkrO5yK9QXNQXtQb5nSzf6cAtgg3RjcIKU7YN1ZHCClO0, enr:-Le4QOeOMkX7n-mUxO6bbVJwskfmJkRDrgYIXnkNF48uJjGRfhrLOrZDPYlI21cRijLkiHeBxeUZ3tCQWd81GObQmZgGhWF6dGVjpTAwLTMxMzM3LWZlYTU4OTFmLTEtMmVmZDNmZDYtMjMzOWRhNDWCaWSCdjSCaXCEfwAAAYlzZWNwMjU2azGhAkk40-9z_r0kf01sYX3bPAcar-KJVqHyNI_kh-xXTZv3g3RjcIKvLYN1ZHCCry0, enr:-Le4QAEwTBfu0tL73Sy1llabbDQVMMiLEz-JgehyTlWHZseeVaqvdViuiAZ6J-SOfhUoyS2f22QJLjDB9OLxt6YEbPAGhWF6dGVjpTAwLTMxMzM3LWZlYTU4OTFmLTEtMmVmZDNmZDYtMjMzOWRhNDWCaWSCdjSCaXCEfwAAAYlzZWNwMjU2azGhA-Qk-b8CyC7MMtZ1qg6WAZ84ahyde2XvLEmgq8_omFPHg3RjcIKYn4N1ZHCCmJ8"}
15:30:01 {"level":30,"time":1748878201609,"pid":45,"hostname":"28f63ed03d9c","module":"kv-store:lmdb-v2:test-2","msg":"Starting data store with maxReaders 16"}
15:30:01 {"level":30,"time":1748878201614,"pid":45,"hostname":"28f63ed03d9c","module":"p2p-archive:lmdb-v2","msg":"Starting data store with maxReaders 16"}
15:30:01 {"level":30,"time":1748878201614,"pid":45,"hostname":"28f63ed03d9c","module":"p2p-peer:lmdb-v2","msg":"Starting data store with maxReaders 16"}
15:30:01 {"level":30,"time":1748878201648,"pid":33,"hostname":"28f63ed03d9c","module":"p2p:0","listen":"127.0.0.1","port":38125,"announce":"/ip4/127.0.0.1/tcp/38125","peerId":"16Uiu2HAmMaJo2MRDstxPsiP38eWU37cTVGvdPHfynhZ1wxNjmeNB","msg":"Started P2P service"}
15:30:01 {"level":30,"time":1748878201648,"pid":33,"hostname":"28f63ed03d9c","module":"p2p","syncedLatestBlock":1,"syncedProvenBlock":1,"syncedFinalizedBlock":1,"msg":"Starting P2P client synced to 1"}
15:30:01 {"level":30,"time":1748878201693,"pid":62,"hostname":"28f63ed03d9c","module":"kv-store:lmdb-v2:test-4","msg":"Starting data store with maxReaders 16"}
15:30:01 {"level":30,"time":1748878201697,"pid":62,"hostname":"28f63ed03d9c","module":"p2p-archive:lmdb-v2","msg":"Starting data store with maxReaders 16"}
15:30:01 {"level":30,"time":1748878201698,"pid":62,"hostname":"28f63ed03d9c","module":"p2p-peer:lmdb-v2","msg":"Starting data store with maxReaders 16"}
15:30:01 {"level":30,"time":1748878201706,"pid":35,"hostname":"28f63ed03d9c","module":"p2p:1","listen":"127.0.0.1","port":44845,"announce":"/ip4/127.0.0.1/tcp/44845","peerId":"16Uiu2HAkzMVzzw28N1iNY4mJp442KoJyAtgXuVr8FNamSq1V1Fk2","msg":"Started P2P service"}
15:30:01 {"level":30,"time":1748878201717,"pid":45,"hostname":"28f63ed03d9c","module":"p2p:2:libp2p_service","msg":"Started libp2p service with protocol version 00-31337-fea5891f-1-2efd3fd6-2339da45"}
15:30:01 {"level":30,"time":1748878201706,"pid":35,"hostname":"28f63ed03d9c","module":"p2p","syncedLatestBlock":1,"syncedProvenBlock":1,"syncedFinalizedBlock":1,"msg":"Starting P2P client synced to 1"}
15:30:01 {"level":30,"time":1748878201722,"pid":45,"hostname":"28f63ed03d9c","module":"p2p:2","msg":"Started libp2p service with protocol version 00-31337-fea5891f-1-2efd3fd6-2339da45"}
15:30:01 {"level":30,"time":1748878201727,"pid":47,"hostname":"28f63ed03d9c","module":"p2p:3","listen":"127.0.0.1","port":38549,"announce":"/ip4/127.0.0.1/tcp/38549","peerId":"16Uiu2HAmP1zSJ58VgTELHHEGYXk3KAdCNWKHmauU6z22gz3xTq5q","msg":"Started P2P service"}
15:30:01 {"level":30,"time":1748878201730,"pid":45,"hostname":"28f63ed03d9c","module":"p2p:2:discv5_service","nodeId":"75e733b86a7c4cac9f8e10e8a90c950f6425f760198f07a98ad38303389d5f09","peerId":"16Uiu2HAmU1a1N8wDNSoigDt9HHj8BZACt4RR1ZbvLEnscA56WTcS","enrUdp":"/ip4/127.0.0.1/udp/39071/p2p/16Uiu2HAmU1a1N8wDNSoigDt9HHj8BZACt4RR1ZbvLEnscA56WTcS","enrTcp":"/ip4/127.0.0.1/tcp/39071/p2p/16Uiu2HAmU1a1N8wDNSoigDt9HHj8BZACt4RR1ZbvLEnscA56WTcS","versions":{"l1ChainId":31337,"l1RollupAddress":"0xfea5891f79ce4b468ff6b4a5604709ed0efe3abc","rollupVersion":1,"l2ProtocolContractsTreeRoot":"0x2efd3fd6b542f09e9f76c84337f46370f67729ce54c815d35866b4cb2a267203","l2CircuitsVkTreeRoot":"0x2339da454741e2360c4e27c6f68767215a5b6ce150527d32d6b6c95aa92ec8c9"},"msg":"DiscV5 service started"}
15:30:01 {"level":30,"time":1748878201727,"pid":47,"hostname":"28f63ed03d9c","module":"p2p","syncedLatestBlock":1,"syncedProvenBlock":1,"syncedFinalizedBlock":1,"msg":"Starting P2P client synced to 1"}
15:30:01 {"level":30,"time":1748878201732,"pid":45,"hostname":"28f63ed03d9c","module":"p2p:2:discv5_service","msg":"Adding 2 bootstrap nodes ENRs: enr:-Le4QALLtyWtVvwYIdNcM0AmLnV-h3V5awFlMVCV2j2ubVG4Z_VDihLn9y8gRgJURpS1caqeaAN941KXyZw0I0qetDQGhWF6dGVjpTAwLTMxMzM3LWZlYTU4OTFmLTEtMmVmZDNmZDYtMjMzOWRhNDWCaWSCdjSCaXCEfwAAAYlzZWNwMjU2azGhA4SGIfTlc13Y7syoC2xkrO5yK9QXNQXtQb5nSzf6cAtgg3RjcIKU7YN1ZHCClO0, enr:-Le4QOeOMkX7n-mUxO6bbVJwskfmJkRDrgYIXnkNF48uJjGRfhrLOrZDPYlI21cRijLkiHeBxeUZ3tCQWd81GObQmZgGhWF6dGVjpTAwLTMxMzM3LWZlYTU4OTFmLTEtMmVmZDNmZDYtMjMzOWRhNDWCaWSCdjSCaXCEfwAAAYlzZWNwMjU2azGhAkk40-9z_r0kf01sYX3bPAcar-KJVqHyNI_kh-xXTZv3g3RjcIKvLYN1ZHCCry0"}
15:30:01 {"level":30,"time":1748878201815,"pid":62,"hostname":"28f63ed03d9c","module":"p2p:4:libp2p_service","msg":"Started libp2p service with protocol version 00-31337-fea5891f-1-2efd3fd6-2339da45"}
15:30:01 {"level":30,"time":1748878201817,"pid":62,"hostname":"28f63ed03d9c","module":"p2p:4","msg":"Started libp2p service with protocol version 00-31337-fea5891f-1-2efd3fd6-2339da45"}
15:30:01 {"level":30,"time":1748878201830,"pid":62,"hostname":"28f63ed03d9c","module":"p2p:4:discv5_service","nodeId":"4aff9a9aa30fe9d3e583f84ff3059351fb4900c40d106440df1e91a5aeacede4","peerId":"16Uiu2HAm9fpP8n74ynKtSYKU6Qk3MzHFnuu7sPJNDdJxtp3jq1P1","enrUdp":"/ip4/127.0.0.1/udp/37609/p2p/16Uiu2HAm9fpP8n74ynKtSYKU6Qk3MzHFnuu7sPJNDdJxtp3jq1P1","enrTcp":"/ip4/127.0.0.1/tcp/37609/p2p/16Uiu2HAm9fpP8n74ynKtSYKU6Qk3MzHFnuu7sPJNDdJxtp3jq1P1","versions":{"l1ChainId":31337,"l1RollupAddress":"0xfea5891f79ce4b468ff6b4a5604709ed0efe3abc","rollupVersion":1,"l2ProtocolContractsTreeRoot":"0x2efd3fd6b542f09e9f76c84337f46370f67729ce54c815d35866b4cb2a267203","l2CircuitsVkTreeRoot":"0x2339da454741e2360c4e27c6f68767215a5b6ce150527d32d6b6c95aa92ec8c9"},"msg":"DiscV5 service started"}
15:30:01 {"level":30,"time":1748878201832,"pid":62,"hostname":"28f63ed03d9c","module":"p2p:4:discv5_service","msg":"Adding 4 bootstrap nodes ENRs: enr:-Le4QALLtyWtVvwYIdNcM0AmLnV-h3V5awFlMVCV2j2ubVG4Z_VDihLn9y8gRgJURpS1caqeaAN941KXyZw0I0qetDQGhWF6dGVjpTAwLTMxMzM3LWZlYTU4OTFmLTEtMmVmZDNmZDYtMjMzOWRhNDWCaWSCdjSCaXCEfwAAAYlzZWNwMjU2azGhA4SGIfTlc13Y7syoC2xkrO5yK9QXNQXtQb5nSzf6cAtgg3RjcIKU7YN1ZHCClO0, enr:-Le4QOeOMkX7n-mUxO6bbVJwskfmJkRDrgYIXnkNF48uJjGRfhrLOrZDPYlI21cRijLkiHeBxeUZ3tCQWd81GObQmZgGhWF6dGVjpTAwLTMxMzM3LWZlYTU4OTFmLTEtMmVmZDNmZDYtMjMzOWRhNDWCaWSCdjSCaXCEfwAAAYlzZWNwMjU2azGhAkk40-9z_r0kf01sYX3bPAcar-KJVqHyNI_kh-xXTZv3g3RjcIKvLYN1ZHCCry0, enr:-Le4QAEwTBfu0tL73Sy1llabbDQVMMiLEz-JgehyTlWHZseeVaqvdViuiAZ6J-SOfhUoyS2f22QJLjDB9OLxt6YEbPAGhWF6dGVjpTAwLTMxMzM3LWZlYTU4OTFmLTEtMmVmZDNmZDYtMjMzOWRhNDWCaWSCdjSCaXCEfwAAAYlzZWNwMjU2azGhA-Qk-b8CyC7MMtZ1qg6WAZ84ahyde2XvLEmgq8_omFPHg3RjcIKYn4N1ZHCCmJ8, enr:-Le4QNx5zXIxtYvc2uaRrML3SiUVAWLo1Ovmim0JnVAasNhQYn6whMVpPiMyFn0Y62Itw_tWuHRaaeNIZy06IeqBvxoGhWF6dGVjpTAwLTMxMzM3LWZlYTU4OTFmLTEtMmVmZDNmZDYtMjMzOWRhNDWCaWSCdjSCaXCEfwAAAYlzZWNwMjU2azGhA5n2KfDZTBjjFfXQtL1UBZ0impT8a8o8RPGvgMLVrc5Yg3RjcIKWlYN1ZHCClpU"}
15:30:01 {"level":30,"time":1748878201875,"pid":45,"hostname":"28f63ed03d9c","module":"p2p:2","listen":"127.0.0.1","port":39071,"announce":"/ip4/127.0.0.1/tcp/39071","peerId":"16Uiu2HAmU1a1N8wDNSoigDt9HHj8BZACt4RR1ZbvLEnscA56WTcS","msg":"Started P2P service"}
15:30:01 {"level":30,"time":1748878201875,"pid":45,"hostname":"28f63ed03d9c","module":"p2p","syncedLatestBlock":1,"syncedProvenBlock":1,"syncedFinalizedBlock":1,"msg":"Starting P2P client synced to 1"}
15:30:02 {"level":30,"time":1748878202088,"pid":62,"hostname":"28f63ed03d9c","module":"p2p:4","listen":"127.0.0.1","port":37609,"announce":"/ip4/127.0.0.1/tcp/37609","peerId":"16Uiu2HAm9fpP8n74ynKtSYKU6Qk3MzHFnuu7sPJNDdJxtp3jq1P1","msg":"Started P2P service"}
15:30:02 {"level":30,"time":1748878202088,"pid":62,"hostname":"28f63ed03d9c","module":"p2p","syncedLatestBlock":1,"syncedProvenBlock":1,"syncedFinalizedBlock":1,"msg":"Starting P2P client synced to 1"}
15:30:16 [15:30:16.240]
INFO:
testbench-ports Workers Ready
15:30:16 [15:30:16.627]
INFO:
testbench-ports Transaction sent from client 2
15:30:17 {"level":50,"time":1748878217007,"pid":33,"hostname":"28f63ed03d9c","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"}
15:30:17 {"level":50,"time":1748878217015,"pid":62,"hostname":"28f63ed03d9c","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"}
15:30:17 {"level":50,"time":1748878217023,"pid":47,"hostname":"28f63ed03d9c","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"}
15:30:17 {"level":50,"time":1748878217033,"pid":35,"hostname":"28f63ed03d9c","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"}
15:30:26 [15:30:26.627]
INFO:
testbench-ports Checking message propagation results
15:30:26 [15:30:26.628]
INFO:
testbench-ports Number of clients that received message: 4
15:30:26 [15:30:26.630]
INFO:
testbench-ports All clients received message
15:30:26 [15:30:26.630]
INFO:
testbench-ports Iteration 1 done
15:30:26 [15:30:26.630]
INFO:
testbench-ports Changing port for client 2
15:30:26 {"level":30,"time":1748878226667,"pid":45,"hostname":"28f63ed03d9c","module":"p2p:reqresp:connection-sampler","msg":"Stopping connection sampler"}
15:30:26 {"level":30,"time":1748878226667,"pid":45,"hostname":"28f63ed03d9c","module":"p2p:reqresp:connection-sampler","msg":"Connection sampler stopped"}
15:30:26 {"level":30,"time":1748878226680,"pid":45,"hostname":"28f63ed03d9c","module":"p2p:2","msg":"LibP2P service stopped"}
15:30:26 {"level":30,"time":1748878226681,"pid":45,"hostname":"28f63ed03d9c","module":"p2p","msg":"P2P client stopped."}
15:30:27 [15:30:27.333]
WARN:
testbench-ports Worker 2 exited unexpectedly with code null and signal SIGSEGV
15:30:36 [15:30:36.632]
INFO:
testbench-ports Changing port for client 2 to 39415
15:30:36 [15:30:36.637]
INFO:
testbench-ports Changing port for client 2 to 39415 with other nodes
{"0":"enr:-Le4QALLtyWtVvwYIdNcM0AmLnV-h3V5awFlMVCV2j2ubVG4Z_VDihLn9y8gRgJURpS1caqeaAN941KXyZw0I0qetDQGhWF6dGVjpTAwLTMxMzM3LWZlYTU4OTFmLTEtMmVmZDNmZDYtMjMzOWRhNDWCaWSCdjSCaXCEfwAAAYlzZWNwMjU2azGhA4SGIfTlc13Y7syoC2xkrO5yK9QXNQXtQb5nSzf6cAtgg3RjcIKU7YN1ZHCClO0","1":"enr:-Le4QOeOMkX7n-mUxO6bbVJwskfmJkRDrgYIXnkNF48uJjGRfhrLOrZDPYlI21cRijLkiHeBxeUZ3tCQWd81GObQmZgGhWF6dGVjpTAwLTMxMzM3LWZlYTU4OTFmLTEtMmVmZDNmZDYtMjMzOWRhNDWCaWSCdjSCaXCEfwAAAYlzZWNwMjU2azGhAkk40-9z_r0kf01sYX3bPAcar-KJVqHyNI_kh-xXTZv3g3RjcIKvLYN1ZHCCry0","2":"enr:-Le4QNx5zXIxtYvc2uaRrML3SiUVAWLo1Ovmim0JnVAasNhQYn6whMVpPiMyFn0Y62Itw_tWuHRaaeNIZy06IeqBvxoGhWF6dGVjpTAwLTMxMzM3LWZlYTU4OTFmLTEtMmVmZDNmZDYtMjMzOWRhNDWCaWSCdjSCaXCEfwAAAYlzZWNwMjU2azGhA5n2KfDZTBjjFfXQtL1UBZ0impT8a8o8RPGvgMLVrc5Yg3RjcIKWlYN1ZHCClpU","3":"enr:-Le4QGTO7vpQq94_p1rXxz4l0DaxS7XeLWNu4rgHQ9dr8IiHCEFdPChOKBzAXXRGAbnNmxmakjKqQ2vF3ZuBqRbVo8IGhWF6dGVjpTAwLTMxMzM3LWZlYTU4OTFmLTEtMmVmZDNmZDYtMjMzOWRhNDWCaWSCdjSCaXCEfwAAAYlzZWNwMjU2azGhAtOjP7wGZm39-R7VJOSN-2ZJalSp6S73U10l7p2oJsqcg3RjcIKS6YN1ZHCCkuk"}
15:30:41 {"level":30,"time":1748878241177,"pid":97,"hostname":"28f63ed03d9c","module":"kv-store:lmdb-v2:test-2","msg":"Starting data store with maxReaders 16"}
15:30:41 {"level":30,"time":1748878241186,"pid":97,"hostname":"28f63ed03d9c","module":"p2p-archive:lmdb-v2","msg":"Starting data store with maxReaders 16"}
15:30:41 {"level":30,"time":1748878241187,"pid":97,"hostname":"28f63ed03d9c","module":"p2p-peer:lmdb-v2","msg":"Starting data store with maxReaders 16"}
15:30:41 {"level":30,"time":1748878241286,"pid":97,"hostname":"28f63ed03d9c","module":"p2p:2:libp2p_service","msg":"Started libp2p service with protocol version 00-31337-fea5891f-1-2efd3fd6-2339da45"}
15:30:41 {"level":30,"time":1748878241289,"pid":97,"hostname":"28f63ed03d9c","module":"p2p:2","msg":"Started libp2p service with protocol version 00-31337-fea5891f-1-2efd3fd6-2339da45"}
15:30:41 {"level":30,"time":1748878241298,"pid":97,"hostname":"28f63ed03d9c","module":"p2p:2:discv5_service","nodeId":"75e733b86a7c4cac9f8e10e8a90c950f6425f760198f07a98ad38303389d5f09","peerId":"16Uiu2HAmU1a1N8wDNSoigDt9HHj8BZACt4RR1ZbvLEnscA56WTcS","enrUdp":"/ip4/127.0.0.1/udp/39415/p2p/16Uiu2HAmU1a1N8wDNSoigDt9HHj8BZACt4RR1ZbvLEnscA56WTcS","enrTcp":"/ip4/127.0.0.1/tcp/39415/p2p/16Uiu2HAmU1a1N8wDNSoigDt9HHj8BZACt4RR1ZbvLEnscA56WTcS","versions":{"l1ChainId":31337,"l1RollupAddress":"0xfea5891f79ce4b468ff6b4a5604709ed0efe3abc","rollupVersion":1,"l2ProtocolContractsTreeRoot":"0x2efd3fd6b542f09e9f76c84337f46370f67729ce54c815d35866b4cb2a267203","l2CircuitsVkTreeRoot":"0x2339da454741e2360c4e27c6f68767215a5b6ce150527d32d6b6c95aa92ec8c9"},"msg":"DiscV5 service started"}
15:30:41 {"level":30,"time":1748878241301,"pid":97,"hostname":"28f63ed03d9c","module":"p2p:2:discv5_service","msg":"Adding 4 bootstrap nodes ENRs: enr:-Le4QALLtyWtVvwYIdNcM0AmLnV-h3V5awFlMVCV2j2ubVG4Z_VDihLn9y8gRgJURpS1caqeaAN941KXyZw0I0qetDQGhWF6dGVjpTAwLTMxMzM3LWZlYTU4OTFmLTEtMmVmZDNmZDYtMjMzOWRhNDWCaWSCdjSCaXCEfwAAAYlzZWNwMjU2azGhA4SGIfTlc13Y7syoC2xkrO5yK9QXNQXtQb5nSzf6cAtgg3RjcIKU7YN1ZHCClO0, enr:-Le4QOeOMkX7n-mUxO6bbVJwskfmJkRDrgYIXnkNF48uJjGRfhrLOrZDPYlI21cRijLkiHeBxeUZ3tCQWd81GObQmZgGhWF6dGVjpTAwLTMxMzM3LWZlYTU4OTFmLTEtMmVmZDNmZDYtMjMzOWRhNDWCaWSCdjSCaXCEfwAAAYlzZWNwMjU2azGhAkk40-9z_r0kf01sYX3bPAcar-KJVqHyNI_kh-xXTZv3g3RjcIKvLYN1ZHCCry0, enr:-Le4QNx5zXIxtYvc2uaRrML3SiUVAWLo1Ovmim0JnVAasNhQYn6whMVpPiMyFn0Y62Itw_tWuHRaaeNIZy06IeqBvxoGhWF6dGVjpTAwLTMxMzM3LWZlYTU4OTFmLTEtMmVmZDNmZDYtMjMzOWRhNDWCaWSCdjSCaXCEfwAAAYlzZWNwMjU2azGhA5n2KfDZTBjjFfXQtL1UBZ0impT8a8o8RPGvgMLVrc5Yg3RjcIKWlYN1ZHCClpU, enr:-Le4QGTO7vpQq94_p1rXxz4l0DaxS7XeLWNu4rgHQ9dr8IiHCEFdPChOKBzAXXRGAbnNmxmakjKqQ2vF3ZuBqRbVo8IGhWF6dGVjpTAwLTMxMzM3LWZlYTU4OTFmLTEtMmVmZDNmZDYtMjMzOWRhNDWCaWSCdjSCaXCEfwAAAYlzZWNwMjU2azGhAtOjP7wGZm39-R7VJOSN-2ZJalSp6S73U10l7p2oJsqcg3RjcIKS6YN1ZHCCkuk"}
15:30:41 {"level":30,"time":1748878241572,"pid":97,"hostname":"28f63ed03d9c","module":"p2p:2","listen":"127.0.0.1","port":39415,"announce":"/ip4/127.0.0.1/tcp/39415","peerId":"16Uiu2HAmU1a1N8wDNSoigDt9HHj8BZACt4RR1ZbvLEnscA56WTcS","msg":"Started P2P service"}
15:30:41 {"level":30,"time":1748878241572,"pid":97,"hostname":"28f63ed03d9c","module":"p2p","syncedLatestBlock":1,"syncedProvenBlock":1,"syncedFinalizedBlock":1,"msg":"Starting P2P client synced to 1"}
15:30:45 [15:30:45.591]
INFO:
testbench-ports Transaction sent from client 1
15:30:45 {"level":50,"time":1748878245884,"pid":47,"hostname":"28f63ed03d9c","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"}
15:30:45 {"level":50,"time":1748878245887,"pid":33,"hostname":"28f63ed03d9c","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"}
15:30:45 {"level":50,"time":1748878245903,"pid":97,"hostname":"28f63ed03d9c","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"}
15:30:55 [15:30:55.592]
INFO:
testbench-ports Checking message propagation results
15:30:55 [15:30:55.592]
INFO:
testbench-ports Number of clients that received message: 3
15:30:55 [15:30:55.595]
INFO:
testbench-ports Cleaning up
15:30:55 [15:30:55.595]
INFO:
testbench-ports Cleaning up 5 worker processes
15:30:55 {"level":30,"time":1748878255636,"pid":47,"hostname":"28f63ed03d9c","module":"p2p:reqresp:connection-sampler","msg":"Stopping connection sampler"}
15:30:55 {"level":30,"time":1748878255636,"pid":47,"hostname":"28f63ed03d9c","module":"p2p:reqresp:connection-sampler","msg":"Connection sampler stopped"}
15:30:55 {"level":30,"time":1748878255644,"pid":62,"hostname":"28f63ed03d9c","module":"p2p:reqresp:connection-sampler","msg":"Stopping connection sampler"}
15:30:55 {"level":30,"time":1748878255648,"pid":97,"hostname":"28f63ed03d9c","module":"p2p:reqresp:connection-sampler","msg":"Stopping connection sampler"}
15:30:55 {"level":30,"time":1748878255644,"pid":62,"hostname":"28f63ed03d9c","module":"p2p:reqresp:connection-sampler","msg":"Connection sampler stopped"}
15:30:55 {"level":30,"time":1748878255649,"pid":97,"hostname":"28f63ed03d9c","module":"p2p:reqresp:connection-sampler","msg":"Connection sampler stopped"}
15:30:55 {"level":30,"time":1748878255662,"pid":62,"hostname":"28f63ed03d9c","module":"p2p:4","msg":"LibP2P service stopped"}
15:30:55 {"level":30,"time":1748878255662,"pid":62,"hostname":"28f63ed03d9c","module":"p2p","msg":"P2P client stopped."}
15:30:55 {"level":30,"time":1748878255666,"pid":35,"hostname":"28f63ed03d9c","module":"p2p:reqresp:connection-sampler","msg":"Stopping connection sampler"}
15:30:55 {"level":30,"time":1748878255674,"pid":97,"hostname":"28f63ed03d9c","module":"p2p:2","msg":"LibP2P service stopped"}
15:30:55 {"level":30,"time":1748878255674,"pid":97,"hostname":"28f63ed03d9c","module":"p2p","msg":"P2P client stopped."}
15:30:55 {"level":30,"time":1748878255677,"pid":33,"hostname":"28f63ed03d9c","module":"p2p:reqresp:connection-sampler","msg":"Stopping connection sampler"}
15:30:55 {"level":30,"time":1748878255666,"pid":35,"hostname":"28f63ed03d9c","module":"p2p:reqresp:connection-sampler","msg":"Connection sampler stopped"}
15:30:55 {"level":30,"time":1748878255680,"pid":47,"hostname":"28f63ed03d9c","module":"p2p:3","msg":"LibP2P service stopped"}
15:30:55 {"level":30,"time":1748878255680,"pid":47,"hostname":"28f63ed03d9c","module":"p2p","msg":"P2P client stopped."}
15:30:55 {"level":30,"time":1748878255690,"pid":35,"hostname":"28f63ed03d9c","module":"p2p:1","msg":"LibP2P service stopped"}
15:30:55 {"level":30,"time":1748878255690,"pid":35,"hostname":"28f63ed03d9c","module":"p2p","msg":"P2P client stopped."}
15:30:55 {"level":30,"time":1748878255677,"pid":33,"hostname":"28f63ed03d9c","module":"p2p:reqresp:connection-sampler","msg":"Connection sampler stopped"}
15:30:55 {"level":30,"time":1748878255704,"pid":33,"hostname":"28f63ed03d9c","module":"p2p:0","msg":"LibP2P service stopped"}
15:30:55 {"level":30,"time":1748878255705,"pid":33,"hostname":"28f63ed03d9c","module":"p2p","msg":"P2P client stopped."}
15:30:55 [15:30:55.851]
WARN:
testbench-ports Worker 3 exited unexpectedly with code null and signal SIGSEGV
15:30:55 [15:30:55.868]
WARN:
testbench-ports Worker 4 exited unexpectedly with code null and signal SIGSEGV
15:30:55 [15:30:55.889]
WARN:
testbench-ports Worker 0 exited unexpectedly with code null and signal SIGSEGV
15:30:55 [15:30:55.908]
WARN:
testbench-ports Worker 2 exited unexpectedly with code null and signal SIGSEGV
15:30:56 [15:30:56.254]
WARN:
testbench-ports Worker 1 exited unexpectedly with code null and signal SIGSEGV
15:30:56 [15:30:56.254]
INFO:
testbench-ports All worker processes cleaned up
15:30:56
FAIL src/testbench/port_change.test.ts (
61.534 s)
15:30:56 Port Change
15:30:56
✕ should change port and propagate the gossip message correctly (60240 ms)
15:30:56
15:30:56
● Port Change › should change port and propagate the gossip message correctly
15:30:56
15:30:56
expect(received).toBe
(expected) // Object.is equality
15:30:56
15:30:56 Expected:
4
15:30:56 Received:
3
15:30:56
15:30:56
75 | logger
.info(
`Number of clients that received message: ${numberOfClientsThatReceivedMessage}`)
;
15:30:56
76 |
15:30:56
> 77 | expect(numberOfClientsThatReceivedMessage).toBe(numberOfClients - 1);
15:30:56
| ^
15:30:56
78 | logger.info('All clients received message');
15:30:56
79 |
15:30:56
80 | workerClientManager.purgeMessageReceivedByClient();
15:30:56
15:30:56
at Object.toBe (testbench/port_change.test.ts:77:52)
15:30:56
15:30:56
Test Suites: 1 failed, 1 total
15:30:56
Tests: 1 failed, 1 total
15:30:56
Snapshots: 0 total
15:30:56
Time: 61.593 s
15:30:56
Ran all test suites matching /p2p\/src\/testbench\/port_change.test.ts/i
.
15:30:56
Force exiting Jest: Have you considered using `--detectOpenHandles` to detect async operations that kept running after all tests finished?