|
| 1 | +2025-10-27 11:33:54.967 DEBUG [tests.conftest] Running fixture setup: test_id |
| 2 | +2025-10-27 11:33:54.967 DEBUG [tests.conftest] Running test: test_relay_unsubscribe_from_non_existing_pubsub_topic with id: 2025-10-27_11-33-54__99238d26-41c0-41ce-9521-b57d1df42998 |
| 3 | +2025-10-27 11:33:54.967 DEBUG [src.steps.common] Running fixture setup: common_setup |
| 4 | +2025-10-27 11:33:54.967 DEBUG [src.steps.relay] Running fixture setup: relay_setup |
| 5 | +2025-10-27 11:33:54.968 DEBUG [src.steps.relay] Running fixture setup: setup_main_relay_nodes |
| 6 | +2025-10-27 11:33:54.974 DEBUG [src.node.docker_mananger] Docker client initialized with image harbor.status.im/wakuorg/nwaku:v0.37.0-rc.4 |
| 7 | +2025-10-27 11:33:54.974 DEBUG [src.node.waku_node] WakuNode instance initialized with log path ./log/docker/node1_2025-10-27_11-33-54__99238d26-41c0-41ce-9521-b57d1df42998__harbor.status.im_wakuorg_nwaku:v0.37.0-rc.4.log |
| 8 | +2025-10-27 11:33:54.974 DEBUG [src.node.waku_node] Starting Node... |
| 9 | +2025-10-27 11:33:54.974 DEBUG [src.node.docker_mananger] Attempting to create or retrieve network waku |
| 10 | +2025-10-27 11:33:54.976 DEBUG [src.node.docker_mananger] Network waku already exists |
| 11 | +2025-10-27 11:33:54.976 DEBUG [src.node.docker_mananger] Generated random external IP 172.18.220.13 |
| 12 | +2025-10-27 11:33:54.976 DEBUG [src.node.docker_mananger] Generated ports ['58654', '58655', '58656', '58657', '58658'] |
| 13 | +2025-10-27 11:33:54.976 DEBUG [src.node.waku_node] RLN credentials were not set |
| 14 | +2025-10-27 11:33:54.976 INFO [src.node.waku_node] RLN credentials not set or credential store not available, starting without RLN |
| 15 | +2025-10-27 11:33:54.976 DEBUG [src.node.waku_node] Using volumes [] |
| 16 | +2025-10-27 11:33:54.977 DEBUG [src.node.docker_mananger] docker run -i -t -p 58654:58654 -p 58655:58655 -p 58656:58656 -p 58657:58657 -p 58658:58658 harbor.status.im/wakuorg/nwaku:v0.37.0-rc.4 --listen-address=0.0.0.0 --rest=true --rest-admin=true --websocket-support=true --log-level=TRACE --rest-relay-cache-capacity=100 --websocket-port=58656 --rest-port=58654 --tcp-port=58655 --discv5-udp-port=58657 --rest-address=0.0.0.0 --nat=extip:172.18.220.13 --peer-exchange=true --discv5-discovery=true --cluster-id=3 --nodekey=0d3f59931b6d7e6f2fb01ffb9262fe5bbbdb62b8dec05ebaedde889d481e1b73 --shard=0 --metrics-server=true --metrics-server-address=0.0.0.0 --metrics-server-port=58658 --metrics-logging=true --relay=true |
| 17 | +2025-10-27 11:33:55.160 DEBUG [src.node.docker_mananger] docker network connect --ip 172.18.220.13 waku 43a85888e073aefcc63a927ed1281f8c1dadbd33102314188bfae26889c8ce97 |
| 18 | +2025-10-27 11:33:55.190 DEBUG [src.node.docker_mananger] Container started with ID 43a85888e073. Setting up logs at ./log/docker/node1_2025-10-27_11-33-54__99238d26-41c0-41ce-9521-b57d1df42998__harbor.status.im_wakuorg_nwaku:v0.37.0-rc.4.log |
| 19 | +2025-10-27 11:33:55.191 DEBUG [src.node.waku_node] Started container from image harbor.status.im/wakuorg/nwaku:v0.37.0-rc.4. REST: 58654 |
| 20 | +2025-10-27 11:33:55.192 DEBUG [src.libs.common] Sleeping for 1 seconds |
| 21 | +2025-10-27 11:33:55.236 ERROR [src.node.docker_mananger] Max retries reached for container f4935b3c8369. Exiting log stream. |
| 22 | +2025-10-27 11:33:55.773 ERROR [src.node.docker_mananger] Max retries reached for container 7600e126f523. Exiting log stream. |
| 23 | +2025-10-27 11:33:56.192 INFO [src.node.api_clients.base_client] curl -v -X GET "http://127.0.0.1:58654/health" -H "Content-Type: application/json" -d 'None' |
| 24 | +2025-10-27 11:33:56.195 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"}]}' |
| 25 | +2025-10-27 11:33:56.196 INFO [src.node.waku_node] Node protocols are initialized !! |
| 26 | +2025-10-27 11:33:56.196 INFO [src.node.api_clients.base_client] curl -v -X GET "http://127.0.0.1:58654/debug/v1/info" -H "Content-Type: application/json" -d 'None' |
| 27 | +2025-10-27 11:33:56.198 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'{"listenAddresses":["/ip4/172.18.220.13/tcp/58655/p2p/16Uiu2HAm5wiYZJfs2vhjuZc4vkJiTnjGYv7P2V4mGjbDVEcWRaZR","/ip4/172.18.220.13/tcp/58656/ws/p2p/16Uiu2HAm5wiYZJfs2vhjuZc4vkJiTnjGYv7P2V4mGjbDVEcWRaZR"],"enrUri":"enr:-L24QLkRUKhFVW1tf-ExWzU4unREofdw099KKdg3iFIR5Nx4cUvjpyVtFyAo06iFWbb83ot7U7Q7na66_DMzOBi_Zi8CgmlkgnY0gmlwhKwS3A2KbXVsdGlhZGRyc5YACASsEtwNBuUfAAoErBLcDQblIN0DgnJzhQADAQAAiXNlY3AyNTZrMaECnEcxlPFZRYek2DwEPTp1DIPT8DCf_dH7GWwbvr1e06yDdGNwguUfg3VkcILlIYV3YWt1MgE"}' |
| 28 | +2025-10-27 11:33:56.199 INFO [src.node.waku_node] REST service is ready !! |
| 29 | +2025-10-27 11:33:56.205 DEBUG [src.node.docker_mananger] Docker client initialized with image harbor.status.im/wakuorg/nwaku:v0.37.0-rc.4 |
| 30 | +2025-10-27 11:33:56.205 DEBUG [src.node.waku_node] WakuNode instance initialized with log path ./log/docker/node2_2025-10-27_11-33-54__99238d26-41c0-41ce-9521-b57d1df42998__harbor.status.im_wakuorg_nwaku:v0.37.0-rc.4.log |
| 31 | +2025-10-27 11:33:56.205 DEBUG [src.node.waku_node] Starting Node... |
| 32 | +2025-10-27 11:33:56.205 DEBUG [src.node.docker_mananger] Attempting to create or retrieve network waku |
| 33 | +2025-10-27 11:33:56.207 DEBUG [src.node.docker_mananger] Network waku already exists |
| 34 | +2025-10-27 11:33:56.207 DEBUG [src.node.docker_mananger] Generated random external IP 172.18.137.108 |
| 35 | +2025-10-27 11:33:56.207 DEBUG [src.node.docker_mananger] Generated ports ['21387', '21388', '21389', '21390', '21391'] |
| 36 | +2025-10-27 11:33:56.207 DEBUG [src.node.waku_node] RLN credentials were not set |
| 37 | +2025-10-27 11:33:56.207 INFO [src.node.waku_node] RLN credentials not set or credential store not available, starting without RLN |
| 38 | +2025-10-27 11:33:56.207 DEBUG [src.node.waku_node] Using volumes [] |
| 39 | +2025-10-27 11:33:56.208 DEBUG [src.node.docker_mananger] docker run -i -t -p 21387:21387 -p 21388:21388 -p 21389:21389 -p 21390:21390 -p 21391:21391 harbor.status.im/wakuorg/nwaku:v0.37.0-rc.4 --listen-address=0.0.0.0 --rest=true --rest-admin=true --websocket-support=true --log-level=TRACE --rest-relay-cache-capacity=100 --websocket-port=21389 --rest-port=21387 --tcp-port=21388 --discv5-udp-port=21390 --rest-address=0.0.0.0 --nat=extip:172.18.137.108 --peer-exchange=true --discv5-discovery=true --cluster-id=3 --nodekey=0f3c9a0fe4251da6cd30b6bdea01bc21bc0cdfa5b8d34a0edf162eadde6de50f --shard=0 --metrics-server=true --metrics-server-address=0.0.0.0 --metrics-server-port=21391 --metrics-logging=true --relay=true --discv5-bootstrap-node=enr:-L24QLkRUKhFVW1tf-ExWzU4unREofdw099KKdg3iFIR5Nx4cUvjpyVtFyAo06iFWbb83ot7U7Q7na66_DMzOBi_Zi8CgmlkgnY0gmlwhKwS3A2KbXVsdGlhZGRyc5YACASsEtwNBuUfAAoErBLcDQblIN0DgnJzhQADAQAAiXNlY3AyNTZrMaECnEcxlPFZRYek2DwEPTp1DIPT8DCf_dH7GWwbvr1e06yDdGNwguUfg3VkcILlIYV3YWt1MgE |
| 40 | +2025-10-27 11:33:56.391 DEBUG [src.node.docker_mananger] docker network connect --ip 172.18.137.108 waku 7045ad18b65fe7124b78709dfa8beafc0e7c5abc08e3b63dc963e5d78f932314 |
| 41 | +2025-10-27 11:33:56.423 DEBUG [src.node.docker_mananger] Container started with ID 7045ad18b65f. Setting up logs at ./log/docker/node2_2025-10-27_11-33-54__99238d26-41c0-41ce-9521-b57d1df42998__harbor.status.im_wakuorg_nwaku:v0.37.0-rc.4.log |
| 42 | +2025-10-27 11:33:56.423 DEBUG [src.node.waku_node] Started container from image harbor.status.im/wakuorg/nwaku:v0.37.0-rc.4. REST: 21387 |
| 43 | +2025-10-27 11:33:56.423 DEBUG [src.libs.common] Sleeping for 1 seconds |
| 44 | +2025-10-27 11:33:57.424 INFO [src.node.api_clients.base_client] curl -v -X GET "http://127.0.0.1:21387/health" -H "Content-Type: application/json" -d 'None' |
| 45 | +2025-10-27 11:33:57.436 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":"READY"},{"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"}]}' |
| 46 | +2025-10-27 11:33:57.437 INFO [src.node.waku_node] Node protocols are initialized !! |
| 47 | +2025-10-27 11:33:57.438 INFO [src.node.api_clients.base_client] curl -v -X GET "http://127.0.0.1:21387/debug/v1/info" -H "Content-Type: application/json" -d 'None' |
| 48 | +2025-10-27 11:33:57.441 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'{"listenAddresses":["/ip4/172.18.137.108/tcp/21388/p2p/16Uiu2HAmT53VwAYjNMS3Hp5ePheF8dRZHMX1uDFHVXaH95aZC96U","/ip4/172.18.137.108/tcp/21389/ws/p2p/16Uiu2HAmT53VwAYjNMS3Hp5ePheF8dRZHMX1uDFHVXaH95aZC96U"],"enrUri":"enr:-L24QL0bXOIbXi9ezpJ7LxCALWtuHDac5qkx--hlkUrn0I-TNKvPoEixEqN2GsaIUZ9w3UFznl1fxXxnvLxV4gCid-wCgmlkgnY0gmlwhKwSiWyKbXVsdGlhZGRyc5YACASsEolsBlOMAAoErBKJbAZTjd0DgnJzhQADAQAAiXNlY3AyNTZrMaED1i0d9X76r5TkofNoG5521gNIJYJwAPexZ0cf2-2qxLWDdGNwglOMg3VkcIJTjoV3YWt1MgE"}' |
| 49 | +2025-10-27 11:33:57.442 INFO [src.node.waku_node] REST service is ready !! |
| 50 | +2025-10-27 11:33:57.442 INFO [src.node.api_clients.base_client] curl -v -X POST "http://127.0.0.1:21387/admin/v1/peers" -H "Content-Type: application/json" -d '["/ip4/172.18.220.13/tcp/58655/p2p/16Uiu2HAm5wiYZJfs2vhjuZc4vkJiTnjGYv7P2V4mGjbDVEcWRaZR"]' |
| 51 | +2025-10-27 11:33:57.445 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'OK' |
| 52 | +2025-10-27 11:33:57.446 INFO [src.node.api_clients.base_client] curl -v -X POST "http://127.0.0.1:58654/relay/v1/subscriptions" -H "Content-Type: application/json" -d '["/waku/2/rs/3/1"]' |
| 53 | +2025-10-27 11:33:57.449 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'OK' |
| 54 | +2025-10-27 11:33:57.450 INFO [src.node.api_clients.base_client] curl -v -X POST "http://127.0.0.1:21387/relay/v1/subscriptions" -H "Content-Type: application/json" -d '["/waku/2/rs/3/1"]' |
| 55 | +2025-10-27 11:33:57.455 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'OK' |
| 56 | +2025-10-27 11:33:57.455 INFO [src.node.api_clients.base_client] curl -v -X POST "http://127.0.0.1:58654/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)'}' |
| 57 | +2025-10-27 11:33:57.462 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'OK' |
| 58 | +2025-10-27 11:33:57.462 DEBUG [src.libs.common] Sleeping for 0.1 seconds |
| 59 | +2025-10-27 11:33:57.562 DEBUG [src.steps.relay] Checking that peer NODE_1:harbor.status.im/wakuorg/nwaku:v0.37.0-rc.4 can find the published message |
| 60 | +2025-10-27 11:33:57.563 INFO [src.node.api_clients.base_client] curl -v -X GET "http://127.0.0.1:58654/relay/v1/messages/%2Fwaku%2F2%2Frs%2F3%2F1" -H "Content-Type: application/json" -d 'None' |
| 61 | +2025-10-27 11:33:57.565 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'[{"payload":"UmVsYXkgd29ya3MhIQ==","contentTopic":"/test/1/waku-relay/proto","version":0,"timestamp":1761564837455821056,"ephemeral":false,"proof":""}]' |
| 62 | +2025-10-27 11:33:57.567 DEBUG [src.steps.relay] Checking that peer NODE_2:harbor.status.im/wakuorg/nwaku:v0.37.0-rc.4 can find the published message |
| 63 | +2025-10-27 11:33:57.567 INFO [src.node.api_clients.base_client] curl -v -X GET "http://127.0.0.1:21387/relay/v1/messages/%2Fwaku%2F2%2Frs%2F3%2F1" -H "Content-Type: application/json" -d 'None' |
| 64 | +2025-10-27 11:33:57.569 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'[{"payload":"UmVsYXkgd29ya3MhIQ==","contentTopic":"/test/1/waku-relay/proto","version":0,"timestamp":1761564837455821056,"ephemeral":false,"proof":""}]' |
| 65 | +2025-10-27 11:33:57.571 INFO [src.node.api_clients.base_client] curl -v -X DELETE "http://127.0.0.1:58654/relay/v1/subscriptions" -H "Content-Type: application/json" -d '["/waku/2/rs/3/1000"]' |
| 66 | +2025-10-27 11:33:57.573 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'OK' |
| 67 | +2025-10-27 11:33:57.573 INFO [src.node.api_clients.base_client] curl -v -X DELETE "http://127.0.0.1:21387/relay/v1/subscriptions" -H "Content-Type: application/json" -d '["/waku/2/rs/3/1000"]' |
| 68 | +2025-10-27 11:33:57.575 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'OK' |
| 69 | +2025-10-27 11:33:57.576 INFO [src.node.api_clients.base_client] curl -v -X POST "http://127.0.0.1:58654/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)'}' |
| 70 | +2025-10-27 11:33:57.579 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'OK' |
| 71 | +2025-10-27 11:33:57.579 DEBUG [src.libs.common] Sleeping for 0.1 seconds |
| 72 | +2025-10-27 11:33:57.680 DEBUG [src.steps.relay] Checking that peer NODE_1:harbor.status.im/wakuorg/nwaku:v0.37.0-rc.4 can find the published message |
| 73 | +2025-10-27 11:33:57.680 INFO [src.node.api_clients.base_client] curl -v -X GET "http://127.0.0.1:58654/relay/v1/messages/%2Fwaku%2F2%2Frs%2F3%2F1" -H "Content-Type: application/json" -d 'None' |
| 74 | +2025-10-27 11:33:57.683 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'[{"payload":"UmVsYXkgd29ya3MhIQ==","contentTopic":"/test/1/waku-relay/proto","version":0,"timestamp":1761564837576342515,"ephemeral":false,"proof":""}]' |
| 75 | +2025-10-27 11:33:57.684 DEBUG [src.steps.relay] Checking that peer NODE_2:harbor.status.im/wakuorg/nwaku:v0.37.0-rc.4 can find the published message |
| 76 | +2025-10-27 11:33:57.684 INFO [src.node.api_clients.base_client] curl -v -X GET "http://127.0.0.1:21387/relay/v1/messages/%2Fwaku%2F2%2Frs%2F3%2F1" -H "Content-Type: application/json" -d 'None' |
| 77 | +2025-10-27 11:33:57.687 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'[{"payload":"UmVsYXkgd29ya3MhIQ==","contentTopic":"/test/1/waku-relay/proto","version":0,"timestamp":1761564837576342515,"ephemeral":false,"proof":""}]' |
| 78 | +2025-10-27 11:33:57.689 DEBUG [tests.conftest] Running fixture teardown: test_setup |
| 79 | +2025-10-27 11:33:57.690 DEBUG [tests.conftest] Running fixture teardown: close_open_nodes |
| 80 | +2025-10-27 11:33:57.690 DEBUG [src.node.waku_node] Stopping container with id 43a85888e073 |
| 81 | +2025-10-27 11:33:58.241 DEBUG [src.node.waku_node] Container stopped. |
| 82 | +2025-10-27 11:33:58.241 DEBUG [src.node.waku_node] Stopping container with id 7045ad18b65f |
| 83 | +2025-10-27 11:33:58.755 DEBUG [src.node.waku_node] Container stopped. |
| 84 | +2025-10-27 11:33:58.756 DEBUG [tests.conftest] Running fixture teardown: check_waku_log_errors |
| 85 | +2025-10-27 11:33:58.763 DEBUG [src.node.docker_mananger] No errors found in the waku logs. |
| 86 | +2025-10-27 11:33:58.767 DEBUG [src.node.docker_mananger] No errors found in the waku logs. |
0 commit comments