2026-04-22 15:08:14 +00:00

102 lines
18 KiB
Plaintext

2026-04-22 14:42:59.839 DEBUG [tests.conftest] Running fixture setup: test_id
2026-04-22 14:42:59.839 DEBUG [tests.conftest] Running test: test_light_push_with_missing_pubsub_topics with id: 2026-04-22_14-42-59__0f36eb68-26e5-4194-877e-1057038b17cc
2026-04-22 14:42:59.839 DEBUG [src.steps.common] Running fixture setup: common_setup
2026-04-22 14:42:59.840 DEBUG [src.steps.light_push] Running fixture setup: light_push_setup
2026-04-22 14:42:59.848 DEBUG [src.node.docker_mananger] Docker client initialized with image wakuorg/nwaku:latest
2026-04-22 14:42:59.848 DEBUG [src.node.waku_node] WakuNode instance initialized with log path ./log/docker/receiving_node1_2026-04-22_14-42-59__0f36eb68-26e5-4194-877e-1057038b17cc__wakuorg_nwaku:latest.log
2026-04-22 14:42:59.848 DEBUG [src.node.waku_node] Starting Node...
2026-04-22 14:42:59.849 DEBUG [src.node.docker_mananger] Attempting to create or retrieve network waku
2026-04-22 14:42:59.850 DEBUG [src.node.docker_mananger] Network waku already exists
2026-04-22 14:42:59.850 DEBUG [src.node.docker_mananger] Generated random external IP 172.18.60.66
2026-04-22 14:42:59.850 DEBUG [src.node.docker_mananger] Generated ports ['38335', '38336', '38337', '38338', '38339']
2026-04-22 14:42:59.851 DEBUG [src.node.waku_node] RLN credentials were not set
2026-04-22 14:42:59.851 INFO [src.node.waku_node] RLN credentials not set or credential store not available, starting without RLN
2026-04-22 14:42:59.851 DEBUG [src.node.waku_node] Using volumes []
2026-04-22 14:42:59.851 DEBUG [src.node.docker_mananger] docker run -i -t -p 38335:38335 -p 38336:38336 -p 38337:38337 -p 38338:38338 -p 38339:38339 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=38337 --rest-port=38335 --tcp-port=38336 --discv5-udp-port=38338 --rest-address=0.0.0.0 --nat=extip:172.18.60.66 --peer-exchange=true --discv5-discovery=true --cluster-id=198 --nodekey=6adeed5b4ad2522ee97beb0ae98fae961ae6b51f5b603cac3bc13ab9b8411ae8 --shard=0 --metrics-server=true --metrics-server-address=0.0.0.0 --metrics-server-port=38339 --metrics-logging=true --lightpush=true --relay=true
2026-04-22 14:43:00.022 ERROR [src.node.docker_mananger] Max retries reached for container 0908eefe146b. Exiting log stream.
2026-04-22 14:43:00.050 DEBUG [src.node.docker_mananger] docker network connect --ip 172.18.60.66 waku f5e413f5d89dcc2a0a1f60dfba7deb3f19e4b8496916396a29abe9bb90f3347f
2026-04-22 14:43:00.086 DEBUG [src.node.docker_mananger] Container started with ID f5e413f5d89d. Setting up logs at ./log/docker/receiving_node1_2026-04-22_14-42-59__0f36eb68-26e5-4194-877e-1057038b17cc__wakuorg_nwaku:latest.log
2026-04-22 14:43:00.087 DEBUG [src.node.waku_node] Started container from image wakuorg/nwaku:latest. REST: 38335
2026-04-22 14:43:00.087 DEBUG [src.libs.common] Sleeping for 1 seconds
2026-04-22 14:43:00.683 ERROR [src.node.docker_mananger] Max retries reached for container 23c89b3fd699. Exiting log stream.
2026-04-22 14:43:01.088 INFO [src.node.api_clients.base_client] curl -v -X GET "http://127.0.0.1:38335/health" -H "Content-Type: application/json" -d 'None'
2026-04-22 14:43:01.091 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_READY","desc":"Node has no relay peers to fullfill push requests"},{"Legacy Lightpush":"NOT_READY","desc":"Node has no relay peers to fullfill push requests"},{"Filter":"NOT_MOUNTED"},{"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"},{"Filter Client":"NOT_READY","desc":"No Filter service peer available yet"},{"Rln Relay":"NOT_MOUNTED"}]}'
2026-04-22 14:43:01.091 INFO [src.node.waku_node] Node protocols are initialized !!
2026-04-22 14:43:01.091 INFO [src.node.api_clients.base_client] curl -v -X GET "http://127.0.0.1:38335/debug/v1/info" -H "Content-Type: application/json" -d 'None'
2026-04-22 14:43:01.093 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'{"listenAddresses":["/ip4/172.18.60.66/tcp/38336/p2p/16Uiu2HAmPLXu1R2rcCwSvBHBGtcMpXd8brtqs7tnm7PgFYeSTg8g","/ip4/172.18.60.66/tcp/38337/ws/p2p/16Uiu2HAmPLXu1R2rcCwSvBHBGtcMpXd8brtqs7tnm7PgFYeSTg8g"],"enrUri":"enr:-L24QO7igzefmIjyUjo5Hjp2bnCjVlpgSs0QhSP-YEEH5udFKboJImdHJAF7xxn-p3wpIATtm60Bv24wBbx1_ZqJVE8CgmlkgnY0gmlwhKwSPEKKbXVsdGlhZGRyc5YACASsEjxCBpXAAAoErBI8QgaVwd0DgnJzhQDGAQAAiXNlY3AyNTZrMaEDnrYw42TH3fQ_7d2cA3vk_9qviTjlriPV4I_tDsbl8LmDdGNwgpXAg3VkcIKVwoV3YWt1Mgk"}'
2026-04-22 14:43:01.093 INFO [src.node.waku_node] REST service is ready !!
2026-04-22 14:43:01.100 DEBUG [src.node.docker_mananger] Docker client initialized with image wakuorg/nwaku:latest
2026-04-22 14:43:01.100 DEBUG [src.node.waku_node] WakuNode instance initialized with log path ./log/docker/receiving_node2_2026-04-22_14-42-59__0f36eb68-26e5-4194-877e-1057038b17cc__wakuorg_nwaku:latest.log
2026-04-22 14:43:01.100 DEBUG [src.node.waku_node] Starting Node...
2026-04-22 14:43:01.100 DEBUG [src.node.docker_mananger] Attempting to create or retrieve network waku
2026-04-22 14:43:01.102 DEBUG [src.node.docker_mananger] Network waku already exists
2026-04-22 14:43:01.102 DEBUG [src.node.docker_mananger] Generated random external IP 172.18.137.252
2026-04-22 14:43:01.102 DEBUG [src.node.docker_mananger] Generated ports ['50290', '50291', '50292', '50293', '50294']
2026-04-22 14:43:01.102 DEBUG [src.node.waku_node] RLN credentials were not set
2026-04-22 14:43:01.102 INFO [src.node.waku_node] RLN credentials not set or credential store not available, starting without RLN
2026-04-22 14:43:01.103 DEBUG [src.node.waku_node] Using volumes []
2026-04-22 14:43:01.103 DEBUG [src.node.docker_mananger] docker run -i -t -p 50290:50290 -p 50291:50291 -p 50292:50292 -p 50293:50293 -p 50294:50294 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=50292 --rest-port=50290 --tcp-port=50291 --discv5-udp-port=50293 --rest-address=0.0.0.0 --nat=extip:172.18.137.252 --peer-exchange=true --discv5-discovery=true --cluster-id=198 --nodekey=a7ccabc6ffffca9adf9d10cfcbec7dd660d501e2db1ccdea3dd572c16727408c --shard=0 --metrics-server=true --metrics-server-address=0.0.0.0 --metrics-server-port=50294 --metrics-logging=true --lightpush=false --relay=true
2026-04-22 14:43:01.305 DEBUG [src.node.docker_mananger] docker network connect --ip 172.18.137.252 waku 3e0503bc9b451f5dc0852a475213044928a9f329acf830465c06ad8f74255807
2026-04-22 14:43:01.335 DEBUG [src.node.docker_mananger] Container started with ID 3e0503bc9b45. Setting up logs at ./log/docker/receiving_node2_2026-04-22_14-42-59__0f36eb68-26e5-4194-877e-1057038b17cc__wakuorg_nwaku:latest.log
2026-04-22 14:43:01.336 DEBUG [src.node.waku_node] Started container from image wakuorg/nwaku:latest. REST: 50290
2026-04-22 14:43:01.336 DEBUG [src.libs.common] Sleeping for 1 seconds
2026-04-22 14:43:02.337 INFO [src.node.api_clients.base_client] curl -v -X GET "http://127.0.0.1:50290/health" -H "Content-Type: application/json" -d 'None'
2026-04-22 14:43:02.340 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"},{"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"},{"Filter Client":"NOT_READY","desc":"No Filter service peer available yet"},{"Rln Relay":"NOT_MOUNTED"}]}'
2026-04-22 14:43:02.340 INFO [src.node.waku_node] Node protocols are initialized !!
2026-04-22 14:43:02.341 INFO [src.node.api_clients.base_client] curl -v -X GET "http://127.0.0.1:50290/debug/v1/info" -H "Content-Type: application/json" -d 'None'
2026-04-22 14:43:02.343 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'{"listenAddresses":["/ip4/172.18.137.252/tcp/50291/p2p/16Uiu2HAmGMFDpTfS7Esw63aET3dgfa5fdWbCyVn6twfXYH3rBwtj","/ip4/172.18.137.252/tcp/50292/ws/p2p/16Uiu2HAmGMFDpTfS7Esw63aET3dgfa5fdWbCyVn6twfXYH3rBwtj"],"enrUri":"enr:-L24QEbBph7A9wUSSwm9hr8YWJjSW4wvJVbY6iCQ2oboZmvve0uphhned6md6atucgeF3_3ymVA3F97oUelNu617XXwCgmlkgnY0gmlwhKwSifyKbXVsdGlhZGRyc5YACASsEon8BsRzAAoErBKJ_AbEdN0DgnJzhQDGAQAAiXNlY3AyNTZrMaEDNuMbqWwCiMMp9NzDG-KUSaimWgHhOeMEW4n3M5VoJbCDdGNwgsRzg3VkcILEdYV3YWt1MgE"}'
2026-04-22 14:43:02.343 INFO [src.node.waku_node] REST service is ready !!
2026-04-22 14:43:02.344 INFO [src.node.api_clients.base_client] curl -v -X POST "http://127.0.0.1:50290/admin/v1/peers" -H "Content-Type: application/json" -d '["/ip4/172.18.60.66/tcp/38336/p2p/16Uiu2HAmPLXu1R2rcCwSvBHBGtcMpXd8brtqs7tnm7PgFYeSTg8g"]'
2026-04-22 14:43:02.369 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'OK'
2026-04-22 14:43:02.383 DEBUG [src.node.docker_mananger] Docker client initialized with image wakuorg/nwaku:latest
2026-04-22 14:43:02.384 DEBUG [src.node.waku_node] WakuNode instance initialized with log path ./log/docker/lightpush_node1_2026-04-22_14-42-59__0f36eb68-26e5-4194-877e-1057038b17cc__wakuorg_nwaku:latest.log
2026-04-22 14:43:02.385 DEBUG [src.node.waku_node] Starting Node...
2026-04-22 14:43:02.385 DEBUG [src.node.docker_mananger] Attempting to create or retrieve network waku
2026-04-22 14:43:02.387 DEBUG [src.node.docker_mananger] Network waku already exists
2026-04-22 14:43:02.387 DEBUG [src.node.docker_mananger] Generated random external IP 172.18.103.159
2026-04-22 14:43:02.387 DEBUG [src.node.docker_mananger] Generated ports ['8999', '9000', '9001', '9002', '9003']
2026-04-22 14:43:02.388 DEBUG [src.node.waku_node] RLN credentials were not set
2026-04-22 14:43:02.389 INFO [src.node.waku_node] RLN credentials not set or credential store not available, starting without RLN
2026-04-22 14:43:02.389 DEBUG [src.node.waku_node] Using volumes []
2026-04-22 14:43:02.389 DEBUG [src.node.docker_mananger] docker run -i -t -p 8999:8999 -p 9000:9000 -p 9001:9001 -p 9002:9002 -p 9003:9003 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=9001 --rest-port=8999 --tcp-port=9000 --discv5-udp-port=9002 --rest-address=0.0.0.0 --nat=extip:172.18.103.159 --peer-exchange=true --discv5-discovery=true --cluster-id=198 --nodekey=6a06bf5b1fb968fab83a10e84606decc17c7f7e278898d2c1eee9683a0b20e22 --shard=0 --metrics-server=true --metrics-server-address=0.0.0.0 --metrics-server-port=9003 --metrics-logging=true --discv5-bootstrap-node=enr:-L24QO7igzefmIjyUjo5Hjp2bnCjVlpgSs0QhSP-YEEH5udFKboJImdHJAF7xxn-p3wpIATtm60Bv24wBbx1_ZqJVE8CgmlkgnY0gmlwhKwSPEKKbXVsdGlhZGRyc5YACASsEjxCBpXAAAoErBI8QgaVwd0DgnJzhQDGAQAAiXNlY3AyNTZrMaEDnrYw42TH3fQ_7d2cA3vk_9qviTjlriPV4I_tDsbl8LmDdGNwgpXAg3VkcIKVwoV3YWt1Mgk --lightpushnode=/ip4/172.18.60.66/tcp/38336/p2p/16Uiu2HAmPLXu1R2rcCwSvBHBGtcMpXd8brtqs7tnm7PgFYeSTg8g --lightpush=true --relay=true
2026-04-22 14:43:02.587 DEBUG [src.node.docker_mananger] docker network connect --ip 172.18.103.159 waku 6ebc5926909220891e1bcaa3c2af9b8ec2fbabf8239c9baa5912ac4c8d1ce1c4
2026-04-22 14:43:02.623 DEBUG [src.node.docker_mananger] Container started with ID 6ebc59269092. Setting up logs at ./log/docker/lightpush_node1_2026-04-22_14-42-59__0f36eb68-26e5-4194-877e-1057038b17cc__wakuorg_nwaku:latest.log
2026-04-22 14:43:02.624 DEBUG [src.node.waku_node] Started container from image wakuorg/nwaku:latest. REST: 8999
2026-04-22 14:43:02.624 DEBUG [src.libs.common] Sleeping for 1 seconds
2026-04-22 14:43:03.625 INFO [src.node.api_clients.base_client] curl -v -X GET "http://127.0.0.1:8999/health" -H "Content-Type: application/json" -d 'None'
2026-04-22 14:43:03.638 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'{"nodeHealth":"READY","connectionStatus":"PartiallyConnected","protocolsHealth":[{"Relay":"READY"},{"Lightpush":"READY"},{"Legacy Lightpush":"READY"},{"Filter":"NOT_MOUNTED"},{"Store":"NOT_MOUNTED"},{"Peer Exchange":"READY"},{"Rendezvous":"NOT_READY","desc":"No Rendezvous peers are available yet"},{"Mix":"NOT_MOUNTED"},{"Lightpush Client":"READY"},{"Legacy Lightpush Client":"READY"},{"Store Client":"NOT_READY","desc":"No Store service peer 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-04-22 14:43:03.639 INFO [src.node.waku_node] Node protocols are initialized !!
2026-04-22 14:43:03.640 INFO [src.node.api_clients.base_client] curl -v -X GET "http://127.0.0.1:8999/debug/v1/info" -H "Content-Type: application/json" -d 'None'
2026-04-22 14:43:03.644 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'{"listenAddresses":["/ip4/172.18.103.159/tcp/9000/p2p/16Uiu2HAmMEV9X3zBZXWriAAn83axz8T6wCR8zqJ2UesBwN8ovsVk","/ip4/172.18.103.159/tcp/9001/ws/p2p/16Uiu2HAmMEV9X3zBZXWriAAn83axz8T6wCR8zqJ2UesBwN8ovsVk"],"enrUri":"enr:-L24QLEgLVTBc5VJKGBmumQ4hDqXz8y0CSwDWUweqpDUjFi_UH2Pf06bTjNXOTGZMgL-91-NEZeKlepdQkVOp44BpbwCgmlkgnY0gmlwhKwSZ5-KbXVsdGlhZGRyc5YACASsEmefBiMoAAoErBJnnwYjKd0DgnJzhQDGAQAAiXNlY3AyNTZrMaEDf3I5GUUWE9SiiSnPimVBogBYHwnfu0dpRmMRQdipRlODdGNwgiMog3VkcIIjKoV3YWt1Mgk"}'
2026-04-22 14:43:03.644 INFO [src.node.waku_node] REST service is ready !!
2026-04-22 14:43:03.644 INFO [src.node.api_clients.base_client] curl -v -X POST "http://127.0.0.1:8999/admin/v1/peers" -H "Content-Type: application/json" -d '["/ip4/172.18.60.66/tcp/38336/p2p/16Uiu2HAmPLXu1R2rcCwSvBHBGtcMpXd8brtqs7tnm7PgFYeSTg8g"]'
2026-04-22 14:43:03.648 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'OK'
2026-04-22 14:43:03.649 INFO [src.node.api_clients.base_client] curl -v -X POST "http://127.0.0.1:8999/admin/v1/peers" -H "Content-Type: application/json" -d '["/ip4/172.18.137.252/tcp/50291/p2p/16Uiu2HAmGMFDpTfS7Esw63aET3dgfa5fdWbCyVn6twfXYH3rBwtj"]'
2026-04-22 14:43:03.680 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'OK'
2026-04-22 14:43:03.680 INFO [src.node.api_clients.base_client] curl -v -X POST "http://127.0.0.1:38335/relay/v1/subscriptions" -H "Content-Type: application/json" -d '["/waku/2/rs/198/0"]'
2026-04-22 14:43:03.689 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'OK'
2026-04-22 14:43:03.689 INFO [src.node.api_clients.base_client] curl -v -X POST "http://127.0.0.1:50290/relay/v1/subscriptions" -H "Content-Type: application/json" -d '["/waku/2/rs/198/0"]'
2026-04-22 14:43:03.693 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'OK'
2026-04-22 14:43:03.693 INFO [src.node.api_clients.base_client] curl -v -X POST "http://127.0.0.1:8999/relay/v1/subscriptions" -H "Content-Type: application/json" -d '["/waku/2/rs/198/0"]'
2026-04-22 14:43:03.698 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'OK'
2026-04-22 14:43:03.700 INFO [src.node.api_clients.base_client] curl -v -X POST "http://127.0.0.1:8999/lightpush/v1/message" -H "Content-Type: application/json" -d '{"message": {"payload": "TGlnaHQgcHVzaCB3b3JrcyEh", "contentTopic": "/myapp/1/latest/proto", "timestamp": 1776868983700621318}}'
2026-04-22 14:43:03.703 ERROR [src.node.api_clients.base_client] HTTP error occurred: 503 Server Error: Service Unavailable for url: http://127.0.0.1:8999/lightpush/v1/message. Response content: b'Failed to request a message push: Pubsub topic must be specified when static sharding is enabled'
2026-04-22 14:43:03.772 DEBUG [tests.conftest] Running fixture teardown: test_setup
2026-04-22 14:43:03.773 DEBUG [tests.conftest] Running fixture teardown: close_open_nodes
2026-04-22 14:43:03.773 DEBUG [src.node.waku_node] Stopping container with id f5e413f5d89d
2026-04-22 14:43:04.252 DEBUG [src.node.waku_node] Container stopped.
2026-04-22 14:43:04.253 DEBUG [src.node.waku_node] Stopping container with id 3e0503bc9b45
2026-04-22 14:43:04.707 DEBUG [src.node.waku_node] Container stopped.
2026-04-22 14:43:04.708 DEBUG [src.node.waku_node] Stopping container with id 6ebc59269092
2026-04-22 14:43:05.065 ERROR [src.node.docker_mananger] Max retries reached for container f5e413f5d89d. Exiting log stream.
2026-04-22 14:43:05.150 DEBUG [src.node.waku_node] Container stopped.
2026-04-22 14:43:05.150 DEBUG [tests.conftest] Running fixture teardown: check_waku_log_errors
2026-04-22 14:43:05.161 DEBUG [src.node.docker_mananger] No errors found in the waku logs.
2026-04-22 14:43:05.171 DEBUG [src.node.docker_mananger] No errors found in the waku logs.
2026-04-22 14:43:05.180 DEBUG [src.node.docker_mananger] No errors found in the waku logs.
2026-04-22 14:43:05.181 DEBUG [tests.conftest] Running fixture teardown: attach_logs_on_fail
2026-04-22 14:43:05.181 DEBUG [tests.conftest] Test failed, attempting to attach logs to the allure reports
2026-04-22 14:43:05.181 DEBUG [src.libs.common] Attaching file ./log/docker/receiving_node1_2026-04-22_14-42-59__0f36eb68-26e5-4194-877e-1057038b17cc__wakuorg_nwaku:latest.log
2026-04-22 14:43:05.182 DEBUG [src.libs.common] Attaching file ./log/docker/lightpush_node1_2026-04-22_14-42-59__0f36eb68-26e5-4194-877e-1057038b17cc__wakuorg_nwaku:latest.log
2026-04-22 14:43:05.182 DEBUG [src.libs.common] Attaching file ./log/docker/receiving_node2_2026-04-22_14-42-59__0f36eb68-26e5-4194-877e-1057038b17cc__wakuorg_nwaku:latest.log