2026-03-18 09:27:31 +00:00

192 lines
37 KiB
Plaintext

2026-03-18 09:08:08.319 DEBUG [tests.conftest] Running fixture setup: test_id
2026-03-18 09:08:08.320 DEBUG [tests.conftest] Running test: test_unsubscribe_from_some_content_topics[content_topic_list1] with id: 2026-03-18_09-08-08__a37ed001-48ba-442e-aeca-5b236b0c0c73
2026-03-18 09:08:08.321 DEBUG [src.steps.common] Running fixture setup: common_setup
2026-03-18 09:08:08.323 DEBUG [src.steps.relay] Running fixture setup: relay_setup
2026-03-18 09:08:08.324 DEBUG [src.steps.sharding] Running fixture setup: sharding_setup
2026-03-18 09:08:08.334 DEBUG [src.node.docker_mananger] Docker client initialized with image harbor.status.im/wakuorg/nwaku:v0.38.0-rc.0
2026-03-18 09:08:08.335 DEBUG [src.node.waku_node] WakuNode instance initialized with log path ./log/docker/node1_2026-03-18_09-08-08__a37ed001-48ba-442e-aeca-5b236b0c0c73__harbor.status.im_wakuorg_nwaku:v0.38.0-rc.0.log
2026-03-18 09:08:08.335 DEBUG [src.node.waku_node] Starting Node...
2026-03-18 09:08:08.335 DEBUG [src.node.docker_mananger] Attempting to create or retrieve network waku
2026-03-18 09:08:08.336 DEBUG [src.node.docker_mananger] Network waku already exists
2026-03-18 09:08:08.336 DEBUG [src.node.docker_mananger] Generated random external IP 172.18.75.74
2026-03-18 09:08:08.337 DEBUG [src.node.docker_mananger] Generated ports ['24486', '24487', '24488', '24489', '24490']
2026-03-18 09:08:08.337 DEBUG [src.node.waku_node] RLN credentials were not set
2026-03-18 09:08:08.337 INFO [src.node.waku_node] RLN credentials not set or credential store not available, starting without RLN
2026-03-18 09:08:08.337 DEBUG [src.node.waku_node] Using volumes []
2026-03-18 09:08:08.337 DEBUG [src.node.docker_mananger] docker run -i -t -p 24486:24486 -p 24487:24487 -p 24488:24488 -p 24489:24489 -p 24490:24490 harbor.status.im/wakuorg/nwaku:v0.38.0-rc.0 --listen-address=0.0.0.0 --rest=true --rest-admin=true --websocket-support=true --log-level=TRACE --rest-relay-cache-capacity=100 --websocket-port=24488 --rest-port=24486 --tcp-port=24487 --discv5-udp-port=24489 --rest-address=0.0.0.0 --nat=extip:172.18.75.74 --peer-exchange=true --discv5-discovery=true --cluster-id=199 --nodekey=951b5a0a8b393b2cf81376bcf0092bffcdd4ccdcedfebf5555ddb6ac5aba88af --shard=0 --metrics-server=true --metrics-server-address=0.0.0.0 --metrics-server-port=24490 --metrics-logging=true --relay=true --filter=true --content-topic=/myapp/1/latest/proto --num-shards-in-network=8
2026-03-18 09:08:08.543 DEBUG [src.node.docker_mananger] docker network connect --ip 172.18.75.74 waku 1216034b8fe681a47b5591bb1abc93d48c8b03cb006d87e972cfcb54d8da1160
2026-03-18 09:08:08.551 ERROR [src.node.docker_mananger] Max retries reached for container 74c48f41db34. Exiting log stream.
2026-03-18 09:08:08.580 DEBUG [src.node.docker_mananger] Container started with ID 1216034b8fe6. Setting up logs at ./log/docker/node1_2026-03-18_09-08-08__a37ed001-48ba-442e-aeca-5b236b0c0c73__harbor.status.im_wakuorg_nwaku:v0.38.0-rc.0.log
2026-03-18 09:08:08.580 DEBUG [src.node.waku_node] Started container from image harbor.status.im/wakuorg/nwaku:v0.38.0-rc.0. REST: 24486
2026-03-18 09:08:08.580 DEBUG [src.libs.common] Sleeping for 1 seconds
2026-03-18 09:08:09.138 ERROR [src.node.docker_mananger] Max retries reached for container b4c5b27f3a48. Exiting log stream.
2026-03-18 09:08:09.581 INFO [src.node.api_clients.base_client] curl -v -X GET "http://127.0.0.1:24486/health" -H "Content-Type: application/json" -d 'None'
2026-03-18 09:08:09.584 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_READY","desc":"Relay is not ready, filter will not be able to sort out messages"},{"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"},{"Rln Relay":"NOT_MOUNTED"}]}'
2026-03-18 09:08:09.584 INFO [src.node.waku_node] Node protocols are initialized !!
2026-03-18 09:08:09.584 INFO [src.node.api_clients.base_client] curl -v -X GET "http://127.0.0.1:24486/debug/v1/info" -H "Content-Type: application/json" -d 'None'
2026-03-18 09:08:09.587 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'{"listenAddresses":["/ip4/172.18.75.74/tcp/24487/p2p/16Uiu2HAmDf8XSNUdUnpW1aEPf9sSzzqVP5R4ubLQXV8SXeG4ZRcn","/ip4/172.18.75.74/tcp/24488/ws/p2p/16Uiu2HAmDf8XSNUdUnpW1aEPf9sSzzqVP5R4ubLQXV8SXeG4ZRcn"],"enrUri":"enr:-L24QHg-Au-oPE30PNQ50sipA1rYFCwRvaXdZG3L0dNqpWRjDxacNTjJDjEP-w7IMCXgJGf-ukliYTp7N9rDn3MO1sACgmlkgnY0gmlwhKwSS0qKbXVsdGlhZGRyc5YACASsEktKBl-nAAoErBJLSgZfqN0DgnJzhQDHAQAAiXNlY3AyNTZrMaEDDuTuLnQzEbgENa-qXNT0U9lr7M-_YItcVyXsKM0-tUuDdGNwgl-ng3VkcIJfqYV3YWt1MgU"}'
2026-03-18 09:08:09.587 INFO [src.node.waku_node] REST service is ready !!
2026-03-18 09:08:09.595 DEBUG [src.node.docker_mananger] Docker client initialized with image harbor.status.im/wakuorg/nwaku:v0.38.0-rc.0
2026-03-18 09:08:09.595 DEBUG [src.node.waku_node] WakuNode instance initialized with log path ./log/docker/node2_2026-03-18_09-08-08__a37ed001-48ba-442e-aeca-5b236b0c0c73__harbor.status.im_wakuorg_nwaku:v0.38.0-rc.0.log
2026-03-18 09:08:09.595 DEBUG [src.node.waku_node] Starting Node...
2026-03-18 09:08:09.595 DEBUG [src.node.docker_mananger] Attempting to create or retrieve network waku
2026-03-18 09:08:09.597 DEBUG [src.node.docker_mananger] Network waku already exists
2026-03-18 09:08:09.597 DEBUG [src.node.docker_mananger] Generated random external IP 172.18.101.110
2026-03-18 09:08:09.597 DEBUG [src.node.docker_mananger] Generated ports ['31746', '31747', '31748', '31749', '31750']
2026-03-18 09:08:09.597 DEBUG [src.node.waku_node] RLN credentials were not set
2026-03-18 09:08:09.597 INFO [src.node.waku_node] RLN credentials not set or credential store not available, starting without RLN
2026-03-18 09:08:09.598 DEBUG [src.node.waku_node] Using volumes []
2026-03-18 09:08:09.598 DEBUG [src.node.docker_mananger] docker run -i -t -p 31746:31746 -p 31747:31747 -p 31748:31748 -p 31749:31749 -p 31750:31750 harbor.status.im/wakuorg/nwaku:v0.38.0-rc.0 --listen-address=0.0.0.0 --rest=true --rest-admin=true --websocket-support=true --log-level=TRACE --rest-relay-cache-capacity=100 --websocket-port=31748 --rest-port=31746 --tcp-port=31747 --discv5-udp-port=31749 --rest-address=0.0.0.0 --nat=extip:172.18.101.110 --peer-exchange=true --discv5-discovery=true --cluster-id=199 --nodekey=99ac1a6fc21cbeff581df9dbfbd4fce133e0faba2b41cbb1dc8598bbed6951ea --shard=0 --metrics-server=true --metrics-server-address=0.0.0.0 --metrics-server-port=31750 --metrics-logging=true --relay=true --discv5-bootstrap-node=enr:-L24QHg-Au-oPE30PNQ50sipA1rYFCwRvaXdZG3L0dNqpWRjDxacNTjJDjEP-w7IMCXgJGf-ukliYTp7N9rDn3MO1sACgmlkgnY0gmlwhKwSS0qKbXVsdGlhZGRyc5YACASsEktKBl-nAAoErBJLSgZfqN0DgnJzhQDHAQAAiXNlY3AyNTZrMaEDDuTuLnQzEbgENa-qXNT0U9lr7M-_YItcVyXsKM0-tUuDdGNwgl-ng3VkcIJfqYV3YWt1MgU --content-topic=/myapp/1/latest/proto --num-shards-in-network=8
2026-03-18 09:08:09.813 DEBUG [src.node.docker_mananger] docker network connect --ip 172.18.101.110 waku aaa95ad0543d119233fef8e044215f86e909bd852338364f642400223075727a
2026-03-18 09:08:09.865 DEBUG [src.node.docker_mananger] Container started with ID aaa95ad0543d. Setting up logs at ./log/docker/node2_2026-03-18_09-08-08__a37ed001-48ba-442e-aeca-5b236b0c0c73__harbor.status.im_wakuorg_nwaku:v0.38.0-rc.0.log
2026-03-18 09:08:09.866 DEBUG [src.node.waku_node] Started container from image harbor.status.im/wakuorg/nwaku:v0.38.0-rc.0. REST: 31746
2026-03-18 09:08:09.867 DEBUG [src.libs.common] Sleeping for 1 seconds
2026-03-18 09:08:10.867 INFO [src.node.api_clients.base_client] curl -v -X GET "http://127.0.0.1:31746/health" -H "Content-Type: application/json" -d 'None'
2026-03-18 09:08:10.876 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":"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"},{"Rln Relay":"NOT_MOUNTED"}]}'
2026-03-18 09:08:10.878 INFO [src.node.waku_node] Node protocols are initialized !!
2026-03-18 09:08:10.878 INFO [src.node.api_clients.base_client] curl -v -X GET "http://127.0.0.1:31746/debug/v1/info" -H "Content-Type: application/json" -d 'None'
2026-03-18 09:08:10.892 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'{"listenAddresses":["/ip4/172.18.101.110/tcp/31747/p2p/16Uiu2HAmGCRWy7Bwz2qpUoFqEX7u5GLCdEeRduRFEkj7AphvtD2r","/ip4/172.18.101.110/tcp/31748/ws/p2p/16Uiu2HAmGCRWy7Bwz2qpUoFqEX7u5GLCdEeRduRFEkj7AphvtD2r"],"enrUri":"enr:-L24QIuxs_Wu-A4o_gl8Ii4ue9Dkk5XeKLJGbyEKuFrpNcHnPYwJqNZlB1oJdtjmsDY8DkO2ozG5csiN69ogV7gH4N4CgmlkgnY0gmlwhKwSZW6KbXVsdGlhZGRyc5YACASsEmVuBnwDAAoErBJlbgZ8BN0DgnJzhQDHAQAAiXNlY3AyNTZrMaEDNKCFqiuQIEQR6PbJK-Szg4ATk2F18b-d7F2Oesgv66ODdGNwgnwDg3VkcIJ8BYV3YWt1MgE"}'
2026-03-18 09:08:10.892 INFO [src.node.waku_node] REST service is ready !!
2026-03-18 09:08:10.893 INFO [src.node.api_clients.base_client] curl -v -X POST "http://127.0.0.1:31746/admin/v1/peers" -H "Content-Type: application/json" -d '["/ip4/172.18.75.74/tcp/24487/p2p/16Uiu2HAmDf8XSNUdUnpW1aEPf9sSzzqVP5R4ubLQXV8SXeG4ZRcn"]'
2026-03-18 09:08:10.898 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'OK'
2026-03-18 09:08:10.899 INFO [src.node.api_clients.base_client] curl -v -X POST "http://127.0.0.1:24486/relay/v1/auto/subscriptions" -H "Content-Type: application/json" -d '["/myapp/1/latest/proto", "/waku/2/content/test.js", "/app/22/sometopic/someencoding", "/toychat/2/huilong/proto", "/statusim/1/community/cbor", "/app/27/sometopic/someencoding", "/app/29/sometopic/someencoding", "/app/20/sometopic/someencoding"]'
2026-03-18 09:08:10.906 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'OK'
2026-03-18 09:08:10.907 INFO [src.node.api_clients.base_client] curl -v -X POST "http://127.0.0.1:31746/relay/v1/auto/subscriptions" -H "Content-Type: application/json" -d '["/myapp/1/latest/proto", "/waku/2/content/test.js", "/app/22/sometopic/someencoding", "/toychat/2/huilong/proto", "/statusim/1/community/cbor", "/app/27/sometopic/someencoding", "/app/29/sometopic/someencoding", "/app/20/sometopic/someencoding"]'
2026-03-18 09:08:10.911 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'OK'
2026-03-18 09:08:10.912 INFO [src.node.api_clients.base_client] curl -v -X POST "http://127.0.0.1:24486/relay/v1/auto/messages" -H "Content-Type: application/json" -d '{"payload": "U2hhcmRpbmcgd29ya3MhIQ==", "contentTopic": "/myapp/1/latest/proto", "timestamp": '$(date +%s%N)'}'
2026-03-18 09:08:10.918 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'OK'
2026-03-18 09:08:10.919 DEBUG [src.libs.common] Sleeping for 0.1 seconds
2026-03-18 09:08:11.019 DEBUG [src.steps.sharding] Checking that peer NODE_1:harbor.status.im/wakuorg/nwaku:v0.38.0-rc.0 can find the published message
2026-03-18 09:08:11.020 INFO [src.node.api_clients.base_client] curl -v -X GET "http://127.0.0.1:24486/relay/v1/auto/messages/%2Fmyapp%2F1%2Flatest%2Fproto" -H "Content-Type: application/json" -d 'None'
2026-03-18 09:08:11.023 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'[{"payload":"U2hhcmRpbmcgd29ya3MhIQ==","contentTopic":"/myapp/1/latest/proto","version":0,"timestamp":1773824890912060603,"ephemeral":false,"proof":""}]'
2026-03-18 09:08:11.024 DEBUG [src.steps.sharding] Checking that peer NODE_2:harbor.status.im/wakuorg/nwaku:v0.38.0-rc.0 can find the published message
2026-03-18 09:08:11.025 INFO [src.node.api_clients.base_client] curl -v -X GET "http://127.0.0.1:31746/relay/v1/auto/messages/%2Fmyapp%2F1%2Flatest%2Fproto" -H "Content-Type: application/json" -d 'None'
2026-03-18 09:08:11.027 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'[{"payload":"U2hhcmRpbmcgd29ya3MhIQ==","contentTopic":"/myapp/1/latest/proto","version":0,"timestamp":1773824890912060603,"ephemeral":false,"proof":""}]'
2026-03-18 09:08:11.029 INFO [src.node.api_clients.base_client] curl -v -X POST "http://127.0.0.1:24486/relay/v1/auto/messages" -H "Content-Type: application/json" -d '{"payload": "U2hhcmRpbmcgd29ya3MhIQ==", "contentTopic": "/waku/2/content/test.js", "timestamp": '$(date +%s%N)'}'
2026-03-18 09:08:11.033 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'OK'
2026-03-18 09:08:11.034 DEBUG [src.libs.common] Sleeping for 0.1 seconds
2026-03-18 09:08:11.135 DEBUG [src.steps.sharding] Checking that peer NODE_1:harbor.status.im/wakuorg/nwaku:v0.38.0-rc.0 can find the published message
2026-03-18 09:08:11.135 INFO [src.node.api_clients.base_client] curl -v -X GET "http://127.0.0.1:24486/relay/v1/auto/messages/%2Fwaku%2F2%2Fcontent%2Ftest.js" -H "Content-Type: application/json" -d 'None'
2026-03-18 09:08:11.138 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'[{"payload":"U2hhcmRpbmcgd29ya3MhIQ==","contentTopic":"/waku/2/content/test.js","version":0,"timestamp":1773824891029175323,"ephemeral":false,"proof":""}]'
2026-03-18 09:08:11.139 DEBUG [src.steps.sharding] Checking that peer NODE_2:harbor.status.im/wakuorg/nwaku:v0.38.0-rc.0 can find the published message
2026-03-18 09:08:11.140 INFO [src.node.api_clients.base_client] curl -v -X GET "http://127.0.0.1:31746/relay/v1/auto/messages/%2Fwaku%2F2%2Fcontent%2Ftest.js" -H "Content-Type: application/json" -d 'None'
2026-03-18 09:08:11.142 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'[{"payload":"U2hhcmRpbmcgd29ya3MhIQ==","contentTopic":"/waku/2/content/test.js","version":0,"timestamp":1773824891029175323,"ephemeral":false,"proof":""}]'
2026-03-18 09:08:11.144 INFO [src.node.api_clients.base_client] curl -v -X POST "http://127.0.0.1:24486/relay/v1/auto/messages" -H "Content-Type: application/json" -d '{"payload": "U2hhcmRpbmcgd29ya3MhIQ==", "contentTopic": "/app/22/sometopic/someencoding", "timestamp": '$(date +%s%N)'}'
2026-03-18 09:08:11.149 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'OK'
2026-03-18 09:08:11.149 DEBUG [src.libs.common] Sleeping for 0.1 seconds
2026-03-18 09:08:11.249 DEBUG [src.steps.sharding] Checking that peer NODE_1:harbor.status.im/wakuorg/nwaku:v0.38.0-rc.0 can find the published message
2026-03-18 09:08:11.250 INFO [src.node.api_clients.base_client] curl -v -X GET "http://127.0.0.1:24486/relay/v1/auto/messages/%2Fapp%2F22%2Fsometopic%2Fsomeencoding" -H "Content-Type: application/json" -d 'None'
2026-03-18 09:08:11.253 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'[{"payload":"U2hhcmRpbmcgd29ya3MhIQ==","contentTopic":"/app/22/sometopic/someencoding","version":0,"timestamp":1773824891144186089,"ephemeral":false,"proof":""}]'
2026-03-18 09:08:11.254 DEBUG [src.steps.sharding] Checking that peer NODE_2:harbor.status.im/wakuorg/nwaku:v0.38.0-rc.0 can find the published message
2026-03-18 09:08:11.255 INFO [src.node.api_clients.base_client] curl -v -X GET "http://127.0.0.1:31746/relay/v1/auto/messages/%2Fapp%2F22%2Fsometopic%2Fsomeencoding" -H "Content-Type: application/json" -d 'None'
2026-03-18 09:08:11.257 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'[{"payload":"U2hhcmRpbmcgd29ya3MhIQ==","contentTopic":"/app/22/sometopic/someencoding","version":0,"timestamp":1773824891144186089,"ephemeral":false,"proof":""}]'
2026-03-18 09:08:11.259 INFO [src.node.api_clients.base_client] curl -v -X POST "http://127.0.0.1:24486/relay/v1/auto/messages" -H "Content-Type: application/json" -d '{"payload": "U2hhcmRpbmcgd29ya3MhIQ==", "contentTopic": "/toychat/2/huilong/proto", "timestamp": '$(date +%s%N)'}'
2026-03-18 09:08:11.264 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'OK'
2026-03-18 09:08:11.265 DEBUG [src.libs.common] Sleeping for 0.1 seconds
2026-03-18 09:08:11.365 DEBUG [src.steps.sharding] Checking that peer NODE_1:harbor.status.im/wakuorg/nwaku:v0.38.0-rc.0 can find the published message
2026-03-18 09:08:11.366 INFO [src.node.api_clients.base_client] curl -v -X GET "http://127.0.0.1:24486/relay/v1/auto/messages/%2Ftoychat%2F2%2Fhuilong%2Fproto" -H "Content-Type: application/json" -d 'None'
2026-03-18 09:08:11.369 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'[{"payload":"U2hhcmRpbmcgd29ya3MhIQ==","contentTopic":"/toychat/2/huilong/proto","version":0,"timestamp":1773824891258930742,"ephemeral":false,"proof":""}]'
2026-03-18 09:08:11.370 DEBUG [src.steps.sharding] Checking that peer NODE_2:harbor.status.im/wakuorg/nwaku:v0.38.0-rc.0 can find the published message
2026-03-18 09:08:11.370 INFO [src.node.api_clients.base_client] curl -v -X GET "http://127.0.0.1:31746/relay/v1/auto/messages/%2Ftoychat%2F2%2Fhuilong%2Fproto" -H "Content-Type: application/json" -d 'None'
2026-03-18 09:08:11.373 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'[{"payload":"U2hhcmRpbmcgd29ya3MhIQ==","contentTopic":"/toychat/2/huilong/proto","version":0,"timestamp":1773824891258930742,"ephemeral":false,"proof":""}]'
2026-03-18 09:08:11.375 INFO [src.node.api_clients.base_client] curl -v -X POST "http://127.0.0.1:24486/relay/v1/auto/messages" -H "Content-Type: application/json" -d '{"payload": "U2hhcmRpbmcgd29ya3MhIQ==", "contentTopic": "/statusim/1/community/cbor", "timestamp": '$(date +%s%N)'}'
2026-03-18 09:08:11.379 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'OK'
2026-03-18 09:08:11.379 DEBUG [src.libs.common] Sleeping for 0.1 seconds
2026-03-18 09:08:11.480 DEBUG [src.steps.sharding] Checking that peer NODE_1:harbor.status.im/wakuorg/nwaku:v0.38.0-rc.0 can find the published message
2026-03-18 09:08:11.481 INFO [src.node.api_clients.base_client] curl -v -X GET "http://127.0.0.1:24486/relay/v1/auto/messages/%2Fstatusim%2F1%2Fcommunity%2Fcbor" -H "Content-Type: application/json" -d 'None'
2026-03-18 09:08:11.483 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'[{"payload":"U2hhcmRpbmcgd29ya3MhIQ==","contentTopic":"/statusim/1/community/cbor","version":0,"timestamp":1773824891374903254,"ephemeral":false,"proof":""}]'
2026-03-18 09:08:11.485 DEBUG [src.steps.sharding] Checking that peer NODE_2:harbor.status.im/wakuorg/nwaku:v0.38.0-rc.0 can find the published message
2026-03-18 09:08:11.485 INFO [src.node.api_clients.base_client] curl -v -X GET "http://127.0.0.1:31746/relay/v1/auto/messages/%2Fstatusim%2F1%2Fcommunity%2Fcbor" -H "Content-Type: application/json" -d 'None'
2026-03-18 09:08:11.488 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'[{"payload":"U2hhcmRpbmcgd29ya3MhIQ==","contentTopic":"/statusim/1/community/cbor","version":0,"timestamp":1773824891374903254,"ephemeral":false,"proof":""}]'
2026-03-18 09:08:11.489 INFO [src.node.api_clients.base_client] curl -v -X POST "http://127.0.0.1:24486/relay/v1/auto/messages" -H "Content-Type: application/json" -d '{"payload": "U2hhcmRpbmcgd29ya3MhIQ==", "contentTopic": "/app/27/sometopic/someencoding", "timestamp": '$(date +%s%N)'}'
2026-03-18 09:08:11.495 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'OK'
2026-03-18 09:08:11.495 DEBUG [src.libs.common] Sleeping for 0.1 seconds
2026-03-18 09:08:11.595 DEBUG [src.steps.sharding] Checking that peer NODE_1:harbor.status.im/wakuorg/nwaku:v0.38.0-rc.0 can find the published message
2026-03-18 09:08:11.596 INFO [src.node.api_clients.base_client] curl -v -X GET "http://127.0.0.1:24486/relay/v1/auto/messages/%2Fapp%2F27%2Fsometopic%2Fsomeencoding" -H "Content-Type: application/json" -d 'None'
2026-03-18 09:08:11.599 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'[{"payload":"U2hhcmRpbmcgd29ya3MhIQ==","contentTopic":"/app/27/sometopic/someencoding","version":0,"timestamp":1773824891489654972,"ephemeral":false,"proof":""}]'
2026-03-18 09:08:11.600 DEBUG [src.steps.sharding] Checking that peer NODE_2:harbor.status.im/wakuorg/nwaku:v0.38.0-rc.0 can find the published message
2026-03-18 09:08:11.601 INFO [src.node.api_clients.base_client] curl -v -X GET "http://127.0.0.1:31746/relay/v1/auto/messages/%2Fapp%2F27%2Fsometopic%2Fsomeencoding" -H "Content-Type: application/json" -d 'None'
2026-03-18 09:08:11.603 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'[{"payload":"U2hhcmRpbmcgd29ya3MhIQ==","contentTopic":"/app/27/sometopic/someencoding","version":0,"timestamp":1773824891489654972,"ephemeral":false,"proof":""}]'
2026-03-18 09:08:11.604 INFO [src.node.api_clients.base_client] curl -v -X POST "http://127.0.0.1:24486/relay/v1/auto/messages" -H "Content-Type: application/json" -d '{"payload": "U2hhcmRpbmcgd29ya3MhIQ==", "contentTopic": "/app/29/sometopic/someencoding", "timestamp": '$(date +%s%N)'}'
2026-03-18 09:08:11.609 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'OK'
2026-03-18 09:08:11.610 DEBUG [src.libs.common] Sleeping for 0.1 seconds
2026-03-18 09:08:11.711 DEBUG [src.steps.sharding] Checking that peer NODE_1:harbor.status.im/wakuorg/nwaku:v0.38.0-rc.0 can find the published message
2026-03-18 09:08:11.711 INFO [src.node.api_clients.base_client] curl -v -X GET "http://127.0.0.1:24486/relay/v1/auto/messages/%2Fapp%2F29%2Fsometopic%2Fsomeencoding" -H "Content-Type: application/json" -d 'None'
2026-03-18 09:08:11.714 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'[{"payload":"U2hhcmRpbmcgd29ya3MhIQ==","contentTopic":"/app/29/sometopic/someencoding","version":0,"timestamp":1773824891604827701,"ephemeral":false,"proof":""}]'
2026-03-18 09:08:11.715 DEBUG [src.steps.sharding] Checking that peer NODE_2:harbor.status.im/wakuorg/nwaku:v0.38.0-rc.0 can find the published message
2026-03-18 09:08:11.716 INFO [src.node.api_clients.base_client] curl -v -X GET "http://127.0.0.1:31746/relay/v1/auto/messages/%2Fapp%2F29%2Fsometopic%2Fsomeencoding" -H "Content-Type: application/json" -d 'None'
2026-03-18 09:08:11.718 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'[{"payload":"U2hhcmRpbmcgd29ya3MhIQ==","contentTopic":"/app/29/sometopic/someencoding","version":0,"timestamp":1773824891604827701,"ephemeral":false,"proof":""}]'
2026-03-18 09:08:11.720 INFO [src.node.api_clients.base_client] curl -v -X POST "http://127.0.0.1:24486/relay/v1/auto/messages" -H "Content-Type: application/json" -d '{"payload": "U2hhcmRpbmcgd29ya3MhIQ==", "contentTopic": "/app/20/sometopic/someencoding", "timestamp": '$(date +%s%N)'}'
2026-03-18 09:08:11.725 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'OK'
2026-03-18 09:08:11.725 DEBUG [src.libs.common] Sleeping for 0.1 seconds
2026-03-18 09:08:11.826 DEBUG [src.steps.sharding] Checking that peer NODE_1:harbor.status.im/wakuorg/nwaku:v0.38.0-rc.0 can find the published message
2026-03-18 09:08:11.826 INFO [src.node.api_clients.base_client] curl -v -X GET "http://127.0.0.1:24486/relay/v1/auto/messages/%2Fapp%2F20%2Fsometopic%2Fsomeencoding" -H "Content-Type: application/json" -d 'None'
2026-03-18 09:08:11.830 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'[{"payload":"U2hhcmRpbmcgd29ya3MhIQ==","contentTopic":"/app/20/sometopic/someencoding","version":0,"timestamp":1773824891719982254,"ephemeral":false,"proof":""}]'
2026-03-18 09:08:11.832 DEBUG [src.steps.sharding] Checking that peer NODE_2:harbor.status.im/wakuorg/nwaku:v0.38.0-rc.0 can find the published message
2026-03-18 09:08:11.833 INFO [src.node.api_clients.base_client] curl -v -X GET "http://127.0.0.1:31746/relay/v1/auto/messages/%2Fapp%2F20%2Fsometopic%2Fsomeencoding" -H "Content-Type: application/json" -d 'None'
2026-03-18 09:08:11.835 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'[{"payload":"U2hhcmRpbmcgd29ya3MhIQ==","contentTopic":"/app/20/sometopic/someencoding","version":0,"timestamp":1773824891719982254,"ephemeral":false,"proof":""}]'
2026-03-18 09:08:11.837 INFO [src.node.api_clients.base_client] curl -v -X DELETE "http://127.0.0.1:24486/relay/v1/auto/subscriptions" -H "Content-Type: application/json" -d '["/myapp/1/latest/proto", "/waku/2/content/test.js", "/app/22/sometopic/someencoding"]'
2026-03-18 09:08:11.847 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'OK'
2026-03-18 09:08:11.848 INFO [src.node.api_clients.base_client] curl -v -X DELETE "http://127.0.0.1:31746/relay/v1/auto/subscriptions" -H "Content-Type: application/json" -d '["/myapp/1/latest/proto", "/waku/2/content/test.js", "/app/22/sometopic/someencoding"]'
2026-03-18 09:08:11.853 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'OK'
2026-03-18 09:08:11.855 INFO [src.node.api_clients.base_client] curl -v -X POST "http://127.0.0.1:24486/relay/v1/auto/messages" -H "Content-Type: application/json" -d '{"payload": "U2hhcmRpbmcgd29ya3MhIQ==", "contentTopic": "/myapp/1/latest/proto", "timestamp": '$(date +%s%N)'}'
2026-03-18 09:08:11.858 ERROR [src.node.api_clients.base_client] HTTP error occurred: 400 Client Error: Bad Request for url: http://127.0.0.1:24486/relay/v1/auto/messages. Response content: b'Failed to publish: publish failed in relay: NoPeersToPublish'
2026-03-18 09:08:11.860 INFO [src.node.api_clients.base_client] curl -v -X POST "http://127.0.0.1:24486/relay/v1/auto/messages" -H "Content-Type: application/json" -d '{"payload": "U2hhcmRpbmcgd29ya3MhIQ==", "contentTopic": "/waku/2/content/test.js", "timestamp": '$(date +%s%N)'}'
2026-03-18 09:08:11.863 ERROR [src.node.api_clients.base_client] HTTP error occurred: 400 Client Error: Bad Request for url: http://127.0.0.1:24486/relay/v1/auto/messages. Response content: b'Failed to publish: publish failed in relay: NoPeersToPublish'
2026-03-18 09:08:11.865 INFO [src.node.api_clients.base_client] curl -v -X POST "http://127.0.0.1:24486/relay/v1/auto/messages" -H "Content-Type: application/json" -d '{"payload": "U2hhcmRpbmcgd29ya3MhIQ==", "contentTopic": "/app/22/sometopic/someencoding", "timestamp": '$(date +%s%N)'}'
2026-03-18 09:08:11.867 ERROR [src.node.api_clients.base_client] HTTP error occurred: 400 Client Error: Bad Request for url: http://127.0.0.1:24486/relay/v1/auto/messages. Response content: b'Failed to publish: publish failed in relay: NoPeersToPublish'
2026-03-18 09:08:11.869 INFO [src.node.api_clients.base_client] curl -v -X POST "http://127.0.0.1:24486/relay/v1/auto/messages" -H "Content-Type: application/json" -d '{"payload": "U2hhcmRpbmcgd29ya3MhIQ==", "contentTopic": "/toychat/2/huilong/proto", "timestamp": '$(date +%s%N)'}'
2026-03-18 09:08:11.873 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'OK'
2026-03-18 09:08:11.873 DEBUG [src.libs.common] Sleeping for 0.1 seconds
2026-03-18 09:08:11.974 DEBUG [src.steps.sharding] Checking that peer NODE_1:harbor.status.im/wakuorg/nwaku:v0.38.0-rc.0 can find the published message
2026-03-18 09:08:11.974 INFO [src.node.api_clients.base_client] curl -v -X GET "http://127.0.0.1:24486/relay/v1/auto/messages/%2Ftoychat%2F2%2Fhuilong%2Fproto" -H "Content-Type: application/json" -d 'None'
2026-03-18 09:08:11.977 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'[{"payload":"U2hhcmRpbmcgd29ya3MhIQ==","contentTopic":"/toychat/2/huilong/proto","version":0,"timestamp":1773824891868946980,"ephemeral":false,"proof":""}]'
2026-03-18 09:08:11.978 DEBUG [src.steps.sharding] Checking that peer NODE_2:harbor.status.im/wakuorg/nwaku:v0.38.0-rc.0 can find the published message
2026-03-18 09:08:11.979 INFO [src.node.api_clients.base_client] curl -v -X GET "http://127.0.0.1:31746/relay/v1/auto/messages/%2Ftoychat%2F2%2Fhuilong%2Fproto" -H "Content-Type: application/json" -d 'None'
2026-03-18 09:08:11.981 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'[{"payload":"U2hhcmRpbmcgd29ya3MhIQ==","contentTopic":"/toychat/2/huilong/proto","version":0,"timestamp":1773824891868946980,"ephemeral":false,"proof":""}]'
2026-03-18 09:08:11.983 INFO [src.node.api_clients.base_client] curl -v -X POST "http://127.0.0.1:24486/relay/v1/auto/messages" -H "Content-Type: application/json" -d '{"payload": "U2hhcmRpbmcgd29ya3MhIQ==", "contentTopic": "/statusim/1/community/cbor", "timestamp": '$(date +%s%N)'}'
2026-03-18 09:08:11.987 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'OK'
2026-03-18 09:08:11.988 DEBUG [src.libs.common] Sleeping for 0.1 seconds
2026-03-18 09:08:12.088 DEBUG [src.steps.sharding] Checking that peer NODE_1:harbor.status.im/wakuorg/nwaku:v0.38.0-rc.0 can find the published message
2026-03-18 09:08:12.089 INFO [src.node.api_clients.base_client] curl -v -X GET "http://127.0.0.1:24486/relay/v1/auto/messages/%2Fstatusim%2F1%2Fcommunity%2Fcbor" -H "Content-Type: application/json" -d 'None'
2026-03-18 09:08:12.091 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'[{"payload":"U2hhcmRpbmcgd29ya3MhIQ==","contentTopic":"/statusim/1/community/cbor","version":0,"timestamp":1773824891982977672,"ephemeral":false,"proof":""}]'
2026-03-18 09:08:12.094 DEBUG [src.steps.sharding] Checking that peer NODE_2:harbor.status.im/wakuorg/nwaku:v0.38.0-rc.0 can find the published message
2026-03-18 09:08:12.094 INFO [src.node.api_clients.base_client] curl -v -X GET "http://127.0.0.1:31746/relay/v1/auto/messages/%2Fstatusim%2F1%2Fcommunity%2Fcbor" -H "Content-Type: application/json" -d 'None'
2026-03-18 09:08:12.097 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'[{"payload":"U2hhcmRpbmcgd29ya3MhIQ==","contentTopic":"/statusim/1/community/cbor","version":0,"timestamp":1773824891982977672,"ephemeral":false,"proof":""}]'
2026-03-18 09:08:12.098 INFO [src.node.api_clients.base_client] curl -v -X POST "http://127.0.0.1:24486/relay/v1/auto/messages" -H "Content-Type: application/json" -d '{"payload": "U2hhcmRpbmcgd29ya3MhIQ==", "contentTopic": "/app/27/sometopic/someencoding", "timestamp": '$(date +%s%N)'}'
2026-03-18 09:08:12.103 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'OK'
2026-03-18 09:08:12.104 DEBUG [src.libs.common] Sleeping for 0.1 seconds
2026-03-18 09:08:12.204 DEBUG [src.steps.sharding] Checking that peer NODE_1:harbor.status.im/wakuorg/nwaku:v0.38.0-rc.0 can find the published message
2026-03-18 09:08:12.205 INFO [src.node.api_clients.base_client] curl -v -X GET "http://127.0.0.1:24486/relay/v1/auto/messages/%2Fapp%2F27%2Fsometopic%2Fsomeencoding" -H "Content-Type: application/json" -d 'None'
2026-03-18 09:08:12.207 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'[{"payload":"U2hhcmRpbmcgd29ya3MhIQ==","contentTopic":"/app/27/sometopic/someencoding","version":0,"timestamp":1773824892098691218,"ephemeral":false,"proof":""}]'
2026-03-18 09:08:12.209 DEBUG [src.steps.sharding] Checking that peer NODE_2:harbor.status.im/wakuorg/nwaku:v0.38.0-rc.0 can find the published message
2026-03-18 09:08:12.209 INFO [src.node.api_clients.base_client] curl -v -X GET "http://127.0.0.1:31746/relay/v1/auto/messages/%2Fapp%2F27%2Fsometopic%2Fsomeencoding" -H "Content-Type: application/json" -d 'None'
2026-03-18 09:08:12.235 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'[{"payload":"U2hhcmRpbmcgd29ya3MhIQ==","contentTopic":"/app/27/sometopic/someencoding","version":0,"timestamp":1773824892098691218,"ephemeral":false,"proof":""}]'
2026-03-18 09:08:12.236 INFO [src.node.api_clients.base_client] curl -v -X POST "http://127.0.0.1:24486/relay/v1/auto/messages" -H "Content-Type: application/json" -d '{"payload": "U2hhcmRpbmcgd29ya3MhIQ==", "contentTopic": "/app/29/sometopic/someencoding", "timestamp": '$(date +%s%N)'}'
2026-03-18 09:08:12.242 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'OK'
2026-03-18 09:08:12.242 DEBUG [src.libs.common] Sleeping for 0.1 seconds
2026-03-18 09:08:12.343 DEBUG [src.steps.sharding] Checking that peer NODE_1:harbor.status.im/wakuorg/nwaku:v0.38.0-rc.0 can find the published message
2026-03-18 09:08:12.344 INFO [src.node.api_clients.base_client] curl -v -X GET "http://127.0.0.1:24486/relay/v1/auto/messages/%2Fapp%2F29%2Fsometopic%2Fsomeencoding" -H "Content-Type: application/json" -d 'None'
2026-03-18 09:08:12.347 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'[{"payload":"U2hhcmRpbmcgd29ya3MhIQ==","contentTopic":"/app/29/sometopic/someencoding","version":0,"timestamp":1773824892236802518,"ephemeral":false,"proof":""}]'
2026-03-18 09:08:12.349 DEBUG [src.steps.sharding] Checking that peer NODE_2:harbor.status.im/wakuorg/nwaku:v0.38.0-rc.0 can find the published message
2026-03-18 09:08:12.349 INFO [src.node.api_clients.base_client] curl -v -X GET "http://127.0.0.1:31746/relay/v1/auto/messages/%2Fapp%2F29%2Fsometopic%2Fsomeencoding" -H "Content-Type: application/json" -d 'None'
2026-03-18 09:08:12.352 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'[{"payload":"U2hhcmRpbmcgd29ya3MhIQ==","contentTopic":"/app/29/sometopic/someencoding","version":0,"timestamp":1773824892236802518,"ephemeral":false,"proof":""}]'
2026-03-18 09:08:12.353 INFO [src.node.api_clients.base_client] curl -v -X POST "http://127.0.0.1:24486/relay/v1/auto/messages" -H "Content-Type: application/json" -d '{"payload": "U2hhcmRpbmcgd29ya3MhIQ==", "contentTopic": "/app/20/sometopic/someencoding", "timestamp": '$(date +%s%N)'}'
2026-03-18 09:08:12.358 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'OK'
2026-03-18 09:08:12.358 DEBUG [src.libs.common] Sleeping for 0.1 seconds
2026-03-18 09:08:12.458 DEBUG [src.steps.sharding] Checking that peer NODE_1:harbor.status.im/wakuorg/nwaku:v0.38.0-rc.0 can find the published message
2026-03-18 09:08:12.459 INFO [src.node.api_clients.base_client] curl -v -X GET "http://127.0.0.1:24486/relay/v1/auto/messages/%2Fapp%2F20%2Fsometopic%2Fsomeencoding" -H "Content-Type: application/json" -d 'None'
2026-03-18 09:08:12.461 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'[{"payload":"U2hhcmRpbmcgd29ya3MhIQ==","contentTopic":"/app/20/sometopic/someencoding","version":0,"timestamp":1773824892353756064,"ephemeral":false,"proof":""}]'
2026-03-18 09:08:12.463 DEBUG [src.steps.sharding] Checking that peer NODE_2:harbor.status.im/wakuorg/nwaku:v0.38.0-rc.0 can find the published message
2026-03-18 09:08:12.463 INFO [src.node.api_clients.base_client] curl -v -X GET "http://127.0.0.1:31746/relay/v1/auto/messages/%2Fapp%2F20%2Fsometopic%2Fsomeencoding" -H "Content-Type: application/json" -d 'None'
2026-03-18 09:08:12.466 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'[{"payload":"U2hhcmRpbmcgd29ya3MhIQ==","contentTopic":"/app/20/sometopic/someencoding","version":0,"timestamp":1773824892353756064,"ephemeral":false,"proof":""}]'
2026-03-18 09:08:12.469 DEBUG [tests.conftest] Running fixture teardown: test_setup
2026-03-18 09:08:12.470 DEBUG [tests.conftest] Running fixture teardown: close_open_nodes
2026-03-18 09:08:12.470 DEBUG [src.node.waku_node] Stopping container with id 1216034b8fe6
2026-03-18 09:08:13.043 DEBUG [src.node.waku_node] Container stopped.
2026-03-18 09:08:13.043 DEBUG [src.node.waku_node] Stopping container with id aaa95ad0543d
2026-03-18 09:08:13.636 DEBUG [src.node.waku_node] Container stopped.
2026-03-18 09:08:13.639 DEBUG [tests.conftest] Running fixture teardown: check_waku_log_errors
2026-03-18 09:08:13.665 DEBUG [src.node.docker_mananger] No errors found in the waku logs.
2026-03-18 09:08:13.677 DEBUG [src.node.docker_mananger] No errors found in the waku logs.