Skip to content

PR #1784 #2518

Sign in to view logs

PR #1784

PR #1784 #2518

GitHub Actions / Test Results failed Dec 9, 2024 in 0s

5 fail, 2 287 pass in 20m 43s

2 292 tests   - 147   2 287 ✅  - 152   20m 43s ⏱️ - 5m 53s
  120 suites  -  25       0 💤 ±  0 
    6 files    -   1       5 ❌ +  5 

Results for commit 82a5c31. ± Comparison against earlier commit ac2bccf.

Annotations

Check warning on line 0 in github.com/lavanet/lava/v4/testutil/e2e

See this annotation in the file changed.

@github-actions github-actions / Test Results

TestLavaProtocol (github.com/lavanet/lava/v4/testutil/e2e) failed 

artifacts/Test Results (Protocol E2E)/protocol-e2e-report.xml [took 4m 51s]
Raw output 
Failed
Dec  9 15:15:10 INF Starting Lava
Dec  9 15:15:10 INF Executing Command: ./scripts/start_env_dev.sh
Dec  9 15:15:10 INF Waiting for Lava
Dec  9 15:15:20 INF Waiting for Lava
Dec  9 15:15:30 INF Waiting for Lava
Dec  9 15:15:40 INF Waiting for Lava
Dec  9 15:15:50 INF Waiting for Lava
Dec  9 15:16:00 INF Waiting for Lava
Dec  9 15:16:10 INF Waiting for Lava
Dec  9 15:16:20 INF Waiting for Lava
Dec  9 15:16:30 INF Waiting for Lava
Dec  9 15:16:40 INF Waiting for Lava
Dec  9 15:16:50 INF Waiting for Lava
Dec  9 15:17:00 INF Waiting for Lava
Dec  9 15:17:10 INF Waiting for Lava
Dec  9 15:17:20 INF Starting Lava OK
Dec  9 15:17:20 INF Staking Lava
Dec  9 15:17:20 INF Executing Command: ./scripts/test/init_e2e.sh
Dec  9 15:18:44 INF stakeLava OK
ETH1
provider lava@1dp8qm722cz0ndlt2pnjtj6uvma7qecxw97pg9g [{127.0.0.1:2225 1 [] [jsonrpc] []}]
provider lava@1jkgwger67hvu83w4ucg6gk00pdceam6pfv3jvd [{127.0.0.1:2224 1 [] [jsonrpc] []}]
provider lava@1kluye25zx056n68e75sl5tydzfq5gxr4vd0t33 [{127.0.0.1:2223 1 [] [jsonrpc] []}]
provider lava@1rnhpr8p6wd0luu2tvjxmzyyrel64un24kg7qeu [{127.0.0.1:2221 1 [debug] [jsonrpc] [archive]}]
provider lava@1xy64p0p7uvll5tpnxjmkrygch035t37pv4qcfv [{127.0.0.1:2222 1 [] [jsonrpc] []}]
LAV1
provider lava@10e7vpyamr9vhl3p85jt3acgj8vc2aujy0d9gya [{127.0.0.1:2265 1 [] [grpc rest tendermintrpc] []}]
provider lava@17vk30y999afet57p8vrtwyrps2yyuauhsmd3h7 [{127.0.0.1:2262 1 [] [grpc rest tendermintrpc] []}]
provider lava@1g0kpuapkphg788qp4fhuczse0arw025uxvz48k [{127.0.0.1:2263 1 [] [grpc rest tendermintrpc] []}]
provider lava@1gtl68yxxyapzppqt88yvw0e8fe68yyqzah9e0k [{127.0.0.1:2261 1 [] [grpc rest tendermintrpc] []}]
provider lava@1wakzx2tgj0grjztew7sm5zpgdtyl6eczfxxgw0 [{127.0.0.1:2264 1 [] [grpc rest tendermintrpc] []}]
Dec  9 15:18:44 INF Staking Lava OK
Dec  9 15:18:44 INF RUNNING TESTS
Dec  9 15:18:44 INF Executing Command: /opt/hostedtoolcache/go/1.20.14/x64/bin/go test ./testutil/e2e/proxy/. -v -timeout 0 eth
Dec  9 15:18:44 INF startJSONRPCProxy OK
Dec  9 15:18:44 INF Waiting JSONRPC Consumer
Dec  9 15:18:45 INF Waiting JSONRPC Consumer
Dec  9 15:18:46 INF Waiting JSONRPC Consumer
Dec  9 15:18:47 INF Waiting JSONRPC Consumer
Dec  9 15:18:48 INF Waiting JSONRPC Consumer
Dec  9 15:18:49 INF Waiting JSONRPC Consumer
Dec  9 15:18:50 INF Waiting JSONRPC Consumer
Dec  9 15:18:51 INF Waiting JSONRPC Consumer
Dec  9 15:18:52 INF Waiting JSONRPC Consumer
Dec  9 15:18:53 INF Waiting JSONRPC Consumer
Dec  9 15:18:54 INF Waiting JSONRPC Consumer
Dec  9 15:18:55 INF Waiting JSONRPC Consumer
Dec  9 15:18:56 INF Waiting JSONRPC Consumer
Dec  9 15:18:57 INF Waiting JSONRPC Consumer
Dec  9 15:18:58 INF Waiting JSONRPC Consumer
Dec  9 15:18:59 INF Waiting JSONRPC Consumer
Dec  9 15:19:00 INF Waiting JSONRPC Consumer
Dec  9 15:19:01 INF Waiting JSONRPC Consumer
Dec  9 15:19:02 INF Waiting JSONRPC Consumer
Dec  9 15:19:03 INF JSONRPCProxy OK
Dec  9 15:19:03 INF Validated proxy is alive got response res=16482926
Dec  9 15:19:03 DBG Executing command /home/runner/go/bin/lavap rpcprovider ./testutil/e2e/e2eConfigs/provider/jsonrpcProvider1.yml --cache-be 127.0.0.1:2777 --chain-id=lava --from servicer1 --geolocation 1 --log_level debug
Dec  9 15:19:03 DBG Executing command /home/runner/go/bin/lavap rpcprovider ./testutil/e2e/e2eConfigs/provider/jsonrpcProvider2.yml --cache-be 127.0.0.1:2777 --chain-id=lava --from servicer2 --geolocation 1 --log_level debug
Dec  9 15:19:03 DBG Executing command /home/runner/go/bin/lavap rpcprovider ./testutil/e2e/e2eConfigs/provider/jsonrpcProvider3.yml --cache-be 127.0.0.1:2777 --chain-id=lava --from servicer3 --geolocation 1 --log_level debug
Dec  9 15:19:03 DBG Executing command /home/runner/go/bin/lavap rpcprovider ./testutil/e2e/e2eConfigs/provider/jsonrpcProvider4.yml --cache-be 127.0.0.1:2777 --chain-id=lava --from servicer4 --geolocation 1 --log_level debug
Dec  9 15:19:03 DBG Executing command /home/runner/go/bin/lavap rpcprovider ./testutil/e2e/e2eConfigs/provider/jsonrpcProvider5.yml --cache-be 127.0.0.1:2777 --chain-id=lava --from servicer5 --geolocation 1 --log_level debug
Dec  9 15:19:03 INF Waiting Provider 127.0.0.1:2221
Dec  9 15:19:05 INF Waiting Provider 127.0.0.1:2221
Dec  9 15:19:07 INF Waiting Provider 127.0.0.1:2221
Dec  9 15:19:09 INF Waiting Provider 127.0.0.1:2221
Dec  9 15:19:11 INF Waiting Provider 127.0.0.1:2221
Dec  9 15:19:13 INF Waiting Provider 127.0.0.1:2221
Dec  9 15:19:15 INF Waiting Provider 127.0.0.1:2221
Dec  9 15:19:17 INF Waiting Provider 127.0.0.1:2221
Dec  9 15:19:19 INF Waiting Provider 127.0.0.1:2221
Dec  9 15:19:21 INF Waiting Provider 127.0.0.1:2221
Dec  9 15:19:23 INF Waiting Provider 127.0.0.1:2221
Dec  9 15:19:25 INF Waiting Provider 127.0.0.1:2221
Dec  9 15:19:27 INF Waiting Provider 127.0.0.1:2221
Dec  9 15:19:29 INF Waiting Provider 127.0.0.1:2221
Dec  9 15:19:29 INF Waiting Provider 127.0.0.1:2222
Dec  9 15:19:29 INF Waiting Provider 127.0.0.1:2223
Dec  9 15:19:29 INF Waiting Provider 127.0.0.1:2224
Dec  9 15:19:29 INF startJSONRPCProvider OK
Dec  9 15:19:29 INF Executing Command: /home/runner/go/bin/lavap rpcconsumer ./testutil/e2e/e2eConfigs/consumer/ethConsumer1.yml --cache-be 127.0.0.1:2778 --chain-id=lava --from user1 --geolocation 1 --log_level debug --allow-insecure-provider-dialing
Dec  9 15:19:29 INF startJSONRPCConsumer OK
Dec  9 15:19:29 INF Waiting JSONRPC Consumer
Dec  9 15:19:30 INF Waiting JSONRPC Consumer
Dec  9 15:19:31 INF Waiting JSONRPC Consumer
Dec  9 15:19:32 INF Waiting JSONRPC Consumer
Dec  9 15:19:33 INF Waiting JSONRPC Consumer
Dec  9 15:19:33 INF JSONRPCConsumer1 OK
Dec  9 15:19:33 INF Validated proxy is alive got response res=16482926
Dec  9 15:19:33 INF Executing Command: /home/runner/go/bin/lavap rpcprovider ./testutil/e2e/e2eConfigs/provider/lavaProvider6 --cache-be 127.0.0.1:2777 --chain-id=lava --from servicer6 --geolocation 1 --log_level debug
Dec  9 15:19:33 INF Executing Command: /home/runner/go/bin/lavap rpcprovider ./testutil/e2e/e2eConfigs/provider/lavaProvider7 --cache-be 127.0.0.1:2777 --chain-id=lava --from servicer7 --geolocation 1 --log_level debug
Dec  9 15:19:33 INF Executing Command: /home/runner/go/bin/lavap rpcprovider ./testutil/e2e/e2eConfigs/provider/lavaProvider8 --cache-be 127.0.0.1:2777 --chain-id=lava --from servicer8 --geolocation 1 --log_level debug
Dec  9 15:19:33 INF Executing Command: /home/runner/go/bin/lavap rpcprovider ./testutil/e2e/e2eConfigs/provider/lavaProvider9 --cache-be 127.0.0.1:2777 --chain-id=lava --from servicer9 --geolocation 1 --log_level debug
Dec  9 15:19:33 INF Executing Command: /home/runner/go/bin/lavap rpcprovider ./testutil/e2e/e2eConfigs/provider/lavaProvider10 --cache-be 127.0.0.1:2777 --chain-id=lava --from servicer10 --geolocation 1 --log_level debug
Dec  9 15:19:33 INF Waiting Provider 127.0.0.1:2261
Dec  9 15:19:35 INF Waiting Provider 127.0.0.1:2261
Dec  9 15:19:37 INF Waiting Provider 127.0.0.1:2261
Dec  9 15:19:38 INF Waiting Provider 127.0.0.1:2262
Dec  9 15:19:38 INF Waiting Provider 127.0.0.1:2263
Dec  9 15:19:38 INF Waiting Provider 127.0.0.1:2264
Dec  9 15:19:38 INF Waiting Provider 127.0.0.1:2265
Dec  9 15:19:38 INF startLavaProviders OK
Dec  9 15:19:38 INF Executing Command: /home/runner/go/bin/lavap rpcconsumer ./testutil/e2e/e2eConfigs/consumer/lavaConsumer1.yml --cache-be 127.0.0.1:2778 --chain-id=lava --from user3 --geolocation 1 --log_level debug --allow-insecure-provider-dialing
Dec  9 15:19:38 INF startRPCConsumer OK
Dec  9 15:19:38 INF Waiting TENDERMINT Consumer
Dec  9 15:19:39 INF Waiting TENDERMINT Consumer
Dec  9 15:19:40 INF Waiting TENDERMINT Consumer
Dec  9 15:19:41 INF Waiting TENDERMINT Consumer
Dec  9 15:19:42 INF Waiting TENDERMINT Consumer
Dec  9 15:19:42 INF checkTendermintConsumer OK
Dec  9 15:19:42 INF Waiting REST Consumer
Dec  9 15:19:43 INF Waiting REST Consumer
Dec  9 15:19:43 INF checkRESTConsumer OK
Dec  9 15:19:43 INF Waiting GRPC Consumer
Dec  9 15:19:43 INF checkGRPCConsumer OK
Dec  9 15:19:43 INF Starting JSONRPC Tests
Dec  9 15:20:01 DBG success writing to file bytesWritten=18640 fileName=03_EthProvider_03 lines=18640
Dec  9 15:20:01 DBG success writing to file bytesWritten=7565 fileName=03_EthProvider_05 lines=7565
Dec  9 15:20:01 DBG success writing to file bytesWritten=418671 fileName=00_StartLava lines=418671
Dec  9 15:20:01 DBG success writing to file bytesWritten=8588 fileName=01_stakeLava lines=8588
Dec  9 15:20:01 DBG success writing to file bytesWritten=16326 fileName=05_LavaProvider_03 lines=16326
Dec  9 15:20:01 DBG success writing to file bytesWritten=17286 fileName=05_LavaProvider_04 lines=17286
Dec  9 15:20:01 ERR startJSONRPCProxy cmd wait err error="signal: killed"
Dec  9 15:20:01 DBG success writing to file bytesWritten=16655 fileName=03_EthProvider_02 lines=16655
Dec  9 15:20:01 DBG success writing to file bytesWritten=83335 fileName=04_jsonConsumer_01 lines=83335
Dec  9 15:20:01 DBG success writing to file bytesWritten=15403 fileName=05_LavaProvider_01 lines=15403
Dec  9 15:20:01 DBG success writing to file bytesWritten=18984 fileName=05_LavaProvider_02 lines=18984
Dec  9 15:20:01 DBG success writing to file bytesWritten=35510 fileName=06_RPCConsumer_01 lines=35510
Dec  9 15:20:01 DBG success writing to file bytesWritten=18640 fileName=03_EthProvider_03 lines=18640
Dec  9 15:20:01 DBG success writing to file bytesWritten=7565 fileName=03_EthProvider_05 lines=7565
Dec  9 15:20:01 DBG success writing to file bytesWritten=418671 fileName=00_StartLava lines=418671
Dec  9 15:20:01 DBG success writing to file bytesWritten=8588 fileName=01_stakeLava lines=8588
Dec  9 15:20:01 DBG success writing to file bytesWritten=16326 fileName=05_LavaProvider_03 lines=16326
Dec  9 15:20:01 DBG success writing to file bytesWritten=17286 fileName=05_LavaProvider_04 lines=17286
Dec  9 15:20:01 ERR startJSONRPCProvider (provider 01) cmd wait err error="signal: killed"
Dec  9 15:20:01 DBG success writing to file bytesWritten=16326 fileName=05_LavaProvider_03 lines=16326
Dec  9 15:20:01 DBG success writing to file bytesWritten=17286 fileName=05_LavaProvider_04 lines=17286
Dec  9 15:20:01 DBG success writing to file bytesWritten=418671 fileName=00_StartLava lines=418671
Dec  9 15:20:01 DBG success writing to file bytesWritten=8588 fileName=01_stakeLava lines=8588
Dec  9 15:20:01 DBG success writing to file bytesWritten=7565 fileName=03_EthProvider_04 lines=7565
Dec  9 15:20:01 DBG success writing to file bytesWritten=16345 fileName=05_LavaProvider_05 lines=16345
Dec  9 15:20:01 ERR startLavaProviders (provider 04) cmd wait err error="signal: killed"
Dec  9 15:20:01 DBG success writing to file bytesWritten=33023 fileName=03_EthProvider_01 lines=33023
Dec  9 15:20:01 DBG success writing to file bytesWritten=7565 fileName=03_EthProvider_04 lines=7565
Dec  9 15:20:01 DBG success writing to file bytesWritten=16345 fileName=05_LavaProvider_05 lines=16345
Dec  9 15:20:01 ERR startLavaProviders (provider 01) cmd wait err error="signal: killed"
Dec  9 15:20:01 DBG success writing to file bytesWritten=8588 fileName=01_stakeLava lines=8588
Dec  9 15:20:01 ERR startJSONRPCProvider (provider 04) cmd wait err error="signal: killed"
Dec  9 15:20:01 DBG success writing to file bytesWritten=35510 fileName=06_RPCConsumer_01 lines=35510
Dec  9 15:20:01 DBG success writing to file bytesWritten=16326 fileName=05_LavaProvider_03 lines=16326
Dec  9 15:20:01 DBG success writing to file bytesWritten=17286 fileName=05_LavaProvider_04 lines=17286
Dec  9 15:20:01 DBG success writing to file bytesWritten=16655 fileName=03_EthProvider_02 lines=16655
Dec  9 15:20:01 DBG success writing to file bytesWritten=83335 fileName=04_jsonConsumer_01 lines=83335
Dec  9 15:20:01 DBG success writing to file bytesWritten=418671 fileName=00_StartLava lines=418671
Dec  9 15:20:01 DBG success writing to file bytesWritten=15403 fileName=05_LavaProvider_01 lines=15403
Dec  9 15:20:01 DBG success writing to file bytesWritten=33023 fileName=03_EthProvider_01 lines=33023
Dec  9 15:20:01 DBG success writing to file bytesWritten=18984 fileName=05_LavaProvider_02 lines=18984
Dec  9 15:20:01 DBG success writing to file bytesWritten=7565 fileName=03_EthProvider_04 lines=7565
Dec  9 15:20:01 DBG success writing to file bytesWritten=18640 fileName=03_EthProvider_03 lines=18640
Dec  9 15:20:01 DBG success writing to file bytesWritten=16345 fileName=05_LavaProvider_05 lines=16345
Dec  9 15:20:01 DBG success writing to file bytesWritten=7565 fileName=03_EthProvider_05 lines=7565
Dec  9 15:20:01 ERR startLavaProviders (provider 05) cmd wait err error="signal: killed"
Dec  9 15:20:01 ERR startRPCConsumer (consumer 01) cmd wait err error="signal: killed"
Dec  9 15:20:01 DBG success writing to file bytesWritten=8588 fileName=01_stakeLava lines=8588
Dec  9 15:20:01 DBG success writing to file bytesWritten=18984 fileName=05_LavaProvider_02 lines=18984
Dec  9 15:20:01 DBG success writing to file bytesWritten=16326 fileName=05_LavaProvider_03 lines=16326
Dec  9 15:20:01 DBG success writing to file bytesWritten=35510 fileName=06_RPCConsumer_01 lines=35510
Dec  9 15:20:01 DBG success writing to file bytesWritten=17286 fileName=05_LavaProvider_04 lines=17286
Dec  9 15:20:01 DBG success writing to file bytesWritten=418671 fileName=00_StartLava lines=418671
Dec  9 15:20:01 DBG success writing to file bytesWritten=16655 fileName=03_EthProvider_02 lines=16655
Dec  9 15:20:01 DBG success writing to file bytesWritten=8588 fileName=01_stakeLava lines=8588
Dec  9 15:20:01 DBG success writing to file bytesWritten=83335 fileName=04_jsonConsumer_01 lines=83335
Dec  9 15:20:01 DBG success writing to file bytesWritten=16326 fileName=05_LavaProvider_03 lines=16326
Dec  9 15:20:01 DBG success writing to file bytesWritten=17286 fileName=05_LavaProvider_04 lines=17286
Dec  9 15:20:01 DBG success writing to file bytesWritten=15403 fileName=05_LavaProvider_01 lines=15403
Dec  9 15:20:01 DBG success writing to file bytesWritten=18640 fileName=03_EthProvider_03 lines=18640
Dec  9 15:20:01 ERR startJSONRPCProvider (provider 03) cmd wait err error="signal: killed"
Dec  9 15:20:01 DBG success writing to file bytesWritten=7565 fileName=03_EthProvider_05 lines=7565
Dec  9 15:20:01 DBG success writing to file bytesWritten=18640 fileName=03_EthProvider_03 lines=18640
Dec  9 15:20:01 DBG success writing to file bytesWritten=17286 fileName=05_LavaProvider_04 lines=17286
Dec  9 15:20:01 DBG success writing to file bytesWritten=7565 fileName=03_EthProvider_05 lines=7565
Dec  9 15:20:01 DBG success writing to file bytesWritten=418671 fileName=00_StartLava lines=418671
Dec  9 15:20:01 DBG success writing to file bytesWritten=33023 fileName=03_EthProvider_01 lines=33023
Dec  9 15:20:01 DBG success writing to file bytesWritten=7565 fileName=03_EthProvider_04 lines=7565
Dec  9 15:20:01 DBG success writing to file bytesWritten=16345 fileName=05_LavaProvider_05 lines=16345
Dec  9 15:20:01 DBG success writing to file bytesWritten=418671 fileName=00_StartLava lines=418671
Dec  9 15:20:01 DBG success writing to file bytesWritten=8588 fileName=01_stakeLava lines=8588
Dec  9 15:20:01 DBG success writing to file bytesWritten=16326 fileName=05_LavaProvider_03 lines=16326
Dec  9 15:20:01 DBG success writing to file bytesWritten=16345 fileName=05_LavaProvider_05 lines=16345
Dec  9 15:20:01 ERR startJSONRPCConsumer (consumer 01) cmd wait err error="signal: killed"
Dec  9 15:20:01 DBG success writing to file bytesWritten=7565 fileName=03_EthProvider_04 lines=7565
Dec  9 15:20:01 ERR startJSONRPCProvider (provider 05) cmd wait err error="signal: killed"
Dec  9 15:20:01 DBG success writing to file bytesWritten=16345 fileName=05_LavaProvider_05 lines=16345
Dec  9 15:20:01 DBG success writing to file bytesWritten=418671 fileName=00_StartLava lines=418671
Dec  9 15:20:01 ERR startLavaProviders (provider 03) cmd wait err error="signal: killed"
Dec  9 15:20:01 ERR startLavaProviders (provider 02) cmd wait err error="signal: killed"
Dec  9 15:20:01 DBG success writing to file bytesWritten=16655 fileName=03_EthProvider_02 lines=16655
Dec  9 15:20:01 DBG success writing to file bytesWritten=18640 fileName=03_EthProvider_03 lines=18640
Dec  9 15:20:01 DBG success writing to file bytesWritten=83335 fileName=04_jsonConsumer_01 lines=83335
Dec  9 15:20:01 DBG success writing to file bytesWritten=7565 fileName=03_EthProvider_05 lines=7565
Dec  9 15:20:01 DBG success writing to file bytesWritten=15403 fileName=05_LavaProvider_01 lines=15403
Dec  9 15:20:01 DBG success writing to file bytesWritten=18984 fileName=05_LavaProvider_02 lines=18984
Dec  9 15:20:01 DBG success writing to file bytesWritten=35510 fileName=06_RPCConsumer_01 lines=35510
Dec  9 15:20:01 DBG success writing to file bytesWritten=18640 fileName=03_EthProvider_03 lines=18640
Dec  9 15:20:01 ERR startJSONRPCProvider (provider 02) cmd wait err error="signal: killed"
Dec  9 15:20:01 DBG success writing to file bytesWritten=7565 fileName=03_EthProvider_05 lines=7565
Dec  9 15:20:01 DBG success writing to file bytesWritten=18640 fileName=03_EthProvider_03 lines=18640
Dec  9 15:20:01 DBG success writing to file bytesWritten=8588 fileName=01_stakeLava lines=8588
Dec  9 15:20:01 DBG success writing to file bytesWritten=16326 fileName=05_LavaProvider_03 lines=16326
Dec  9 15:20:01 DBG success writing to file bytesWritten=17286 fileName=05_LavaProvider_04 lines=17286
Dec  9 15:20:01 DBG success writing to file bytesWritten=7565 fileName=03_EthProvider_05 lines=7565
Dec  9 15:20:01 DBG success writing to file bytesWritten=418671 fileName=00_StartLava lines=420905
Dec  9 15:20:01 DBG success writing to file bytesWritten=8588 fileName=01_stakeLava lines=8588
Dec  9 15:20:01 DBG success writing to file bytesWritten=16326 fileName=05_LavaProvider_03 lines=16326
Dec  9 15:20:01 DBG success writing to file bytesWritten=428473 fileName=00_StartLava lines=431454
Dec  9 15:20:01 DBG success writing to file bytesWritten=8588 fileName=01_stakeLava lines=8588
Dec  9 15:20:01 DBG success writing to file bytesWritten=16326 fileName=05_LavaProvider_03 lines=16326
Dec  9 15:20:01 DBG success writing to file bytesWritten=437126 fileName=00_StartLava lines=440728
Dec  9 15:20:01 DBG success writing to file bytesWritten=8588 fileName=01_stakeLava lines=8588
Dec  9 15:20:01 DBG success writing to file bytesWritten=16326 fileName=05_LavaProvider_03 lines=16326
Dec  9 15:20:01 DBG success writing to file bytesWritten=17286 fileName=05_LavaProvider_04 lines=17286
Dec  9 15:20:01 DBG success writing to file bytesWritten=17286 fileName=05_LavaProvider_04 lines=17286
Dec  9 15:20:01 DBG success writing to file bytesWritten=17286 fileName=05_LavaProvider_04 lines=17286
Dec  9 15:20:01 DBG success writing to file bytesWritten=2914679 fileName=02_jsonProxy lines=2914679
Dec  9 15:20:01 DBG success writing to file bytesWritten=33023 fileName=03_EthProvider_01 lines=33023
Dec  9 15:20:01 DBG success writing to file bytesWritten=7565 fileName=03_EthProvider_04 lines=7565
Dec  9 15:20:01 DBG success writing to file bytesWritten=16345 fileName=05_LavaProvider_05 lines=16345
Dec  9 15:20:01 DBG success writing to file bytesWritten=16655 fileName=03_EthProvider_02 lines=16655
Dec  9 15:20:01 DBG success writing to file bytesWritten=2914679 fileName=02_jsonProxy lines=2914679
Dec  9 15:20:01 DBG success writing to file bytesWritten=83335 fileName=04_jsonConsumer_01 lines=83335
Dec  9 15:20:01 DBG success writing to file bytesWritten=15403 fileName=05_LavaProvider_01 lines=15403
Dec  9 15:20:01 DBG success writing to file bytesWritten=18984 fileName=05_LavaProvider_02 lines=18984
Dec  9 15:20:01 DBG success writing to file bytesWritten=35510 fileName=06_RPCConsumer_01 lines=35510
ERROR:  Dec  9 15:19:06 ERR Failed To Connect to cache at address error="context deadline exceeded" address=127.0.0.1:2777
ERROR:  Dec  9 15:19:36 ERR Failed To Connect to cache at address error="context deadline exceeded" address=127.0.0.1:2777
ERROR:  Dec  9 15:19:06 ERR Failed To Connect to cache at address error="context deadline exceeded" address=127.0.0.1:2777
ERROR:  Dec  9 15:19:06 ERR Failed To Connect to cache at address error="context deadline exceeded" address=127.0.0.1:2777
ERROR:  Dec  9 15:19:36 ERR Failed To Connect to cache at address error="context deadline exceeded" address=127.0.0.1:2777
ERROR:  Dec  9 15:19:36 ERR Failed To Connect to cache at address error="context deadline exceeded" address=127.0.0.1:2777
ERROR:  Dec  9 15:19:06 ERR Failed To Connect to cache at address error="context deadline exceeded" address=127.0.0.1:2777
ERROR:  Dec  9 15:19:36 ERR Failed To Connect to cache at address error="context deadline exceeded" address=127.0.0.1:2777
ERROR:  Dec  9 15:19:36 ERR Failed To Connect to cache at address error="context deadline exceeded" address=127.0.0.1:2777
ERROR:  Dec  9 15:19:06 ERR Failed To Connect to cache at address error="context deadline exceeded" address=127.0.0.1:2777
ERROR:  Dec  9 15:19:32 ERR Failed To Connect to cache at address error="context deadline exceeded" address=127.0.0.1:2778
Dec  9 15:19:48 ERR failed getting responses from providers error="No pairings available." GUID=17016919630825368818 endpoint=ETH1jsonrpc relayProcessor="relayProcessor {resultsManager {success 0, nodeErrors:0, protocolErrors:0}, unwantedAddresses: ,currentlyUsedAddresses:}" userIp=127.0.0.1
Dec  9 15:19:48 ERR UniqueGuidResponseForError error="No pairings available." msgSeed=17016919630825368818
Dec  9 15:19:48 ERR jsonrpc http error="No pairings available." GUID=17016919630825368818 HasError=true method=POST path=http://127.0.0.1:3331/ request="{\"jsonrpc\":\"2.0\",\"id\":10,\"method\":\"eth_getBlockByNumber\",\"params\":[\"0x0\",true]}" response="{\"Error_GUID\":\"17016919630825368818\",\"Error\":\"No pairings available.\"}" timeTaken=17.315878ms
Dec  9 15:19:52 ERR failed getting responses from providers error="No pairings available." GUID=8042375026061314733 endpoint=ETH1jsonrpc relayProcessor="relayProcessor {resultsManager {success 0, nodeErrors:0, protocolErrors:0}, unwantedAddresses: ,currentlyUsedAddresses:}" userIp=127.0.0.1
ERROR:  Dec  9 15:19:41 ERR Failed To Connect to cache at address error="context deadline exceeded" address=127.0.0.1:2778

