98 lines
18 KiB
Plaintext
Raw Blame History

This file contains ambiguous Unicode characters

This file contains Unicode characters that might be confused with other characters. If you think that this is intentional, you can safely ignore this warning. Use the Escape button to reveal them.

2026-04-22 23:54:01.024 INFO [tests.conftest] Fleet bootstrap patch active NODE1→/dns4/node-01.do-ams3.waku.test.status.im/tcp/30303/p2p/16Uiu2HAkykgaECHswi3YKJ5dMLbq2kPVCo89fcyTd38UcQD6ej5W NODE2→/dns4/node-01.gc-us-central1-a.waku.test.status.im/tcp/30303/p2p/16Uiu2HAmDCp8XJ9z1ev18zuv8NHekAsjNyezAvmMfFEJkiharitG (additional nodes→/dns4/node-01.do-ams3.waku.test.status.im/tcp/30303/p2p/16Uiu2HAkykgaECHswi3YKJ5dMLbq2kPVCo89fcyTd38UcQD6ej5W) dns_discovery_url=enrtree://AOGYWMBYOUIMOENHXCHILPKY3ZRFEULMFI4DOM442QSZ73TT2A7VI@test.waku.nodes.status.im
2026-04-22 23:54:01.024 DEBUG [tests.conftest] Running fixture setup: test_id
2026-04-22 23:54:01.025 DEBUG [tests.conftest] Running test: test_filter_subscribe_to_single_topics with id: 2026-04-22_23-54-01__6a986c4c-d2d2-4237-8b58-e77efccc7b54
2026-04-22 23:54:01.025 DEBUG [src.steps.common] Running fixture setup: common_setup
2026-04-22 23:54:01.025 DEBUG [src.steps.filter] Running fixture setup: filter_setup
2026-04-22 23:54:01.026 DEBUG [src.steps.filter] Running fixture setup: setup_main_relay_node
2026-04-22 23:54:01.031 DEBUG [src.node.docker_mananger] Docker client initialized with image wakuorg/nwaku:latest
2026-04-22 23:54:01.032 DEBUG [src.node.waku_node] WakuNode instance initialized with log path ./log/docker/node1_2026-04-22_23-54-01__6a986c4c-d2d2-4237-8b58-e77efccc7b54__wakuorg_nwaku:latest.log
2026-04-22 23:54:01.032 DEBUG [tests.conftest] fleet_joined_start: injecting waku.test bootstrap args into WakuNode.start()
2026-04-22 23:54:01.032 DEBUG [tests.conftest] fleet_joined_start: NODE1 bootstrapping from config-n1.toml (/dns4/node-01.do-ams3.waku.test.status.im/tcp/30303/p2p/16Uiu2HAkykgaECHswi3YKJ5dMLbq2kPVCo89fcyTd38UcQD6ej5W)
2026-04-22 23:54:01.032 DEBUG [tests.conftest] fleet_joined_start: staticnode=/dns4/node-01.do-ams3.waku.test.status.im/tcp/30303/p2p/16Uiu2HAkykgaECHswi3YKJ5dMLbq2kPVCo89fcyTd38UcQD6ej5W dns_discovery_url=enrtree://AOGYWMBYOUIMOENHXCHILPKY3ZRFEULMFI4DOM442QSZ73TT2A7VI@test.waku.nodes.status.im
2026-04-22 23:54:01.032 DEBUG [src.node.waku_node] Starting Node...
2026-04-22 23:54:01.032 DEBUG [src.node.docker_mananger] Attempting to create or retrieve network waku
2026-04-22 23:54:01.035 DEBUG [src.node.docker_mananger] Network waku already exists
2026-04-22 23:54:01.035 DEBUG [src.node.docker_mananger] Generated random external IP 172.18.165.6
2026-04-22 23:54:01.035 DEBUG [src.node.docker_mananger] Generated ports ['7647', '7648', '7649', '7650', '7651']
2026-04-22 23:54:01.035 DEBUG [src.node.waku_node] RLN credentials were not set
2026-04-22 23:54:01.035 INFO [src.node.waku_node] RLN credentials not set or credential store not available, starting without RLN
2026-04-22 23:54:01.035 DEBUG [src.node.waku_node] Using volumes []
2026-04-22 23:54:01.035 DEBUG [src.node.docker_mananger] docker run -i -t -p 7647:7647 -p 7648:7648 -p 7649:7649 -p 7650:7650 -p 7651:7651 wakuorg/nwaku:latest --listen-address=0.0.0.0 --rest=true --rest-admin=true --websocket-support=true --log-level=TRACE --rest-relay-cache-capacity=100 --websocket-port=7649 --rest-port=7647 --tcp-port=7648 --discv5-udp-port=7650 --rest-address=0.0.0.0 --nat=extip:172.18.165.6 --peer-exchange=true --discv5-discovery=true --cluster-id=198 --nodekey=dc25c4eefecf45478bab6259a4ddbbdea8b56a9d110efd9c9676c005c2c1bb94 --shard=0 --metrics-server=true --metrics-server-address=0.0.0.0 --metrics-server-port=7651 --metrics-logging=true --relay=true --filter=true --staticnode=/dns4/node-01.do-ams3.waku.test.status.im/tcp/30303/p2p/16Uiu2HAkykgaECHswi3YKJ5dMLbq2kPVCo89fcyTd38UcQD6ej5W --dns-discovery=true --dns-discovery-url=enrtree://AOGYWMBYOUIMOENHXCHILPKY3ZRFEULMFI4DOM442QSZ73TT2A7VI@test.waku.nodes.status.im
2026-04-22 23:54:01.220 DEBUG [src.node.docker_mananger] docker network connect --ip 172.18.165.6 waku babc95093ab70964b59de29a6b85962a448c2345d599df81e12bdd4485aa6c92
2026-04-22 23:54:01.253 DEBUG [src.node.docker_mananger] Container started with ID babc95093ab7. Setting up logs at ./log/docker/node1_2026-04-22_23-54-01__6a986c4c-d2d2-4237-8b58-e77efccc7b54__wakuorg_nwaku:latest.log
2026-04-22 23:54:01.254 DEBUG [src.node.waku_node] Started container from image wakuorg/nwaku:latest. REST: 7647
2026-04-22 23:54:01.254 DEBUG [src.libs.common] Sleeping for 1 seconds
2026-04-22 23:54:01.428 ERROR [src.node.docker_mananger] Max retries reached for container b435bc911ed9. Exiting log stream.
2026-04-22 23:54:01.833 ERROR [src.node.docker_mananger] Max retries reached for container 1e0f21d40f18. Exiting log stream.
2026-04-22 23:54:02.254 INFO [src.node.api_clients.base_client] curl -v -X GET "http://127.0.0.1:7647/health" -H "Content-Type: application/json" -d 'None'
2026-04-22 23:54:02.257 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'{"nodeHealth":"INITIALIZING","connectionStatus":"Disconnected","protocolsHealth":[]}'
2026-04-22 23:54:02.357 INFO [src.node.api_clients.base_client] curl -v -X GET "http://127.0.0.1:7647/health" -H "Content-Type: application/json" -d 'None'
2026-04-22 23:54:02.359 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'{"nodeHealth":"INITIALIZING","connectionStatus":"Disconnected","protocolsHealth":[]}'
2026-04-22 23:54:02.459 INFO [src.node.api_clients.base_client] curl -v -X GET "http://127.0.0.1:7647/health" -H "Content-Type: application/json" -d 'None'
2026-04-22 23:54:02.462 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'{"nodeHealth":"INITIALIZING","connectionStatus":"Disconnected","protocolsHealth":[]}'
2026-04-22 23:54:02.562 INFO [src.node.api_clients.base_client] curl -v -X GET "http://127.0.0.1:7647/health" -H "Content-Type: application/json" -d 'None'
2026-04-22 23:54:02.564 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'{"nodeHealth":"INITIALIZING","connectionStatus":"Disconnected","protocolsHealth":[]}'
2026-04-22 23:54:02.665 INFO [src.node.api_clients.base_client] curl -v -X GET "http://127.0.0.1:7647/health" -H "Content-Type: application/json" -d 'None'
2026-04-22 23:54:02.667 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'{"nodeHealth":"INITIALIZING","connectionStatus":"Disconnected","protocolsHealth":[]}'
2026-04-22 23:54:02.768 INFO [src.node.api_clients.base_client] curl -v -X GET "http://127.0.0.1:7647/health" -H "Content-Type: application/json" -d 'None'
2026-04-22 23:54:02.770 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'{"nodeHealth":"INITIALIZING","connectionStatus":"Disconnected","protocolsHealth":[]}'
2026-04-22 23:54:02.870 INFO [src.node.api_clients.base_client] curl -v -X GET "http://127.0.0.1:7647/health" -H "Content-Type: application/json" -d 'None'
2026-04-22 23:54:02.873 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'{"nodeHealth":"READY","connectionStatus":"PartiallyConnected","protocolsHealth":[{"Relay":"NOT_READY","desc":"No connected peers"},{"Lightpush":"NOT_MOUNTED"},{"Legacy Lightpush":"NOT_MOUNTED"},{"Filter":"NOT_READY","desc":"Relay is not ready, filter will not be able to sort out messages"},{"Store":"NOT_MOUNTED"},{"Peer Exchange":"READY"},{"Rendezvous":"NOT_READY","desc":"No Rendezvous peers are available yet"},{"Mix":"NOT_MOUNTED"},{"Lightpush Client":"READY"},{"Legacy Lightpush Client":"READY"},{"Store Client":"READY"},{"Filter Client":"READY"},{"Rln Relay":"NOT_MOUNTED"}]}'
2026-04-22 23:54:02.873 INFO [src.node.waku_node] Node protocols are initialized !!
2026-04-22 23:54:02.873 INFO [src.node.api_clients.base_client] curl -v -X GET "http://127.0.0.1:7647/debug/v1/info" -H "Content-Type: application/json" -d 'None'
2026-04-22 23:54:02.876 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'{"listenAddresses":["/ip4/172.18.165.6/tcp/7648/p2p/16Uiu2HAkvyGALsPZe1Rwtb69TKSmp2qL8iqCGrhSV7EaYUvSLzk9","/ip4/172.18.165.6/tcp/7649/ws/p2p/16Uiu2HAkvyGALsPZe1Rwtb69TKSmp2qL8iqCGrhSV7EaYUvSLzk9"],"enrUri":"enr:-L24QAh1eW9HBj4du78bceJQlNJVNSmOiyvvCdwBvTuEyp9kcIRtihds96_pUq6_sdSrYjjRTxRq4_iqcNYPCKg9l4ICgmlkgnY0gmlwhKwSpQaKbXVsdGlhZGRyc5YACASsEqUGBh3gAAoErBKlBgYd4d0DgnJzhQDGAQAAiXNlY3AyNTZrMaECFvNXcwLqs_eIXoCucyYy1IljUMuvvhUkVJwICy54sbKDdGNwgh3gg3VkcIId4oV3YWt1MgU"}'
2026-04-22 23:54:02.876 INFO [src.node.waku_node] REST service is ready !!
2026-04-22 23:54:02.876 DEBUG [src.steps.filter] Running fixture setup: setup_main_filter_node
2026-04-22 23:54:02.885 DEBUG [src.node.docker_mananger] Docker client initialized with image wakuorg/nwaku:latest
2026-04-22 23:54:02.885 DEBUG [src.node.waku_node] WakuNode instance initialized with log path ./log/docker/node2_2026-04-22_23-54-01__6a986c4c-d2d2-4237-8b58-e77efccc7b54__wakuorg_nwaku:latest.log
2026-04-22 23:54:02.885 DEBUG [tests.conftest] fleet_joined_start: injecting waku.test bootstrap args into WakuNode.start()
2026-04-22 23:54:02.885 DEBUG [tests.conftest] fleet_joined_start: NODE2 bootstrapping from config-n2.toml (/dns4/node-01.gc-us-central1-a.waku.test.status.im/tcp/30303/p2p/16Uiu2HAmDCp8XJ9z1ev18zuv8NHekAsjNyezAvmMfFEJkiharitG)
2026-04-22 23:54:02.885 DEBUG [tests.conftest] fleet_joined_start: dropping local discv5_bootstrap_node=enr:-L24QAh1eW9HBj4du78bceJQlNJVNSmOiyvvCdwBvTuEyp9kcIRtihds96_pUq6_sdSrYjjRTxRq4_iqcNYPCKg9l4ICgmlkgnY0gmlwhKwSpQaKbXVsdGlhZGRyc5YACASsEqUGBh3gAAoErBKlBgYd4d0DgnJzhQDGAQAAiXNlY3AyNTZrMaECFvNXcwLqs_eIXoCucyYy1IljUMuvvhUkVJwICy54sbKDdGNwgh3gg3VkcIId4oV3YWt1MgU (fleet DNS discovery replaces it)
2026-04-22 23:54:02.885 DEBUG [tests.conftest] fleet_joined_start: staticnode=/dns4/node-01.gc-us-central1-a.waku.test.status.im/tcp/30303/p2p/16Uiu2HAmDCp8XJ9z1ev18zuv8NHekAsjNyezAvmMfFEJkiharitG dns_discovery_url=enrtree://AOGYWMBYOUIMOENHXCHILPKY3ZRFEULMFI4DOM442QSZ73TT2A7VI@test.waku.nodes.status.im
2026-04-22 23:54:02.885 DEBUG [src.node.waku_node] Starting Node...
2026-04-22 23:54:02.886 DEBUG [src.node.docker_mananger] Attempting to create or retrieve network waku
2026-04-22 23:54:02.887 DEBUG [src.node.docker_mananger] Network waku already exists
2026-04-22 23:54:02.887 DEBUG [src.node.docker_mananger] Generated random external IP 172.18.122.131
2026-04-22 23:54:02.887 DEBUG [src.node.docker_mananger] Generated ports ['19177', '19178', '19179', '19180', '19181']
2026-04-22 23:54:02.887 DEBUG [src.node.waku_node] RLN credentials were not set
2026-04-22 23:54:02.887 INFO [src.node.waku_node] RLN credentials not set or credential store not available, starting without RLN
2026-04-22 23:54:02.888 DEBUG [src.node.waku_node] Using volumes []
2026-04-22 23:54:02.888 DEBUG [src.node.docker_mananger] docker run -i -t -p 19177:19177 -p 19178:19178 -p 19179:19179 -p 19180:19180 -p 19181:19181 wakuorg/nwaku:latest --listen-address=0.0.0.0 --rest=true --rest-admin=true --websocket-support=true --log-level=TRACE --rest-relay-cache-capacity=100 --websocket-port=19179 --rest-port=19177 --tcp-port=19178 --discv5-udp-port=19180 --rest-address=0.0.0.0 --nat=extip:172.18.122.131 --peer-exchange=true --discv5-discovery=true --cluster-id=198 --nodekey=41e2ddbdce0ddb78bbc8cdcbec005da79089dbddf09515d776eceaebfc5b1f4a --shard=0 --metrics-server=true --metrics-server-address=0.0.0.0 --metrics-server-port=19181 --metrics-logging=true --relay=false --filternode=/ip4/172.18.165.6/tcp/7648/p2p/16Uiu2HAkvyGALsPZe1Rwtb69TKSmp2qL8iqCGrhSV7EaYUvSLzk9 --staticnode=/dns4/node-01.gc-us-central1-a.waku.test.status.im/tcp/30303/p2p/16Uiu2HAmDCp8XJ9z1ev18zuv8NHekAsjNyezAvmMfFEJkiharitG --dns-discovery=true --dns-discovery-url=enrtree://AOGYWMBYOUIMOENHXCHILPKY3ZRFEULMFI4DOM442QSZ73TT2A7VI@test.waku.nodes.status.im
2026-04-22 23:54:03.075 DEBUG [src.node.docker_mananger] docker network connect --ip 172.18.122.131 waku 6c5fc8870d0bdd3229dcfc6d967199fd42fa6d20659aa2913d4e4cb214b80517
2026-04-22 23:54:03.104 DEBUG [src.node.docker_mananger] Container started with ID 6c5fc8870d0b. Setting up logs at ./log/docker/node2_2026-04-22_23-54-01__6a986c4c-d2d2-4237-8b58-e77efccc7b54__wakuorg_nwaku:latest.log
2026-04-22 23:54:03.104 DEBUG [src.node.waku_node] Started container from image wakuorg/nwaku:latest. REST: 19177
2026-04-22 23:54:03.104 DEBUG [src.libs.common] Sleeping for 1 seconds
2026-04-22 23:54:04.105 INFO [src.node.api_clients.base_client] curl -v -X GET "http://127.0.0.1:19177/health" -H "Content-Type: application/json" -d 'None'
2026-04-22 23:54:04.124 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'{"nodeHealth":"INITIALIZING","connectionStatus":"Disconnected","protocolsHealth":[]}'
2026-04-22 23:54:04.225 INFO [src.node.api_clients.base_client] curl -v -X GET "http://127.0.0.1:19177/health" -H "Content-Type: application/json" -d 'None'
2026-04-22 23:54:04.227 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'{"nodeHealth":"INITIALIZING","connectionStatus":"Disconnected","protocolsHealth":[]}'
2026-04-22 23:54:04.328 INFO [src.node.api_clients.base_client] curl -v -X GET "http://127.0.0.1:19177/health" -H "Content-Type: application/json" -d 'None'
2026-04-22 23:54:04.330 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'{"nodeHealth":"INITIALIZING","connectionStatus":"Disconnected","protocolsHealth":[]}'
2026-04-22 23:54:04.430 INFO [src.node.api_clients.base_client] curl -v -X GET "http://127.0.0.1:19177/health" -H "Content-Type: application/json" -d 'None'
2026-04-22 23:54:04.434 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'{"nodeHealth":"READY","connectionStatus":"PartiallyConnected","protocolsHealth":[{"Relay":"NOT_MOUNTED"},{"Lightpush":"NOT_MOUNTED"},{"Legacy Lightpush":"NOT_MOUNTED"},{"Filter":"NOT_MOUNTED"},{"Store":"NOT_MOUNTED"},{"Peer Exchange":"READY"},{"Rendezvous":"NOT_READY","desc":"No Rendezvous peers are available yet"},{"Mix":"NOT_MOUNTED"},{"Lightpush Client":"READY"},{"Legacy Lightpush Client":"READY"},{"Store Client":"READY"},{"Filter Client":"READY"},{"Rln Relay":"NOT_MOUNTED"}]}'
2026-04-22 23:54:04.434 INFO [src.node.waku_node] Node protocols are initialized !!
2026-04-22 23:54:04.434 INFO [src.node.api_clients.base_client] curl -v -X GET "http://127.0.0.1:19177/debug/v1/info" -H "Content-Type: application/json" -d 'None'
2026-04-22 23:54:04.436 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'{"listenAddresses":["/ip4/172.18.122.131/tcp/19178/p2p/16Uiu2HAm5rNg87uYWHz6yscCrJQGQCjVwhVqrnohYj5y1VPdVQng","/ip4/172.18.122.131/tcp/19179/ws/p2p/16Uiu2HAm5rNg87uYWHz6yscCrJQGQCjVwhVqrnohYj5y1VPdVQng"],"enrUri":"enr:-L24QPtkznfuhMQbYoVcBXdvSqvrzKQ1iui1esOsAkqzTLEEABxgb3TO_C4Z7R3YX5z8DsF2kAPgMBa75_DeLBd2AlICgmlkgnY0gmlwhKwSeoOKbXVsdGlhZGRyc5YACASsEnqDBkrqAAoErBJ6gwZK690DgnJzhQDGAQAAiXNlY3AyNTZrMaECmujSs79zsBlvQ0zTJTfABJxxVlWHda5IDkQDmOHolfWDdGNwgkrqg3VkcIJK7IV3YWt1MgA"}'
2026-04-22 23:54:04.436 INFO [src.node.waku_node] REST service is ready !!
2026-04-22 23:54:04.437 INFO [src.node.api_clients.base_client] curl -v -X POST "http://127.0.0.1:19177/admin/v1/peers" -H "Content-Type: application/json" -d '["/ip4/172.18.165.6/tcp/7648/p2p/16Uiu2HAkvyGALsPZe1Rwtb69TKSmp2qL8iqCGrhSV7EaYUvSLzk9"]'
2026-04-22 23:54:04.456 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'OK'
2026-04-22 23:54:04.461 INFO [src.node.api_clients.base_client] curl -v -X POST "http://127.0.0.1:7647/relay/v1/subscriptions" -H "Content-Type: application/json" -d '["/waku/2/rs/198/1"]'
2026-04-22 23:54:04.470 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'OK'
2026-04-22 23:54:04.471 INFO [src.node.api_clients.base_client] curl -v -X POST "http://127.0.0.1:19177/filter/v2/subscriptions" -H "Content-Type: application/json" -d '{"requestId": "30ad74ea-adbe-44b0-95c0-21fc6e0aa7c2", "contentFilters": ["/test/1/waku-filter/proto"], "pubsubTopic": "/waku/2/rs/198/1"}'
2026-04-22 23:54:04.481 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'{"requestId":"30ad74ea-adbe-44b0-95c0-21fc6e0aa7c2","statusDesc":"OK"}'
2026-04-22 23:54:04.481 INFO [src.node.api_clients.base_client] curl -v -X POST "http://127.0.0.1:7647/relay/v1/messages/%2Fwaku%2F2%2Frs%2F198%2F1" -H "Content-Type: application/json" -d '{"payload": "RmlsdGVyIHdvcmtzISE=", "contentTopic": "/test/1/waku-filter/proto", "timestamp": '$(date +%s%N)'}'
2026-04-22 23:54:04.489 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'OK'
2026-04-22 23:54:04.489 DEBUG [src.libs.common] Sleeping for 0.1 seconds
2026-04-22 23:54:04.590 DEBUG [src.steps.filter] Checking that peer NODE_2:wakuorg/nwaku:latest can find the published message
2026-04-22 23:54:04.590 INFO [src.node.api_clients.base_client] curl -v -X GET "http://127.0.0.1:19177/filter/v2/messages/%2Ftest%2F1%2Fwaku-filter%2Fproto" -H "Content-Type: application/json" -d 'None'
2026-04-22 23:54:04.593 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'[{"payload":"RmlsdGVyIHdvcmtzISE=","contentTopic":"/test/1/waku-filter/proto","version":0,"timestamp":1776902044481836294,"ephemeral":false}]'
2026-04-22 23:54:04.595 DEBUG [tests.conftest] Running fixture teardown: test_setup
2026-04-22 23:54:04.597 DEBUG [tests.conftest] Running fixture teardown: close_open_nodes
2026-04-22 23:54:04.597 DEBUG [src.node.waku_node] Stopping container with id babc95093ab7
2026-04-22 23:54:05.051 DEBUG [src.node.waku_node] Container stopped.
2026-04-22 23:54:05.051 DEBUG [src.node.waku_node] Stopping container with id 6c5fc8870d0b
2026-04-22 23:54:05.491 DEBUG [src.node.waku_node] Container stopped.
2026-04-22 23:54:05.493 DEBUG [tests.conftest] Running fixture teardown: check_waku_log_errors
2026-04-22 23:54:05.511 DEBUG [src.node.docker_mananger] No errors found in the waku logs.
2026-04-22 23:54:05.525 DEBUG [src.node.docker_mananger] No errors found in the waku logs.