85 lines
14 KiB
Plaintext

2025-12-12 04:18:28.529 DEBUG [tests.conftest] Running fixture setup: test_id
2025-12-12 04:18:28.530 DEBUG [tests.conftest] Running test: test_store_with_wrong_hash with id: 2025-12-12_04-18-28__a952da2a-7bd4-4525-9b1f-04bcf41b7313
2025-12-12 04:18:28.531 DEBUG [src.steps.common] Running fixture setup: common_setup
2025-12-12 04:18:28.531 DEBUG [src.steps.store] Running fixture setup: store_setup
2025-12-12 04:18:28.532 DEBUG [src.steps.store] Running fixture setup: node_setup
2025-12-12 04:18:28.541 DEBUG [src.node.docker_mananger] Docker client initialized with image wakuorg/nwaku:latest
2025-12-12 04:18:28.541 DEBUG [src.node.waku_node] WakuNode instance initialized with log path ./log/docker/publishing_node1_2025-12-12_04-18-28__a952da2a-7bd4-4525-9b1f-04bcf41b7313__wakuorg_nwaku:latest.log
2025-12-12 04:18:28.541 DEBUG [src.node.waku_node] Starting Node...
2025-12-12 04:18:28.541 DEBUG [src.node.docker_mananger] Attempting to create or retrieve network waku
2025-12-12 04:18:28.543 DEBUG [src.node.docker_mananger] Network waku already exists
2025-12-12 04:18:28.544 DEBUG [src.node.docker_mananger] Generated random external IP 172.18.106.161
2025-12-12 04:18:28.544 DEBUG [src.node.docker_mananger] Generated ports ['32195', '32196', '32197', '32198', '32199']
2025-12-12 04:18:28.544 DEBUG [src.node.waku_node] RLN credentials were not set
2025-12-12 04:18:28.544 INFO [src.node.waku_node] RLN credentials not set or credential store not available, starting without RLN
2025-12-12 04:18:28.544 DEBUG [src.node.waku_node] Using volumes []
2025-12-12 04:18:28.545 DEBUG [src.node.docker_mananger] docker run -i -t -p 32195:32195 -p 32196:32196 -p 32197:32197 -p 32198:32198 -p 32199:32199 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=32197 --rest-port=32195 --tcp-port=32196 --discv5-udp-port=32198 --rest-address=0.0.0.0 --nat=extip:172.18.106.161 --peer-exchange=true --discv5-discovery=true --cluster-id=3 --nodekey=914ed355353a5bef40a7dfea7bfbf0bfd3ed4b3b277dfb847cccc8a878c0036e --shard=0 --metrics-server=true --metrics-server-address=0.0.0.0 --metrics-server-port=32199 --metrics-logging=true --store=true --relay=true
2025-12-12 04:18:28.736 DEBUG [src.node.docker_mananger] docker network connect --ip 172.18.106.161 waku 3c1c5719daeb04d4fc8ec77476a9e59e5deba5df052eadfd47327e4c94c7c8b1
2025-12-12 04:18:28.767 DEBUG [src.node.docker_mananger] Container started with ID 3c1c5719daeb. Setting up logs at ./log/docker/publishing_node1_2025-12-12_04-18-28__a952da2a-7bd4-4525-9b1f-04bcf41b7313__wakuorg_nwaku:latest.log
2025-12-12 04:18:28.768 DEBUG [src.node.waku_node] Started container from image wakuorg/nwaku:latest. REST: 32195
2025-12-12 04:18:28.768 DEBUG [src.libs.common] Sleeping for 1 seconds
2025-12-12 04:18:28.798 ERROR [src.node.docker_mananger] Max retries reached for container 5ba34dd18b7f. Exiting log stream.
2025-12-12 04:18:29.363 ERROR [src.node.docker_mananger] Max retries reached for container d5f8bc9f0d7b. Exiting log stream.
2025-12-12 04:18:29.768 INFO [src.node.api_clients.base_client] curl -v -X GET "http://127.0.0.1:32195/health" -H "Content-Type: application/json" -d 'None'
2025-12-12 04:18:29.772 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":"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"}]}'
2025-12-12 04:18:29.772 INFO [src.node.waku_node] Node protocols are initialized !!
2025-12-12 04:18:29.772 INFO [src.node.api_clients.base_client] curl -v -X GET "http://127.0.0.1:32195/debug/v1/info" -H "Content-Type: application/json" -d 'None'
2025-12-12 04:18:29.774 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'{"listenAddresses":["/ip4/172.18.106.161/tcp/32196/p2p/16Uiu2HAmFze7X2AZRBzFEeX4V5G9JuLGTYWAEENmQhsgSG9txMqh","/ip4/172.18.106.161/tcp/32197/ws/p2p/16Uiu2HAmFze7X2AZRBzFEeX4V5G9JuLGTYWAEENmQhsgSG9txMqh"],"enrUri":"enr:-L24QJ-VYof4V5txyOZyIPI0BtwX5atysdTtyNDwB9jHssbvKQQ0eAMbnbf9TBj97vw1khwzDMcniw-ruAulZn2_bDcCgmlkgnY0gmlwhKwSaqGKbXVsdGlhZGRyc5YACASsEmqhBn3EAAoErBJqoQZ9xd0DgnJzhQADAQAAiXNlY3AyNTZrMaEDMZvLXspPosrF0kovaueyq37kSV_Ws-J8NpFNDNP4DuCDdGNwgn3Eg3VkcIJ9xoV3YWt1MgM"}'
2025-12-12 04:18:29.774 INFO [src.node.waku_node] REST service is ready !!
2025-12-12 04:18:29.781 DEBUG [src.node.docker_mananger] Docker client initialized with image wakuorg/nwaku:latest
2025-12-12 04:18:29.782 DEBUG [src.node.waku_node] WakuNode instance initialized with log path ./log/docker/store_node1_2025-12-12_04-18-28__a952da2a-7bd4-4525-9b1f-04bcf41b7313__wakuorg_nwaku:latest.log
2025-12-12 04:18:29.782 DEBUG [src.node.waku_node] Starting Node...
2025-12-12 04:18:29.782 DEBUG [src.node.docker_mananger] Attempting to create or retrieve network waku
2025-12-12 04:18:29.783 DEBUG [src.node.docker_mananger] Network waku already exists
2025-12-12 04:18:29.783 DEBUG [src.node.docker_mananger] Generated random external IP 172.18.29.255
2025-12-12 04:18:29.783 DEBUG [src.node.docker_mananger] Generated ports ['26242', '26243', '26244', '26245', '26246']
2025-12-12 04:18:29.783 DEBUG [src.node.waku_node] RLN credentials were not set
2025-12-12 04:18:29.784 INFO [src.node.waku_node] RLN credentials not set or credential store not available, starting without RLN
2025-12-12 04:18:29.784 DEBUG [src.node.waku_node] Using volumes []
2025-12-12 04:18:29.784 DEBUG [src.node.docker_mananger] docker run -i -t -p 26242:26242 -p 26243:26243 -p 26244:26244 -p 26245:26245 -p 26246:26246 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=26244 --rest-port=26242 --tcp-port=26243 --discv5-udp-port=26245 --rest-address=0.0.0.0 --nat=extip:172.18.29.255 --peer-exchange=true --discv5-discovery=true --cluster-id=3 --nodekey=5e29466cb1cb4cc1fd3d86dbcba4c1f44a399e862c0a39bc6ec94edbf09edbac --shard=0 --metrics-server=true --metrics-server-address=0.0.0.0 --metrics-server-port=26246 --metrics-logging=true --discv5-bootstrap-node=enr:-L24QJ-VYof4V5txyOZyIPI0BtwX5atysdTtyNDwB9jHssbvKQQ0eAMbnbf9TBj97vw1khwzDMcniw-ruAulZn2_bDcCgmlkgnY0gmlwhKwSaqGKbXVsdGlhZGRyc5YACASsEmqhBn3EAAoErBJqoQZ9xd0DgnJzhQADAQAAiXNlY3AyNTZrMaEDMZvLXspPosrF0kovaueyq37kSV_Ws-J8NpFNDNP4DuCDdGNwgn3Eg3VkcIJ9xoV3YWt1MgM --storenode=/ip4/172.18.106.161/tcp/32196/p2p/16Uiu2HAmFze7X2AZRBzFEeX4V5G9JuLGTYWAEENmQhsgSG9txMqh --store=true --relay=true
2025-12-12 04:18:29.965 DEBUG [src.node.docker_mananger] docker network connect --ip 172.18.29.255 waku fe6cb470067659a85a34cc46e77fb6904816b394f7a597c0e2f386cc50e0d4cd
2025-12-12 04:18:29.998 DEBUG [src.node.docker_mananger] Container started with ID fe6cb4700676. Setting up logs at ./log/docker/store_node1_2025-12-12_04-18-28__a952da2a-7bd4-4525-9b1f-04bcf41b7313__wakuorg_nwaku:latest.log
2025-12-12 04:18:29.998 DEBUG [src.node.waku_node] Started container from image wakuorg/nwaku:latest. REST: 26242
2025-12-12 04:18:30.000 DEBUG [src.libs.common] Sleeping for 1 seconds
2025-12-12 04:18:31.000 INFO [src.node.api_clients.base_client] curl -v -X GET "http://127.0.0.1:26242/health" -H "Content-Type: application/json" -d 'None'
2025-12-12 04:18:31.004 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'{"nodeHealth":"READY","protocolsHealth":[{"Relay":"READY"},{"Rln 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":"READY"},{"Filter Client":"NOT_READY","desc":"No Filter service peer available yet"}]}'
2025-12-12 04:18:31.004 INFO [src.node.waku_node] Node protocols are initialized !!
2025-12-12 04:18:31.004 INFO [src.node.api_clients.base_client] curl -v -X GET "http://127.0.0.1:26242/debug/v1/info" -H "Content-Type: application/json" -d 'None'
2025-12-12 04:18:31.007 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'{"listenAddresses":["/ip4/172.18.29.255/tcp/26243/p2p/16Uiu2HAm71SMS4Br51NbzAvfzTuh8vfqninZUsGgzgRmYuHhyWUe","/ip4/172.18.29.255/tcp/26244/ws/p2p/16Uiu2HAm71SMS4Br51NbzAvfzTuh8vfqninZUsGgzgRmYuHhyWUe"],"enrUri":"enr:-L24QOSi86NdZinHU9UPimxmChGg5HCRTBcD13YyKuKZLV18TB1lkvzB8hd78VfItvFO56VxsYWeOx8_tUXE6kj5vjwCgmlkgnY0gmlwhKwSHf-KbXVsdGlhZGRyc5YACASsEh3_BmaDAAoErBId_wZmhN0DgnJzhQADAQAAiXNlY3AyNTZrMaECrBblTNDKgWtPjNQRe6mKOD4pvMrXGqlftvwymZst2xeDdGNwgmaDg3VkcIJmhYV3YWt1MgM"}'
2025-12-12 04:18:31.007 INFO [src.node.waku_node] REST service is ready !!
2025-12-12 04:18:31.007 INFO [src.node.api_clients.base_client] curl -v -X POST "http://127.0.0.1:26242/admin/v1/peers" -H "Content-Type: application/json" -d '["/ip4/172.18.106.161/tcp/32196/p2p/16Uiu2HAmFze7X2AZRBzFEeX4V5G9JuLGTYWAEENmQhsgSG9txMqh"]'
2025-12-12 04:18:31.010 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'OK'
2025-12-12 04:18:31.010 INFO [src.node.api_clients.base_client] curl -v -X POST "http://127.0.0.1:32195/relay/v1/subscriptions" -H "Content-Type: application/json" -d '["/waku/2/rs/3/0"]'
2025-12-12 04:18:31.013 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'OK'
2025-12-12 04:18:31.013 INFO [src.node.api_clients.base_client] curl -v -X POST "http://127.0.0.1:26242/relay/v1/subscriptions" -H "Content-Type: application/json" -d '["/waku/2/rs/3/0"]'
2025-12-12 04:18:31.015 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'OK'
2025-12-12 04:18:31.016 DEBUG [src.steps.store] Relaying message
2025-12-12 04:18:31.016 INFO [src.node.api_clients.base_client] curl -v -X POST "http://127.0.0.1:32195/relay/v1/messages/%2Fwaku%2F2%2Frs%2F3%2F0" -H "Content-Type: application/json" -d '{"payload": "TWVzc2FnZV8w", "contentTopic": "/myapp/1/latest/proto", "timestamp": '$(date +%s%N)'}'
2025-12-12 04:18:31.021 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'OK'
2025-12-12 04:18:31.022 DEBUG [src.libs.common] Sleeping for 0.2 seconds
2025-12-12 04:18:31.223 DEBUG [src.steps.store] Relaying message
2025-12-12 04:18:31.223 INFO [src.node.api_clients.base_client] curl -v -X POST "http://127.0.0.1:32195/relay/v1/messages/%2Fwaku%2F2%2Frs%2F3%2F0" -H "Content-Type: application/json" -d '{"payload": "TWVzc2FnZV8x", "contentTopic": "/myapp/1/latest/proto", "timestamp": '$(date +%s%N)'}'
2025-12-12 04:18:31.228 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'OK'
2025-12-12 04:18:31.228 DEBUG [src.libs.common] Sleeping for 0.2 seconds
2025-12-12 04:18:31.429 DEBUG [src.steps.store] Relaying message
2025-12-12 04:18:31.429 INFO [src.node.api_clients.base_client] curl -v -X POST "http://127.0.0.1:32195/relay/v1/messages/%2Fwaku%2F2%2Frs%2F3%2F0" -H "Content-Type: application/json" -d '{"payload": "TWVzc2FnZV8y", "contentTopic": "/myapp/1/latest/proto", "timestamp": '$(date +%s%N)'}'
2025-12-12 04:18:31.433 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'OK'
2025-12-12 04:18:31.434 DEBUG [src.libs.common] Sleeping for 0.2 seconds
2025-12-12 04:18:31.635 DEBUG [src.steps.store] Relaying message
2025-12-12 04:18:31.635 INFO [src.node.api_clients.base_client] curl -v -X POST "http://127.0.0.1:32195/relay/v1/messages/%2Fwaku%2F2%2Frs%2F3%2F0" -H "Content-Type: application/json" -d '{"payload": "TWVzc2FnZV8z", "contentTopic": "/myapp/1/latest/proto", "timestamp": '$(date +%s%N)'}'
2025-12-12 04:18:31.642 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'OK'
2025-12-12 04:18:31.643 DEBUG [src.libs.common] Sleeping for 0.2 seconds
2025-12-12 04:18:31.843 INFO [src.node.api_clients.base_client] curl -v -X GET "http://127.0.0.1:32195/store/v3/messages?pubsubTopic=%2Fwaku%2F2%2Frs%2F3%2F0&hashes=0x2825a854ff292c6384d8b6a37fbc389e4024982c51f80f636fa545bb72201fc1&pageSize=50&ascending=true" -H "Content-Type: application/json" -d 'None'
2025-12-12 04:18:31.847 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'{"requestId":"","statusCode":200,"statusDesc":"OK","messages":[]}'
2025-12-12 04:18:31.847 INFO [src.node.api_clients.base_client] curl -v -X GET "http://127.0.0.1:26242/store/v3/messages?pubsubTopic=%2Fwaku%2F2%2Frs%2F3%2F0&hashes=0x2825a854ff292c6384d8b6a37fbc389e4024982c51f80f636fa545bb72201fc1&pageSize=50&ascending=true" -H "Content-Type: application/json" -d 'None'
2025-12-12 04:18:31.849 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'{"requestId":"","statusCode":200,"statusDesc":"OK","messages":[]}'
2025-12-12 04:18:31.851 DEBUG [tests.conftest] Running fixture teardown: test_setup
2025-12-12 04:18:31.852 DEBUG [tests.conftest] Running fixture teardown: close_open_nodes
2025-12-12 04:18:31.852 DEBUG [src.node.waku_node] Stopping container with id 3c1c5719daeb
2025-12-12 04:18:32.403 DEBUG [src.node.waku_node] Container stopped.
2025-12-12 04:18:32.404 DEBUG [src.node.waku_node] Stopping container with id fe6cb4700676
2025-12-12 04:18:32.936 DEBUG [src.node.waku_node] Container stopped.
2025-12-12 04:18:32.937 DEBUG [tests.conftest] Running fixture teardown: check_waku_log_errors
2025-12-12 04:18:32.943 DEBUG [src.node.docker_mananger] No errors found in the waku logs.
2025-12-12 04:18:32.950 DEBUG [src.node.docker_mananger] No errors found in the waku logs.