2026-02-04 17:29:28 +00:00

63 lines
11 KiB
Plaintext

2026-02-04 17:15:52.118 DEBUG [tests.conftest] Running fixture setup: test_id
2026-02-04 17:15:52.118 DEBUG [tests.conftest] Running test: test_relay_no_subscription with id: 2026-02-04_17-15-52__3258a6ae-b7b5-45f8-9c45-a05f7d542b51
2026-02-04 17:15:52.119 DEBUG [src.steps.common] Running fixture setup: common_setup
2026-02-04 17:15:52.119 DEBUG [src.steps.relay] Running fixture setup: relay_setup
2026-02-04 17:15:52.119 DEBUG [src.steps.relay] Running fixture setup: setup_main_relay_nodes
2026-02-04 17:15:52.126 DEBUG [src.node.docker_mananger] Docker client initialized with image wakuorg/nwaku:latest
2026-02-04 17:15:52.126 DEBUG [src.node.waku_node] WakuNode instance initialized with log path ./log/docker/node1_2026-02-04_17-15-52__3258a6ae-b7b5-45f8-9c45-a05f7d542b51__wakuorg_nwaku:latest.log
2026-02-04 17:15:52.126 DEBUG [src.node.waku_node] Starting Node...
2026-02-04 17:15:52.126 DEBUG [src.node.docker_mananger] Attempting to create or retrieve network waku
2026-02-04 17:15:52.127 DEBUG [src.node.docker_mananger] Network waku already exists
2026-02-04 17:15:52.127 DEBUG [src.node.docker_mananger] Generated random external IP 172.18.231.61
2026-02-04 17:15:52.127 DEBUG [src.node.docker_mananger] Generated ports ['46784', '46785', '46786', '46787', '46788']
2026-02-04 17:15:52.128 DEBUG [src.node.waku_node] RLN credentials were not set
2026-02-04 17:15:52.128 INFO [src.node.waku_node] RLN credentials not set or credential store not available, starting without RLN
2026-02-04 17:15:52.128 DEBUG [src.node.waku_node] Using volumes []
2026-02-04 17:15:52.128 DEBUG [src.node.docker_mananger] docker run -i -t -p 46784:46784 -p 46785:46785 -p 46786:46786 -p 46787:46787 -p 46788:46788 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=46786 --rest-port=46784 --tcp-port=46785 --discv5-udp-port=46787 --rest-address=0.0.0.0 --nat=extip:172.18.231.61 --peer-exchange=true --discv5-discovery=true --cluster-id=3 --nodekey=add6198376bae2e3f0fa4babe0334dfd7fc15f9f55011dd39fcd234e0dccbbca --shard=0 --metrics-server=true --metrics-server-address=0.0.0.0 --metrics-server-port=46788 --metrics-logging=true --relay=true
2026-02-04 17:15:52.304 DEBUG [src.node.docker_mananger] docker network connect --ip 172.18.231.61 waku a6656354992da7f5f68af400565560b46e4e7d9e4134a6ee53dee67d44bd67a1
2026-02-04 17:15:52.337 DEBUG [src.node.docker_mananger] Container started with ID a6656354992d. Setting up logs at ./log/docker/node1_2026-02-04_17-15-52__3258a6ae-b7b5-45f8-9c45-a05f7d542b51__wakuorg_nwaku:latest.log
2026-02-04 17:15:52.337 DEBUG [src.node.waku_node] Started container from image wakuorg/nwaku:latest. REST: 46784
2026-02-04 17:15:52.338 DEBUG [src.libs.common] Sleeping for 1 seconds
2026-02-04 17:15:52.356 ERROR [src.node.docker_mananger] Max retries reached for container 8d52beaf7c15. Exiting log stream.
2026-02-04 17:15:52.928 ERROR [src.node.docker_mananger] Max retries reached for container c87eb5960bf5. Exiting log stream.
2026-02-04 17:15:53.338 INFO [src.node.api_clients.base_client] curl -v -X GET "http://127.0.0.1:46784/health" -H "Content-Type: application/json" -d 'None'
2026-02-04 17:15:53.341 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'{"nodeHealth":"READY","protocolsHealth":[{"Relay":"NOT_READY","desc":"No connected peers"},{"Rln Relay":"NOT_MOUNTED"},{"Lightpush":"NOT_MOUNTED"},{"Legacy Lightpush":"NOT_MOUNTED"},{"Filter":"NOT_MOUNTED"},{"Store":"NOT_MOUNTED"},{"Legacy Store":"NOT_MOUNTED"},{"Peer Exchange":"READY"},{"Rendezvous":"NOT_READY","desc":"No Rendezvous peers are available yet"},{"Mix":"NOT_MOUNTED"},{"Lightpush Client":"NOT_READY","desc":"No Lightpush service peer available yet"},{"Legacy Lightpush Client":"NOT_READY","desc":"No Lightpush service peer available yet"},{"Store Client":"NOT_READY","desc":"No Store service peer available yet, neither Store service set up for the node"},{"Legacy Store Client":"NOT_READY","desc":"No Legacy Store service peers are available yet, neither Store service set up for the node"},{"Filter Client":"NOT_READY","desc":"No Filter service peer available yet"}]}'
2026-02-04 17:15:53.341 INFO [src.node.waku_node] Node protocols are initialized !!
2026-02-04 17:15:53.342 INFO [src.node.api_clients.base_client] curl -v -X GET "http://127.0.0.1:46784/debug/v1/info" -H "Content-Type: application/json" -d 'None'
2026-02-04 17:15:53.344 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'{"listenAddresses":["/ip4/172.18.231.61/tcp/46785/p2p/16Uiu2HAm1rtxbLmqFCfBDsxDtXMbiuD2XQmFmwPgtqxusSY54qn6","/ip4/172.18.231.61/tcp/46786/ws/p2p/16Uiu2HAm1rtxbLmqFCfBDsxDtXMbiuD2XQmFmwPgtqxusSY54qn6"],"enrUri":"enr:-L24QKmRHP52NRt8FxKuE_LToU5cR81o0pgsxzi4VB8GqIT4fYb6TPqEpF5s8Ev_GYBYInUjciGUrVLAhiHyhR_NmJQCgmlkgnY0gmlwhKwS5z2KbXVsdGlhZGRyc5YACASsEuc9BrbBAAoErBLnPQa2wt0DgnJzhQADAQAAiXNlY3AyNTZrMaECX5xRSWHmxPfR8rZBwj7DJJ6Hk05DHCMUFsM4qUI__U-DdGNwgrbBg3VkcIK2w4V3YWt1MgE"}'
2026-02-04 17:15:53.344 INFO [src.node.waku_node] REST service is ready !!
2026-02-04 17:15:53.350 DEBUG [src.node.docker_mananger] Docker client initialized with image wakuorg/nwaku:latest
2026-02-04 17:15:53.350 DEBUG [src.node.waku_node] WakuNode instance initialized with log path ./log/docker/node2_2026-02-04_17-15-52__3258a6ae-b7b5-45f8-9c45-a05f7d542b51__wakuorg_nwaku:latest.log
2026-02-04 17:15:53.351 DEBUG [src.node.waku_node] Starting Node...
2026-02-04 17:15:53.351 DEBUG [src.node.docker_mananger] Attempting to create or retrieve network waku
2026-02-04 17:15:53.352 DEBUG [src.node.docker_mananger] Network waku already exists
2026-02-04 17:15:53.352 DEBUG [src.node.docker_mananger] Generated random external IP 172.18.46.213
2026-02-04 17:15:53.352 DEBUG [src.node.docker_mananger] Generated ports ['19072', '19073', '19074', '19075', '19076']
2026-02-04 17:15:53.352 DEBUG [src.node.waku_node] RLN credentials were not set
2026-02-04 17:15:53.353 INFO [src.node.waku_node] RLN credentials not set or credential store not available, starting without RLN
2026-02-04 17:15:53.353 DEBUG [src.node.waku_node] Using volumes []
2026-02-04 17:15:53.353 DEBUG [src.node.docker_mananger] docker run -i -t -p 19072:19072 -p 19073:19073 -p 19074:19074 -p 19075:19075 -p 19076:19076 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=19074 --rest-port=19072 --tcp-port=19073 --discv5-udp-port=19075 --rest-address=0.0.0.0 --nat=extip:172.18.46.213 --peer-exchange=true --discv5-discovery=true --cluster-id=3 --nodekey=cbe5dbdedcea98f1328dcbef08abdaa3ca1c6f8be3bd4ff533a8f0bb53eda8f0 --shard=0 --metrics-server=true --metrics-server-address=0.0.0.0 --metrics-server-port=19076 --metrics-logging=true --relay=true --discv5-bootstrap-node=enr:-L24QKmRHP52NRt8FxKuE_LToU5cR81o0pgsxzi4VB8GqIT4fYb6TPqEpF5s8Ev_GYBYInUjciGUrVLAhiHyhR_NmJQCgmlkgnY0gmlwhKwS5z2KbXVsdGlhZGRyc5YACASsEuc9BrbBAAoErBLnPQa2wt0DgnJzhQADAQAAiXNlY3AyNTZrMaECX5xRSWHmxPfR8rZBwj7DJJ6Hk05DHCMUFsM4qUI__U-DdGNwgrbBg3VkcIK2w4V3YWt1MgE
2026-02-04 17:15:53.536 DEBUG [src.node.docker_mananger] docker network connect --ip 172.18.46.213 waku 1a0a07523ce5d86c30388d92d9e3610380fe24503e8277f99d19c0a6a0db0f30
2026-02-04 17:15:53.567 DEBUG [src.node.docker_mananger] Container started with ID 1a0a07523ce5. Setting up logs at ./log/docker/node2_2026-02-04_17-15-52__3258a6ae-b7b5-45f8-9c45-a05f7d542b51__wakuorg_nwaku:latest.log
2026-02-04 17:15:53.567 DEBUG [src.node.waku_node] Started container from image wakuorg/nwaku:latest. REST: 19072
2026-02-04 17:15:53.567 DEBUG [src.libs.common] Sleeping for 1 seconds
2026-02-04 17:15:54.568 INFO [src.node.api_clients.base_client] curl -v -X GET "http://127.0.0.1:19072/health" -H "Content-Type: application/json" -d 'None'
2026-02-04 17:15:54.585 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'{"nodeHealth":"READY","protocolsHealth":[{"Relay":"READY"},{"Rln Relay":"NOT_MOUNTED"},{"Lightpush":"NOT_MOUNTED"},{"Legacy Lightpush":"NOT_MOUNTED"},{"Filter":"NOT_MOUNTED"},{"Store":"NOT_MOUNTED"},{"Legacy Store":"NOT_MOUNTED"},{"Peer Exchange":"READY"},{"Rendezvous":"NOT_READY","desc":"No Rendezvous peers are available yet"},{"Mix":"NOT_MOUNTED"},{"Lightpush Client":"NOT_READY","desc":"No Lightpush service peer available yet"},{"Legacy Lightpush Client":"NOT_READY","desc":"No Lightpush service peer available yet"},{"Store Client":"NOT_READY","desc":"No Store service peer available yet, neither Store service set up for the node"},{"Legacy Store Client":"NOT_READY","desc":"No Legacy Store service peers are available yet, neither Store service set up for the node"},{"Filter Client":"NOT_READY","desc":"No Filter service peer available yet"}]}'
2026-02-04 17:15:54.586 INFO [src.node.waku_node] Node protocols are initialized !!
2026-02-04 17:15:54.587 INFO [src.node.api_clients.base_client] curl -v -X GET "http://127.0.0.1:19072/debug/v1/info" -H "Content-Type: application/json" -d 'None'
2026-02-04 17:15:54.591 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'{"listenAddresses":["/ip4/172.18.46.213/tcp/19073/p2p/16Uiu2HAm5oLVs65bMQjCi3zPj67c2rvosaSHhpW7KXVbEiTyEtRd","/ip4/172.18.46.213/tcp/19074/ws/p2p/16Uiu2HAm5oLVs65bMQjCi3zPj67c2rvosaSHhpW7KXVbEiTyEtRd"],"enrUri":"enr:-L24QAcN3WkqNC0iqS38Sxf_MVp5EIAF2KHlvMNYfqaYQpAPRFvtG3Qx9pDbpxZGc3ynN5Ni416mNNs577HZmUrognUCgmlkgnY0gmlwhKwSLtWKbXVsdGlhZGRyc5YACASsEi7VBkqBAAoErBIu1QZKgt0DgnJzhQADAQAAiXNlY3AyNTZrMaECmiGehSviQp1RVq47-H0FzaSyaAtFG3R6hfsOh8kNcEiDdGNwgkqBg3VkcIJKg4V3YWt1MgE"}'
2026-02-04 17:15:54.591 INFO [src.node.waku_node] REST service is ready !!
2026-02-04 17:15:54.591 INFO [src.node.api_clients.base_client] curl -v -X POST "http://127.0.0.1:19072/admin/v1/peers" -H "Content-Type: application/json" -d '["/ip4/172.18.231.61/tcp/46785/p2p/16Uiu2HAm1rtxbLmqFCfBDsxDtXMbiuD2XQmFmwPgtqxusSY54qn6"]'
2026-02-04 17:15:54.594 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'OK'
2026-02-04 17:15:54.595 INFO [src.node.api_clients.base_client] curl -v -X POST "http://127.0.0.1:46784/relay/v1/messages/%2Fwaku%2F2%2Frs%2F3%2F1" -H "Content-Type: application/json" -d '{"payload": "UmVsYXkgd29ya3MhIQ==", "contentTopic": "/test/1/waku-relay/proto", "timestamp": '$(date +%s%N)'}'
2026-02-04 17:15:54.598 ERROR [src.node.api_clients.base_client] HTTP error occurred: 400 Client Error: Bad Request for url: http://127.0.0.1:46784/relay/v1/messages/%2Fwaku%2F2%2Frs%2F3%2F1. Response content: b'Failed to publish: Node not subscribed to topic: /waku/2/rs/3/1'
2026-02-04 17:15:54.599 DEBUG [tests.conftest] Running fixture teardown: test_setup
2026-02-04 17:15:54.600 DEBUG [tests.conftest] Running fixture teardown: close_open_nodes
2026-02-04 17:15:54.600 DEBUG [src.node.waku_node] Stopping container with id a6656354992d
2026-02-04 17:15:55.134 DEBUG [src.node.waku_node] Container stopped.
2026-02-04 17:15:55.134 DEBUG [src.node.waku_node] Stopping container with id 1a0a07523ce5
2026-02-04 17:15:55.637 DEBUG [src.node.waku_node] Container stopped.
2026-02-04 17:15:55.639 DEBUG [tests.conftest] Running fixture teardown: check_waku_log_errors
2026-02-04 17:15:55.643 DEBUG [src.node.docker_mananger] No errors found in the waku logs.
2026-02-04 17:15:55.647 DEBUG [src.node.docker_mananger] No errors found in the waku logs.