|
| 1 | +2025-12-09 04:10:01.966 DEBUG [tests.conftest] Running fixture setup: test_id |
| 2 | +2025-12-09 04:10:01.967 DEBUG [tests.conftest] Running test: test_time_filter_matches_one_message with id: 2025-12-09_04-10-01__b5812111-fff0-4064-9162-7a600a76b23f |
| 3 | +2025-12-09 04:10:01.967 DEBUG [src.steps.common] Running fixture setup: common_setup |
| 4 | +2025-12-09 04:10:01.968 DEBUG [src.steps.store] Running fixture setup: store_setup |
| 5 | +2025-12-09 04:10:01.968 DEBUG [src.steps.store] Running fixture setup: node_setup |
| 6 | +2025-12-09 04:10:01.976 DEBUG [src.node.docker_mananger] Docker client initialized with image wakuorg/nwaku:latest |
| 7 | +2025-12-09 04:10:01.976 DEBUG [src.node.waku_node] WakuNode instance initialized with log path ./log/docker/publishing_node1_2025-12-09_04-10-01__b5812111-fff0-4064-9162-7a600a76b23f__wakuorg_nwaku:latest.log |
| 8 | +2025-12-09 04:10:01.976 DEBUG [src.node.waku_node] Starting Node... |
| 9 | +2025-12-09 04:10:01.977 DEBUG [src.node.docker_mananger] Attempting to create or retrieve network waku |
| 10 | +2025-12-09 04:10:01.979 DEBUG [src.node.docker_mananger] Network waku already exists |
| 11 | +2025-12-09 04:10:01.980 DEBUG [src.node.docker_mananger] Generated random external IP 172.18.136.200 |
| 12 | +2025-12-09 04:10:01.980 DEBUG [src.node.docker_mananger] Generated ports ['30246', '30247', '30248', '30249', '30250'] |
| 13 | +2025-12-09 04:10:01.980 DEBUG [src.node.waku_node] RLN credentials were not set |
| 14 | +2025-12-09 04:10:01.980 INFO [src.node.waku_node] RLN credentials not set or credential store not available, starting without RLN |
| 15 | +2025-12-09 04:10:01.980 DEBUG [src.node.waku_node] Using volumes [] |
| 16 | +2025-12-09 04:10:01.981 DEBUG [src.node.docker_mananger] docker run -i -t -p 30246:30246 -p 30247:30247 -p 30248:30248 -p 30249:30249 -p 30250:30250 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=30248 --rest-port=30246 --tcp-port=30247 --discv5-udp-port=30249 --rest-address=0.0.0.0 --nat=extip:172.18.136.200 --peer-exchange=true --discv5-discovery=true --cluster-id=3 --nodekey=ca2dfe951edecfad38e7e8dbdcf0f0749344fc3ee1cc65a78ab371f55bce3d4e --shard=0 --metrics-server=true --metrics-server-address=0.0.0.0 --metrics-server-port=30250 --metrics-logging=true --store=true --relay=true |
| 17 | +2025-12-09 04:10:02.160 DEBUG [src.node.docker_mananger] docker network connect --ip 172.18.136.200 waku f883f81d7d8be553b9b813fd6f4f00d4bbaa4ebf9e5eb9528309670194276930 |
| 18 | +2025-12-09 04:10:02.191 DEBUG [src.node.docker_mananger] Container started with ID f883f81d7d8b. Setting up logs at ./log/docker/publishing_node1_2025-12-09_04-10-01__b5812111-fff0-4064-9162-7a600a76b23f__wakuorg_nwaku:latest.log |
| 19 | +2025-12-09 04:10:02.192 DEBUG [src.node.waku_node] Started container from image wakuorg/nwaku:latest. REST: 30246 |
| 20 | +2025-12-09 04:10:02.194 DEBUG [src.libs.common] Sleeping for 1 seconds |
| 21 | +2025-12-09 04:10:02.248 ERROR [src.node.docker_mananger] Max retries reached for container 3f146c485fc0. Exiting log stream. |
| 22 | +2025-12-09 04:10:02.799 ERROR [src.node.docker_mananger] Max retries reached for container c15abc4a0f79. Exiting log stream. |
| 23 | +2025-12-09 04:10:03.194 INFO [src.node.api_clients.base_client] curl -v -X GET "http://127.0.0.1:30246/health" -H "Content-Type: application/json" -d 'None' |
| 24 | +2025-12-09 04:10:03.197 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"}]}' |
| 25 | +2025-12-09 04:10:03.197 INFO [src.node.waku_node] Node protocols are initialized !! |
| 26 | +2025-12-09 04:10:03.197 INFO [src.node.api_clients.base_client] curl -v -X GET "http://127.0.0.1:30246/debug/v1/info" -H "Content-Type: application/json" -d 'None' |
| 27 | +2025-12-09 04:10:03.199 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'{"listenAddresses":["/ip4/172.18.136.200/tcp/30247/p2p/16Uiu2HAmNdDK8KN2i5QDB3rY6qNydmNdhjqbbPVb7hGS1JjvwacZ","/ip4/172.18.136.200/tcp/30248/ws/p2p/16Uiu2HAmNdDK8KN2i5QDB3rY6qNydmNdhjqbbPVb7hGS1JjvwacZ"],"enrUri":"enr:-L24QIC2YJTxwbn0OYt7ASkB6n9OBWPhbm1junF7KwI-uhuVfdxCF-_Lfs4j-8vShgOh0Wk9zlomsN8Zdygg4QKWmPcCgmlkgnY0gmlwhKwSiMiKbXVsdGlhZGRyc5YACASsEojIBnYnAAoErBKIyAZ2KN0DgnJzhQADAQAAiXNlY3AyNTZrMaEDlCBdWCnPGmL7zLx5koFOy2lQbPlEEwc0jx8xypR1N_KDdGNwgnYng3VkcIJ2KYV3YWt1MgM"}' |
| 28 | +2025-12-09 04:10:03.200 INFO [src.node.waku_node] REST service is ready !! |
| 29 | +2025-12-09 04:10:03.206 DEBUG [src.node.docker_mananger] Docker client initialized with image wakuorg/nwaku:latest |
| 30 | +2025-12-09 04:10:03.207 DEBUG [src.node.waku_node] WakuNode instance initialized with log path ./log/docker/store_node1_2025-12-09_04-10-01__b5812111-fff0-4064-9162-7a600a76b23f__wakuorg_nwaku:latest.log |
| 31 | +2025-12-09 04:10:03.207 DEBUG [src.node.waku_node] Starting Node... |
| 32 | +2025-12-09 04:10:03.207 DEBUG [src.node.docker_mananger] Attempting to create or retrieve network waku |
| 33 | +2025-12-09 04:10:03.208 DEBUG [src.node.docker_mananger] Network waku already exists |
| 34 | +2025-12-09 04:10:03.209 DEBUG [src.node.docker_mananger] Generated random external IP 172.18.45.212 |
| 35 | +2025-12-09 04:10:03.209 DEBUG [src.node.docker_mananger] Generated ports ['5566', '5567', '5568', '5569', '5570'] |
| 36 | +2025-12-09 04:10:03.209 DEBUG [src.node.waku_node] RLN credentials were not set |
| 37 | +2025-12-09 04:10:03.209 INFO [src.node.waku_node] RLN credentials not set or credential store not available, starting without RLN |
| 38 | +2025-12-09 04:10:03.209 DEBUG [src.node.waku_node] Using volumes [] |
| 39 | +2025-12-09 04:10:03.209 DEBUG [src.node.docker_mananger] docker run -i -t -p 5566:5566 -p 5567:5567 -p 5568:5568 -p 5569:5569 -p 5570:5570 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=5568 --rest-port=5566 --tcp-port=5567 --discv5-udp-port=5569 --rest-address=0.0.0.0 --nat=extip:172.18.45.212 --peer-exchange=true --discv5-discovery=true --cluster-id=3 --nodekey=078cfe7dbdd45d2cb235e7bca5c9a0b4dbefe76d5ef7e5c35caaafac6ae985e2 --shard=0 --metrics-server=true --metrics-server-address=0.0.0.0 --metrics-server-port=5570 --metrics-logging=true --discv5-bootstrap-node=enr:-L24QIC2YJTxwbn0OYt7ASkB6n9OBWPhbm1junF7KwI-uhuVfdxCF-_Lfs4j-8vShgOh0Wk9zlomsN8Zdygg4QKWmPcCgmlkgnY0gmlwhKwSiMiKbXVsdGlhZGRyc5YACASsEojIBnYnAAoErBKIyAZ2KN0DgnJzhQADAQAAiXNlY3AyNTZrMaEDlCBdWCnPGmL7zLx5koFOy2lQbPlEEwc0jx8xypR1N_KDdGNwgnYng3VkcIJ2KYV3YWt1MgM --storenode=/ip4/172.18.136.200/tcp/30247/p2p/16Uiu2HAmNdDK8KN2i5QDB3rY6qNydmNdhjqbbPVb7hGS1JjvwacZ --store=true --relay=true |
| 40 | +2025-12-09 04:10:03.392 DEBUG [src.node.docker_mananger] docker network connect --ip 172.18.45.212 waku e0cbe603ac5a6f9a4166ebe58b7817e483f7f961429c33340d75c5cbff4fac6e |
| 41 | +2025-12-09 04:10:03.427 DEBUG [src.node.docker_mananger] Container started with ID e0cbe603ac5a. Setting up logs at ./log/docker/store_node1_2025-12-09_04-10-01__b5812111-fff0-4064-9162-7a600a76b23f__wakuorg_nwaku:latest.log |
| 42 | +2025-12-09 04:10:03.428 DEBUG [src.node.waku_node] Started container from image wakuorg/nwaku:latest. REST: 5566 |
| 43 | +2025-12-09 04:10:03.428 DEBUG [src.libs.common] Sleeping for 1 seconds |
| 44 | +2025-12-09 04:10:04.429 INFO [src.node.api_clients.base_client] curl -v -X GET "http://127.0.0.1:5566/health" -H "Content-Type: application/json" -d 'None' |
| 45 | +2025-12-09 04:10:04.433 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"}]}' |
| 46 | +2025-12-09 04:10:04.433 INFO [src.node.waku_node] Node protocols are initialized !! |
| 47 | +2025-12-09 04:10:04.434 INFO [src.node.api_clients.base_client] curl -v -X GET "http://127.0.0.1:5566/debug/v1/info" -H "Content-Type: application/json" -d 'None' |
| 48 | +2025-12-09 04:10:04.436 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'{"listenAddresses":["/ip4/172.18.45.212/tcp/5567/p2p/16Uiu2HAkySNycb2Gjd5xD6NpsFriRBT4MYZe7chnEgHFG42M8cnF","/ip4/172.18.45.212/tcp/5568/ws/p2p/16Uiu2HAkySNycb2Gjd5xD6NpsFriRBT4MYZe7chnEgHFG42M8cnF"],"enrUri":"enr:-L24QEfPS_Nn6XRP0z2QUvbO1fkA3UGty0autB-g0z-UuQ4nbW4QNAkufohpdYsd-xLTQxWb6tJj8w8lLNe_VXomjcwCgmlkgnY0gmlwhKwSLdSKbXVsdGlhZGRyc5YACASsEi3UBhW_AAoErBIt1AYVwN0DgnJzhQADAQAAiXNlY3AyNTZrMaECO50aYjMwIyOgF5XVNJXa0whXmFX6HsNUP6CVvf8c2mSDdGNwghW_g3VkcIIVwYV3YWt1MgM"}' |
| 49 | +2025-12-09 04:10:04.436 INFO [src.node.waku_node] REST service is ready !! |
| 50 | +2025-12-09 04:10:04.437 INFO [src.node.api_clients.base_client] curl -v -X POST "http://127.0.0.1:5566/admin/v1/peers" -H "Content-Type: application/json" -d '["/ip4/172.18.136.200/tcp/30247/p2p/16Uiu2HAmNdDK8KN2i5QDB3rY6qNydmNdhjqbbPVb7hGS1JjvwacZ"]' |
| 51 | +2025-12-09 04:10:04.439 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'OK' |
| 52 | +2025-12-09 04:10:04.439 INFO [src.node.api_clients.base_client] curl -v -X POST "http://127.0.0.1:30246/relay/v1/subscriptions" -H "Content-Type: application/json" -d '["/waku/2/rs/3/0"]' |
| 53 | +2025-12-09 04:10:04.442 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'OK' |
| 54 | +2025-12-09 04:10:04.442 INFO [src.node.api_clients.base_client] curl -v -X POST "http://127.0.0.1:5566/relay/v1/subscriptions" -H "Content-Type: application/json" -d '["/waku/2/rs/3/0"]' |
| 55 | +2025-12-09 04:10:04.444 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'OK' |
| 56 | +2025-12-09 04:10:04.445 DEBUG [src.steps.store] Relaying message |
| 57 | +2025-12-09 04:10:04.445 INFO [src.node.api_clients.base_client] curl -v -X POST "http://127.0.0.1:30246/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)'}' |
| 58 | +2025-12-09 04:10:04.450 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'OK' |
| 59 | +2025-12-09 04:10:04.451 DEBUG [src.libs.common] Sleeping for 0.2 seconds |
| 60 | +2025-12-09 04:10:04.651 DEBUG [src.steps.store] Relaying message |
| 61 | +2025-12-09 04:10:04.652 INFO [src.node.api_clients.base_client] curl -v -X POST "http://127.0.0.1:30246/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)'}' |
| 62 | +2025-12-09 04:10:04.656 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'OK' |
| 63 | +2025-12-09 04:10:04.657 DEBUG [src.libs.common] Sleeping for 0.2 seconds |
| 64 | +2025-12-09 04:10:04.859 DEBUG [src.steps.store] Relaying message |
| 65 | +2025-12-09 04:10:04.859 INFO [src.node.api_clients.base_client] curl -v -X POST "http://127.0.0.1:30246/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)'}' |
| 66 | +2025-12-09 04:10:04.863 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'OK' |
| 67 | +2025-12-09 04:10:04.863 DEBUG [src.libs.common] Sleeping for 0.2 seconds |
| 68 | +2025-12-09 04:10:05.065 DEBUG [src.steps.store] Relaying message |
| 69 | +2025-12-09 04:10:05.065 INFO [src.node.api_clients.base_client] curl -v -X POST "http://127.0.0.1:30246/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)'}' |
| 70 | +2025-12-09 04:10:05.070 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'OK' |
| 71 | +2025-12-09 04:10:05.070 DEBUG [src.libs.common] Sleeping for 0.2 seconds |
| 72 | +2025-12-09 04:10:05.271 DEBUG [src.steps.store] Relaying message |
| 73 | +2025-12-09 04:10:05.271 INFO [src.node.api_clients.base_client] curl -v -X POST "http://127.0.0.1:30246/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)'}' |
| 74 | +2025-12-09 04:10:05.275 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'OK' |
| 75 | +2025-12-09 04:10:05.275 DEBUG [src.libs.common] Sleeping for 0.2 seconds |
| 76 | +2025-12-09 04:10:05.476 DEBUG [src.steps.store] Relaying message |
| 77 | +2025-12-09 04:10:05.476 INFO [src.node.api_clients.base_client] curl -v -X POST "http://127.0.0.1:30246/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)'}' |
| 78 | +2025-12-09 04:10:05.481 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'OK' |
| 79 | +2025-12-09 04:10:05.481 DEBUG [src.libs.common] Sleeping for 0.2 seconds |
| 80 | +2025-12-09 04:10:05.682 INFO [src.node.api_clients.base_client] curl -v -X GET "http://127.0.0.1:30246/store/v3/messages?pubsubTopic=%2Fwaku%2F2%2Frs%2F3%2F0&startTime=1765253401445283168&endTime=1765253401445483168&pageSize=20&ascending=true" -H "Content-Type: application/json" -d 'None' |
| 81 | +2025-12-09 04:10:05.685 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'{"requestId":"","statusCode":200,"statusDesc":"OK","messages":[{"messageHash":"0xdf60ad7cf2952ab3b746eb1f0adac11d056db89de5248bdd66e0db160fc6c8be"}]}' |
| 82 | +2025-12-09 04:10:05.685 INFO [src.node.api_clients.base_client] curl -v -X GET "http://127.0.0.1:5566/store/v3/messages?pubsubTopic=%2Fwaku%2F2%2Frs%2F3%2F0&startTime=1765253401445283168&endTime=1765253401445483168&pageSize=20&ascending=true" -H "Content-Type: application/json" -d 'None' |
| 83 | +2025-12-09 04:10:05.687 INFO [src.node.api_clients.base_client] Response status code: 200. Response content: b'{"requestId":"","statusCode":200,"statusDesc":"OK","messages":[{"messageHash":"0xdf60ad7cf2952ab3b746eb1f0adac11d056db89de5248bdd66e0db160fc6c8be"}]}' |
| 84 | +2025-12-09 04:10:05.689 DEBUG [tests.conftest] Running fixture teardown: test_setup |
| 85 | +2025-12-09 04:10:05.690 DEBUG [tests.conftest] Running fixture teardown: close_open_nodes |
| 86 | +2025-12-09 04:10:05.690 DEBUG [src.node.waku_node] Stopping container with id f883f81d7d8b |
| 87 | +2025-12-09 04:10:06.245 DEBUG [src.node.waku_node] Container stopped. |
| 88 | +2025-12-09 04:10:06.245 DEBUG [src.node.waku_node] Stopping container with id e0cbe603ac5a |
| 89 | +2025-12-09 04:10:06.805 DEBUG [src.node.waku_node] Container stopped. |
| 90 | +2025-12-09 04:10:06.806 DEBUG [tests.conftest] Running fixture teardown: check_waku_log_errors |
| 91 | +2025-12-09 04:10:06.812 DEBUG [src.node.docker_mananger] No errors found in the waku logs. |
| 92 | +2025-12-09 04:10:06.819 DEBUG [src.node.docker_mananger] No errors found in the waku logs. |
0 commit comments