Parent Log:
http://ci.aztec-labs.com/5cecbe08e8addeb9
Command: 70e6b74970b88943: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/7e0e317f165df53c61d215c65fa54a77bb34d133
Env: REF_NAME=gh-readonly-queue/next/pr-14344-0ccd45bb6c3b4fcac95cdc0eea72b2dfe7bb3e74 CURRENT_VERSION=0.87.4 CI_FULL=1
Date: Thu May 29 12:59:47 UTC 2025
System: ARCH=amd64 CPUS=128 MEM=493Gi HOSTNAME=pr-14344_amd64_x1-full
Resources: CPU_LIST=0-127 CPUS=10 MEM=16g TIMEOUT=600s
History:
http://ci.aztec-labs.com/list/9b8c9f29fec539b7
12:59:47 +++ id -u
12:59:47 +++ id -g
12:59:47 ++ 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'\'''
12:59:48 + cid=e3a068da16db8fff1fb0fb2baa24180f349050199ea75e72bc97304b9d40301d
12:59:48 + set +x
12:59:51 [12:59:51.828]
INFO:
testbench-ports Starting test setup
12:59:51 [12:59:51.836]
INFO:
testbench-ports Loading config from /home/aztec-dev/aztec-packages/yarn-project/p2p/testbench/configurations/normal-degree-5-nodes.json
12:59:51 [12:59:51.838]
INFO:
testbench-ports Creating 5 clients
12:59:51 [12:59:51.998]
INFO:
testbench-ports Creating client 0
12:59:52 [12:59:52.010]
INFO:
testbench-ports Creating client 1
12:59:52 [12:59:52.022]
INFO:
testbench-ports Creating client 2
12:59:52 [12:59:52.032]
INFO:
testbench-ports Creating client 3
12:59:52 [12:59:52.045]
INFO:
testbench-ports Creating client 4
12:59:59 {"level":30,"time":1748523599803,"pid":47,"hostname":"e3a068da16db","module":"kv-store:lmdb-v2:test-3","msg":"Starting data store with maxReaders 16"}
12:59:59 {"level":30,"time":1748523599809,"pid":47,"hostname":"e3a068da16db","module":"p2p-archive:lmdb-v2","msg":"Starting data store with maxReaders 16"}
12:59:59 {"level":30,"time":1748523599811,"pid":47,"hostname":"e3a068da16db","module":"p2p-peer:lmdb-v2","msg":"Starting data store with maxReaders 16"}
12:59:59 {"level":30,"time":1748523599917,"pid":33,"hostname":"e3a068da16db","module":"kv-store:lmdb-v2:test-0","msg":"Starting data store with maxReaders 16"}
12:59:59 {"level":30,"time":1748523599930,"pid":33,"hostname":"e3a068da16db","module":"p2p-archive:lmdb-v2","msg":"Starting data store with maxReaders 16"}
12:59:59 {"level":30,"time":1748523599932,"pid":33,"hostname":"e3a068da16db","module":"p2p-peer:lmdb-v2","msg":"Starting data store with maxReaders 16"}
12:59:59 {"level":30,"time":1748523599965,"pid":47,"hostname":"e3a068da16db","module":"p2p:3:libp2p_service","msg":"Started libp2p service with protocol version 00-31337-b9723f2e-1-04c8734f-15fab803"}
12:59:59 {"level":30,"time":1748523599967,"pid":47,"hostname":"e3a068da16db","module":"p2p:3","msg":"Started libp2p service with protocol version 00-31337-b9723f2e-1-04c8734f-15fab803"}
12:59:59 {"level":30,"time":1748523599983,"pid":47,"hostname":"e3a068da16db","module":"p2p:3:discv5_service","nodeId":"659654c1963c0b7c9de361cf1d8d1a9746431f385353c4eb9cb170cbd2abfe09","peerId":"16Uiu2HAmQBhaYXqpucgti45a7TDNdxEX7wdSvnsQZtK49z16udzH","enrUdp":"/ip4/127.0.0.1/udp/43169/p2p/16Uiu2HAmQBhaYXqpucgti45a7TDNdxEX7wdSvnsQZtK49z16udzH","enrTcp":"/ip4/127.0.0.1/tcp/43169/p2p/16Uiu2HAmQBhaYXqpucgti45a7TDNdxEX7wdSvnsQZtK49z16udzH","versions":{"l1ChainId":31337,"l1RollupAddress":"0xb9723f2ecb6b670f5d6c5c80da7ced8324b92ea9","rollupVersion":1,"l2ProtocolContractsTreeRoot":"0x04c8734fe53542a4a3eb215d4cea15265cd74f541195fbce3c5d9adf6d58a301","l2CircuitsVkTreeRoot":"0x15fab8030ad1b6a6edea427da1ed0b247d196faf8eb1579f1c0897b19d16f22b"},"msg":"DiscV5 service started"}
13:00:00 {"level":30,"time":1748523599987,"pid":47,"hostname":"e3a068da16db","module":"p2p:3:discv5_service","msg":"Adding 3 bootstrap nodes ENRs: enr:-Le4QJ9QoOXS1Fo5qklGWyGOnTjxAbBaBzjlIDUFcRNf2I6uJuscX4vWPq5KynvNSrLmW5DECSYLXKVuiEZjxuSfGuwGhWF6dGVjpTAwLTMxMzM3LWI5NzIzZjJlLTEtMDRjODczNGYtMTVmYWI4MDOCaWSCdjSCaXCEfwAAAYlzZWNwMjU2azGhA6AWQxuq-jUVMmMBj6dJVbqpdKhs1WTiCGgV89zkwPvCg3RjcIKRc4N1ZHCCkXM, enr:-Le4QN0Bu5aDKMc63OCq6363TQSXFn_ITEBHqDD7YQUTsZ-jUjYLQ4jeDD3DvqsNYNw1fErSXQN1y0sbu7FP0sGL36oGhWF6dGVjpTAwLTMxMzM3LWI5NzIzZjJlLTEtMDRjODczNGYtMTVmYWI4MDOCaWSCdjSCaXCEfwAAAYlzZWNwMjU2azGhA84WGIWAM5RXKncgToryiy7iG-1lI-7634Jq7il5HJ9Vg3RjcIKIY4N1ZHCCiGM, enr:-Le4QNLtD96ishOKjQy08VxQpQRlaHWM-xaQymOJbQLABKEPaA9PtLvC3aeuxfMBQy51UjRylyFxIlPda7V_yquU6CcGhWF6dGVjpTAwLTMxMzM3LWI5NzIzZjJlLTEtMDRjODczNGYtMTVmYWI4MDOCaWSCdjSCaXCEfwAAAYlzZWNwMjU2azGhA2k-a58BTqrZVyADIOUPEjce3kYF5hXQjZOR_Ae7Wx-vg3RjcIKEc4N1ZHCChHM"}
13:00:00 {"level":30,"time":1748523600104,"pid":33,"hostname":"e3a068da16db","module":"p2p:0:libp2p_service","msg":"Started libp2p service with protocol version 00-31337-b9723f2e-1-04c8734f-15fab803"}
13:00:00 {"level":30,"time":1748523600107,"pid":33,"hostname":"e3a068da16db","module":"p2p:0","msg":"Started libp2p service with protocol version 00-31337-b9723f2e-1-04c8734f-15fab803"}
13:00:00 {"level":30,"time":1748523600118,"pid":33,"hostname":"e3a068da16db","module":"p2p:0:discv5_service","nodeId":"bfc8147dd84add90def1a68deb254afd05ed8343d95064c48575a11d397a16d5","peerId":"16Uiu2HAmPRuGfxwS5HN1PB2wDiAtKA9AjvDqzW6qV7qjReaxx5rq","enrUdp":"/ip4/127.0.0.1/udp/37235/p2p/16Uiu2HAmPRuGfxwS5HN1PB2wDiAtKA9AjvDqzW6qV7qjReaxx5rq","enrTcp":"/ip4/127.0.0.1/tcp/37235/p2p/16Uiu2HAmPRuGfxwS5HN1PB2wDiAtKA9AjvDqzW6qV7qjReaxx5rq","versions":{"l1ChainId":31337,"l1RollupAddress":"0xb9723f2ecb6b670f5d6c5c80da7ced8324b92ea9","rollupVersion":1,"l2ProtocolContractsTreeRoot":"0x04c8734fe53542a4a3eb215d4cea15265cd74f541195fbce3c5d9adf6d58a301","l2CircuitsVkTreeRoot":"0x15fab8030ad1b6a6edea427da1ed0b247d196faf8eb1579f1c0897b19d16f22b"},"msg":"DiscV5 service started"}
13:00:00 {"level":30,"time":1748523600138,"pid":47,"hostname":"e3a068da16db","module":"p2p:3","listen":"127.0.0.1","port":43169,"announce":"/ip4/127.0.0.1/tcp/43169","peerId":"16Uiu2HAmQBhaYXqpucgti45a7TDNdxEX7wdSvnsQZtK49z16udzH","msg":"Started P2P service"}
13:00:00 {"level":30,"time":1748523600139,"pid":47,"hostname":"e3a068da16db","module":"p2p","syncedLatestBlock":1,"syncedProvenBlock":1,"syncedFinalizedBlock":1,"msg":"Starting P2P client synced to 1"}
13:00:00 {"level":30,"time":1748523600214,"pid":33,"hostname":"e3a068da16db","module":"p2p:0","listen":"127.0.0.1","port":37235,"announce":"/ip4/127.0.0.1/tcp/37235","peerId":"16Uiu2HAmPRuGfxwS5HN1PB2wDiAtKA9AjvDqzW6qV7qjReaxx5rq","msg":"Started P2P service"}
13:00:00 {"level":30,"time":1748523600215,"pid":33,"hostname":"e3a068da16db","module":"p2p","syncedLatestBlock":1,"syncedProvenBlock":1,"syncedFinalizedBlock":1,"msg":"Starting P2P client synced to 1"}
13:00:00 {"level":30,"time":1748523600363,"pid":40,"hostname":"e3a068da16db","module":"kv-store:lmdb-v2:test-2","msg":"Starting data store with maxReaders 16"}
13:00:00 {"level":30,"time":1748523600377,"pid":40,"hostname":"e3a068da16db","module":"p2p-archive:lmdb-v2","msg":"Starting data store with maxReaders 16"}
13:00:00 {"level":30,"time":1748523600379,"pid":40,"hostname":"e3a068da16db","module":"p2p-peer:lmdb-v2","msg":"Starting data store with maxReaders 16"}
13:00:00 {"level":30,"time":1748523600555,"pid":40,"hostname":"e3a068da16db","module":"p2p:2:libp2p_service","msg":"Started libp2p service with protocol version 00-31337-b9723f2e-1-04c8734f-15fab803"}
13:00:00 {"level":30,"time":1748523600558,"pid":40,"hostname":"e3a068da16db","module":"p2p:2","msg":"Started libp2p service with protocol version 00-31337-b9723f2e-1-04c8734f-15fab803"}
13:00:00 {"level":30,"time":1748523600582,"pid":40,"hostname":"e3a068da16db","module":"p2p:2:discv5_service","nodeId":"f146cec5b1f16bcab3c2c7c9cbdf4e1f155eb06faedccfa953c6207fb2a04a78","peerId":"16Uiu2HAmKjpN46iV5ZGgc1x7ayLmxEDxUuVNjJPj4zYbm6v9oHV8","enrUdp":"/ip4/127.0.0.1/udp/33907/p2p/16Uiu2HAmKjpN46iV5ZGgc1x7ayLmxEDxUuVNjJPj4zYbm6v9oHV8","enrTcp":"/ip4/127.0.0.1/tcp/33907/p2p/16Uiu2HAmKjpN46iV5ZGgc1x7ayLmxEDxUuVNjJPj4zYbm6v9oHV8","versions":{"l1ChainId":31337,"l1RollupAddress":"0xb9723f2ecb6b670f5d6c5c80da7ced8324b92ea9","rollupVersion":1,"l2ProtocolContractsTreeRoot":"0x04c8734fe53542a4a3eb215d4cea15265cd74f541195fbce3c5d9adf6d58a301","l2CircuitsVkTreeRoot":"0x15fab8030ad1b6a6edea427da1ed0b247d196faf8eb1579f1c0897b19d16f22b"},"msg":"DiscV5 service started"}
13:00:00 {"level":30,"time":1748523600614,"pid":39,"hostname":"e3a068da16db","module":"kv-store:lmdb-v2:test-1","msg":"Starting data store with maxReaders 16"}
13:00:00 {"level":30,"time":1748523600585,"pid":40,"hostname":"e3a068da16db","module":"p2p:2:discv5_service","msg":"Adding 2 bootstrap nodes ENRs: enr:-Le4QJ9QoOXS1Fo5qklGWyGOnTjxAbBaBzjlIDUFcRNf2I6uJuscX4vWPq5KynvNSrLmW5DECSYLXKVuiEZjxuSfGuwGhWF6dGVjpTAwLTMxMzM3LWI5NzIzZjJlLTEtMDRjODczNGYtMTVmYWI4MDOCaWSCdjSCaXCEfwAAAYlzZWNwMjU2azGhA6AWQxuq-jUVMmMBj6dJVbqpdKhs1WTiCGgV89zkwPvCg3RjcIKRc4N1ZHCCkXM, enr:-Le4QN0Bu5aDKMc63OCq6363TQSXFn_ITEBHqDD7YQUTsZ-jUjYLQ4jeDD3DvqsNYNw1fErSXQN1y0sbu7FP0sGL36oGhWF6dGVjpTAwLTMxMzM3LWI5NzIzZjJlLTEtMDRjODczNGYtMTVmYWI4MDOCaWSCdjSCaXCEfwAAAYlzZWNwMjU2azGhA84WGIWAM5RXKncgToryiy7iG-1lI-7634Jq7il5HJ9Vg3RjcIKIY4N1ZHCCiGM"}
13:00:00 {"level":30,"time":1748523600621,"pid":39,"hostname":"e3a068da16db","module":"p2p-archive:lmdb-v2","msg":"Starting data store with maxReaders 16"}
13:00:00 {"level":30,"time":1748523600622,"pid":39,"hostname":"e3a068da16db","module":"p2p-peer:lmdb-v2","msg":"Starting data store with maxReaders 16"}
13:00:00 {"level":30,"time":1748523600782,"pid":39,"hostname":"e3a068da16db","module":"p2p:1:libp2p_service","msg":"Started libp2p service with protocol version 00-31337-b9723f2e-1-04c8734f-15fab803"}
13:00:00 {"level":30,"time":1748523600784,"pid":39,"hostname":"e3a068da16db","module":"p2p:1","msg":"Started libp2p service with protocol version 00-31337-b9723f2e-1-04c8734f-15fab803"}
13:00:00 {"level":30,"time":1748523600790,"pid":40,"hostname":"e3a068da16db","module":"p2p:2","listen":"127.0.0.1","port":33907,"announce":"/ip4/127.0.0.1/tcp/33907","peerId":"16Uiu2HAmKjpN46iV5ZGgc1x7ayLmxEDxUuVNjJPj4zYbm6v9oHV8","msg":"Started P2P service"}
13:00:00 {"level":30,"time":1748523600795,"pid":39,"hostname":"e3a068da16db","module":"p2p:1:discv5_service","nodeId":"f0354652cae732055fde012c10099281171a69a018d4c567c18346fe074d100d","peerId":"16Uiu2HAmSXTsspcr3NaDtg3MsJdiy9NgbpuC2E9oQSSpmvqPHP1W","enrUdp":"/ip4/127.0.0.1/udp/34915/p2p/16Uiu2HAmSXTsspcr3NaDtg3MsJdiy9NgbpuC2E9oQSSpmvqPHP1W","enrTcp":"/ip4/127.0.0.1/tcp/34915/p2p/16Uiu2HAmSXTsspcr3NaDtg3MsJdiy9NgbpuC2E9oQSSpmvqPHP1W","versions":{"l1ChainId":31337,"l1RollupAddress":"0xb9723f2ecb6b670f5d6c5c80da7ced8324b92ea9","rollupVersion":1,"l2ProtocolContractsTreeRoot":"0x04c8734fe53542a4a3eb215d4cea15265cd74f541195fbce3c5d9adf6d58a301","l2CircuitsVkTreeRoot":"0x15fab8030ad1b6a6edea427da1ed0b247d196faf8eb1579f1c0897b19d16f22b"},"msg":"DiscV5 service started"}
13:00:00 {"level":30,"time":1748523600790,"pid":40,"hostname":"e3a068da16db","module":"p2p","syncedLatestBlock":1,"syncedProvenBlock":1,"syncedFinalizedBlock":1,"msg":"Starting P2P client synced to 1"}
13:00:00 {"level":30,"time":1748523600796,"pid":39,"hostname":"e3a068da16db","module":"p2p:1:discv5_service","msg":"Adding 1 bootstrap nodes ENRs: enr:-Le4QJ9QoOXS1Fo5qklGWyGOnTjxAbBaBzjlIDUFcRNf2I6uJuscX4vWPq5KynvNSrLmW5DECSYLXKVuiEZjxuSfGuwGhWF6dGVjpTAwLTMxMzM3LWI5NzIzZjJlLTEtMDRjODczNGYtMTVmYWI4MDOCaWSCdjSCaXCEfwAAAYlzZWNwMjU2azGhA6AWQxuq-jUVMmMBj6dJVbqpdKhs1WTiCGgV89zkwPvCg3RjcIKRc4N1ZHCCkXM"}
13:00:00 {"level":30,"time":1748523600971,"pid":39,"hostname":"e3a068da16db","module":"p2p:1","listen":"127.0.0.1","port":34915,"announce":"/ip4/127.0.0.1/tcp/34915","peerId":"16Uiu2HAmSXTsspcr3NaDtg3MsJdiy9NgbpuC2E9oQSSpmvqPHP1W","msg":"Started P2P service"}
13:00:00 {"level":30,"time":1748523600972,"pid":39,"hostname":"e3a068da16db","module":"p2p","syncedLatestBlock":1,"syncedProvenBlock":1,"syncedFinalizedBlock":1,"msg":"Starting P2P client synced to 1"}
13:00:01 {"level":30,"time":1748523601001,"pid":56,"hostname":"e3a068da16db","module":"kv-store:lmdb-v2:test-4","msg":"Starting data store with maxReaders 16"}
13:00:01 {"level":30,"time":1748523601010,"pid":56,"hostname":"e3a068da16db","module":"p2p-archive:lmdb-v2","msg":"Starting data store with maxReaders 16"}
13:00:01 {"level":30,"time":1748523601016,"pid":56,"hostname":"e3a068da16db","module":"p2p-peer:lmdb-v2","msg":"Starting data store with maxReaders 16"}
13:00:01 {"level":30,"time":1748523601215,"pid":56,"hostname":"e3a068da16db","module":"p2p:4:libp2p_service","msg":"Started libp2p service with protocol version 00-31337-b9723f2e-1-04c8734f-15fab803"}
13:00:01 {"level":30,"time":1748523601218,"pid":56,"hostname":"e3a068da16db","module":"p2p:4","msg":"Started libp2p service with protocol version 00-31337-b9723f2e-1-04c8734f-15fab803"}
13:00:01 {"level":30,"time":1748523601238,"pid":56,"hostname":"e3a068da16db","module":"p2p:4:discv5_service","nodeId":"8beda25fce1cb40381c0e3b124ac7db482a8dfe0e03358673bee0839970ea5d4","peerId":"16Uiu2HAm7d5RcnXAAVXX8vRt5jDktbQ6XGqaw8v3fyFYzbs1zQcU","enrUdp":"/ip4/127.0.0.1/udp/40793/p2p/16Uiu2HAm7d5RcnXAAVXX8vRt5jDktbQ6XGqaw8v3fyFYzbs1zQcU","enrTcp":"/ip4/127.0.0.1/tcp/40793/p2p/16Uiu2HAm7d5RcnXAAVXX8vRt5jDktbQ6XGqaw8v3fyFYzbs1zQcU","versions":{"l1ChainId":31337,"l1RollupAddress":"0xb9723f2ecb6b670f5d6c5c80da7ced8324b92ea9","rollupVersion":1,"l2ProtocolContractsTreeRoot":"0x04c8734fe53542a4a3eb215d4cea15265cd74f541195fbce3c5d9adf6d58a301","l2CircuitsVkTreeRoot":"0x15fab8030ad1b6a6edea427da1ed0b247d196faf8eb1579f1c0897b19d16f22b"},"msg":"DiscV5 service started"}
13:00:01 {"level":30,"time":1748523601243,"pid":56,"hostname":"e3a068da16db","module":"p2p:4:discv5_service","msg":"Adding 4 bootstrap nodes ENRs: enr:-Le4QJ9QoOXS1Fo5qklGWyGOnTjxAbBaBzjlIDUFcRNf2I6uJuscX4vWPq5KynvNSrLmW5DECSYLXKVuiEZjxuSfGuwGhWF6dGVjpTAwLTMxMzM3LWI5NzIzZjJlLTEtMDRjODczNGYtMTVmYWI4MDOCaWSCdjSCaXCEfwAAAYlzZWNwMjU2azGhA6AWQxuq-jUVMmMBj6dJVbqpdKhs1WTiCGgV89zkwPvCg3RjcIKRc4N1ZHCCkXM, enr:-Le4QN0Bu5aDKMc63OCq6363TQSXFn_ITEBHqDD7YQUTsZ-jUjYLQ4jeDD3DvqsNYNw1fErSXQN1y0sbu7FP0sGL36oGhWF6dGVjpTAwLTMxMzM3LWI5NzIzZjJlLTEtMDRjODczNGYtMTVmYWI4MDOCaWSCdjSCaXCEfwAAAYlzZWNwMjU2azGhA84WGIWAM5RXKncgToryiy7iG-1lI-7634Jq7il5HJ9Vg3RjcIKIY4N1ZHCCiGM, enr:-Le4QNLtD96ishOKjQy08VxQpQRlaHWM-xaQymOJbQLABKEPaA9PtLvC3aeuxfMBQy51UjRylyFxIlPda7V_yquU6CcGhWF6dGVjpTAwLTMxMzM3LWI5NzIzZjJlLTEtMDRjODczNGYtMTVmYWI4MDOCaWSCdjSCaXCEfwAAAYlzZWNwMjU2azGhA2k-a58BTqrZVyADIOUPEjce3kYF5hXQjZOR_Ae7Wx-vg3RjcIKEc4N1ZHCChHM, enr:-Le4QO8ierKUgfJQxkZ7o83tXfQDRxEckL67n9AIf73d_6pcbLH0sRHzHDX-aZlIUxOyPWOdY83xruGO5SWygR7vHgoGhWF6dGVjpTAwLTMxMzM3LWI5NzIzZjJlLTEtMDRjODczNGYtMTVmYWI4MDOCaWSCdjSCaXCEfwAAAYlzZWNwMjU2azGhA6tOmQNOtpWitemFJYiRAzmDZyuP8fDtrNLNNfjbcYK6g3RjcIKooYN1ZHCCqKE"}
13:00:01 {"level":30,"time":1748523601662,"pid":56,"hostname":"e3a068da16db","module":"p2p:4","listen":"127.0.0.1","port":40793,"announce":"/ip4/127.0.0.1/tcp/40793","peerId":"16Uiu2HAm7d5RcnXAAVXX8vRt5jDktbQ6XGqaw8v3fyFYzbs1zQcU","msg":"Started P2P service"}
13:00:01 {"level":30,"time":1748523601662,"pid":56,"hostname":"e3a068da16db","module":"p2p","syncedLatestBlock":1,"syncedProvenBlock":1,"syncedFinalizedBlock":1,"msg":"Starting P2P client synced to 1"}
13:00:12 [13:00:12.068]
INFO:
testbench-ports Workers Ready
13:00:12 [13:00:12.379]
INFO:
testbench-ports Transaction sent from client 3
13:00:12 {"level":50,"time":1748523612846,"pid":56,"hostname":"e3a068da16db","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"}
13:00:12 {"level":50,"time":1748523612850,"pid":33,"hostname":"e3a068da16db","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"}
13:00:12 {"level":50,"time":1748523612856,"pid":39,"hostname":"e3a068da16db","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"}
13:00:12 {"level":50,"time":1748523612855,"pid":40,"hostname":"e3a068da16db","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"}
13:00:22 [13:00:22.381]
INFO:
testbench-ports Checking message propagation results
13:00:22 [13:00:22.383]
INFO:
testbench-ports Number of clients that received message: 4
13:00:22 [13:00:22.389]
INFO:
testbench-ports All clients received message
13:00:22 [13:00:22.389]
INFO:
testbench-ports Iteration 1 done
13:00:22 [13:00:22.390]
INFO:
testbench-ports Changing port for client 3
13:00:22 {"level":30,"time":1748523622477,"pid":47,"hostname":"e3a068da16db","module":"p2p:reqresp:connection-sampler","msg":"Stopping connection sampler"}
13:00:22 {"level":30,"time":1748523622478,"pid":47,"hostname":"e3a068da16db","module":"p2p:reqresp:connection-sampler","msg":"Connection sampler stopped"}
13:00:22 {"level":30,"time":1748523622503,"pid":47,"hostname":"e3a068da16db","module":"p2p:3","msg":"LibP2P service stopped"}
13:00:22 {"level":30,"time":1748523622503,"pid":47,"hostname":"e3a068da16db","module":"p2p","msg":"P2P client stopped."}
13:00:23 [13:00:23.276]
WARN:
testbench-ports Worker 3 exited unexpectedly with code null and signal SIGSEGV
13:00:32 [13:00:32.391]
INFO:
testbench-ports Changing port for client 3 to 36579
13:00:32 [13:00:32.405]
INFO:
testbench-ports Changing port for client 3 to 36579 with other nodes
{"0":"enr:-Le4QJ9QoOXS1Fo5qklGWyGOnTjxAbBaBzjlIDUFcRNf2I6uJuscX4vWPq5KynvNSrLmW5DECSYLXKVuiEZjxuSfGuwGhWF6dGVjpTAwLTMxMzM3LWI5NzIzZjJlLTEtMDRjODczNGYtMTVmYWI4MDOCaWSCdjSCaXCEfwAAAYlzZWNwMjU2azGhA6AWQxuq-jUVMmMBj6dJVbqpdKhs1WTiCGgV89zkwPvCg3RjcIKRc4N1ZHCCkXM","1":"enr:-Le4QN0Bu5aDKMc63OCq6363TQSXFn_ITEBHqDD7YQUTsZ-jUjYLQ4jeDD3DvqsNYNw1fErSXQN1y0sbu7FP0sGL36oGhWF6dGVjpTAwLTMxMzM3LWI5NzIzZjJlLTEtMDRjODczNGYtMTVmYWI4MDOCaWSCdjSCaXCEfwAAAYlzZWNwMjU2azGhA84WGIWAM5RXKncgToryiy7iG-1lI-7634Jq7il5HJ9Vg3RjcIKIY4N1ZHCCiGM","2":"enr:-Le4QNLtD96ishOKjQy08VxQpQRlaHWM-xaQymOJbQLABKEPaA9PtLvC3aeuxfMBQy51UjRylyFxIlPda7V_yquU6CcGhWF6dGVjpTAwLTMxMzM3LWI5NzIzZjJlLTEtMDRjODczNGYtMTVmYWI4MDOCaWSCdjSCaXCEfwAAAYlzZWNwMjU2azGhA2k-a58BTqrZVyADIOUPEjce3kYF5hXQjZOR_Ae7Wx-vg3RjcIKEc4N1ZHCChHM","3":"enr:-Le4QOcZMtOmqDuB33zDItFNxI4av0bBnlJFfrbFMmkqezPyb-6t15gLaem2aTcj1sTejpbuYwCbKLY_MsS_PtDf4WoGhWF6dGVjpTAwLTMxMzM3LWI5NzIzZjJlLTEtMDRjODczNGYtMTVmYWI4MDOCaWSCdjSCaXCEfwAAAYlzZWNwMjU2azGhArU4JEKMNxgZXoSOmgQmK8obkBKz4R52cQhTkRsromhtg3RjcIKfWYN1ZHCCn1k"}
13:00:38 {"level":30,"time":1748523638790,"pid":97,"hostname":"e3a068da16db","module":"kv-store:lmdb-v2:test-3","msg":"Starting data store with maxReaders 16"}
13:00:38 {"level":30,"time":1748523638807,"pid":97,"hostname":"e3a068da16db","module":"p2p-archive:lmdb-v2","msg":"Starting data store with maxReaders 16"}
13:00:38 {"level":30,"time":1748523638810,"pid":97,"hostname":"e3a068da16db","module":"p2p-peer:lmdb-v2","msg":"Starting data store with maxReaders 16"}
13:00:39 {"level":30,"time":1748523639011,"pid":97,"hostname":"e3a068da16db","module":"p2p:3:libp2p_service","msg":"Started libp2p service with protocol version 00-31337-b9723f2e-1-04c8734f-15fab803"}
13:00:39 {"level":30,"time":1748523639013,"pid":97,"hostname":"e3a068da16db","module":"p2p:3","msg":"Started libp2p service with protocol version 00-31337-b9723f2e-1-04c8734f-15fab803"}
13:00:39 {"level":30,"time":1748523639034,"pid":97,"hostname":"e3a068da16db","module":"p2p:3:discv5_service","nodeId":"659654c1963c0b7c9de361cf1d8d1a9746431f385353c4eb9cb170cbd2abfe09","peerId":"16Uiu2HAmQBhaYXqpucgti45a7TDNdxEX7wdSvnsQZtK49z16udzH","enrUdp":"/ip4/127.0.0.1/udp/36579/p2p/16Uiu2HAmQBhaYXqpucgti45a7TDNdxEX7wdSvnsQZtK49z16udzH","enrTcp":"/ip4/127.0.0.1/tcp/36579/p2p/16Uiu2HAmQBhaYXqpucgti45a7TDNdxEX7wdSvnsQZtK49z16udzH","versions":{"l1ChainId":31337,"l1RollupAddress":"0xb9723f2ecb6b670f5d6c5c80da7ced8324b92ea9","rollupVersion":1,"l2ProtocolContractsTreeRoot":"0x04c8734fe53542a4a3eb215d4cea15265cd74f541195fbce3c5d9adf6d58a301","l2CircuitsVkTreeRoot":"0x15fab8030ad1b6a6edea427da1ed0b247d196faf8eb1579f1c0897b19d16f22b"},"msg":"DiscV5 service started"}
13:00:39 {"level":30,"time":1748523639045,"pid":97,"hostname":"e3a068da16db","module":"p2p:3:discv5_service","msg":"Adding 4 bootstrap nodes ENRs: enr:-Le4QJ9QoOXS1Fo5qklGWyGOnTjxAbBaBzjlIDUFcRNf2I6uJuscX4vWPq5KynvNSrLmW5DECSYLXKVuiEZjxuSfGuwGhWF6dGVjpTAwLTMxMzM3LWI5NzIzZjJlLTEtMDRjODczNGYtMTVmYWI4MDOCaWSCdjSCaXCEfwAAAYlzZWNwMjU2azGhA6AWQxuq-jUVMmMBj6dJVbqpdKhs1WTiCGgV89zkwPvCg3RjcIKRc4N1ZHCCkXM, enr:-Le4QN0Bu5aDKMc63OCq6363TQSXFn_ITEBHqDD7YQUTsZ-jUjYLQ4jeDD3DvqsNYNw1fErSXQN1y0sbu7FP0sGL36oGhWF6dGVjpTAwLTMxMzM3LWI5NzIzZjJlLTEtMDRjODczNGYtMTVmYWI4MDOCaWSCdjSCaXCEfwAAAYlzZWNwMjU2azGhA84WGIWAM5RXKncgToryiy7iG-1lI-7634Jq7il5HJ9Vg3RjcIKIY4N1ZHCCiGM, enr:-Le4QNLtD96ishOKjQy08VxQpQRlaHWM-xaQymOJbQLABKEPaA9PtLvC3aeuxfMBQy51UjRylyFxIlPda7V_yquU6CcGhWF6dGVjpTAwLTMxMzM3LWI5NzIzZjJlLTEtMDRjODczNGYtMTVmYWI4MDOCaWSCdjSCaXCEfwAAAYlzZWNwMjU2azGhA2k-a58BTqrZVyADIOUPEjce3kYF5hXQjZOR_Ae7Wx-vg3RjcIKEc4N1ZHCChHM, enr:-Le4QOcZMtOmqDuB33zDItFNxI4av0bBnlJFfrbFMmkqezPyb-6t15gLaem2aTcj1sTejpbuYwCbKLY_MsS_PtDf4WoGhWF6dGVjpTAwLTMxMzM3LWI5NzIzZjJlLTEtMDRjODczNGYtMTVmYWI4MDOCaWSCdjSCaXCEfwAAAYlzZWNwMjU2azGhArU4JEKMNxgZXoSOmgQmK8obkBKz4R52cQhTkRsromhtg3RjcIKfWYN1ZHCCn1k"}
13:00:39 {"level":30,"time":1748523639692,"pid":97,"hostname":"e3a068da16db","module":"p2p:3","listen":"127.0.0.1","port":36579,"announce":"/ip4/127.0.0.1/tcp/36579","peerId":"16Uiu2HAmQBhaYXqpucgti45a7TDNdxEX7wdSvnsQZtK49z16udzH","msg":"Started P2P service"}
13:00:39 {"level":30,"time":1748523639692,"pid":97,"hostname":"e3a068da16db","module":"p2p","syncedLatestBlock":1,"syncedProvenBlock":1,"syncedFinalizedBlock":1,"msg":"Starting P2P client synced to 1"}
13:00:43 [13:00:43.742]
INFO:
testbench-ports Transaction sent from client 4
13:00:44 {"level":50,"time":1748523644206,"pid":33,"hostname":"e3a068da16db","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"}
13:00:44 {"level":50,"time":1748523644207,"pid":40,"hostname":"e3a068da16db","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"}
13:00:44 {"level":50,"time":1748523644258,"pid":97,"hostname":"e3a068da16db","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"}
13:00:53 [13:00:53.743]
INFO:
testbench-ports Checking message propagation results
13:00:53 [13:00:53.744]
INFO:
testbench-ports Number of clients that received message: 3
13:00:53 [13:00:53.750]
INFO:
testbench-ports Cleaning up
13:00:53 [13:00:53.751]
INFO:
testbench-ports Cleaning up 5 worker processes
13:00:53 {"level":30,"time":1748523653840,"pid":97,"hostname":"e3a068da16db","module":"p2p:reqresp:connection-sampler","msg":"Stopping connection sampler"}
13:00:53 {"level":30,"time":1748523653840,"pid":97,"hostname":"e3a068da16db","module":"p2p:reqresp:connection-sampler","msg":"Connection sampler stopped"}
13:00:53 {"level":30,"time":1748523653859,"pid":56,"hostname":"e3a068da16db","module":"p2p:reqresp:connection-sampler","msg":"Stopping connection sampler"}
13:00:53 {"level":30,"time":1748523653864,"pid":97,"hostname":"e3a068da16db","module":"p2p:3","msg":"LibP2P service stopped"}
13:00:53 {"level":30,"time":1748523653864,"pid":97,"hostname":"e3a068da16db","module":"p2p","msg":"P2P client stopped."}
13:00:53 {"level":30,"time":1748523653872,"pid":40,"hostname":"e3a068da16db","module":"p2p:reqresp:connection-sampler","msg":"Stopping connection sampler"}
13:00:53 {"level":30,"time":1748523653875,"pid":33,"hostname":"e3a068da16db","module":"p2p:reqresp:connection-sampler","msg":"Stopping connection sampler"}
13:00:53 {"level":30,"time":1748523653873,"pid":40,"hostname":"e3a068da16db","module":"p2p:reqresp:connection-sampler","msg":"Connection sampler stopped"}
13:00:53 {"level":30,"time":1748523653876,"pid":33,"hostname":"e3a068da16db","module":"p2p:reqresp:connection-sampler","msg":"Connection sampler stopped"}
13:00:53 {"level":30,"time":1748523653891,"pid":39,"hostname":"e3a068da16db","module":"p2p:reqresp:connection-sampler","msg":"Stopping connection sampler"}
13:00:53 {"level":30,"time":1748523653892,"pid":39,"hostname":"e3a068da16db","module":"p2p:reqresp:connection-sampler","msg":"Connection sampler stopped"}
13:00:53 {"level":30,"time":1748523653896,"pid":33,"hostname":"e3a068da16db","module":"p2p:0","msg":"LibP2P service stopped"}
13:00:53 {"level":30,"time":1748523653897,"pid":33,"hostname":"e3a068da16db","module":"p2p","msg":"P2P client stopped."}
13:00:53 {"level":30,"time":1748523653860,"pid":56,"hostname":"e3a068da16db","module":"p2p:reqresp:connection-sampler","msg":"Connection sampler stopped"}
13:00:53 {"level":30,"time":1748523653901,"pid":40,"hostname":"e3a068da16db","module":"p2p:2","msg":"LibP2P service stopped"}
13:00:53 {"level":30,"time":1748523653901,"pid":40,"hostname":"e3a068da16db","module":"p2p","msg":"P2P client stopped."}
13:00:53 {"level":30,"time":1748523653908,"pid":39,"hostname":"e3a068da16db","module":"p2p:1","msg":"LibP2P service stopped"}
13:00:53 {"level":30,"time":1748523653908,"pid":39,"hostname":"e3a068da16db","module":"p2p","msg":"P2P client stopped."}
13:00:53 {"level":30,"time":1748523653912,"pid":56,"hostname":"e3a068da16db","module":"p2p:4","msg":"LibP2P service stopped"}
13:00:53 {"level":30,"time":1748523653913,"pid":56,"hostname":"e3a068da16db","module":"p2p","msg":"P2P client stopped."}
13:00:54 [13:00:54.091]
WARN:
testbench-ports Worker 3 exited unexpectedly with code null and signal SIGSEGV
13:00:54 [13:00:54.107]
WARN:
testbench-ports Worker 0 exited unexpectedly with code null and signal SIGSEGV
13:00:54 [13:00:54.138]
WARN:
testbench-ports Worker 2 exited unexpectedly with code null and signal SIGSEGV
13:00:54 [13:00:54.174]
WARN:
testbench-ports Worker 1 exited unexpectedly with code null and signal SIGSEGV
13:00:54 [13:00:54.796]
WARN:
testbench-ports Worker 4 exited unexpectedly with code null and signal SIGSEGV
13:00:54 [13:00:54.798]
INFO:
testbench-ports All worker processes cleaned up
13:00:54
FAIL src/testbench/port_change.test.ts (
64.849 s)
13:00:54 Port Change
13:00:54
✕ should change port and propagate the gossip message correctly (62974 ms)
13:00:54
13:00:54
● Port Change › should change port and propagate the gossip message correctly
13:00:54
13:00:54
expect(received).toBe
(expected) // Object.is equality
13:00:54
13:00:54 Expected:
4
13:00:54 Received:
3
13:00:54
13:00:54
75 | logger
.info(
`Number of clients that received message: ${numberOfClientsThatReceivedMessage}`)
;
13:00:54
76 |
13:00:54
> 77 | expect(numberOfClientsThatReceivedMessage).toBe(numberOfClients - 1);
13:00:54
| ^
13:00:54
78 | logger.info('All clients received message');
13:00:54
79 |
13:00:54
80 | workerClientManager.purgeMessageReceivedByClient();
13:00:54
13:00:54
at Object.toBe (testbench/port_change.test.ts:77:52)
13:00:54
13:00:54
Test Suites: 1 failed, 1 total
13:00:54
Tests: 1 failed, 1 total
13:00:54
Snapshots: 0 total
13:00:54
Time: 64.912 s
13:00:54
Ran all test suites matching /p2p\/src\/testbench\/port_change.test.ts/i
.
13:00:54
Force exiting Jest: Have you considered using `--detectOpenHandles` to detect async operations that kept running after all tests finished?