70 lines
12 KiB
Plaintext

2026-03-04 04:35:46.054 DEBUG [tests.conftest] Running fixture setup: test_id
2026-03-04 04:35:46.055 DEBUG [tests.conftest] Running test: test_main_node_relay_and_store__peer_only_store with id: 2026-03-04_04-35-46__80afab1a-e7d3-4898-b0d6-7b8242ebe31b
2026-03-04 04:35:46.055 DEBUG [src.steps.common] Running fixture setup: common_setup
2026-03-04 04:35:46.055 DEBUG [src.steps.store] Running fixture setup: store_setup
2026-03-04 04:35:46.066 DEBUG [src.node.docker_mananger] Docker client initialized with image wakuorg/nwaku:latest
2026-03-04 04:35:46.067 DEBUG [src.node.waku_node] WakuNode instance initialized with log path ./log/docker/publishing_node1_2026-03-04_04-35-46__80afab1a-e7d3-4898-b0d6-7b8242ebe31b__wakuorg_nwaku:latest.log
2026-03-04 04:35:46.067 DEBUG [src.node.waku_node] Starting Node...
2026-03-04 04:35:46.068 DEBUG [src.node.docker_mananger] Attempting to create or retrieve network waku
2026-03-04 04:35:46.070 DEBUG [src.node.docker_mananger] Network waku already exists
2026-03-04 04:35:46.071 DEBUG [src.node.docker_mananger] Generated random external IP 172.18.150.252
2026-03-04 04:35:46.071 DEBUG [src.node.docker_mananger] Generated ports ['18457', '18458', '18459', '18460', '18461']
2026-03-04 04:35:46.072 DEBUG [src.node.waku_node] RLN credentials were not set
2026-03-04 04:35:46.072 INFO [src.node.waku_node] RLN credentials not set or credential store not available, starting without RLN
2026-03-04 04:35:46.072 DEBUG [src.node.waku_node] Using volumes []
2026-03-04 04:35:46.072 DEBUG [src.node.docker_mananger] docker run -i -t -p 18457:18457 -p 18458:18458 -p 18459:18459 -p 18460:18460 -p 18461:18461 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=18459 --rest-port=18457 --tcp-port=18458 --discv5-udp-port=18460 --rest-address=0.0.0.0 --nat=extip:172.18.150.252 --peer-exchange=true --discv5-discovery=true --cluster-id=3 --nodekey=2eec62eb34a08d5deb0fd2f9ab18a6cddff52a06f9aad26e71f09bc20c0eae7a --shard=0 --metrics-server=true --metrics-server-address=0.0.0.0 --metrics-server-port=18461 --metrics-logging=true --store=true --relay=true
2026-03-04 04:35:46.270 DEBUG [src.node.docker_mananger] docker network connect --ip 172.18.150.252 waku a02883eeaa4d0f11f81015f5545b22299c86e68fb174865c1dd8e49158c1bdcc
2026-03-04 04:35:46.307 DEBUG [src.node.docker_mananger] Container started with ID a02883eeaa4d. Setting up logs at ./log/docker/publishing_node1_2026-03-04_04-35-46__80afab1a-e7d3-4898-b0d6-7b8242ebe31b__wakuorg_nwaku:latest.log
2026-03-04 04:35:46.308 DEBUG [src.node.waku_node] Started container from image wakuorg/nwaku:latest. REST: 18457
2026-03-04 04:35:46.309 DEBUG [src.libs.common] Sleeping for 1 seconds
2026-03-04 04:35:46.326 ERROR [src.node.docker_mananger] Max retries reached for container 3299c5d47a75. Exiting log stream.
2026-03-04 04:35:46.898 ERROR [src.node.docker_mananger] Max retries reached for container 1c497953f5df. Exiting log stream.
2026-03-04 04:35:47.309 INFO [src.node.api_clients.base_client] curl -v -X GET "http://127.0.0.1:18457/health" -H "Content-Type: application/json" -d 'None'
2026-03-04 04:35:47.312 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'{"nodeHealth":"READY","connectionStatus":"Disconnected","protocolsHealth":[{"Relay":"NOT_READY","desc":"No connected peers"},{"Lightpush":"NOT_MOUNTED"},{"Legacy Lightpush":"NOT_MOUNTED"},{"Filter":"NOT_MOUNTED"},{"Store":"READY"},{"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":"READY"},{"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"},{"Rln Relay":"NOT_MOUNTED"}]}'
2026-03-04 04:35:47.312 INFO [src.node.waku_node] Node protocols are initialized !!
2026-03-04 04:35:47.312 INFO [src.node.api_clients.base_client] curl -v -X GET "http://127.0.0.1:18457/debug/v1/info" -H "Content-Type: application/json" -d 'None'
2026-03-04 04:35:47.314 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'{"listenAddresses":["/ip4/172.18.150.252/tcp/18458/p2p/16Uiu2HAmLZnD9Eea4iTX6KSUNKZUbhK2qZoq3PpS5WysJg2kLjvx","/ip4/172.18.150.252/tcp/18459/ws/p2p/16Uiu2HAmLZnD9Eea4iTX6KSUNKZUbhK2qZoq3PpS5WysJg2kLjvx"],"enrUri":"enr:-L24QFs7GtiL19RFrSIcHYvVYGrzX6fQeehTE9RSf0Yqu7uXHK2jmsgY-LHop5l_U0iee6n0PhwKBIPlFOB-pqWq5UYCgmlkgnY0gmlwhKwSlvyKbXVsdGlhZGRyc5YACASsEpb8BkgaAAoErBKW_AZIG90DgnJzhQADAQAAiXNlY3AyNTZrMaEDdYfdNWP3hArMCyX42zmxNsA5WceQWJ9K2gnjHhK5-KmDdGNwgkgag3VkcIJIHIV3YWt1MgM"}'
2026-03-04 04:35:47.315 INFO [src.node.waku_node] REST service is ready !!
2026-03-04 04:35:47.322 DEBUG [src.node.docker_mananger] Docker client initialized with image wakuorg/nwaku:latest
2026-03-04 04:35:47.322 DEBUG [src.node.waku_node] WakuNode instance initialized with log path ./log/docker/store_node1_2026-03-04_04-35-46__80afab1a-e7d3-4898-b0d6-7b8242ebe31b__wakuorg_nwaku:latest.log
2026-03-04 04:35:47.322 DEBUG [src.node.waku_node] Starting Node...
2026-03-04 04:35:47.323 DEBUG [src.node.docker_mananger] Attempting to create or retrieve network waku
2026-03-04 04:35:47.324 DEBUG [src.node.docker_mananger] Network waku already exists
2026-03-04 04:35:47.324 DEBUG [src.node.docker_mananger] Generated random external IP 172.18.125.32
2026-03-04 04:35:47.324 DEBUG [src.node.docker_mananger] Generated ports ['57871', '57872', '57873', '57874', '57875']
2026-03-04 04:35:47.324 DEBUG [src.node.waku_node] RLN credentials were not set
2026-03-04 04:35:47.324 INFO [src.node.waku_node] RLN credentials not set or credential store not available, starting without RLN
2026-03-04 04:35:47.325 DEBUG [src.node.waku_node] Using volumes []
2026-03-04 04:35:47.325 DEBUG [src.node.docker_mananger] docker run -i -t -p 57871:57871 -p 57872:57872 -p 57873:57873 -p 57874:57874 -p 57875:57875 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=57873 --rest-port=57871 --tcp-port=57872 --discv5-udp-port=57874 --rest-address=0.0.0.0 --nat=extip:172.18.125.32 --peer-exchange=true --discv5-discovery=true --cluster-id=3 --nodekey=c4dbac2c0b9bab5ee0efb700c6fed8a831a2bc454f5ddfd7aa75b2a462eaac7d --shard=0 --metrics-server=true --metrics-server-address=0.0.0.0 --metrics-server-port=57875 --metrics-logging=true --discv5-bootstrap-node=enr:-L24QFs7GtiL19RFrSIcHYvVYGrzX6fQeehTE9RSf0Yqu7uXHK2jmsgY-LHop5l_U0iee6n0PhwKBIPlFOB-pqWq5UYCgmlkgnY0gmlwhKwSlvyKbXVsdGlhZGRyc5YACASsEpb8BkgaAAoErBKW_AZIG90DgnJzhQADAQAAiXNlY3AyNTZrMaEDdYfdNWP3hArMCyX42zmxNsA5WceQWJ9K2gnjHhK5-KmDdGNwgkgag3VkcIJIHIV3YWt1MgM --storenode=/ip4/172.18.150.252/tcp/18458/p2p/16Uiu2HAmLZnD9Eea4iTX6KSUNKZUbhK2qZoq3PpS5WysJg2kLjvx --store=true --relay=false
2026-03-04 04:35:47.516 DEBUG [src.node.docker_mananger] docker network connect --ip 172.18.125.32 waku 260bb726231c97afbcf0f7208addf0e6b4006dfd1ada4913cd8276201ef854cf
2026-03-04 04:35:47.553 DEBUG [src.node.docker_mananger] Container started with ID 260bb726231c. Setting up logs at ./log/docker/store_node1_2026-03-04_04-35-46__80afab1a-e7d3-4898-b0d6-7b8242ebe31b__wakuorg_nwaku:latest.log
2026-03-04 04:35:47.553 DEBUG [src.node.waku_node] Started container from image wakuorg/nwaku:latest. REST: 57871
2026-03-04 04:35:47.554 DEBUG [src.libs.common] Sleeping for 1 seconds
2026-03-04 04:35:48.555 INFO [src.node.api_clients.base_client] curl -v -X GET "http://127.0.0.1:57871/health" -H "Content-Type: application/json" -d 'None'
2026-03-04 04:35:48.558 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'{"nodeHealth":"READY","connectionStatus":"Disconnected","protocolsHealth":[{"Relay":"NOT_MOUNTED"},{"Lightpush":"NOT_MOUNTED"},{"Legacy Lightpush":"NOT_MOUNTED"},{"Filter":"NOT_MOUNTED"},{"Store":"READY"},{"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":"READY"},{"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"},{"Rln Relay":"NOT_MOUNTED"}]}'
2026-03-04 04:35:48.558 INFO [src.node.waku_node] Node protocols are initialized !!
2026-03-04 04:35:48.558 INFO [src.node.api_clients.base_client] curl -v -X GET "http://127.0.0.1:57871/debug/v1/info" -H "Content-Type: application/json" -d 'None'
2026-03-04 04:35:48.560 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'{"listenAddresses":["/ip4/172.18.125.32/tcp/57872/p2p/16Uiu2HAmRpgTnnjV9fyGTwsvJ6Rh8nFqx4FApfhGgzVYaZTzcEU5","/ip4/172.18.125.32/tcp/57873/ws/p2p/16Uiu2HAmRpgTnnjV9fyGTwsvJ6Rh8nFqx4FApfhGgzVYaZTzcEU5"],"enrUri":"enr:-L24QCxdgzEs5HbnR4O1Xo0bmW6McX2wPKsD5SHN66QvuEuKKZAq6wASgRF-5CC_vWU-PqemvZ9gA5iRc1V16S2XyGkCgmlkgnY0gmlwhKwSfSCKbXVsdGlhZGRyc5YACASsEn0gBuIQAAoErBJ9IAbiEd0DgnJzhQADAQAAiXNlY3AyNTZrMaEDw6ODHxQa3nO1O7i7PRAhY8IPsjKAv6v-QLEUWYGyBL6DdGNwguIQg3VkcILiEoV3YWt1MgI"}'
2026-03-04 04:35:48.560 INFO [src.node.waku_node] REST service is ready !!
2026-03-04 04:35:48.561 INFO [src.node.api_clients.base_client] curl -v -X POST "http://127.0.0.1:57871/admin/v1/peers" -H "Content-Type: application/json" -d '["/ip4/172.18.150.252/tcp/18458/p2p/16Uiu2HAmLZnD9Eea4iTX6KSUNKZUbhK2qZoq3PpS5WysJg2kLjvx"]'
2026-03-04 04:35:48.596 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'OK'
2026-03-04 04:35:48.599 INFO [src.node.api_clients.base_client] curl -v -X POST "http://127.0.0.1:18457/relay/v1/subscriptions" -H "Content-Type: application/json" -d '["/waku/2/rs/3/0"]'
2026-03-04 04:35:48.618 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'OK'
2026-03-04 04:35:48.620 DEBUG [src.steps.store] Relaying message
2026-03-04 04:35:48.621 INFO [src.node.api_clients.base_client] curl -v -X POST "http://127.0.0.1:18457/relay/v1/messages/%2Fwaku%2F2%2Frs%2F3%2F0" -H "Content-Type: application/json" -d '{"payload": "U3RvcmUgd29ya3MhIQ==", "contentTopic": "/myapp/1/latest/proto", "timestamp": '$(date +%s%N)'}'
2026-03-04 04:35:48.628 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'OK'
2026-03-04 04:35:48.629 DEBUG [src.libs.common] Sleeping for 0.2 seconds
2026-03-04 04:35:48.830 DEBUG [src.steps.store] Checking that peer wakuorg/nwaku:latest can find the stored messages
2026-03-04 04:35:48.830 INFO [src.node.api_clients.base_client] curl -v -X GET "http://127.0.0.1:18457/store/v3/messages?pubsubTopic=%2Fwaku%2F2%2Frs%2F3%2F0&pageSize=5&ascending=true&pubsubTopic=%2Fwaku%2F2%2Frs%2F3%2F0" -H "Content-Type: application/json" -d 'None'
2026-03-04 04:35:48.833 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'{"requestId":"","statusCode":200,"statusDesc":"OK","messages":[]}'
2026-03-04 04:35:48.833 DEBUG [src.steps.store] messages length is 0
2026-03-04 04:35:48.834 DEBUG [tests.conftest] Running fixture teardown: test_setup
2026-03-04 04:35:48.836 DEBUG [tests.conftest] Running fixture teardown: close_open_nodes
2026-03-04 04:35:48.836 DEBUG [src.node.waku_node] Stopping container with id a02883eeaa4d
2026-03-04 04:35:49.389 DEBUG [src.node.waku_node] Container stopped.
2026-03-04 04:35:49.389 DEBUG [src.node.waku_node] Stopping container with id 260bb726231c
2026-03-04 04:35:49.970 DEBUG [src.node.waku_node] Container stopped.
2026-03-04 04:35:49.974 DEBUG [tests.conftest] Running fixture teardown: check_waku_log_errors
2026-03-04 04:35:49.983 DEBUG [src.node.docker_mananger] No errors found in the waku logs.
2026-03-04 04:35:49.987 DEBUG [src.node.docker_mananger] No errors found in the waku logs.