Check warning on line 0 in github.com/lavanet/lava/v4/protocol/integration

See this annotation in the file changed.

@github-actions github-actions / Test Results

TestConsumerProviderWithProviders (github.com/lavanet/lava/v4/protocol/integration) failed 

artifacts/Test Results (Protocol)/protocol-report.xml [took 9s]
Raw output 
Failed

Check warning on line 0 in github.com/lavanet/lava/v4/protocol/integration

See this annotation in the file changed.

@github-actions github-actions / Test Results

TestConsumerProviderWithProviders/basic-success (github.com/lavanet/lava/v4/protocol/integration) failed 

artifacts/Test Results (Protocol)/protocol-report.xml [took 7s]
Raw output 
Failed
Dec  9 15:15:39 INF setting log level loglevel=debug
Dec  9 15:15:39 DBG batched nodeUrls by services batch="map[|websocket|:{NetworkAddress:{Address: KeyPem: CertPem: DisableTLS:false} ChainID:LAV1 ApiInterface:tendermintrpc Geolocation:1 NodeUrls:[ws://127.0.0.1:46707, addons: (websocket), internal-path: ]} ||:{NetworkAddress:{Address: KeyPem: CertPem: DisableTLS:false} ChainID:LAV1 ApiInterface:tendermintrpc Geolocation:1 NodeUrls:[http://127.0.0.1:46077]}]"
Dec  9 15:15:39 DBG router keys chainProxyRouter="map[|websocket|:[{ChainProxy:0xc00770f560 addonsSupported:map[:{}] methodsRouted:map[]}] ||:[{ChainProxy:0xc0073c7b00 addonsSupported:map[:{}] methodsRouted:map[]}]]"
Dec  9 15:15:39 DBG chainRouter created chainProxyRouter="map[|websocket|:[{ChainProxy:0xc00770f560 addonsSupported:map[:{}] methodsRouted:map[]}] ||:[{ChainProxy:0xc0073c7b00 addonsSupported:map[:{}] methodsRouted:map[]}]]"
Dec  9 15:15:39 INF Finished adding Clients Asynchronously free clients=1 url=http://127.0.0.1:46077
Dec  9 15:15:39 INF Finished adding Clients Asynchronously free clients=1 url="ws://127.0.0.1:46707, addons: (websocket), internal-path: "
badger 2024/12/09 15:15:39 INFO: Set nextTxnTs to 0
Dec  9 15:15:39 DBG Chain Tracker Updated block hashes ApiInterface= ChainID= blocksKept=1 blocksQueried=0 blocksQueueLen=1 latestHash=stubHash-1000 latest_block=1000 nextBlocksUpdate=1100
Dec  9 15:15:39 DBG ChainTracker fetched init data successfully
Dec  9 15:15:39 WRN Warning: Using Self signed certificate is not recommended, this will not allow https connections to be established
Dec  9 15:15:40 INF [++] Provider Listening on Address Address="{Address:localhost:1027 KeyPem: CertPem: DisableTLS:false}" apiInterface=tendermintrpc chainID=LAV1
Dec  9 15:15:40 INF New provider listener active address="{Address:localhost:1027 KeyPem: CertPem: DisableTLS:false}"
Dec  9 15:15:40 INF setting log level loglevel=debug
Dec  9 15:15:40 DBG batched nodeUrls by services batch="map[|websocket|:{NetworkAddress:{Address: KeyPem: CertPem: DisableTLS:false} ChainID:LAV1 ApiInterface:tendermintrpc Geolocation:1 NodeUrls:[ws://127.0.0.1:44653, addons: (websocket), internal-path: ]} ||:{NetworkAddress:{Address: KeyPem: CertPem: DisableTLS:false} ChainID:LAV1 ApiInterface:tendermintrpc Geolocation:1 NodeUrls:[http://127.0.0.1:41209]}]"
Dec  9 15:15:40 INF Finished adding Clients Asynchronously free clients=1 url=http://127.0.0.1:41209
Dec  9 15:15:40 DBG router keys chainProxyRouter="map[|websocket|:[{ChainProxy:0xc00707b560 addonsSupported:map[:{}] methodsRouted:map[]}] ||:[{ChainProxy:0xc00707b320 addonsSupported:map[:{}] methodsRouted:map[]}]]"
Dec  9 15:15:40 DBG chainRouter created chainProxyRouter="map[|websocket|:[{ChainProxy:0xc00707b560 addonsSupported:map[:{}] methodsRouted:map[]}] ||:[{ChainProxy:0xc00707b320 addonsSupported:map[:{}] methodsRouted:map[]}]]"
Dec  9 15:15:40 INF Finished adding Clients Asynchronously free clients=1 url="ws://127.0.0.1:44653, addons: (websocket), internal-path: "
badger 2024/12/09 15:15:40 INFO: Set nextTxnTs to 0
Dec  9 15:15:40 DBG Chain Tracker Updated block hashes ApiInterface= ChainID= blocksKept=1 blocksQueried=0 blocksQueueLen=1 latestHash=stubHash-1000 latest_block=1000 nextBlocksUpdate=1100
Dec  9 15:15:40 DBG ChainTracker fetched init data successfully
Dec  9 15:15:40 WRN Warning: Using Self signed certificate is not recommended, this will not allow https connections to be established
Dec  9 15:15:40 INF [++] Provider Listening on Address Address="{Address:localhost:1028 KeyPem: CertPem: DisableTLS:false}" apiInterface=tendermintrpc chainID=LAV1
Dec  9 15:15:40 INF New provider listener active address="{Address:localhost:1028 KeyPem: CertPem: DisableTLS:false}"
Dec  9 15:15:40 INF setting log level loglevel=debug
Dec  9 15:15:40 DBG batched nodeUrls by services batch="map[|websocket|:{NetworkAddress:{Address: KeyPem: CertPem: DisableTLS:false} ChainID:LAV1 ApiInterface:tendermintrpc Geolocation:1 NodeUrls:[ws://127.0.0.1:44367, addons: (websocket), internal-path: ]} ||:{NetworkAddress:{Address: KeyPem: CertPem: DisableTLS:false} ChainID:LAV1 ApiInterface:tendermintrpc Geolocation:1 NodeUrls:[http://127.0.0.1:44559]}]"
Dec  9 15:15:40 INF Finished adding Clients Asynchronously free clients=1 url=http://127.0.0.1:44559
Dec  9 15:15:40 DBG router keys chainProxyRouter="map[|websocket|:[{ChainProxy:0xc001b29680 addonsSupported:map[:{}] methodsRouted:map[]}] ||:[{ChainProxy:0xc001b29440 addonsSupported:map[:{}] methodsRouted:map[]}]]"
Dec  9 15:15:40 INF Finished adding Clients Asynchronously free clients=1 url="ws://127.0.0.1:44367, addons: (websocket), internal-path: "
Dec  9 15:15:40 DBG chainRouter created chainProxyRouter="map[|websocket|:[{ChainProxy:0xc001b29680 addonsSupported:map[:{}] methodsRouted:map[]}] ||:[{ChainProxy:0xc001b29440 addonsSupported:map[:{}] methodsRouted:map[]}]]"
badger 2024/12/09 15:15:40 INFO: Set nextTxnTs to 0
Dec  9 15:15:40 DBG Chain Tracker Updated block hashes ApiInterface= ChainID= blocksKept=1 blocksQueried=0 blocksQueueLen=1 latestHash=stubHash-1000 latest_block=1000 nextBlocksUpdate=1100
Dec  9 15:15:40 DBG ChainTracker fetched init data successfully
Dec  9 15:15:40 WRN Warning: Using Self signed certificate is not recommended, this will not allow https connections to be established
Dec  9 15:15:40 INF [++] Provider Listening on Address Address="{Address:localhost:1029 KeyPem: CertPem: DisableTLS:false}" apiInterface=tendermintrpc chainID=LAV1
Dec  9 15:15:40 INF New provider listener active address="{Address:localhost:1029 KeyPem: CertPem: DisableTLS:false}"
Dec  9 15:15:40 INF setting log level loglevel=debug
Dec  9 15:15:40 DBG batched nodeUrls by services batch="map[|websocket|:{NetworkAddress:{Address: KeyPem: CertPem: DisableTLS:false} ChainID:LAV1 ApiInterface:tendermintrpc Geolocation:1 NodeUrls:[ws://127.0.0.1:42495, addons: (websocket), internal-path: ]} ||:{NetworkAddress:{Address: KeyPem: CertPem: DisableTLS:false} ChainID:LAV1 ApiInterface:tendermintrpc Geolocation:1 NodeUrls:[http://127.0.0.1:36579]}]"
Dec  9 15:15:40 INF Finished adding Clients Asynchronously free clients=1 url=http://127.0.0.1:36579
Dec  9 15:15:40 DBG router keys chainProxyRouter="map[|websocket|:[{ChainProxy:0xc00770f440 addonsSupported:map[:{}] methodsRouted:map[]}] ||:[{ChainProxy:0xc00770f200 addonsSupported:map[:{}] methodsRouted:map[]}]]"
Dec  9 15:15:40 INF Finished adding Clients Asynchronously free clients=1 url="ws://127.0.0.1:42495, addons: (websocket), internal-path: "
Dec  9 15:15:40 DBG chainRouter created chainProxyRouter="map[|websocket|:[{ChainProxy:0xc00770f440 addonsSupported:map[:{}] methodsRouted:map[]}] ||:[{ChainProxy:0xc00770f200 addonsSupported:map[:{}] methodsRouted:map[]}]]"
badger 2024/12/09 15:15:40 INFO: Set nextTxnTs to 0
Dec  9 15:15:40 DBG Chain Tracker Updated block hashes ApiInterface= ChainID= blocksKept=1 blocksQueried=0 blocksQueueLen=1 latestHash=stubHash-1000 latest_block=1000 nextBlocksUpdate=1100
Dec  9 15:15:40 DBG ChainTracker fetched init data successfully
Dec  9 15:15:40 WRN Warning: Using Self signed certificate is not recommended, this will not allow https connections to be established
Dec  9 15:15:41 INF [++] Provider Listening on Address Address="{Address:localhost:1030 KeyPem: CertPem: DisableTLS:false}" apiInterface=tendermintrpc chainID=LAV1
Dec  9 15:15:41 INF New provider listener active address="{Address:localhost:1030 KeyPem: CertPem: DisableTLS:false}"
Dec  9 15:15:41 INF setting log level loglevel=debug
Dec  9 15:15:41 DBG batched nodeUrls by services batch="map[|websocket|:{NetworkAddress:{Address: KeyPem: CertPem: DisableTLS:false} ChainID:LAV1 ApiInterface:tendermintrpc Geolocation:1 NodeUrls:[ws://127.0.0.1:36797, addons: (websocket), internal-path: ]} ||:{NetworkAddress:{Address: KeyPem: CertPem: DisableTLS:false} ChainID:LAV1 ApiInterface:tendermintrpc Geolocation:1 NodeUrls:[http://127.0.0.1:43553]}]"
Dec  9 15:15:41 INF Finished adding Clients Asynchronously free clients=1 url=http://127.0.0.1:43553
Dec  9 15:15:41 DBG router keys chainProxyRouter="map[|websocket|:[{ChainProxy:0xc007adf7a0 addonsSupported:map[:{}] methodsRouted:map[]}] ||:[{ChainProxy:0xc007adf560 addonsSupported:map[:{}] methodsRouted:map[]}]]"
Dec  9 15:15:41 DBG chainRouter created chainProxyRouter="map[|websocket|:[{ChainProxy:0xc007adf7a0 addonsSupported:map[:{}] methodsRouted:map[]}] ||:[{ChainProxy:0xc007adf560 addonsSupported:map[:{}] methodsRouted:map[]}]]"
Dec  9 15:15:41 INF Finished adding Clients Asynchronously free clients=1 url="ws://127.0.0.1:36797, addons: (websocket), internal-path: "
badger 2024/12/09 15:15:41 INFO: Set nextTxnTs to 0
Dec  9 15:15:41 DBG Chain Tracker Updated block hashes ApiInterface= ChainID= blocksKept=1 blocksQueried=0 blocksQueueLen=1 latestHash=stubHash-1000 latest_block=1000 nextBlocksUpdate=1100
Dec  9 15:15:41 DBG ChainTracker fetched init data successfully
Dec  9 15:15:41 WRN Warning: Using Self signed certificate is not recommended, this will not allow https connections to be established
Dec  9 15:15:41 INF [++] Provider Listening on Address Address="{Address:localhost:1031 KeyPem: CertPem: DisableTLS:false}" apiInterface=tendermintrpc chainID=LAV1
Dec  9 15:15:41 INF New provider listener active address="{Address:localhost:1031 KeyPem: CertPem: DisableTLS:false}"
Dec  9 15:15:41 INF setting log level loglevel=debug
Dec  9 15:15:41 DBG batched nodeUrls by services batch="map[|websocket|:{NetworkAddress:{Address: KeyPem: CertPem: DisableTLS:false} ChainID:LAV1 ApiInterface:tendermintrpc Geolocation:1 NodeUrls:[ws://127.0.0.1:37977, addons: (websocket), internal-path: ]} ||:{NetworkAddress:{Address: KeyPem: CertPem: DisableTLS:false} ChainID:LAV1 ApiInterface:tendermintrpc Geolocation:1 NodeUrls:[http://127.0.0.1:37525]}]"
Dec  9 15:15:41 INF Finished adding Clients Asynchronously free clients=1 url=http://127.0.0.1:37525
Dec  9 15:15:41 DBG router keys chainProxyRouter="map[|websocket|:[{ChainProxy:0xc000be07e0 addonsSupported:map[:{}] methodsRouted:map[]}] ||:[{ChainProxy:0xc00707be60 addonsSupported:map[:{}] methodsRouted:map[]}]]"
Dec  9 15:15:41 DBG chainRouter created chainProxyRouter="map[|websocket|:[{ChainProxy:0xc000be07e0 addonsSupported:map[:{}] methodsRouted:map[]}] ||:[{ChainProxy:0xc00707be60 addonsSupported:map[:{}] methodsRouted:map[]}]]"
Dec  9 15:15:41 INF Finished adding Clients Asynchronously free clients=1 url="ws://127.0.0.1:37977, addons: (websocket), internal-path: "
Dec  9 15:15:41 DBG updated providers epoch=100 spec=LAV1tendermintrpc
Dec  9 15:15:41 INF New relic missing environment file
Dec  9 15:15:41 DBG Sending crafted relays apiInterface=tendermintrpc chainId=LAV1

 ┌───────────────────────────────────────────────────┐ 
 │                   Fiber v2.52.5                   │ 
 │               http://127.0.0.1:1026               │ 
 │                                                   │ 
 │ Handlers ............ 13  Processes ........... 1 │ 
 │ Prefork ....... Disabled  PID ............. 18525 │ 
 └───────────────────────────────────────────────────┘ 

