114 lines
16 KiB
Plaintext
114 lines
16 KiB
Plaintext
[35mDEBUG [0m tests.conftest:conftest.py:51 Running fixture setup: test_id
|
||
[35mDEBUG [0m tests.conftest:conftest.py:57 Running test: test_metrics_after_relay_publish with id: 2024-09-12_04-24-22__66785e61-e6d9-43db-9024-2d240910fc0f
|
||
[35mDEBUG [0m src.steps.common:common.py:17 Running fixture setup: common_setup
|
||
[35mDEBUG [0m src.steps.filter:filter.py:28 Running fixture setup: filter_setup
|
||
[35mDEBUG [0m src.steps.light_push:light_push.py:27 Running fixture setup: light_push_setup
|
||
[35mDEBUG [0m src.steps.relay:relay.py:28 Running fixture setup: relay_setup
|
||
[35mDEBUG [0m src.steps.store:store.py:28 Running fixture setup: store_setup
|
||
[35mDEBUG [0m src.steps.relay:relay.py:34 Running fixture setup: setup_main_relay_nodes
|
||
[35mDEBUG [0m src.node.docker_mananger:docker_mananger.py:18 Docker client initialized with image wakuorg/nwaku:latest
|
||
[35mDEBUG [0m src.node.waku_node:waku_node.py:85 WakuNode instance initialized with log path ./log/docker/node1_2024-09-12_04-24-22__66785e61-e6d9-43db-9024-2d240910fc0f__wakuorg_nwaku:latest.log
|
||
[35mDEBUG [0m src.node.waku_node:waku_node.py:89 Starting Node...
|
||
[35mDEBUG [0m src.node.docker_mananger:docker_mananger.py:21 Attempting to create or retrieve network waku
|
||
[35mDEBUG [0m src.node.docker_mananger:docker_mananger.py:24 Network waku already exists
|
||
[35mDEBUG [0m src.node.docker_mananger:docker_mananger.py:81 Generated random external IP 172.18.145.192
|
||
[35mDEBUG [0m src.node.docker_mananger:docker_mananger.py:74 Generated ports ['49007', '49008', '49009', '49010', '49011']
|
||
[35mDEBUG [0m src.node.waku_node:waku_node.py:434 RLN credentials were not set
|
||
[32mINFO [0m src.node.waku_node:waku_node.py:163 RLN credentials not set or credential store not available, starting without RLN
|
||
[35mDEBUG [0m src.node.waku_node:waku_node.py:165 Using volumes []
|
||
[35mDEBUG [0m src.node.docker_mananger:docker_mananger.py:48 docker run -i -t -p 49007:49007 -p 49008:49008 -p 49009:49009 -p 49010:49010 -p 49011:49011 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=49009 --rest-port=49007 --tcp-port=49008 --discv5-udp-port=49010 --rest-address=0.0.0.0 --nat=extip:172.18.145.192 --peer-exchange=true --discv5-discovery=true --cluster-id=3 --nodekey=e8132c59744c6ec52dbe2efe6d0f6db56cc9a8b9f9ad5a1f68a85f5c23da8b7d --shard=0 --metrics-server=true --metrics-server-address=0.0.0.0 --metrics-server-port=49011 --metrics-logging=true --relay=true
|
||
[35mDEBUG [0m src.node.docker_mananger:docker_mananger.py:54 docker network connect --ip 172.18.145.192 waku b891775bc575074cca5cc8dd6844ca1a9a861d79df27aaf6e9edf221a0a3b21c
|
||
[35mDEBUG [0m src.node.docker_mananger:docker_mananger.py:57 Container started with ID b891775bc575. Setting up logs at ./log/docker/node1_2024-09-12_04-24-22__66785e61-e6d9-43db-9024-2d240910fc0f__wakuorg_nwaku:latest.log
|
||
[35mDEBUG [0m src.node.waku_node:waku_node.py:177 Started container from image wakuorg/nwaku:latest. REST: 49007
|
||
[35mDEBUG [0m src.libs.common:common.py:35 Sleeping for 1 seconds
|
||
[32mINFO [0m src.node.api_clients.base_client:base_client.py:37 curl -v -X GET "http://127.0.0.1:49007/health" -H "Content-Type: application/json" -d 'None'
|
||
[32mINFO [0m src.node.api_clients.base_client:base_client.py:22 Response status code: 200. Response content: b'{"nodeHealth":"Ready","protocolsHealth":[]}'
|
||
[32mINFO [0m src.node.waku_node:waku_node.py:277 Node protocols are initialized !!
|
||
[32mINFO [0m src.node.api_clients.base_client:base_client.py:37 curl -v -X GET "http://127.0.0.1:49007/debug/v1/info" -H "Content-Type: application/json" -d 'None'
|
||
[32mINFO [0m src.node.api_clients.base_client:base_client.py:22 Response status code: 200. Response content: b'{"listenAddresses":["/ip4/172.18.145.192/tcp/49008/p2p/16Uiu2HAm8Q1RvV79rdJgfyP3yZtSpUjJMV6awbwGeSQXg67S7nUR","/ip4/172.18.145.192/tcp/49009/ws/p2p/16Uiu2HAm8Q1RvV79rdJgfyP3yZtSpUjJMV6awbwGeSQXg67S7nUR"],"enrUri":"enr:-LO4QMxLXJccKjHqufvfO0pHoTiPvNpanEsy38gpD0-PmDKRDtj7jYkrU-AoQYSbqEJvtEhb5j4vbLvFDXRxEy6Fv8ABgmlkgnY0gmlwhKwSkcCKbXVsdGlhZGRyc4wACgSsEpHABr9x3QOCcnOFAAMBAACJc2VjcDI1NmsxoQLAusLc7feFQ0GwknuDTseriE5aIjHr1XE4CZm0ml7jioN0Y3CCv3CDdWRwgr9yhXdha3UyAQ"}'
|
||
[32mINFO [0m src.node.waku_node:waku_node.py:282 REST service is ready !!
|
||
[35mDEBUG [0m src.node.docker_mananger:docker_mananger.py:18 Docker client initialized with image wakuorg/nwaku:latest
|
||
[35mDEBUG [0m src.node.waku_node:waku_node.py:85 WakuNode instance initialized with log path ./log/docker/node2_2024-09-12_04-24-22__66785e61-e6d9-43db-9024-2d240910fc0f__wakuorg_nwaku:latest.log
|
||
[35mDEBUG [0m src.node.waku_node:waku_node.py:89 Starting Node...
|
||
[35mDEBUG [0m src.node.docker_mananger:docker_mananger.py:21 Attempting to create or retrieve network waku
|
||
[35mDEBUG [0m src.node.docker_mananger:docker_mananger.py:24 Network waku already exists
|
||
[35mDEBUG [0m src.node.docker_mananger:docker_mananger.py:81 Generated random external IP 172.18.179.216
|
||
[35mDEBUG [0m src.node.docker_mananger:docker_mananger.py:74 Generated ports ['44404', '44405', '44406', '44407', '44408']
|
||
[35mDEBUG [0m src.node.waku_node:waku_node.py:434 RLN credentials were not set
|
||
[32mINFO [0m src.node.waku_node:waku_node.py:163 RLN credentials not set or credential store not available, starting without RLN
|
||
[35mDEBUG [0m src.node.waku_node:waku_node.py:165 Using volumes []
|
||
[35mDEBUG [0m src.node.docker_mananger:docker_mananger.py:48 docker run -i -t -p 44404:44404 -p 44405:44405 -p 44406:44406 -p 44407:44407 -p 44408:44408 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=44406 --rest-port=44404 --tcp-port=44405 --discv5-udp-port=44407 --rest-address=0.0.0.0 --nat=extip:172.18.179.216 --peer-exchange=true --discv5-discovery=true --cluster-id=3 --nodekey=7ffa7e5ebbe7ea188b543a1d3332a3a2bdf9ac07315c9afce55389c9d09eafe2 --shard=0 --metrics-server=true --metrics-server-address=0.0.0.0 --metrics-server-port=44408 --metrics-logging=true --relay=true --discv5-bootstrap-node=enr:-LO4QMxLXJccKjHqufvfO0pHoTiPvNpanEsy38gpD0-PmDKRDtj7jYkrU-AoQYSbqEJvtEhb5j4vbLvFDXRxEy6Fv8ABgmlkgnY0gmlwhKwSkcCKbXVsdGlhZGRyc4wACgSsEpHABr9x3QOCcnOFAAMBAACJc2VjcDI1NmsxoQLAusLc7feFQ0GwknuDTseriE5aIjHr1XE4CZm0ml7jioN0Y3CCv3CDdWRwgr9yhXdha3UyAQ
|
||
[35mDEBUG [0m src.node.docker_mananger:docker_mananger.py:54 docker network connect --ip 172.18.179.216 waku 54da077e4e533eb0d2b4eb1d699f88926bb9554fcb26a56a7a27a300c77cfdd5
|
||
[35mDEBUG [0m src.node.docker_mananger:docker_mananger.py:57 Container started with ID 54da077e4e53. Setting up logs at ./log/docker/node2_2024-09-12_04-24-22__66785e61-e6d9-43db-9024-2d240910fc0f__wakuorg_nwaku:latest.log
|
||
[35mDEBUG [0m src.node.waku_node:waku_node.py:177 Started container from image wakuorg/nwaku:latest. REST: 44404
|
||
[35mDEBUG [0m src.libs.common:common.py:35 Sleeping for 1 seconds
|
||
[32mINFO [0m src.node.api_clients.base_client:base_client.py:37 curl -v -X GET "http://127.0.0.1:44404/health" -H "Content-Type: application/json" -d 'None'
|
||
[32mINFO [0m src.node.api_clients.base_client:base_client.py:22 Response status code: 200. Response content: b'{"nodeHealth":"Ready","protocolsHealth":[]}'
|
||
[32mINFO [0m src.node.waku_node:waku_node.py:277 Node protocols are initialized !!
|
||
[32mINFO [0m src.node.api_clients.base_client:base_client.py:37 curl -v -X GET "http://127.0.0.1:44404/debug/v1/info" -H "Content-Type: application/json" -d 'None'
|
||
[32mINFO [0m src.node.api_clients.base_client:base_client.py:22 Response status code: 200. Response content: b'{"listenAddresses":["/ip4/172.18.179.216/tcp/44405/p2p/16Uiu2HAmQURrpNRgD1B2SBvyeZZiJXHe5ks73v17D6PiYD8yro8z","/ip4/172.18.179.216/tcp/44406/ws/p2p/16Uiu2HAmQURrpNRgD1B2SBvyeZZiJXHe5ks73v17D6PiYD8yro8z"],"enrUri":"enr:-LO4QHKOk6J2BkBJMSpEV5WOmeQBcVtrEDVG3nEyyyPZ3hh-aJ1-gFNzFMgmQaW1dTkerWRNqjHnmefJ4qWjDFh2hMgBgmlkgnY0gmlwhKwSs9iKbXVsdGlhZGRyc4wACgSsErPYBq123QOCcnOFAAMBAACJc2VjcDI1NmsxoQOvl7KLv3uFjbEoe-IgqnSDhVO8b9qsxd5dumV1cBH8T4N0Y3CCrXWDdWRwgq13hXdha3UyAQ"}'
|
||
[32mINFO [0m src.node.waku_node:waku_node.py:282 REST service is ready !!
|
||
[32mINFO [0m src.node.api_clients.base_client:base_client.py:37 curl -v -X POST "http://127.0.0.1:44404/admin/v1/peers" -H "Content-Type: application/json" -d '["/ip4/172.18.145.192/tcp/49008/p2p/16Uiu2HAm8Q1RvV79rdJgfyP3yZtSpUjJMV6awbwGeSQXg67S7nUR"]'
|
||
[32mINFO [0m src.node.api_clients.base_client:base_client.py:22 Response status code: 200. Response content: b'OK'
|
||
[35mDEBUG [0m src.steps.relay:relay.py:59 Running fixture setup: subscribe_main_relay_nodes
|
||
[32mINFO [0m src.node.api_clients.base_client:base_client.py:37 curl -v -X POST "http://127.0.0.1:49007/relay/v1/subscriptions" -H "Content-Type: application/json" -d '["/waku/2/rs/3/1"]'
|
||
[32mINFO [0m src.node.api_clients.base_client:base_client.py:22 Response status code: 200. Response content: b'OK'
|
||
[32mINFO [0m src.node.api_clients.base_client:base_client.py:37 curl -v -X POST "http://127.0.0.1:44404/relay/v1/subscriptions" -H "Content-Type: application/json" -d '["/waku/2/rs/3/1"]'
|
||
[32mINFO [0m src.node.api_clients.base_client:base_client.py:22 Response status code: 200. Response content: b'OK'
|
||
[32mINFO [0m src.node.api_clients.base_client:base_client.py:37 curl -v -X POST "http://127.0.0.1:49007/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)'}'
|
||
[32mINFO [0m src.node.api_clients.base_client:base_client.py:22 Response status code: 200. Response content: b'OK'
|
||
[35mDEBUG [0m src.libs.common:common.py:35 Sleeping for 0.1 seconds
|
||
[35mDEBUG [0m src.steps.relay:relay.py:123 Checking that peer NODE_1:wakuorg/nwaku:latest can find the published message
|
||
[32mINFO [0m src.node.api_clients.base_client:base_client.py:37 curl -v -X GET "http://127.0.0.1:49007/relay/v1/messages/%2Fwaku%2F2%2Frs%2F3%2F1" -H "Content-Type: application/json" -d 'None'
|
||
[32mINFO [0m src.node.api_clients.base_client:base_client.py:22 Response status code: 200. Response content: b'[{"payload":"UmVsYXkgd29ya3MhIQ==","contentTopic":"/test/1/waku-relay/proto","version":0,"timestamp":1726115066050525184,"ephemeral":false}]'
|
||
[35mDEBUG [0m src.steps.relay:relay.py:123 Checking that peer NODE_2:wakuorg/nwaku:latest can find the published message
|
||
[32mINFO [0m src.node.api_clients.base_client:base_client.py:37 curl -v -X GET "http://127.0.0.1:44404/relay/v1/messages/%2Fwaku%2F2%2Frs%2F3%2F1" -H "Content-Type: application/json" -d 'None'
|
||
[32mINFO [0m src.node.api_clients.base_client:base_client.py:22 Response status code: 200. Response content: b'[{"payload":"UmVsYXkgd29ya3MhIQ==","contentTopic":"/test/1/waku-relay/proto","version":0,"timestamp":1726115066050525184,"ephemeral":false}]'
|
||
[32mINFO [0m src.steps.relay:relay.py:71 WARM UP successful!!
|
||
[32mINFO [0m src.node.api_clients.base_client:base_client.py:37 curl -v -X POST "http://127.0.0.1:49007/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)'}'
|
||
[32mINFO [0m src.node.api_clients.base_client:base_client.py:22 Response status code: 200. Response content: b'OK'
|
||
[35mDEBUG [0m src.libs.common:common.py:35 Sleeping for 0.5 seconds
|
||
[32mINFO [0m src.node.api_clients.base_client:base_client.py:37 curl -v -X GET "http://127.0.0.1:44404/relay/v1/messages/%2Fwaku%2F2%2Frs%2F3%2F1" -H "Content-Type: application/json" -d 'None'
|
||
[32mINFO [0m src.node.api_clients.base_client:base_client.py:22 Response status code: 200. Response content: b'[{"payload":"UmVsYXkgd29ya3MhIQ==","contentTopic":"/test/1/waku-relay/proto","version":0,"timestamp":1726115066173585664,"ephemeral":false}]'
|
||
[35mDEBUG [0m src.libs.common:common.py:35 Sleeping for 5 seconds
|
||
[35mDEBUG [0m src.steps.metrics:metrics.py:15 Checking metric: libp2p_peers has 1
|
||
[35mDEBUG [0m src.steps.metrics:metrics.py:27 Found metric: libp2p_peers with value 1.0
|
||
[35mDEBUG [0m src.steps.metrics:metrics.py:15 Checking metric: libp2p_pubsub_peers has 1
|
||
[35mDEBUG [0m src.steps.metrics:metrics.py:27 Found metric: libp2p_pubsub_peers with value 1.0
|
||
[35mDEBUG [0m src.steps.metrics:metrics.py:15 Checking metric: libp2p_pubsub_topics has 1
|
||
[35mDEBUG [0m src.steps.metrics:metrics.py:27 Found metric: libp2p_pubsub_topics with value 2.0
|
||
[35mDEBUG [0m src.steps.metrics:metrics.py:15 Checking metric: libp2p_pubsub_subscriptions_total has 1
|
||
[35mDEBUG [0m src.steps.metrics:metrics.py:27 Found metric: libp2p_pubsub_subscriptions_total with value 2.0
|
||
[35mDEBUG [0m src.steps.metrics:metrics.py:15 Checking metric: libp2p_gossipsub_peers_per_topic_mesh{topic="other"} has 1
|
||
[35mDEBUG [0m src.steps.metrics:metrics.py:27 Found metric: libp2p_gossipsub_peers_per_topic_mesh{topic="other"} with value 2.0
|
||
[35mDEBUG [0m src.steps.metrics:metrics.py:15 Checking metric: waku_peer_store_size has 1
|
||
[35mDEBUG [0m src.steps.metrics:metrics.py:27 Found metric: waku_peer_store_size with value 1.0
|
||
[35mDEBUG [0m src.steps.metrics:metrics.py:15 Checking metric: waku_histogram_message_size_count has 1
|
||
[35mDEBUG [0m src.steps.metrics:metrics.py:27 Found metric: waku_histogram_message_size_count with value 2.0
|
||
[35mDEBUG [0m src.steps.metrics:metrics.py:15 Checking metric: waku_node_messages_total{type="relay"} has 1
|
||
[35mDEBUG [0m src.steps.metrics:metrics.py:27 Found metric: waku_node_messages_total{type="relay"} with value 2.0
|
||
[35mDEBUG [0m src.steps.metrics:metrics.py:15 Checking metric: libp2p_peers has 1
|
||
[35mDEBUG [0m src.steps.metrics:metrics.py:27 Found metric: libp2p_peers with value 1.0
|
||
[35mDEBUG [0m src.steps.metrics:metrics.py:15 Checking metric: libp2p_pubsub_peers has 1
|
||
[35mDEBUG [0m src.steps.metrics:metrics.py:27 Found metric: libp2p_pubsub_peers with value 1.0
|
||
[35mDEBUG [0m src.steps.metrics:metrics.py:15 Checking metric: libp2p_pubsub_topics has 1
|
||
[35mDEBUG [0m src.steps.metrics:metrics.py:27 Found metric: libp2p_pubsub_topics with value 2.0
|
||
[35mDEBUG [0m src.steps.metrics:metrics.py:15 Checking metric: libp2p_pubsub_subscriptions_total has 1
|
||
[35mDEBUG [0m src.steps.metrics:metrics.py:27 Found metric: libp2p_pubsub_subscriptions_total with value 2.0
|
||
[35mDEBUG [0m src.steps.metrics:metrics.py:15 Checking metric: libp2p_gossipsub_peers_per_topic_mesh{topic="other"} has 1
|
||
[35mDEBUG [0m src.steps.metrics:metrics.py:27 Found metric: libp2p_gossipsub_peers_per_topic_mesh{topic="other"} with value 2.0
|
||
[35mDEBUG [0m src.steps.metrics:metrics.py:15 Checking metric: waku_peer_store_size has 1
|
||
[35mDEBUG [0m src.steps.metrics:metrics.py:27 Found metric: waku_peer_store_size with value 1.0
|
||
[35mDEBUG [0m src.steps.metrics:metrics.py:15 Checking metric: waku_histogram_message_size_count has 1
|
||
[35mDEBUG [0m src.steps.metrics:metrics.py:27 Found metric: waku_histogram_message_size_count with value 2.0
|
||
[35mDEBUG [0m src.steps.metrics:metrics.py:15 Checking metric: waku_node_messages_total{type="relay"} has 1
|
||
[35mDEBUG [0m src.steps.metrics:metrics.py:27 Found metric: waku_node_messages_total{type="relay"} with value 2.0
|
||
[35mDEBUG [0m tests.conftest:conftest.py:59 Running fixture teardown: test_setup
|
||
[35mDEBUG [0m tests.conftest:conftest.py:83 Running fixture teardown: close_open_nodes
|
||
[35mDEBUG [0m src.node.waku_node:waku_node.py:221 Stopping container with id b891775bc575
|
||
[35mDEBUG [0m src.node.waku_node:waku_node.py:228 Container stopped.
|
||
[35mDEBUG [0m src.node.waku_node:waku_node.py:221 Stopping container with id 54da077e4e53
|
||
[35mDEBUG [0m src.node.waku_node:waku_node.py:228 Container stopped.
|
||
[35mDEBUG [0m tests.conftest:conftest.py:98 Running fixture teardown: check_waku_log_errors
|
||
[35mDEBUG [0m src.node.docker_mananger:docker_mananger.py:117 No errors found in the waku logs.
|
||
[35mDEBUG [0m src.node.docker_mananger:docker_mananger.py:117 No errors found in the waku logs. |