Dec  9 15:15:41 WRN provider data not found, using default address=cosmos1xs4pew8e2ea9ctvv66mzukdde45alxf6ygj3qc
Dec  9 15:15:41 DBG Provider got relay request GUID=9745270758427676735 relay addon= relay extensions= relay_timeout=29.999922164s request.SessionId=8958534548783151220 request.cu=10 request.relayNumber=1 requestBlock=-2 seenBlock=0
Dec  9 15:15:41 DBG provider registered consumer consumer=cosmos14gth2jcy2c6wpnqu6yq4052r6nchr3vehurl6c epoch=100
Dec  9 15:15:41 DBG Provider creating new sessionID SessionID=8958534548783151220 epoch=100
Dec  9 15:15:41 DBG Before Update Normal PrepareSessionForUsage GUID=9745270758427676735 relayNum=0 relayRequestTotalCU=10 sps.CuSum=10 sps.LatestRelayCu=10 sps.sessionId=8958534548783151220
Dec  9 15:15:41 ERR failed to unmarshal id from response error="invalid character '\x00' looking for beginning of value" id=
Dec  9 15:15:41 ERR tendermintRPC error error="failed to unmarshal id from response ErrMsg: invalid character '\x00' looking for beginning of value {id:}: invalid character '\x00' looking for beginning of value"
Dec  9 15:15:41 ERR Sending chainMsg failed error="tendermintRPC error ErrMsg: failed to unmarshal id from response ErrMsg: invalid character '\x00' looking for beginning of value {id:}: invalid character '\x00' looking for beginning of value: failed to unmarshal id from response ErrMsg: invalid character '\x00' looking for beginning of value {id:}: invalid character '\x00' looking for beginning of value" GUID=9745270758427676735 attempt=0 specID=LAV1
Dec  9 15:15:41 ERR TryRelay Failed error="Sending chainMsg failed ErrMsg: tendermintRPC error ErrMsg: failed to unmarshal id from response ErrMsg: invalid character '\x00' looking for beginning of value {id:}: invalid character '\x00' looking for beginning of value: failed to unmarshal id from response ErrMsg: invalid character '\x00' looking for beginning of value {id:}: invalid character '\x00' looking for beginning of value {attempt:0,GUID:9745270758427676735,specID:LAV1}: tendermintRPC error ErrMsg: failed to unmarshal id from response ErrMsg: invalid character '\x00' looking for beginning of value {id:}: invalid character '\x00' looking for beginning of value: failed to unmarshal id from response ErrMsg: invalid character '\x00' looking for beginning of value {id:}: invalid character '\x00' looking for beginning of value" GUID=9745270758427676735 request.SessionId=8958534548783151220 request.userAddr=cosmos14gth2jcy2c6wpnqu6yq4052r6nchr3vehurl6c timed_out=false
Dec  9 15:15:41 DBG Provider returned a relay response GUID=9745270758427676735 relay_timeout=29.998741515s request.SessionId=8958534548783151220 request.cu=10 request.relayNumber=1 timeTaken=1.182502ms
Dec  9 15:15:41 DBG could not send relay to provider error="rpc error: code = Unknown desc = failed to unmarshal id from response ErrMsg: invalid character '\x00' looking for beginning of value {id:}: tendermintRPC error ErrMsg: failed to unmarshal id from response ErrMsg: invalid character '\x00' looking for beginning of value {id:}: invalid character '\x00' looking for beginning of value: Sending chainMsg failed ErrMsg: tendermintRPC error ErrMsg: failed to unmarshal id from response ErrMsg: invalid character '\x00' looking for beginning of value {id:}: invalid character '\x00' looking for beginning of value: failed to unmarshal id from response ErrMsg: invalid character '\x00' looking for beginning of value {id:}: invalid character '\x00' looking for beginning of value {attempt:0,GUID:9745270758427676735,specID:LAV1}: TryRelay Failed ErrMsg: Sending chainMsg failed ErrMsg: tendermintRPC error ErrMsg: failed to unmarshal id from response ErrMsg: invalid character '\x00' looking for beginning of value {id:}: invalid character '\x00' looking for beginning of value: failed to unmarshal id from response ErrMsg: invalid character '\x00' looking for beginning of value {id:}: invalid character '\x00' looking for beginning of value {attempt:0,GUID:9745270758427676735,specID:LAV1}: tendermintRPC error ErrMsg: failed to unmarshal id from response ErrMsg: invalid character '\x00' looking for beginning of value {id:}: invalid character '\x00' looking for beginning of value: failed to unmarshal id from response ErrMsg: invalid character '\x00' looking for beginning of value {id:}: invalid character '\x00' looking for beginning of value {request.SessionId:8958534548783151220,request.userAddr:cosmos14gth2jcy2c6wpnqu6yq4052r6nchr3vehurl6c,GUID:9745270758427676735,timed_out:false}" GUID=9745270758427676735 provider=cosmos1wfwkkxjygqa94nl2c9j4zrrtaqgqe4wtfz7nnk
Dec  9 15:15:41 ERR failed relay, insufficient results error="rpc error: code = Unknown desc = failed to unmarshal id from response ErrMsg: invalid character '\x00' looking for beginning of value {id:}: tendermintRPC error ErrMsg: failed to unmarshal id from response ErrMsg: invalid character '\x00' looking for beginning of value {id:}: invalid character '\x00' looking for beginning of value: Sending chainMsg failed ErrMsg: tendermintRPC error ErrMsg: failed to unmarshal id from response ErrMsg: invalid character '\x00' looking for beginning of value {id:}: invalid character '\x00' looking for beginning of value: failed to unmarshal id from response ErrMsg: invalid character '\x00' looking for beginning of value {id:}: invalid character '\x00' looking for beginning of value {attempt:0,GUID:9745270758427676735,specID:LAV1}: TryRelay Failed ErrMsg: Sending chainMsg failed ErrMsg: tendermintRPC error ErrMsg: failed to unmarshal id from response ErrMsg: invalid character '\x00' looking for beginning of value {id:}: invalid character '\x00' looking for beginning of value: failed to unmarshal id from response ErrMsg: invalid character '\x00' looking for beginning of value {id:}: invalid character '\x00' looking for beginning of value {attempt:0,GUID:9745270758427676735,specID:LAV1}: tendermintRPC error ErrMsg: failed to unmarshal id from response ErrMsg: invalid character '\x00' looking for beginning of value {id:}: invalid character '\x00' looking for beginning of value: failed to unmarshal id from response ErrMsg: invalid character '\x00' looking for beginning of value {id:}: invalid character '\x00' looking for beginning of value {request.SessionId:8958534548783151220,request.userAddr:cosmos14gth2jcy2c6wpnqu6yq4052r6nchr3vehurl6c,GUID:9745270758427676735,timed_out:false}"
Dec  9 15:15:41 ERR [-] failed sending init relay error="failed relay, insufficient results ErrMsg: rpc error: code = Unknown desc = failed to unmarshal id from response ErrMsg: invalid character '\x00' looking for beginning of value {id:}: tendermintRPC error ErrMsg: failed to unmarshal id from response ErrMsg: invalid character '\x00' looking for beginning of value {id:}: invalid character '\x00' looking for beginning of value: Sending chainMsg failed ErrMsg: tendermintRPC error ErrMsg: failed to unmarshal id from response ErrMsg: invalid character '\x00' looking for beginning of value {id:}: invalid character '\x00' looking for beginning of value: failed to unmarshal id from response ErrMsg: invalid character '\x00' looking for beginning of value {id:}: invalid character '\x00' looking for beginning of value {attempt:0,GUID:9745270758427676735,specID:LAV1}: TryRelay Failed ErrMsg: Sending chainMsg failed ErrMsg: tendermintRPC error ErrMsg: failed to unmarshal id from response ErrMsg: invalid character '\x00' looking for beginning of value {id:}: invalid character '\x00' looking for beginning of value: failed to unmarshal id from response ErrMsg: invalid character '\x00' looking for beginning of value {id:}: invalid character '\x00' looking for beginning of value {attempt:0,GUID:9745270758427676735,specID:LAV1}: tendermintRPC error ErrMsg: failed to unmarshal id from response ErrMsg: invalid character '\x00' looking for beginning of value {id:}: invalid character '\x00' looking for beginning of value: failed to unmarshal id from response ErrMsg: invalid character '\x00' looking for beginning of value {id:}: invalid character '\x00' looking for beginning of value {request.SessionId:8958534548783151220,request.userAddr:cosmos14gth2jcy2c6wpnqu6yq4052r6nchr3vehurl6c,GUID:9745270758427676735,timed_out:false}: rpc error: code = Unknown desc = failed to unmarshal id from response ErrMsg: invalid character '\x00' looking for beginning of value {id:}: tendermintRPC error ErrMsg: failed to unmarshal id from response ErrMsg: invalid character '\x00' looking for beginning of value {id:}: invalid character '\x00' looking for beginning of value: Sending chainMsg failed ErrMsg: tendermintRPC error ErrMsg: failed to unmarshal id from response ErrMsg: invalid character '\x00' looking for beginning of value {id:}: invalid character '\x00' looking for beginning of value: failed to unmarshal id from response ErrMsg: invalid character '\x00' looking for beginning of value {id:}: invalid character '\x00' looking for beginning of value {attempt:0,GUID:9745270758427676735,specID:LAV1}: TryRelay Failed ErrMsg: Sending chainMsg failed ErrMsg: tendermintRPC error ErrMsg: failed to unmarshal id from response ErrMsg: invalid character '\x00' looking for beginning of value {id:}: invalid character '\x00' looking for beginning of value: failed to unmarshal id from response ErrMsg: invalid character '\x00' looking for beginning of value {id:}: invalid character '\x00' looking for beginning of value {attempt:0,GUID:9745270758427676735,specID:LAV1}: tendermintRPC error ErrMsg: failed to unmarshal id from response ErrMsg: invalid character '\x00' looking for beginning of value {id:}: invalid character '\x00' looking for beginning of value: failed to unmarshal id from response ErrMsg: invalid character '\x00' looking for beginning of value {id:}: invalid character '\x00' looking for beginning of value {request.SessionId:8958534548783151220,request.userAddr:cosmos14gth2jcy2c6wpnqu6yq4052r6nchr3vehurl6c,GUID:9745270758427676735,timed_out:false}" APIInterface=tendermintrpc chainID=LAV1 relayProcessor="relayProcessor {resultsManager {success 0, nodeErrors:0, protocolErrors:1}, unwantedAddresses: cosmos1wfwkkxjygqa94nl2c9j4zrrtaqgqe4wtfz7nnk,currentlyUsedAddresses:}"
Dec  9 15:15:41 DBG Provider got relay request GUID=9745270758427676735 relay addon= relay extensions= relay_timeout=29.999927925s request.SessionId=3241550844812751381 request.cu=10 request.relayNumber=1 requestBlock=-2 seenBlock=0
Dec  9 15:15:41 DBG provider registered consumer consumer=cosmos14gth2jcy2c6wpnqu6yq4052r6nchr3vehurl6c epoch=100
Dec  9 15:15:41 DBG Provider creating new sessionID SessionID=3241550844812751381 epoch=100
Dec  9 15:15:41 DBG Before Update Normal PrepareSessionForUsage GUID=9745270758427676735 relayNum=0 relayRequestTotalCU=10 sps.CuSum=10 sps.LatestRelayCu=10 sps.sessionId=3241550844812751381
Dec  9 15:15:41 ERR failed to unmarshal id from response error="invalid character '\x00' looking for beginning of value" id=
Dec  9 15:15:41 ERR tendermintRPC error error="failed to unmarshal id from response ErrMsg: invalid character '\x00' looking for beginning of value {id:}: invalid character '\x00' looking for beginning of value"
Dec  9 15:15:41 ERR Sending chainMsg failed error="tendermintRPC error ErrMsg: failed to unmarshal id from response ErrMsg: invalid character '\x00' looking for beginning of value {id:}: invalid character '\x00' looking for beginning of value: failed to unmarshal id from response ErrMsg: invalid character '\x00' looking for beginning of value {id:}: invalid character '\x00' looking for beginning of value" GUID=9745270758427676735 attempt=0 specID=LAV1
Dec  9 15:15:41 ERR TryRelay Failed error="Sending chainMsg failed ErrMsg: tendermintRPC error ErrMsg: failed to unmarshal id from response ErrMsg: invalid character '\x00' looking for beginning of value {id:}: invalid character '\x00' looking for beginning of value: failed to unmarshal id from response ErrMsg: invalid character '\x00' looking for beginning of value {id:}: invalid character '\x00' looking for beginning of value {attempt:0,GUID:9745270758427676735,specID:LAV1}: tendermintRPC error ErrMsg: failed to unmarshal id from response ErrMsg: invalid character '\x00' looking for beginning of value {id:}: invalid character '\x00' looking for beginning of value: failed to unmarshal id from response ErrMsg: invalid character '\x00' looking for beginning of value {id:}: invalid character '\x00' looking for beginning of value" GUID=9745270758427676735 request.SessionId=3241550844812751381 request.userAddr=cosmos14gth2jcy2c6wpnqu6yq4052r6nchr3vehurl6c timed_out=false
Dec  9 15:15:41 DBG Provider returned a relay response GUID=9745270758427676735 relay_timeout=29.998915386s request.SessionId=3241550844812751381 request.cu=10 request.relayNumber=1 timeTaken=1.015385ms
Dec  9 15:15:41 DBG could not send relay to provider error="rpc error: code = Unknown desc = failed to unmarshal id from response ErrMsg: invalid character '\x00' looking for beginning of value {id:}: tendermintRPC error ErrMsg: failed to unmarshal id from response ErrMsg: invalid character '\x00' looking for beginning of value {id:}: invalid character '\x00' looking for beginning of value: Sending chainMsg failed ErrMsg: tendermintRPC error ErrMsg: failed to unmarshal id from response ErrMsg: invalid character '\x00' looking for beginning of value {id:}: invalid character '\x00' looking for beginning of value: failed to unmarshal id from response ErrMsg: invalid character '\x00' looking for beginning of value {id:}: invalid character '\x00' looking for beginning of value {attempt:0,GUID:9745270758427676735,specID:LAV1}: TryRelay Failed ErrMsg: Sending chainMsg failed ErrMsg: tendermintRPC error ErrMsg: failed to unmarshal id from response ErrMsg: invalid character '\x00' looking for beginning of value {id:}: invalid character '\x00' looking for beginning of value: failed to unmarshal id from response ErrMsg: invalid character '\x00' looking for beginning of value {id:}: invalid character '\x00' looking for beginning of value {attempt:0,GUID:9745270758427676735,specID:LAV1}: tendermintRPC error ErrMsg: failed to unmarshal id from response ErrMsg: invalid character '\x00' looking for beginning of value {id:}: invalid character '\x00' looking for beginning of value: failed to unmarshal id from response ErrMsg: invalid character '\x00' looking for beginning of value {id:}: invalid character '\x00' looking for beginning of value {request.SessionId:3241550844812751381,request.userAddr:cosmos14gth2jcy2c6wpnqu6yq4052r6nchr3vehurl6c,GUID:9745270758427676735,timed_out:false}" GUID=9745270758427676735 provider=cosmos1y7e8pqluk9y98c4nz98nl9g7n660sj8a05x4ag
Dec  9 15:15:41 ERR failed relay, insufficient results error="rpc error: code = Unknown desc = failed to unmarshal id from response ErrMsg: invalid character '\x00' looking for beginning of value {id:}: tendermintRPC error ErrMsg: failed to unmarshal id from response ErrMsg: invalid character '\x00' looking for beginning of value {id:}: invalid character '\x00' looking for beginning of value: Sending chainMsg failed ErrMsg: tendermintRPC error ErrMsg: failed to unmarshal id from response ErrMsg: invalid character '\x00' looking for beginning of value {id:}: invalid character '\x00' looking for beginning of value: failed to unmarshal id from response ErrMsg: invalid character '\x00' looking for beginning of value {id:}: invalid character '\x00' looking for beginning of value {attempt:0,GUID:9745270758427676735,specID:LAV1}: TryRelay Failed ErrMsg: Sending chainMsg failed ErrMsg: tendermintRPC error ErrMsg: failed to unmarshal id from response ErrMsg: invalid character '\x00' looking for beginning of value {id:}: invalid character '\x00' looking for beginning of value: failed to unmarshal id from response ErrMsg: invalid character '\x00' looking for beginning of value {id:}: invalid character '\x00' looking for beginning of value {attempt:0,GUID:9745270758427676735,specID:LAV1}: tendermintRPC error ErrMsg: failed to unmarshal id from response ErrMsg: invalid character '\x00' looking for beginning of value {id:}: invalid character '\x00' looking for beginning of value: failed to unmarshal id from response ErrMsg: invalid character '\x00' looking for beginning of value {id:}: invalid character '\x00' looking for beginning of value {request.SessionId:8958534548783151220,request.userAddr:cosmos14gth2jcy2c6wpnqu6yq4052r6nchr3vehurl6c,GUID:9745270758427676735,timed_out:false}"
Dec  9 15:15:41 ERR [-] failed sending init relay error="failed relay, insufficient results ErrMsg: rpc error: code = Unknown desc = failed to unmarshal id from response ErrMsg: invalid character '\x00' looking for beginning of value {id:}: tendermintRPC error ErrMsg: failed to unmarshal id from response ErrMsg: invalid character '\x00' looking for beginning of value {id:}: invalid character '\x00' looking for beginning of value: Sending chainMsg failed ErrMsg: tendermintRPC error ErrMsg: failed to unmarshal id from response ErrMsg: invalid character '\x00' looking for beginning of value {id:}: invalid character '\x00' looking for beginning of value: failed to unmarshal id from response ErrMsg: invalid character '\x00' looking for beginning of value {id:}: invalid character '\x00' looking for beginning of value {attempt:0,GUID:9745270758427676735,specID:LAV1}: TryRelay Failed ErrMsg: Sending chainMsg failed ErrMsg: tendermintRPC error ErrMsg: failed to unmarshal id from response ErrMsg: invalid character '\x00' looking for beginning of value {id:}: invalid character '\x00' looking for beginning of value: failed to unmarshal id from response ErrMsg: invalid character '\x00' looking for beginning of value {id:}: invalid character '\x00' looking for beginning of value {attempt:0,GUID:9745270758427676735,specID:LAV1}: tendermintRPC error ErrMsg: failed to unmarshal id from response ErrMsg: invalid character '\x00' looking for beginning of value {id:}: invalid character '\x00' looking for beginning of value: failed to unmarshal id from response ErrMsg: invalid character '\x00' looking for beginning of value {id:}: invalid character '\x00' looking for beginning of value {request.SessionId:8958534548783151220,request.userAddr:cosmos14gth2jcy2c6wpnqu6yq4052r6nchr3vehurl6c,GUID:9745270758427676735,timed_out:false}: rpc error: code = Unknown desc = failed to unmarshal id from response ErrMsg: invalid character '\x00' looking for beginning of value {id:}: tendermintRPC error ErrMsg: failed to unmarshal id from response ErrMsg: invalid character '\x00' looking for beginning of value {id:}: invalid character '\x00' looking for beginning of value: Sending chainMsg failed ErrMsg: tendermintRPC error ErrMsg: failed to unmarshal id from response ErrMsg: invalid character '\x00' looking for beginning of value {id:}: invalid character '\x00' looking for beginning of value: failed to unmarshal id from response ErrMsg: invalid character '\x00' looking for beginning of value {id:}: invalid character '\x00' looking for beginning of value {attempt:0,GUID:9745270758427676735,specID:LAV1}: TryRelay Failed ErrMsg: Sending chainMsg failed ErrMsg: tendermintRPC error ErrMsg: failed to unmarshal id from response ErrMsg: invalid character '\x00' looking for beginning of value {id:}: invalid character '\x00' looking for beginning of value: failed to unmarshal id from response ErrMsg: invalid character '\x00' looking for beginning of value {id:}: invalid character '\x00' looking for beginning of value {attempt:0,GUID:9745270758427676735,specID:LAV1}: tendermintRPC error ErrMsg: failed to unmarshal id from response ErrMsg: invalid character '\x00' looking for beginning of value {id:}: invalid character '\x00' looking for beginning of value: failed to unmarshal id from response ErrMsg: invalid character '\x00' looking for beginning of value {id:}: invalid character '\x00' looking for beginning of value {request.SessionId:8958534548783151220,request.userAddr:cosmos14gth2jcy2c6wpnqu6yq4052r6nchr3vehurl6c,GUID:9745270758427676735,timed_out:false}" APIInterface=tendermintrpc chainID=LAV1 relayProcessor="relayProcessor {resultsManager {success 0, nodeErrors:0, protocolErrors:2}, unwantedAddresses: cosmos1wfwkkxjygqa94nl2c9j4zrrtaqgqe4wtfz7nnk;cosmos1y7e8pqluk9y98c4nz98nl9g7n660sj8a05x4ag,currentlyUsedAddresses:}"
Dec  9 15:15:41 DBG Provider got relay request GUID=9745270758427676735 relay addon= relay extensions= relay_timeout=29.9999152s request.SessionId=3322304816276007382 request.cu=10 request.relayNumber=1 requestBlock=-2 seenBlock=0
Dec  9 15:15:41 DBG provider registered consumer consumer=cosmos14gth2jcy2c6wpnqu6yq4052r6nchr3vehurl6c epoch=100
Dec  9 15:15:41 DBG Provider creating new sessionID SessionID=3322304816276007382 epoch=100
Dec  9 15:15:41 DBG Before Update Normal PrepareSessionForUsage GUID=9745270758427676735 relayNum=0 relayRequestTotalCU=10 sps…ionId=8958534548783151220 request.cu=4730 request.relayNumber=474 timeTaken=4.377651ms
Dec  9 15:15:47 DBG saving rewards snapshot to the DB proofs=1
Dec  9 15:15:47 INF Saved rewards snapshot to the DB successfully proofs=1
Dec  9 15:15:47 DBG QoS Availability report Availability=0.978902953586497890 down percent=0.002109704641350211
Dec  9 15:15:47 DBG tendermint http in/out GUID=9730311188201159464 HasError=false method=GET path=http://localhost:1026/status request= response="{\"reply\": 3}" timeTaken=5.816856ms
Dec  9 15:15:47 DBG urirpc in <<< GUID=6990924804233294236 _msg=status dappID=DefaultDappID headers="[{Name:Host Value:localhost:1026} {Name:User-Agent Value:Go-http-client/1.1} {Name:Accept-Encoding Value:gzip}]"
Dec  9 15:15:47 DBG Provider got relay request GUID=6990924804233294236 relay addon= relay extensions= relay_timeout=29.999923652s request.SessionId=3241550844812751381 request.cu=5050 request.relayNumber=506 requestBlock=-2 seenBlock=1000
Dec  9 15:15:47 DBG Before Update Normal PrepareSessionForUsage GUID=6990924804233294236 relayNum=505 relayRequestTotalCU=5050 sps.CuSum=5050 sps.LatestRelayCu=10 sps.sessionId=3241550844812751381
Dec  9 15:15:47 DBG Provider Finished Relay Successfully GUID=6990924804233294236 method=status replyBlock=1000 request.SessionId=3241550844812751381 request.relayNumber=506 requestedBlock=1000
Dec  9 15:15:47 DBG Provider returned a relay response GUID=6990924804233294236 relay_timeout=29.996006433s request.SessionId=3241550844812751381 request.cu=5050 request.relayNumber=506 timeTaken=3.919653ms
Dec  9 15:15:47 DBG saving rewards snapshot to the DB proofs=1
Dec  9 15:15:47 INF Saved rewards snapshot to the DB successfully proofs=1
Dec  9 15:15:47 DBG QoS Availability report Availability=0.980237154150197630 down percent=0.001976284584980237
Dec  9 15:15:47 DBG tendermint http in/out GUID=6990924804233294236 HasError=false method=GET path=http://localhost:1026/status request= response="{\"reply\": 2}" timeTaken=5.199125ms
Dec  9 15:15:47 DBG urirpc in <<< GUID=14169409218588142707 _msg=status dappID=DefaultDappID headers="[{Name:Host Value:localhost:1026} {Name:User-Agent Value:Go-http-client/1.1} {Name:Accept-Encoding Value:gzip}]"
Dec  9 15:15:47 DBG Provider got relay request GUID=14169409218588142707 relay addon= relay extensions= relay_timeout=29.999921276s request.SessionId=3241550844812751381 request.cu=5060 request.relayNumber=507 requestBlock=-2 seenBlock=1000
Dec  9 15:15:47 DBG Before Update Normal PrepareSessionForUsage GUID=14169409218588142707 relayNum=506 relayRequestTotalCU=5060 sps.CuSum=5060 sps.LatestRelayCu=10 sps.sessionId=3241550844812751381
Dec  9 15:15:47 DBG Provider Finished Relay Successfully GUID=14169409218588142707 method=status replyBlock=1000 request.SessionId=3241550844812751381 request.relayNumber=507 requestedBlock=1000
Dec  9 15:15:47 DBG Provider returned a relay response GUID=14169409218588142707 relay_timeout=29.996030455s request.SessionId=3241550844812751381 request.cu=5060 request.relayNumber=507 timeTaken=3.892515ms
Dec  9 15:15:47 DBG saving rewards snapshot to the DB proofs=1
Dec  9 15:15:47 INF Saved rewards snapshot to the DB successfully proofs=1
Dec  9 15:15:47 DBG QoS Availability report Availability=0.980276134122287970 down percent=0.001972386587771203
Dec  9 15:15:47 DBG tendermint http in/out GUID=14169409218588142707 HasError=false method=GET path=http://localhost:1026/status request= response="{\"reply\": 2}" timeTaken=5.220413ms
Dec  9 15:15:47 DBG urirpc in <<< GUID=16277296149465383079 _msg=status dappID=DefaultDappID headers="[{Name:Host Value:localhost:1026} {Name:User-Agent Value:Go-http-client/1.1} {Name:Accept-Encoding Value:gzip}]"
Dec  9 15:15:47 DBG Provider got relay request GUID=16277296149465383079 relay addon= relay extensions= relay_timeout=29.999903666s request.SessionId=3241550844812751381 request.cu=5070 request.relayNumber=508 requestBlock=-2 seenBlock=1000
Dec  9 15:15:47 DBG Before Update Normal PrepareSessionForUsage GUID=16277296149465383079 relayNum=507 relayRequestTotalCU=5070 sps.CuSum=5070 sps.LatestRelayCu=10 sps.sessionId=3241550844812751381
Dec  9 15:15:47 DBG Provider Finished Relay Successfully GUID=16277296149465383079 method=status replyBlock=1000 request.SessionId=3241550844812751381 request.relayNumber=508 requestedBlock=1000
Dec  9 15:15:47 DBG Provider returned a relay response GUID=16277296149465383079 relay_timeout=29.9960371s request.SessionId=3241550844812751381 request.cu=5070 request.relayNumber=508 timeTaken=3.86869ms
Dec  9 15:15:47 DBG saving rewards snapshot to the DB proofs=1
Dec  9 15:15:47 INF Saved rewards snapshot to the DB successfully proofs=1
Dec  9 15:15:47 DBG QoS Availability report Availability=0.980314960629921260 down percent=0.001968503937007874
Dec  9 15:15:47 DBG tendermint http in/out GUID=16277296149465383079 HasError=false method=GET path=http://localhost:1026/status request= response="{\"reply\": 2}" timeTaken=5.14554ms
Dec  9 15:15:47 DBG urirpc in <<< GUID=11569877317668775120 _msg=status dappID=DefaultDappID headers="[{Name:Host Value:localhost:1026} {Name:User-Agent Value:Go-http-client/1.1} {Name:Accept-Encoding Value:gzip}]"
Dec  9 15:15:47 DBG Provider got relay request GUID=11569877317668775120 relay addon= relay extensions= relay_timeout=29.999916091s request.SessionId=3241550844812751381 request.cu=5080 request.relayNumber=509 requestBlock=-2 seenBlock=1000
Dec  9 15:15:47 DBG Before Update Normal PrepareSessionForUsage GUID=11569877317668775120 relayNum=508 relayRequestTotalCU=5080 sps.CuSum=5080 sps.LatestRelayCu=10 sps.sessionId=3241550844812751381
Dec  9 15:15:47 DBG Provider Finished Relay Successfully GUID=11569877317668775120 method=status replyBlock=1000 request.SessionId=3241550844812751381 request.relayNumber=509 requestedBlock=1000
Dec  9 15:15:47 DBG Provider returned a relay response GUID=11569877317668775120 relay_timeout=29.996053729s request.SessionId=3241550844812751381 request.cu=5080 request.relayNumber=509 timeTaken=3.864376ms
Dec  9 15:15:47 DBG saving rewards snapshot to the DB proofs=1
Dec  9 15:15:47 INF Saved rewards snapshot to the DB successfully proofs=1
Dec  9 15:15:47 DBG QoS Availability report Availability=0.980353634577603140 down percent=0.001964636542239686
Dec  9 15:15:47 DBG tendermint http in/out GUID=11569877317668775120 HasError=false method=GET path=http://localhost:1026/status request= response="{\"reply\": 2}" timeTaken=5.106087ms
Dec  9 15:15:47 DBG urirpc in <<< GUID=18318012465878109658 _msg=status dappID=DefaultDappID headers="[{Name:Host Value:localhost:1026} {Name:User-Agent Value:Go-http-client/1.1} {Name:Accept-Encoding Value:gzip}]"
Dec  9 15:15:47 DBG Provider got relay request GUID=18318012465878109658 relay addon= relay extensions= relay_timeout=29.999925854s request.SessionId=3241550844812751381 request.cu=5090 request.relayNumber=510 requestBlock=-2 seenBlock=1000
Dec  9 15:15:47 DBG Before Update Normal PrepareSessionForUsage GUID=18318012465878109658 relayNum=509 relayRequestTotalCU=5090 sps.CuSum=5090 sps.LatestRelayCu=10 sps.sessionId=3241550844812751381
Dec  9 15:15:47 DBG Provider Finished Relay Successfully GUID=18318012465878109658 method=status replyBlock=1000 request.SessionId=3241550844812751381 request.relayNumber=510 requestedBlock=1000
Dec  9 15:15:47 DBG Provider returned a relay response GUID=18318012465878109658 relay_timeout=29.996050842s request.SessionId=3241550844812751381 request.cu=5090 request.relayNumber=510 timeTaken=3.876505ms
Dec  9 15:15:47 DBG saving rewards snapshot to the DB proofs=1
Dec  9 15:15:47 INF Saved rewards snapshot to the DB successfully proofs=1
Dec  9 15:15:47 DBG QoS Availability report Availability=0.980392156862745100 down percent=0.001960784313725490
Dec  9 15:15:47 DBG tendermint http in/out GUID=18318012465878109658 HasError=false method=GET path=http://localhost:1026/status request= response="{\"reply\": 2}" timeTaken=5.143961ms
Dec  9 15:15:47 DBG urirpc in <<< GUID=7515706772486143867 _msg=status dappID=DefaultDappID headers="[{Name:Host Value:localhost:1026} {Name:User-Agent Value:Go-http-client/1.1} {Name:Accept-Encoding Value:gzip}]"
Dec  9 15:15:47 DBG Provider got relay request GUID=7515706772486143867 relay addon= relay extensions= relay_timeout=29.999920758s request.SessionId=3241550844812751381 request.cu=5100 request.relayNumber=511 requestBlock=-2 seenBlock=1000
Dec  9 15:15:47 DBG Before Update Normal PrepareSessionForUsage GUID=7515706772486143867 relayNum=510 relayRequestTotalCU=5100 sps.CuSum=5100 sps.LatestRelayCu=10 sps.sessionId=3241550844812751381
Dec  9 15:15:47 DBG Provider Finished Relay Successfully GUID=7515706772486143867 method=status replyBlock=1000 request.SessionId=3241550844812751381 request.relayNumber=511 requestedBlock=1000
Dec  9 15:15:47 DBG Provider returned a relay response GUID=7515706772486143867 relay_timeout=29.995957386s request.SessionId=3241550844812751381 request.cu=5100 request.relayNumber=511 timeTaken=3.965396ms
Dec  9 15:15:47 DBG saving rewards snapshot to the DB proofs=1
Dec  9 15:15:47 INF Saved rewards snapshot to the DB successfully proofs=1
Dec  9 15:15:47 DBG QoS Availability report Availability=0.980430528375733860 down percent=0.001956947162426614
Dec  9 15:15:47 DBG tendermint http in/out GUID=7515706772486143867 HasError=false method=GET path=http://localhost:1026/status request= response="{\"reply\": 2}" timeTaken=5.4325ms
Dec  9 15:15:47 DBG urirpc in <<< GUID=7409879236819430499 _msg=status dappID=DefaultDappID headers="[{Name:Host Value:localhost:1026} {Name:User-Agent Value:Go-http-client/1.1} {Name:Accept-Encoding Value:gzip}]"
Dec  9 15:15:47 DBG Provider got relay request GUID=7409879236819430499 relay addon= relay extensions= relay_timeout=29.999928906s request.SessionId=8958534548783151220 request.cu=4740 request.relayNumber=475 requestBlock=-2 seenBlock=1000
Dec  9 15:15:47 DBG Before Update Normal PrepareSessionForUsage GUID=7409879236819430499 relayNum=474 relayRequestTotalCU=4740 sps.CuSum=4740 sps.LatestRelayCu=10 sps.sessionId=8958534548783151220
Dec  9 15:15:47 DBG Provider Finished Relay Successfully GUID=7409879236819430499 method=status replyBlock=1000 request.SessionId=8958534548783151220 request.relayNumber=475 requestedBlock=1000
Dec  9 15:15:47 DBG Provider returned a relay response GUID=7409879236819430499 relay_timeout=29.996215471s request.SessionId=8958534548783151220 request.cu=4740 request.relayNumber=475 timeTaken=3.714917ms
Dec  9 15:15:47 DBG saving rewards snapshot to the DB proofs=1
Dec  9 15:15:47 INF Saved rewards snapshot to the DB successfully proofs=1
Dec  9 15:15:47 DBG QoS Availability report Availability=0.978947368421052630 down percent=0.002105263157894737
Dec  9 15:15:47 DBG tendermint http in/out GUID=7409879236819430499 HasError=false method=GET path=http://localhost:1026/status request= response="{\"reply\": 3}" timeTaken=4.995186ms
Dec  9 15:15:47 DBG urirpc in <<< GUID=6704832631223196237 _msg=status dappID=DefaultDappID headers="[{Name:Host Value:localhost:1026} {Name:User-Agent Value:Go-http-client/1.1} {Name:Accept-Encoding Value:gzip}]"
Dec  9 15:15:47 DBG Provider got relay request GUID=6704832631223196237 relay addon= relay extensions= relay_timeout=29.999920558s request.SessionId=3241550844812751381 request.cu=5110 request.relayNumber=512 requestBlock=-2 seenBlock=1000
Dec  9 15:15:47 DBG Before Update Normal PrepareSessionForUsage GUID=6704832631223196237 relayNum=511 relayRequestTotalCU=5110 sps.CuSum=5110 sps.LatestRelayCu=10 sps.sessionId=3241550844812751381
Dec  9 15:15:47 DBG Provider Finished Relay Successfully GUID=6704832631223196237 method=status replyBlock=1000 request.SessionId=3241550844812751381 request.relayNumber=512 requestedBlock=1000
Dec  9 15:15:47 DBG Provider returned a relay response GUID=6704832631223196237 relay_timeout=29.995976745s request.SessionId=3241550844812751381 request.cu=5110 request.relayNumber=512 timeTaken=3.946127ms
Dec  9 15:15:47 DBG saving rewards snapshot to the DB proofs=1
Dec  9 15:15:47 INF Saved rewards snapshot to the DB successfully proofs=1
Dec  9 15:15:47 DBG QoS Availability report Availability=0.980468750000000000 down percent=0.001953125000000000
Dec  9 15:15:47 DBG tendermint http in/out GUID=6704832631223196237 HasError=false method=GET path=http://localhost:1026/status request= response="{\"reply\": 2}" timeTaken=5.195596ms
Dec  9 15:15:47 DBG urirpc in <<< GUID=4856841873840443276 _msg=status dappID=DefaultDappID headers="[{Name:Accept-Encoding Value:gzip} {Name:Host Value:localhost:1026} {Name:User-Agent Value:Go-http-client/1.1}]"
Dec  9 15:15:47 DBG Provider got relay request GUID=4856841873840443276 relay addon= relay extensions= relay_timeout=29.999923231s request.SessionId=8958534548783151220 request.cu=4750 request.relayNumber=476 requestBlock=-2 seenBlock=1000
Dec  9 15:15:47 DBG Before Update Normal PrepareSessionForUsage GUID=4856841873840443276 relayNum=475 relayRequestTotalCU=4750 sps.CuSum=4750 sps.LatestRelayCu=10 sps.sessionId=8958534548783151220
Dec  9 15:15:47 DBG Provider Finished Relay Successfully GUID=4856841873840443276 method=status replyBlock=1000 request.SessionId=8958534548783151220 request.relayNumber=476 requestedBlock=1000
Dec  9 15:15:47 DBG Provider returned a relay response GUID=4856841873840443276 relay_timeout=29.996083589s request.SessionId=8958534548783151220 request.cu=4750 request.relayNumber=476 timeTaken=3.841866ms
Dec  9 15:15:47 DBG saving rewards snapshot to the DB proofs=1
Dec  9 15:15:47 INF Saved rewards snapshot to the DB successfully proofs=1
Dec  9 15:15:47 DBG QoS Availability report Availability=0.978991596638655460 down percent=0.002100840336134454
Dec  9 15:15:47 DBG tendermint http in/out GUID=4856841873840443276 HasError=false method=GET path=http://localhost:1026/status request= response="{\"reply\": 3}" timeTaken=5.243294ms
Dec  9 15:15:47 DBG urirpc in <<< GUID=7725930633451492275 _msg=status dappID=DefaultDappID headers="[{Name:Host Value:localhost:1026} {Name:User-Agent Value:Go-http-client/1.1} {Name:Accept-Encoding Value:gzip}]"
Dec  9 15:15:47 DBG Provider got relay request GUID=7725930633451492275 relay addon= relay extensions= relay_timeout=29.999916995s request.SessionId=8958534548783151220 request.cu=4760 request.relayNumber=477 requestBlock=-2 seenBlock=1000
Dec  9 15:15:47 DBG Before Update Normal PrepareSessionForUsage GUID=7725930633451492275 relayNum=476 relayRequestTotalCU=4760 sps.CuSum=4760 sps.LatestRelayCu=10 sps.sessionId=8958534548783151220
Dec  9 15:15:47 DBG Provider Finished Relay Successfully GUID=7725930633451492275 method=status replyBlock=1000 request.SessionId=8958534548783151220 request.relayNumber=477 requestedBlock=1000
Dec  9 15:15:47 DBG Provider returned a relay response GUID=7725930633451492275 relay_timeout=29.996061826s request.SessionId=8958534548783151220 request.cu=4760 request.relayNumber=477 timeTaken=3.856892ms
Dec  9 15:15:47 DBG saving rewards snapshot to the DB proofs=1
Dec  9 15:15:47 INF Saved rewards snapshot to the DB successfully proofs=1
Dec  9 15:15:47 DBG QoS Availability report Availability=0.979035639412997900 down percent=0.002096436058700210
Dec  9 15:15:47 DBG tendermint http in/out GUID=7725930633451492275 HasError=false method=GET path=http://localhost:1026/status request= response="{\"reply\": 3}" timeTaken=5.204959ms
Dec  9 15:15:47 DBG urirpc in <<< GUID=5775942324870560276 _msg=status dappID=DefaultDappID headers="[{Name:Host Value:localhost:1026} {Name:User-Agent Value:Go-http-client/1.1} {Name:Accept-Encoding Value:gzip}]"
Dec  9 15:15:47 DBG Provider got relay request GUID=5775942324870560276 relay addon= relay extensions= relay_timeout=29.99991631s request.SessionId=8958534548783151220 request.cu=4770 request.relayNumber=478 requestBlock=-2 seenBlock=1000
Dec  9 15:15:47 DBG Before Update Normal PrepareSessionForUsage GUID=5775942324870560276 relayNum=477 relayRequestTotalCU=4770 sps.CuSum=4770 sps.LatestRelayCu=10 sps.sessionId=8958534548783151220
Dec  9 15:15:47 DBG Provider Finished Relay Successfully GUID=5775942324870560276 method=status replyBlock=1000 request.SessionId=8958534548783151220 request.relayNumber=478 requestedBlock=1000
Dec  9 15:15:47 DBG Provider returned a relay response GUID=5775942324870560276 relay_timeout=29.996077535s request.SessionId=8958534548783151220 request.cu=4770 request.relayNumber=478 timeTaken=3.841159ms
Dec  9 15:15:47 DBG saving rewards snapshot to the DB proofs=1
Dec  9 15:15:47 INF Saved rewards snapshot to the DB successfully proofs=1
Dec  9 15:15:47 DBG QoS Availability report Availability=0.979079497907949790 down percent=0.002092050209205021
Dec  9 15:15:47 DBG tendermint http in/out GUID=5775942324870560276 HasError=false method=GET path=http://localhost:1026/status request= response="{\"reply\": 3}" timeTaken=5.237652ms
Dec  9 15:15:47 DBG urirpc in <<< GUID=1245334373494771585 _msg=status dappID=DefaultDappID headers="[{Name:Accept-Encoding Value:gzip} {Name:Host Value:localhost:1026} {Name:User-Agent Value:Go-http-client/1.1}]"
Dec  9 15:15:47 DBG Provider got relay request GUID=1245334373494771585 relay addon= relay extensions= relay_timeout=29.999913589s request.SessionId=8958534548783151220 request.cu=4780 request.relayNumber=479 requestBlock=-2 seenBlock=1000
Dec  9 15:15:47 DBG Before Update Normal PrepareSessionForUsage GUID=1245334373494771585 relayNum=478 relayRequestTotalCU=4780 sps.CuSum=4780 sps.LatestRelayCu=10 sps.sessionId=8958534548783151220
Dec  9 15:15:47 DBG Provider Finished Relay Successfully GUID=1245334373494771585 method=status replyBlock=1000 request.SessionId=8958534548783151220 request.relayNumber=479 requestedBlock=1000
Dec  9 15:15:47 DBG Provider returned a relay response GUID=1245334373494771585 relay_timeout=29.996044014s request.SessionId=8958534548783151220 request.cu=4780 request.relayNumber=479 timeTaken=3.871538ms
Dec  9 15:15:47 DBG saving rewards snapshot to the DB proofs=1
Dec  9 15:15:47 INF Saved rewards snapshot to the DB successfully proofs=1
Dec  9 15:15:47 DBG QoS Availability report Availability=0.979123173277661800 down percent=0.002087682672233820
Dec  9 15:15:47 DBG tendermint http in/out GUID=1245334373494771585 HasError=false method=GET path=http://localhost:1026/status request= response="{\"reply\": 3}" timeTaken=5.205666ms
Dec  9 15:15:47 DBG urirpc in <<< GUID=6119446509661292276 _msg=status dappID=DefaultDappID headers="[{Name:Host Value:localhost:1026} {Name:User-Agent Value:Go-http-client/1.1} {Name:Accept-Encoding Value:gzip}]"
Dec  9 15:15:47 DBG Provider got relay request GUID=6119446509661292276 relay addon= relay extensions= relay_timeout=29.999891874s request.SessionId=3241550844812751381 request.cu=5120 request.relayNumber=513 requestBlock=-2 seenBlock=1000
Dec  9 15:15:47 DBG Before Update Normal PrepareSessionForUsage GUID=6119446509661292276 relayNum=512 relayRequestTotalCU=5120 sps.CuSum=5120 sps.LatestRelayCu=10 sps.sessionId=3241550844812751381
Dec  9 15:15:47 DBG Provider Finished Relay Successfully GUID=6119446509661292276 method=status replyBlock=1000 request.SessionId=3241550844812751381 request.relayNumber=513 requestedBlock=1000
Dec  9 15:15:47 DBG Provider returned a relay response GUID=6119446509661292276 relay_timeout=29.995993318s request.SessionId=3241550844812751381 request.cu=5120 request.relayNumber=513 timeTaken=3.90093ms
Dec  9 15:15:47 DBG saving rewards snapshot to the DB proofs=1
Dec  9 15:15:47 INF Saved rewards snapshot to the DB successfully proofs=1
Dec  9 15:15:47 DBG QoS Availability report Availability=0.980506822612085770 down percent=0.001949317738791423
Dec  9 15:15:47 DBG tendermint http in/out GUID=6119446509661292276 HasError=false method=GET path=http://localhost:1026/status request= response="{\"reply\": 2}" timeTaken=5.332389ms
Dec  9 15:15:47 DBG urirpc in <<< GUID=9663279342280489375 _msg=status dappID=DefaultDappID headers="[{Name:Host Value:localhost:1026} {Name:User-Agent Value:Go-http-client/1.1} {Name:Accept-Encoding Value:gzip}]"
Dec  9 15:15:47 DBG Provider got relay request GUID=9663279342280489375 relay addon= relay extensions= relay_timeout=29.999917093s request.SessionId=3241550844812751381 request.cu=5130 request.relayNumber=514 requestBlock=-2 seenBlock=1000
Dec  9 15:15:47 DBG Before Update Normal PrepareSessionForUsage GUID=9663279342280489375 relayNum=513 relayRequestTotalCU=5130 sps.CuSum=5130 sps.LatestRelayCu=10 sps.sessionId=3241550844812751381
Dec  9 15:15:47 DBG Provider Finished Relay Successfully GUID=9663279342280489375 method=status replyBlock=1000 request.SessionId=3241550844812751381 request.relayNumber=514 requestedBlock=1000
Dec  9 15:15:47 DBG Provider returned a relay response GUID=9663279342280489375 relay_timeout=29.996131767s request.SessionId=3241550844812751381 request.cu=5130 request.relayNumber=514 timeTaken=3.788ms
Dec  9 15:15:47 DBG saving rewards snapshot to the DB proofs=1
Dec  9 15:15:47 INF Saved rewards snapshot to the DB successfully proofs=1
Dec  9 15:15:47 DBG QoS Availability report Availability=0.980544747081712060 down percent=0.001945525291828794
Dec  9 15:15:47 DBG tendermint http in/out GUID=9663279342280489375 HasError=false method=GET path=http://localhost:1026/status request= response="{\"reply\": 2}" timeTaken=5.036674ms
Dec  9 15:15:47 DBG urirpc in <<< GUID=2575740795551415482 _msg=status dappID=DefaultDappID headers="[{Name:Host Value:localhost:1026} {Name:User-Agent Value:Go-http-client/1.1} {Name:Accept-Encoding Value:gzip}]"
Dec  9 15:15:47 DBG Provider got relay request GUID=2575740795551415482 relay addon= relay extensions= relay_timeout=29.999912501s request.SessionId=3241550844812751381 request.cu=5140 request.relayNumber=515 requestBlock=-2 seenBlock=1000
Dec  9 15:15:47 DBG Before Update Normal PrepareSessionForUsage GUID=2575740795551415482 relayNum=514 relayRequestTotalCU=5140 sps.CuSum=5140 sps.LatestRelayCu=10 sps.sessionId=3241550844812751381
Dec  9 15:15:47 DBG Provider Finished Relay Successfully GUID=2575740795551415482 method=status replyBlock=1000 request.SessionId=3241550844812751381 request.relayNumber=515 requestedBlock=1000
Dec  9 15:15:47 DBG Provider returned a relay response GUID=2575740795551415482 relay_timeout=29.996002194s request.SessionId=3241550844812751381 request.cu=5140 request.relayNumber=515 timeTaken=3.912982ms
Dec  9 15:15:47 DBG saving rewards snapshot to the DB proofs=1
Dec  9 15:15:47 INF Saved rewards snapshot to the DB successfully proofs=1
Dec  9 15:15:47 DBG QoS Availability report Availability=0.980582524271844660 down percent=0.001941747572815534
Dec  9 15:15:47 DBG tendermint http in/out GUID=2575740795551415482 HasError=false method=GET path=http://localhost:1026/status request= response="{\"reply\": 2}" timeTaken=5.222015ms
Dec  9 15:15:47 DBG urirpc in <<< GUID=2358091429976272934 _msg=status dappID=DefaultDappID headers="[{Name:Accept-Encoding Value:gzip} {Name:Host Value:localhost:1026} {Name:User-Agent Value:Go-http-client/1.1}]"
Dec  9 15:15:47 DBG Provider got relay request GUID=2358091429976272934 relay addon= relay extensions= relay_timeout=29.999919524s request.SessionId=8958534548783151220 request.cu=4790 request.relayNumber=480 requestBlock=-2 seenBlock=1000
Dec  9 15:15:47 DBG Before Update Normal PrepareSessionForUsage GUID=2358091429976272934 relayNum=479 relayRequestTotalCU=4790 sps.CuSum=4790 sps.LatestRelayCu=10 sps.sessionId=8958534548783151220
Dec  9 15:15:47 DBG Provider Finished Relay Successfully GUID=2358091429976272934 method=status replyBlock=1000 request.SessionId=8958534548783151220 request.relayNumber=480 requestedBlock=1000
Dec  9 15:15:47 DBG Provider returned a relay response GUID=2358091429976272934 relay_timeout=29.996068425s request.SessionId=8958534548783151220 request.cu=4790 request.relayNumber=480 timeTaken=3.852762ms
Dec  9 15:15:47 DBG saving rewards snapshot to the DB proofs=1
Dec  9 15:15:47 INF Saved rewards snapshot to the DB successfully proofs=1
Dec  9 15:15:47 DBG QoS Availability report Availability=0.979166666666666670 down percent=0.002083333333333333
Dec  9 15:15:47 DBG tendermint http in/out GUID=2358091429976272934 HasError=false method=GET path=http://localhost:1026/status request= response="{\"reply\": 3}" timeTaken=5.158762ms
Dec  9 15:15:47 DBG urirpc in <<< GUID=1891486098625625304 _msg=status dappID=DefaultDappID headers="[{Name:Host Value:localhost:1026} {Name:User-Agent Value:Go-http-client/1.1} {Name:Accept-Encoding Value:gzip}]"
Dec  9 15:15:47 DBG Provider got relay request GUID=1891486098625625304 relay addon= relay extensions= relay_timeout=29.999931082s request.SessionId=3241550844812751381 request.cu=5150 request.relayNumber=516 requestBlock=-2 seenBlock=1000
Dec  9 15:15:47 DBG Before Update Normal PrepareSessionForUsage GUID=1891486098625625304 relayNum=515 relayRequestTotalCU=5150 sps.CuSum=5150 sps.LatestRelayCu=10 sps.sessionId=3241550844812751381
Dec  9 15:15:47 DBG Provider Finished Relay Successfully GUID=1891486098625625304 method=status replyBlock=1000 request.SessionId=3241550844812751381 request.relayNumber=516 requestedBlock=1000
Dec  9 15:15:47 DBG Provider returned a relay response GUID=1891486098625625304 relay_timeout=29.996074148s request.SessionId=3241550844812751381 request.cu=5150 request.relayNumber=516 timeTaken=3.858546ms
Dec  9 15:15:47 DBG saving rewards snapshot to the DB proofs=1
Dec  9 15:15:47 INF Saved rewards snapshot to the DB successfully proofs=1
Dec  9 15:15:47 DBG QoS Availability report Availability=0.980620155038759690 down percent=0.001937984496124031
Dec  9 15:15:47 DBG tendermint http in/out GUID=1891486098625625304 HasError=false method=GET path=http://localhost:1026/status request= response="{\"reply\": 2}" timeTaken=5.160847ms
Dec  9 15:15:47 DBG urirpc in <<< GUID=1463296580788298603 _msg=status dappID=DefaultDappID headers="[{Name:Host Value:localhost:1026} {Name:User-Agent Value:Go-http-client/1.1} {Name:Accept-Encoding Value:gzip}]"
Dec  9 15:15:47 DBG Provider got relay request GUID=1463296580788298603 relay addon= relay extensions= relay_timeout=29.999902446s request.SessionId=8958534548783151220 request.cu=4800 request.relayNumber=481 requestBlock=-2 seenBlock=1000
Dec  9 15:15:47 DBG Before Update Normal PrepareSessionForUsage GUID=1463296580788298603 relayNum=480 relayRequestTotalCU=4800 sps.CuSum=4800 sps.LatestRelayCu=10 sps.sessionId=8958534548783151220
Dec  9 15:15:47 DBG Provider Finished Relay Successfully GUID=1463296580788298603 method=status replyBlock=1000 request.SessionId=8958534548783151220 request.relayNumber=481 requestedBlock=1000
Dec  9 15:15:47 DBG Provider returned a relay response GUID=1463296580788298603 relay_timeout=29.99598125s request.SessionId=8958534548783151220 request.cu=4800 request.relayNumber=481 timeTaken=3.92332ms
Dec  9 15:15:47 DBG saving rewards snapshot to the DB proofs=1
Dec  9 15:15:47 INF Saved rewards snapshot to the DB successfully proofs=1
Dec  9 15:15:47 DBG QoS Availability report Availability=0.979209979209979210 down percent=0.002079002079002079
Dec  9 15:15:47 DBG tendermint http in/out GUID=1463296580788298603 HasError=false method=GET path=http://localhost:1026/status request= response="{\"reply\": 3}" timeTaken=5.28942ms
Dec  9 15:15:47 DBG urirpc in <<< GUID=2036671338478685288 _msg=status dappID=DefaultDappID headers="[{Name:Host Value:localhost:1026} {Name:User-Agent Value:Go-http-client/1.1} {Name:Accept-Encoding Value:gzip}]"
Dec  9 15:15:47 DBG Provider got relay request GUID=2036671338478685288 relay addon= relay extensions= relay_timeout=29.999912531s request.SessionId=3241550844812751381 request.cu=5160 request.relayNumber=517 requestBlock=-2 seenBlock=1000
Dec  9 15:15:47 DBG Before Update Normal PrepareSessionForUsage GUID=2036671338478685288 relayNum=516 relayRequestTotalCU=5160 sps.CuSum=5160 sps.LatestRelayCu=10 sps.sessionId=3241550844812751381
Dec  9 15:15:47 DBG Provider Finished Relay Successfully GUID=2036671338478685288 method=status replyBlock=1000 request.SessionId=3241550844812751381 request.relayNumber=517 requestedBlock=1000
Dec  9 15:15:47 DBG Provider returned a relay response GUID=2036671338478685288 relay_timeout=29.996026093s request.SessionId=3241550844812751381 request.cu=5160 request.relayNumber=517 timeTaken=3.888944ms
Dec  9 15:15:47 DBG saving rewards snapshot to the DB proofs=1
Dec  9 15:15:47 INF Saved rewards snapshot to the DB successfully proofs=1
Dec  9 15:15:47 DBG QoS Availability report Availability=0.980657640232108320 down percent=0.001934235976789168
Dec  9 15:15:47 DBG tendermint http in/out GUID=2036671338478685288 HasError=false method=GET path=http://localhost:1026/status request= response="{\"reply\": 2}" timeTaken=5.243059ms
Dec  9 15:15:47 DBG urirpc in <<< GUID=6999381293117962935 _msg=status dappID=DefaultDappID headers="[{Name:Accept-Encoding Value:gzip} {Name:Host Value:localhost:1026} {Name:User-Agent Value:Go-http-client/1.1}]"
Dec  9 15:15:47 DBG Provider got relay request GUID=6999381293117962935 relay addon= relay extensions= relay_timeout=29.99992788s request.SessionId=3241550844812751381 request.cu=5170 request.relayNumber=518 requestBlock=-2 seenBlock=1000
Dec  9 15:15:47 DBG Before Update Normal PrepareSessionForUsage GUID=6999381293117962935 relayNum=517 relayRequestTotalCU=5170 sps.CuSum=5170 sps.LatestRelayCu=10 sps.sessionId=3241550844812751381
Dec  9 15:15:47 DBG Provider Finished Relay Successfully GUID=6999381293117962935 method=status replyBlock=1000 request.SessionId=3241550844812751381 request.relayNumber=518 requestedBlock=1000
Dec  9 15:15:47 DBG Provider returned a relay response GUID=6999381293117962935 relay_timeout=29.996070881s request.SessionId=3241550844812751381 request.cu=5170 request.relayNumber=518 timeTaken=3.860024ms
Dec  9 15:15:47 DBG saving rewards snapshot to the DB proofs=1
Dec  9 15:15:47 INF Saved rewards snapshot to the DB successfully proofs=1
Dec  9 15:15:47 DBG QoS Availability report Availability=0.980694980694980690 down percent=0.001930501930501931
Dec  9 15:15:47 DBG tendermint http in/out GUID=6999381293117962935 HasError=false method=GET path=http://localhost:1026/status request= response="{\"reply\": 2}" timeTaken=5.184346ms
Dec  9 15:15:47 DBG urirpc in <<< GUID=129129409401699916 _msg=status dappID=DefaultDappID headers="[{Name:Host Value:localhost:1026} {Name:User-Agent Value:Go-http-client/1.1} {Name:Accept-Encoding Value:gzip}]"
Dec  9 15:15:47 DBG Provider got relay request GUID=129129409401699916 relay addon= relay extensions= relay_timeout=29.99991647s request.SessionId=8958534548783151220 request.cu=4810 request.relayNumber=482 requestBlock=-2 seenBlock=1000
Dec  9 15:15:47 DBG Before Update Normal PrepareSessionForUsage GUID=129129409401699916 relayNum=481 relayRequestTotalCU=4810 sps.CuSum=4810 sps.LatestRelayCu=10 sps.sessionId=8958534548783151220
Dec  9 15:15:47 DBG Provider Finished Relay Successfully GUID=129129409401699916 method=status replyBlock=1000 request.SessionId=8958534548783151220 request.relayNumber=482 requestedBlock=1000
Dec  9 15:15:47 DBG Provider returned a relay response GUID=129129409401699916 relay_timeout=29.996055948s request.SessionId=8958534548783151220 request.cu=4810 request.relayNumber=482 timeTaken=3.862345ms
Dec  9 15:15:47 DBG saving rewards snapshot to the DB proofs=1
Dec  9 15:15:47 INF Saved rewards snapshot to the DB successfully proofs=1
Dec  9 15:15:47 DBG QoS Availability report Availability=0.979253112033195020 down percent=0.002074688796680498
Dec  9 15:15:47 DBG tendermint http in/out GUID=129129409401699916 HasError=false method=GET path=http://localhost:1026/status request= response="{\"reply\": 3}" timeTaken=5.124828ms
    protocol_test.go:626: 
        	Error Trace:	/home/runner/work/lava/lava/protocol/integration/protocol_test.go:626
        	Error:      	"map[2:517 3:481 4:2 5:1]" should have 5 item(s), but has 4
        	Test:       	TestConsumerProviderWithProviders/basic-success

Check warning on line 0 in github.com/lavanet/lava/v4/protocol/lavasession

See this annotation in the file changed.

@github-actions github-actions / Test Results

TestMaximumBlockedSessionsErrorsInPairingListEmpty (github.com/lavanet/lava/v4/protocol/lavasession) failed 

artifacts/Test Results (Protocol)/protocol-report.xml [took 0s]
Raw output 
Failed
Dec  9 15:15:59 DBG updated providers epoch=20 spec=stubstub
Dec  9 15:15:59 DBG 1
Dec  9 15:15:59 WRN provider data not found, using default address=provider0
Dec  9 15:15:59 WRN provider data not found, using default address=provider0
Dec  9 15:15:59 DBG Blocking consumer session ConsecutiveErrors="Block the provider\nSession went out of sync with the provider;" errorsCount=1 id=8333286314785799330
Dec  9 15:15:59 WRN Identified SyncLoss in provider, allowing retry error="Block the provider\nSession went out of sync with the provider" address=provider0
Dec  9 15:15:59 DBG CSM Blocking provider address=provider0 allowing_second_chance=true errorsForReport="Block the provider\nSession went out of sync with the provider;"
Dec  9 15:15:59 WRN Provider pairing list is empty, resetting state. addon= extensions=
Dec  9 15:15:59 WRN provider data not found, using default address=provider0
Dec  9 15:15:59 WRN provider data not found, using default address=provider0
Dec  9 15:15:59 DBG Blocking consumer session ConsecutiveErrors="Block the provider\nSession went out of sync with the provider;" errorsCount=1 id=97084399154661724
Dec  9 15:15:59 WRN Identified SyncLoss in provider, allowing retry error="Block the provider\nSession went out of sync with the provider" address=provider0
Dec  9 15:15:59 DBG CSM Blocking provider address=provider0 allowing_second_chance=true errorsForReport="Block the provider\nSession went out of sync with the provider;"
Dec  9 15:15:59 INF Reporting Provider for unresponsiveness Provider address=provider0
Dec  9 15:15:59 WRN Provider pairing list is empty, resetting state. addon= extensions=
Dec  9 15:15:59 WRN provider data not found, using default address=provider0
Dec  9 15:15:59 WRN provider data not found, using default address=provider0
Dec  9 15:15:59 DBG Blocking consumer session ConsecutiveErrors="Block the provider\nSession went out of sync with the provider;" errorsCount=1 id=5093931096959480926
Dec  9 15:15:59 WRN Identified SyncLoss in provider, allowing retry error="Block the provider\nSession went out of sync with the provider" address=provider0
Dec  9 15:15:59 DBG CSM Blocking provider address=provider0 allowing_second_chance=true errorsForReport="Block the provider\nSession went out of sync with the provider;"
Dec  9 15:15:59 WRN Provider pairing list is empty, resetting state. addon= extensions=
Dec  9 15:15:59 WRN provider data not found, using default address=provider0
Dec  9 15:15:59 WRN provider data not found, using default address=provider0
Dec  9 15:15:59 DBG Blocking consumer session ConsecutiveErrors="Block the provider\nSession went out of sync with the provider;" errorsCount=1 id=6642264090965461560
Dec  9 15:15:59 WRN Identified SyncLoss in provider, allowing retry error="Block the provider\nSession went out of sync with the provider" address=provider0
Dec  9 15:15:59 DBG CSM Blocking provider address=provider0 allowing_second_chance=true errorsForReport="Block the provider\nSession went out of sync with the provider;"
Dec  9 15:15:59 WRN Provider pairing list is empty, resetting state. addon= extensions=
Dec  9 15:15:59 WRN provider data not found, using default address=provider0
Dec  9 15:15:59 WRN provider data not found, using default address=provider0
Dec  9 15:15:59 DBG Blocking consumer session ConsecutiveErrors="Block the provider\nSession went out of sync with the provider;" errorsCount=1 id=8110767841152042737
Dec  9 15:15:59 WRN Identified SyncLoss in provider, allowing retry error="Block the provider\nSession went out of sync with the provider" address=provider0
Dec  9 15:15:59 DBG CSM Blocking provider address=provider0 allowing_second_chance=true errorsForReport="Block the provider\nSession went out of sync with the provider;"
Dec  9 15:15:59 WRN Provider pairing list is empty, resetting state. addon= extensions=
Dec  9 15:15:59 WRN provider data not found, using default address=provider0
Dec  9 15:15:59 WRN provider data not found, using default address=provider0
Dec  9 15:15:59 DBG Blocking consumer session ConsecutiveErrors="Block the provider\nSession went out of sync with the provider;" errorsCount=1 id=5912564257254186891
Dec  9 15:15:59 WRN Identified SyncLoss in provider, allowing retry error="Block the provider\nSession went out of sync with the provider" address=provider0
Dec  9 15:15:59 DBG CSM Blocking provider address=provider0 allowing_second_chance=true errorsForReport="Block the provider\nSession went out of sync with the provider;"
Dec  9 15:15:59 WRN Provider pairing list is empty, resetting state. addon= extensions=
Dec  9 15:15:59 WRN provider data not found, using default address=provider0
Dec  9 15:15:59 WRN provider data not found, using default address=provider0
Dec  9 15:15:59 DBG Blocking consumer session ConsecutiveErrors="Block the provider\nSession went out of sync with the provider;" errorsCount=1 id=3249321287110452485
Dec  9 15:15:59 WRN Identified SyncLoss in provider, allowing retry error="Block the provider\nSession went out of sync with the provider" address=provider0
Dec  9 15:15:59 DBG CSM Blocking provider address=provider0 allowing_second_chance=true errorsForReport="Block the provider\nSession went out of sync with the provider;"
Dec  9 15:15:59 WRN Provider pairing list is empty, resetting state. addon= extensions=
Dec  9 15:15:59 WRN provider data not found, using default address=provider0
Dec  9 15:15:59 WRN provider data not found, using default address=provider0
Dec  9 15:15:59 DBG Blocking consumer session ConsecutiveErrors="Block the provider\nSession went out of sync with the provider;" errorsCount=1 id=6980966890920771146
Dec  9 15:15:59 WRN Identified SyncLoss in provider, allowing retry error="Block the provider\nSession went out of sync with the provider" address=provider0
Dec  9 15:15:59 DBG CSM Blocking provider address=provider0 allowing_second_chance=true errorsForReport="Block the provider\nSession went out of sync with the provider;"
Dec  9 15:15:59 WRN Provider pairing list is empty, resetting state. addon= extensions=
Dec  9 15:15:59 WRN provider data not found, using default address=provider0
Dec  9 15:15:59 WRN provider data not found, using default address=provider0
Dec  9 15:15:59 DBG Blocking consumer session ConsecutiveErrors="Block the provider\nSession went out of sync with the provider;" errorsCount=1 id=4335000691446052418
Dec  9 15:15:59 WRN Identified SyncLoss in provider, allowing retry error="Block the provider\nSession went out of sync with the provider" address=provider0
Dec  9 15:15:59 DBG CSM Blocking provider address=provider0 allowing_second_chance=true errorsForReport="Block the provider\nSession went out of sync with the provider;"
Dec  9 15:15:59 WRN Provider pairing list is empty, resetting state. addon= extensions=
Dec  9 15:15:59 WRN provider data not found, using default address=provider0
Dec  9 15:15:59 WRN provider data not found, using default address=provider0
Dec  9 15:15:59 DBG Blocking consumer session ConsecutiveErrors="Block the provider\nSession went out of sync with the provider;" errorsCount=1 id=7671981490425961766
Dec  9 15:15:59 WRN Identified SyncLoss in provider, allowing retry error="Block the provider\nSession went out of sync with the provider" address=provider0
Dec  9 15:15:59 DBG CSM Blocking provider address=provider0 allowing_second_chance=true errorsForReport="Block the provider\nSession went out of sync with the provider;"
Dec  9 15:15:59 WRN Provider pairing list is empty, resetting state. addon= extensions=
Dec  9 15:15:59 WRN provider data not found, using default address=provider0
Dec  9 15:15:59 WRN provider data not found, using default address=provider0
Dec  9 15:15:59 DBG Blocking consumer session ConsecutiveErrors="Block the provider\nSession went out of sync with the provider;" errorsCount=1 id=4411238587882790172
Dec  9 15:15:59 WRN Identified SyncLoss in provider, allowing retry error="Block the provider\nSession went out of sync with the provider" address=provider0
Dec  9 15:15:59 DBG CSM Blocking provider address=provider0 allowing_second_chance=true errorsForReport="Block the provider\nSession went out of sync with the provider;"
Dec  9 15:15:59 WRN Provider pairing list is empty, resetting state. addon= extensions=
Dec  9 15:15:59 WRN provider data not found, using default address=provider0
Dec  9 15:15:59 WRN provider data not found, using default address=provider0
Dec  9 15:15:59 DBG Blocking consumer session ConsecutiveErrors="Block the provider\nSession went out of sync with the provider;" errorsCount=1 id=6270072487297515879
Dec  9 15:15:59 WRN Identified SyncLoss in provider, allowing retry error="Block the provider\nSession went out of sync with the provider" address=provider0
Dec  9 15:15:59 DBG CSM Blocking provider address=provider0 allowing_second_chance=true errorsForReport="Block the provider\nSession went out of sync with the provider;"
Dec  9 15:15:59 WRN Provider pairing list is empty, resetting state. addon= extensions=
Dec  9 15:15:59 WRN provider data not found, using default address=provider0
Dec  9 15:15:59 WRN provider data not found, using default address=provider0
Dec  9 15:15:59 DBG Blocking consumer session ConsecutiveErrors="Block the provider\nSession went out of sync with the provider;" errorsCount=1 id=4851644860853361960
Dec  9 15:15:59 WRN Identified SyncLoss in provider, allowing retry error="Block the provider\nSession went out of sync with the provider" address=provider0
Dec  9 15:15:59 DBG CSM Blocking provider address=provider0 allowing_second_chance=true errorsForReport="Block the provider\nSession went out of sync with the provider;"
Dec  9 15:15:59 WRN Provider pairing list is empty, resetting state. addon= extensions=
Dec  9 15:15:59 WRN provider data not found, using default address=provider0
Dec  9 15:15:59 WRN provider data not found, using default address=provider0
Dec  9 15:15:59 DBG Blocking consumer session ConsecutiveErrors="Block the provider\nSession went out of sync with the provider;" errorsCount=1 id=9004011302740441194
Dec  9 15:15:59 WRN Identified SyncLoss in provider, allowing retry error="Block the provider\nSession went out of sync with the provider" address=provider0
Dec  9 15:15:59 DBG CSM Blocking provider address=provider0 allowing_second_chance=true errorsForReport="Block the provider\nSession went out of sync with the provider;"
Dec  9 15:15:59 WRN Provider pairing list is empty, resetting state. addon= extensions=
Dec  9 15:15:59 WRN provider data not found, using default address=provider0
Dec  9 15:15:59 WRN provider data not found, using default address=provider0
Dec  9 15:15:59 DBG Blocking consumer session ConsecutiveErrors="Block the provider\nSession went out of sync with the provider;" errorsCount=1 id=158052315953876220
Dec  9 15:15:59 WRN Identified SyncLoss in provider, allowing retry error="Block the provider\nSession went out of sync with the provider" address=provider0
Dec  9 15:15:59 DBG CSM Blocking provider address=provider0 allowing_second_chance=true errorsForReport="Block the provider\nSession went out of sync with the provider;"
Dec  9 15:15:59 WRN Provider pairing list is empty, resetting state. addon= extensions=
Dec  9 15:15:59 WRN provider data not found, using default address=provider0
Dec  9 15:15:59 WRN provider data not found, using default address=provider0
Dec  9 15:15:59 DBG Blocking consumer session ConsecutiveErrors="Block the provider\nSession went out of sync with the provider;" errorsCount=1 id=4180051911843875164
Dec  9 15:15:59 WRN Identified SyncLoss in provider, allowing retry error="Block the provider\nSession went out of sync with the provider" address=provider0
Dec  9 15:15:59 DBG CSM Blocking provider address=provider0 allowing_second_chance=true errorsForReport="Block the provider\nSession went out of sync with the provider;"
Dec  9 15:15:59 WRN Provider pairing list is empty, resetting state. addon= extensions=
Dec  9 15:15:59 WRN provider data not found, using default address=provider0
Dec  9 15:15:59 WRN provider data not found, using default address=provider0
Dec  9 15:15:59 DBG Blocking consumer session ConsecutiveErrors="Block the provider\nSession went out of sync with the provider;" errorsCount=1 id=1185168793683516004
Dec  9 15:15:59 WRN Identified SyncLoss in provider, allowing retry error="Block the provider\nSession went out of sync with the provider" address=provider0
Dec  9 15:15:59 DBG CSM Blocking provider address=provider0 allowing_second_chance=true errorsForReport="Block the provider\nSession went out of sync with the provider;"
Dec  9 15:15:59 WRN Provider pairing list is empty, resetting state. addon= extensions=
Dec  9 15:15:59 WRN provider data not found, using default address=provider0
Dec  9 15:15:59 WRN provider data not found, using default address=provider0
Dec  9 15:15:59 DBG Blocking consumer session ConsecutiveErrors="Block the provider\nSession went out of sync with the provider;" errorsCount=1 id=7084116953756524696
Dec  9 15:15:59 WRN Identified SyncLoss in provider, allowing retry error="Block the provider\nSession went out of sync with the provider" address=provider0
Dec  9 15:15:59 DBG CSM Blocking provider address=provider0 allowing_second_chance=true errorsForReport="Block the provider\nSession went out of sync with the provider;"
Dec  9 15:15:59 WRN Provider pairing list is empty, resetting state. addon= extensions=
Dec  9 15:15:59 WRN provider data not found, using default address=provider0
Dec  9 15:15:59 WRN provider data not found, using default address=provider0
Dec  9 15:15:59 DBG Blocking consumer session ConsecutiveErrors="Block the provider\nSession went out of sync with the provider;" errorsCount=1 id=2676427914413934312
Dec  9 15:15:59 WRN Identified SyncLoss in provider, allowing retry error="Block the provider\nSession went out of sync with the provider" address=provider0
Dec  9 15:15:59 DBG CSM Blocking provider address=provider0 allowing_second_chance=true errorsForReport="Block the provider\nSession went out of sync with the provider;"
Dec  9 15:15:59 WRN Provider pairing list is empty, resetting state. addon= extensions=
Dec  9 15:15:59 WRN provider data not found, using default address=provider0
Dec  9 15:15:59 WRN provider data not found, using default address=provider0
Dec  9 15:15:59 DBG Blocking consumer session ConsecutiveErrors="Block the provider\nSession went out of sync with the provider;" errorsCount=1 id=5483321351698416805
Dec  9 15:15:59 WRN Identified SyncLoss in provider, allowing retry error="Block the provider\nSession went out of sync with the provider" address=provider0
Dec  9 15:15:59 DBG CSM Blocking provider address=provider0 allowing_second_chance=true errorsForReport="Block the provider\nSession went out of sync with the provider;"
Dec  9 15:15:59 WRN Provider pairing list is empty, resetting state. addon= extensions=
Dec  9 15:15:59 WRN provider data not found, using default address=provider0
Dec  9 15:15:59 WRN provider data not found, using default address=provider0
Dec  9 15:15:59 DBG Blocking consumer session ConsecutiveErrors="Block the provider\nSession went out of sync with the provider;" errorsCount=1 id=551390574028413655
Dec  9 15:15:59 WRN Identified SyncLoss in provider, allowing retry error="Block the provider\nSession went out of sync with the provider" address=provider0
Dec  9 15:15:59 DBG CSM Blocking provider address=provider0 allowing_second_chance=true errorsForReport="Block the provider\nSession went out of sync with the provider;"
Dec  9 15:15:59 WRN Provider pairing list is empty, resetting state. addon= extensions=
Dec  9 15:15:59 WRN provider data not found, using default address=provider0
Dec  9 15:15:59 WRN provider data not found, using default address=provider0
Dec  9 15:15:59 DBG Blocking consumer session ConsecutiveErrors="Block the provider\nSession went out of sync with the provider;" errorsCount=1 id=3858319703234103589
Dec  9 15:15:59 WRN Identified SyncLoss in provider, allowing retry error="Block the provider\nSession went out of sync with the provider" address=provider0
Dec  9 15:15:59 DBG CSM Blocking provider address=provider0 allowing_second_chance=true errorsForReport="Block the provider\nSession went out of sync with the provider;"
Dec  9 15:15:59 WRN Provider pairing list is empty, resetting state. addon= extensions=
Dec  9 15:15:59 WRN provider data not found, using default address=provider0
Dec  9 15:15:59 WRN provider data not found, using default address=provider0
Dec  9 15:15:59 DBG Blocking consumer session ConsecutiveErrors="Block the provider\nSession went out of sync with the provider;" errorsCount=1 id=5988867004236965290
Dec  9 15:15:59 WRN Identified SyncLoss in provider, allowing retry error="Block the provider\nSession went out of sync with the provider" address=provider0
Dec  9 15:15:59 DBG CSM Blocking provider address=provider0 allowing_second_chance=true errorsForReport="Block the provider\nSession went out of sync with the provider;"
Dec  9 15:15:59 WRN Provider pairing list is empty, resetting state. addon= extensions=
Dec  9 15:15:59 WRN provider data not found, using default address=provider0
Dec  9 15:15:59 WRN provider data not found, using default address=provider0
Dec  9 15:15:59 DBG Blocking consumer session ConsecutiveErrors="Block the provider\nSession went out of sync with the provider;" errorsCount=1 id=2409246972582709170
Dec  9 15:15:59 WRN Identified SyncLoss in provider, allowing retry error="Block the provider\nSession went out of sync with the provider" address=provider0
Dec  9 15:15:59 DBG CSM Blocking provider address=provider0 allowing_second_chance=true errorsForReport="Block the provider\nSession went out of sync with the provider;"
Dec  9 15:15:59 WRN Provider pairing list is empty, resetting state. addon= extensions=
Dec  9 15:15:59 WRN provider data not found, using default address=provider0
Dec  9 15:15:59 WRN provider data not found, using default address=provider0
Dec  9 15:15:59 DBG Blocking consumer session ConsecutiveErrors="Block the provider\nSession went out of sync with the provider;" errorsCount=1 id=211047875692418789
Dec  9 15:15:59 WRN Identified SyncLoss in provider, allowing retry error="Block the provider\nSession went out of sync with the provider" address=provider0
Dec  9 15:15:59 DBG CSM Blocking provider address=provider0 allowing_second_chance=true errorsForReport="Block the provider\nSession went out of sync with the provider;"
Dec  9 15:15:59 WRN Provider pairing list is empty, resetting state. addon= extensions=
Dec  9 15:15:59 WRN provider data not found, using default address=provider0
Dec  9 15:15:59 WRN provider data not found, using default address=provider0
Dec  9 15:15:59 DBG Blocking consumer session ConsecutiveErrors="Block the provider\nSession went out of sync with the provider;" errorsCount=1 id=1831433151788068327
Dec  9 15:15:59 WRN Identified SyncLoss in provider, allowing retry error="Block the provider\nSession went out of sync with the provider" address=provider0
Dec  9 15:15:59 DBG CSM Blocking provider address=provider0 allowing_second_chance=true errorsForReport="Block the provider\nSession went out of sync with the provider;"
Dec  9 15:15:59 WRN Provider pairing list is empty, resetting state. addon= extensions=
Dec  9 15:15:59 WRN provider data not found, using default address=provider0
Dec  9 15:15:59 WRN provider data not found, using default address=provider0
Dec  9 15:15:59 DBG Blocking consumer session ConsecutiveErrors="Block the provider\nSession went out of sync with the provider;" errorsCount=1 id=2169865348353164656
Dec  9 15:15:59 WRN Identified SyncLoss in provider, allowing retry error="Block the provider\nSession went out of sync with the provider" address=provider0
Dec  9 15:15:59 DBG CSM Blocking provider address=provider0 allowing_second_chance=true errorsForReport="Block the provider\nSession went out of sync with the provider;"
Dec  9 15:15:59 WRN Provider pairing list is empty, resetting state. addon= extensions=
Dec  9 15:15:59 WRN provider data not found, using default address=provider0
Dec  9 15:15:59 WRN provider data not found, using default address=provider0
Dec  9 15:15:59 DBG Blocking consumer session ConsecutiveErrors="Block the provider\nSession went out of sync with the provider;" errorsCount=1 id=1429627748799372935
Dec  9 15:15:59 WRN Identified SyncLoss in provider, allowing retry error="Block the provider\nSession went out of sync with the provider" address=provider0
Dec  9 15:15:59 DBG CSM Blocking provider address=provider0 allowing_second_chance=true errorsForReport="Block the provider\nSession went out of sync with the provider;"
Dec  9 15:15:59 WRN Provider pairing list is empty, resetting state. addon= extensions=
Dec  9 15:15:59 WRN provider data not found, using default address=provider0
Dec  9 15:15:59 WRN provider data not found, using default address=provider0
Dec  9 15:15:59 DBG Blocking consumer session ConsecutiveErrors="Block the provider\nSession went out of sync with the provider;" errorsCount=1 id=2133592743327805960
Dec  9 15:15:59 WRN Identified SyncLoss in provider, allowing retry error="Block the provider\nSession went out of sync with the provider" address=provider0
Dec  9 15:15:59 DBG CSM Blocking provider address=provider0 allowing_second_chance=true errorsForReport="Block the provider\nSession went out of sync with the provider;"
Dec  9 15:15:59 WRN Provider pairing list is empty, resetting state. addon= extensions=
Dec  9 15:15:59 WRN provider data not found, using default address=provider0
Dec  9 15:15:59 WRN provider data not found, using default address=provider0
Dec  9 15:15:59 DBG Blocking consumer session ConsecutiveErrors="Block the provider\nSession went out of sync with the provider;" errorsCount=1 id=4414288208087159755
Dec  9 15:15:59 WRN Identified SyncLoss in provider, allowing retry error="Block the provider\nSession went out of sync with the provider" address=provider0
Dec  9 15:15:59 DBG CSM Blocking provider address=provider0 allowing_second_chance=true errorsForReport="Block the provider\nSession went out of sync with the provider;"
Dec  9 15:15:59 WRN Provider pairing list is empty, resetting state. addon= extensions=
Dec  9 15:15:59 DBG Blocking consumer session ConsecutiveErrors="Block the provider\nSession went out of sync with the provider;" errorsCount=1 id=871505400466131665
Dec  9 15:15:59 WRN Identified SyncLoss in provider, allowing retry error="Block the provider\nSession went out of sync with the provider" address=provider0
Dec  9 15:15:59 DBG CSM Blocking provider address=provider0 allowing_second_chance=true errorsForReport="Block the provider\nSession went out of sync with the provider;"
Dec  9 15:15:59 WRN Provider pairing list is empty, resetting state. addon= extensions=
Dec  9 15:15:59 DBG Blocking consumer session ConsecutiveErrors="Block the provider\nSession went out of sync with the provider;" errorsCount=1 id=8239061863456041998
Dec  9 15:15:59 WRN Identified SyncLoss in provider, allowing retry error="Block the provider\nSession went out of sync with the provider" address=provider0
Dec  9 15:15:59 DBG CSM Blocking provider address=provider0 allowing_second_chance=true errorsForReport="Block the provider\nSession went out of sync with the provider;"
Dec  9 15:15:59 WRN Provider pairing list is empty, resetting state. addon= extensions=
Dec  9 15:15:59 WRN [Optimizer] cannot calculate selection tiers error="invalid QoS availability, availability is non-positive: 0.000000000000000000" provider=provider0 qos_report="latency:\"10000000000000000\" availability:\"0\" sync:\"100000000000000000\" "
Dec  9 15:15:59 DBG No providers returned by the optimizer IgnoredProviderList=map[] Provider list=provider0
Dec  9 15:15:59 DBG stub could not get a provider addresses error="No pairings available."
Dec  9 15:15:59 WRN [Optimizer] cannot calculate selection tiers error="invalid QoS availability, availability is non-positive: 0.000000000000000000" provider=provider0 qos_report="latency:\"10000000000000000\" availability:\"0\" sync:\"100000000000000000\" "
Dec  9 15:15:59 DBG No providers returned by the optimizer IgnoredProviderList=map[] Provider list=provider0
Dec  9 15:15:59 DBG stub could not get a provider addresses error="No pairings available."
    consumer_session_manager_test.go:1090: 
        	Error Trace:	/home/runner/work/lava/lava/protocol/lavasession/consumer_session_manager_test.go:1090
        	Error:      	Received unexpected error:
        	            	No pairings available.
        	Test:       	TestMaximumBlockedSessionsErrorsInPairingListEmpty

Check warning on line 0 in github.com/lavanet/lava/v4/x/pairing/types

See this annotation in the file changed.

@github-actions github-actions / Test Results

TestQosReport (github.com/lavanet/lava/v4/x/pairing/types) failed 

artifacts/Test Results (Consensus)/x-report.xml [took 0s]
Raw output 
Failed
    qos_report_test.go:218: 
        	Error Trace:	/home/runner/work/lava/lava/x/pairing/types/qos_report_test.go:218
        	Error:      	Should be true
        	Test:       	TestQosReport
View more details on GitHub Actions