Skip to content

PR #1784 #2519

Sign in to view logs

PR #1784

PR #1784 #2519

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

1 errors, 2 fail, 2 289 pass in 14m 46s

2 292 tests  ±0   2 289 ✅ +1   14m 46s ⏱️ - 1m 8s
  120 suites ±0       0 💤 ±0 
    6 files   ±0       2 ❌  - 1   1 🔥 ±0 

Results for commit 11304a1. ± Comparison against earlier commit 82a5c31.

Annotations

Check failure 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) with error 

artifacts/Test Results (Protocol E2E)/protocol-e2e-report.xml [took 0s]
Raw output 
No test result found
Dec  9 15:58:12 INF Starting Lava
Dec  9 15:58:12 INF Executing Command: ./scripts/start_env_dev.sh
Dec  9 15:58:12 INF Waiting for Lava
Dec  9 15:58:22 INF Waiting for Lava
Dec  9 15:58:32 INF Waiting for Lava
Dec  9 15:58:42 INF Waiting for Lava
Dec  9 15:58:52 INF Waiting for Lava
Dec  9 15:59:02 INF Waiting for Lava
Dec  9 15:59:12 INF Waiting for Lava
Dec  9 15:59:22 INF Waiting for Lava
Dec  9 15:59:32 INF Waiting for Lava
Dec  9 15:59:42 INF Waiting for Lava
Dec  9 15:59:52 INF Waiting for Lava
Dec  9 16:00:02 INF Waiting for Lava
Dec  9 16:00:12 INF Waiting for Lava
Dec  9 16:00:22 INF Waiting for Lava
Dec  9 16:00:32 INF Waiting for Lava
Dec  9 16:00:42 INF Waiting for Lava
Dec  9 16:00:52 INF Waiting for Lava
Dec  9 16:01:02 INF Starting Lava OK
Dec  9 16:01:02 INF Staking Lava
Dec  9 16:01:02 INF Executing Command: ./scripts/test/init_e2e.sh
Dec  9 16:02:07 INF stakeLava OK
ETH1
provider lava@137kzxnkdrrw5a0yyvc9wvyu0ujxlwc3dvcxpuc [{127.0.0.1:2224 1 [] [jsonrpc] []}]
provider lava@1flhc2krvtv03m5s406s4edfedrne0wdfs0fsqa [{127.0.0.1:2225 1 [] [jsonrpc] []}]
provider lava@1ulhnl7cg9yvn9rtpkd7p05wkc3vtqvjmt5aal7 [{127.0.0.1:2222 1 [] [jsonrpc] []}]
provider lava@1vnml0703zq4h4rz8pdqhy6rl825qhuwn55n7gn [{127.0.0.1:2221 1 [debug] [jsonrpc] [archive]}]
provider lava@1xsux2df5lusssfz0eg6l6n0a5n9r3jnd8gwa92 [{127.0.0.1:2223 1 [] [jsonrpc] []}]
LAV1
provider lava@15xdefahp7h2kvexgjn08aqs960yd5metczzxql [{127.0.0.1:2264 1 [] [grpc rest tendermintrpc] []}]
provider lava@163lkl77avrwjx428wrysw4d688x4lcw3zsd58w [{127.0.0.1:2262 1 [] [grpc rest tendermintrpc] []}]
provider lava@18gr56wreq4suvecg7urjlk0ef6ujka9e236393 [{127.0.0.1:2263 1 [] [grpc rest tendermintrpc] []}]
provider lava@1gatyr4tnjju7exysflt0rykxjsfs9cfkp0erxs [{127.0.0.1:2265 1 [] [grpc rest tendermintrpc] []}]
provider lava@1p3wge4engdmw465sjdk5s0fl0nrwyddf2fdlpc [{127.0.0.1:2261 1 [] [grpc rest tendermintrpc] []}]
Dec  9 16:02:07 INF Staking Lava OK
Dec  9 16:02:07 INF RUNNING TESTS
Dec  9 16:02:07 INF Executing Command: /opt/hostedtoolcache/go/1.20.14/x64/bin/go test ./testutil/e2e/proxy/. -v -timeout 0 eth
Dec  9 16:02:07 INF startJSONRPCProxy OK
Dec  9 16:02:07 INF Waiting JSONRPC Consumer
Dec  9 16:02:08 INF Waiting JSONRPC Consumer
Dec  9 16:02:09 INF Waiting JSONRPC Consumer
Dec  9 16:02:10 INF Waiting JSONRPC Consumer
Dec  9 16:02:11 INF Waiting JSONRPC Consumer
Dec  9 16:02:12 INF Waiting JSONRPC Consumer
Dec  9 16:02:13 INF Waiting JSONRPC Consumer
Dec  9 16:02:14 INF Waiting JSONRPC Consumer
Dec  9 16:02:15 INF Waiting JSONRPC Consumer
Dec  9 16:02:16 INF Waiting JSONRPC Consumer
Dec  9 16:02:17 INF Waiting JSONRPC Consumer
Dec  9 16:02:18 INF Waiting JSONRPC Consumer
Dec  9 16:02:19 INF Waiting JSONRPC Consumer
Dec  9 16:02:20 INF Waiting JSONRPC Consumer
Dec  9 16:02:21 INF Waiting JSONRPC Consumer
Dec  9 16:02:22 INF Waiting JSONRPC Consumer
Dec  9 16:02:23 INF Waiting JSONRPC Consumer
Dec  9 16:02:24 INF Waiting JSONRPC Consumer
Dec  9 16:02:25 INF Waiting JSONRPC Consumer
Dec  9 16:02:25 INF JSONRPCProxy OK
Dec  9 16:02:25 INF Validated proxy is alive got response res=16482926
Dec  9 16:02:25 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 16:02:25 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 16:02:25 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 16:02:25 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 16:02:25 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 16:02:25 INF Waiting Provider 127.0.0.1:2221
Dec  9 16:02:27 INF Waiting Provider 127.0.0.1:2221
Dec  9 16:02:29 INF Waiting Provider 127.0.0.1:2221
Dec  9 16:02:31 INF Waiting Provider 127.0.0.1:2221
Dec  9 16:02:33 INF Waiting Provider 127.0.0.1:2221
Dec  9 16:02:35 INF Waiting Provider 127.0.0.1:2221
Dec  9 16:02:37 INF Waiting Provider 127.0.0.1:2221
Dec  9 16:02:39 INF Waiting Provider 127.0.0.1:2221
Dec  9 16:02:41 INF Waiting Provider 127.0.0.1:2221
Dec  9 16:02:43 INF Waiting Provider 127.0.0.1:2221
Dec  9 16:02:45 INF Waiting Provider 127.0.0.1:2221
Dec  9 16:02:47 INF Waiting Provider 127.0.0.1:2221
Dec  9 16:02:49 INF Waiting Provider 127.0.0.1:2221
Dec  9 16:02:50 INF Waiting Provider 127.0.0.1:2222
Dec  9 16:02:50 INF Waiting Provider 127.0.0.1:2223
Dec  9 16:02:50 INF Waiting Provider 127.0.0.1:2224
Dec  9 16:02:50 INF startJSONRPCProvider OK
Dec  9 16:02:50 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 16:02:50 INF startJSONRPCConsumer OK
Dec  9 16:02:50 INF Waiting JSONRPC Consumer
Dec  9 16:02:51 INF Waiting JSONRPC Consumer
Dec  9 16:02:52 INF Waiting JSONRPC Consumer
Dec  9 16:02:53 INF Waiting JSONRPC Consumer
Dec  9 16:02:54 INF Waiting JSONRPC Consumer
Dec  9 16:02:54 INF JSONRPCConsumer1 OK
Dec  9 16:02:54 INF Validated proxy is alive got response res=16482926
Dec  9 16:02:54 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 16:02:54 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 16:02:54 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 16:02:54 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 16:02:54 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 16:02:54 INF Waiting Provider 127.0.0.1:2261
Dec  9 16:02:56 INF Waiting Provider 127.0.0.1:2261
Dec  9 16:02:58 INF Waiting Provider 127.0.0.1:2261
Dec  9 16:03:00 INF Waiting Provider 127.0.0.1:2261
Dec  9 16:03:00 INF Waiting Provider 127.0.0.1:2262
Dec  9 16:03:00 INF Waiting Provider 127.0.0.1:2263
Dec  9 16:03:00 INF Waiting Provider 127.0.0.1:2264
Dec  9 16:03:00 INF Waiting Provider 127.0.0.1:2265
Dec  9 16:03:00 INF startLavaProviders OK
Dec  9 16:03:00 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 16:03:00 INF startRPCConsumer OK
Dec  9 16:03:00 INF Waiting TENDERMINT Consumer
Dec  9 16:03:01 INF Waiting TENDERMINT Consumer
Dec  9 16:03:02 INF Waiting TENDERMINT Consumer
Dec  9 16:03:03 INF Waiting TENDERMINT Consumer
Dec  9 16:03:04 INF Waiting TENDERMINT Consumer
Dec  9 16:03:05 INF Waiting TENDERMINT Consumer
Dec  9 16:03:05 INF checkTendermintConsumer OK
Dec  9 16:03:05 INF Waiting REST Consumer
Dec  9 16:03:05 INF checkRESTConsumer OK
Dec  9 16:03:05 INF Waiting GRPC Consumer
Dec  9 16:03:05 INF checkGRPCConsumer OK
Dec  9 16:03:05 INF Starting JSONRPC Tests
Dec  9 16:03:24 DBG success writing to file bytesWritten=452529 fileName=00_StartLava lines=452529
Dec  9 16:03:24 DBG success writing to file bytesWritten=17302 fileName=05_LavaProvider_03 lines=17302
Dec  9 16:03:24 DBG success writing to file bytesWritten=17246 fileName=05_LavaProvider_04 lines=17246
Dec  9 16:03:24 DBG success writing to file bytesWritten=33263 fileName=06_RPCConsumer_01 lines=33263
Dec  9 16:03:24 DBG success writing to file bytesWritten=17316 fileName=05_LavaProvider_01 lines=17316
Dec  9 16:03:24 DBG success writing to file bytesWritten=15405 fileName=05_LavaProvider_02 lines=15405
Dec  9 16:03:24 DBG success writing to file bytesWritten=8248 fileName=01_stakeLava lines=8248
Dec  9 16:03:24 DBG success writing to file bytesWritten=23509 fileName=03_EthProvider_01 lines=23509
Dec  9 16:03:24 DBG success writing to file bytesWritten=26151 fileName=03_EthProvider_05 lines=26151
Dec  9 16:03:24 DBG success writing to file bytesWritten=83309 fileName=04_jsonConsumer_01 lines=83309
Dec  9 16:03:24 DBG success writing to file bytesWritten=16327 fileName=05_LavaProvider_05 lines=16327
Dec  9 16:03:24 ERR startLavaProviders (provider 05) cmd wait err error="signal: killed"
Dec  9 16:03:24 DBG success writing to file bytesWritten=17316 fileName=05_LavaProvider_01 lines=17316
Dec  9 16:03:24 DBG success writing to file bytesWritten=15405 fileName=05_LavaProvider_02 lines=15405
Dec  9 16:03:24 DBG success writing to file bytesWritten=26151 fileName=03_EthProvider_05 lines=26151
Dec  9 16:03:24 DBG success writing to file bytesWritten=83309 fileName=04_jsonConsumer_01 lines=83309
Dec  9 16:03:24 DBG success writing to file bytesWritten=16327 fileName=05_LavaProvider_05 lines=16327
Dec  9 16:03:24 DBG success writing to file bytesWritten=8248 fileName=01_stakeLava lines=8248
Dec  9 16:03:24 DBG success writing to file bytesWritten=23509 fileName=03_EthProvider_01 lines=23509
Dec  9 16:03:24 DBG success writing to file bytesWritten=7566 fileName=03_EthProvider_03 lines=7566
Dec  9 16:03:24 DBG success writing to file bytesWritten=19460 fileName=03_EthProvider_04 lines=19460
Dec  9 16:03:24 ERR startJSONRPCProvider (provider 03) cmd wait err error="signal: killed"
Dec  9 16:03:24 ERR startJSONRPCProvider (provider 04) cmd wait err error="signal: killed"
Dec  9 16:03:24 DBG success writing to file bytesWritten=8248 fileName=01_stakeLava lines=8248
Dec  9 16:03:24 DBG success writing to file bytesWritten=23509 fileName=03_EthProvider_01 lines=23509
Dec  9 16:03:24 DBG success writing to file bytesWritten=26151 fileName=03_EthProvider_05 lines=26151
Dec  9 16:03:24 DBG success writing to file bytesWritten=83309 fileName=04_jsonConsumer_01 lines=83309
Dec  9 16:03:24 DBG success writing to file bytesWritten=16327 fileName=05_LavaProvider_05 lines=16327
Dec  9 16:03:24 ERR startJSONRPCProvider (provider 01) cmd wait err error="signal: killed"
Dec  9 16:03:24 DBG success writing to file bytesWritten=15405 fileName=05_LavaProvider_02 lines=15405
Dec  9 16:03:24 DBG success writing to file bytesWritten=17316 fileName=05_LavaProvider_01 lines=17316
Dec  9 16:03:24 DBG success writing to file bytesWritten=23509 fileName=03_EthProvider_01 lines=23509
Dec  9 16:03:24 DBG success writing to file bytesWritten=26151 fileName=03_EthProvider_05 lines=26151
Dec  9 16:03:24 DBG success writing to file bytesWritten=83309 fileName=04_jsonConsumer_01 lines=83309
Dec  9 16:03:24 DBG success writing to file bytesWritten=16327 fileName=05_LavaProvider_05 lines=16327
Dec  9 16:03:24 DBG success writing to file bytesWritten=8248 fileName=01_stakeLava lines=8248
Dec  9 16:03:24 DBG success writing to file bytesWritten=7565 fileName=03_EthProvider_02 lines=7565
Dec  9 16:03:24 ERR startJSONRPCProvider (provider 05) cmd wait err error="signal: killed"
Dec  9 16:03:24 DBG success writing to file bytesWritten=17316 fileName=05_LavaProvider_01 lines=17316
Dec  9 16:03:24 DBG success writing to file bytesWritten=7566 fileName=03_EthProvider_03 lines=7566
Dec  9 16:03:24 DBG success writing to file bytesWritten=15405 fileName=05_LavaProvider_02 lines=15405
Dec  9 16:03:24 DBG success writing to file bytesWritten=19460 fileName=03_EthProvider_04 lines=19460
Dec  9 16:03:24 DBG success writing to file bytesWritten=8248 fileName=01_stakeLava lines=8248
Dec  9 16:03:24 DBG success writing to file bytesWritten=23509 fileName=03_EthProvider_01 lines=23509
Dec  9 16:03:24 DBG success writing to file bytesWritten=26151 fileName=03_EthProvider_05 lines=26151
Dec  9 16:03:24 DBG success writing to file bytesWritten=83309 fileName=04_jsonConsumer_01 lines=83309
Dec  9 16:03:24 ERR startLavaProviders (provider 03) cmd wait err error="signal: killed"
Dec  9 16:03:24 DBG success writing to file bytesWritten=16327 fileName=05_LavaProvider_05 lines=16327
Dec  9 16:03:24 ERR startJSONRPCProvider (provider 02) cmd wait err error="signal: killed"
Dec  9 16:03:24 DBG success writing to file bytesWritten=8248 fileName=01_stakeLava lines=8248
Dec  9 16:03:24 DBG success writing to file bytesWritten=8248 fileName=01_stakeLava lines=8248
Dec  9 16:03:24 ERR startJSONRPCProxy cmd wait err error="signal: killed"
Dec  9 16:03:24 DBG success writing to file bytesWritten=8248 fileName=01_stakeLava lines=8248
Dec  9 16:03:24 DBG success writing to file bytesWritten=23509 fileName=03_EthProvider_01 lines=23509
Dec  9 16:03:24 DBG success writing to file bytesWritten=16327 fileName=05_LavaProvider_05 lines=16327
Dec  9 16:03:24 ERR startJSONRPCConsumer (consumer 01) cmd wait err error="signal: killed"
Dec  9 16:03:24 DBG success writing to file bytesWritten=17316 fileName=05_LavaProvider_01 lines=17316
Dec  9 16:03:24 DBG success writing to file bytesWritten=15405 fileName=05_LavaProvider_02 lines=15405
Dec  9 16:03:24 DBG success writing to file bytesWritten=8248 fileName=01_stakeLava lines=8248
Dec  9 16:03:24 ERR startLavaProviders (provider 01) cmd wait err error="signal: killed"
Dec  9 16:03:24 DBG success writing to file bytesWritten=17316 fileName=05_LavaProvider_01 lines=17316
Dec  9 16:03:24 DBG success writing to file bytesWritten=23509 fileName=03_EthProvider_01 lines=23509
Dec  9 16:03:24 DBG success writing to file bytesWritten=26151 fileName=03_EthProvider_05 lines=26151
Dec  9 16:03:24 DBG success writing to file bytesWritten=26151 fileName=03_EthProvider_05 lines=26151
Dec  9 16:03:24 DBG success writing to file bytesWritten=83309 fileName=04_jsonConsumer_01 lines=83309
Dec  9 16:03:24 DBG success writing to file bytesWritten=16327 fileName=05_LavaProvider_05 lines=16327
Dec  9 16:03:24 DBG success writing to file bytesWritten=23509 fileName=03_EthProvider_01 lines=23509
Dec  9 16:03:24 DBG success writing to file bytesWritten=83309 fileName=04_jsonConsumer_01 lines=83309
Dec  9 16:03:24 ERR startLavaProviders (provider 02) cmd wait err error="signal: killed"
Dec  9 16:03:24 DBG success writing to file bytesWritten=8248 fileName=01_stakeLava lines=8248
Dec  9 16:03:24 DBG success writing to file bytesWritten=15405 fileName=05_LavaProvider_02 lines=15405
Dec  9 16:03:24 DBG success writing to file bytesWritten=23509 fileName=03_EthProvider_01 lines=23509
Dec  9 16:03:24 DBG success writing to file bytesWritten=26151 fileName=03_EthProvider_05 lines=26151
Dec  9 16:03:24 DBG success writing to file bytesWritten=26151 fileName=03_EthProvider_05 lines=26151
Dec  9 16:03:24 DBG success writing to file bytesWritten=23509 fileName=03_EthProvider_01 lines=23509
Dec  9 16:03:24 DBG success writing to file bytesWritten=26151 fileName=03_EthProvider_05 lines=26151
Dec  9 16:03:24 DBG success writing to file bytesWritten=16327 fileName=05_LavaProvider_05 lines=16327
Dec  9 16:03:24 DBG success writing to file bytesWritten=26151 fileName=03_EthProvider_05 lines=26151
Dec  9 16:03:24 DBG success writing to file bytesWritten=83309 fileName=04_jsonConsumer_01 lines=83309
Dec  9 16:03:24 ERR startRPCConsumer (consumer 01) cmd wait err error="signal: killed"
Dec  9 16:03:24 DBG success writing to file bytesWritten=2752810 fileName=02_jsonProxy lines=2752810
Dec  9 16:03:24 ERR startLavaProviders (provider 04) cmd wait err error="signal: killed"
Dec  9 16:03:24 DBG success writing to file bytesWritten=482629 fileName=00_StartLava lines=482629
Dec  9 16:03:24 DBG success writing to file bytesWritten=17302 fileName=05_LavaProvider_03 lines=17302
Dec  9 16:03:24 DBG success writing to file bytesWritten=17246 fileName=05_LavaProvider_04 lines=17246
Dec  9 16:03:24 DBG success writing to file bytesWritten=33263 fileName=06_RPCConsumer_01 lines=33263
Dec  9 16:03:24 DBG success writing to file bytesWritten=17302 fileName=05_LavaProvider_03 lines=17302
Dec  9 16:03:24 DBG success writing to file bytesWritten=17246 fileName=05_LavaProvider_04 lines=17246
Dec  9 16:03:24 DBG success writing to file bytesWritten=33263 fileName=06_RPCConsumer_01 lines=33263
Dec  9 16:03:24 DBG success writing to file bytesWritten=83309 fileName=04_jsonConsumer_01 lines=83309
Dec  9 16:03:24 DBG success writing to file bytesWritten=17316 fileName=05_LavaProvider_01 lines=17316
Dec  9 16:03:24 DBG success writing to file bytesWritten=15405 fileName=05_LavaProvider_02 lines=15405
Dec  9 16:03:24 DBG success writing to file bytesWritten=16327 fileName=05_LavaProvider_05 lines=16327
Dec  9 16:03:24 DBG success writing to file bytesWritten=16327 fileName=05_LavaProvider_05 lines=16327
Dec  9 16:03:24 DBG success writing to file bytesWritten=2752810 fileName=02_jsonProxy lines=2752810
Dec  9 16:03:24 DBG success writing to file bytesWritten=16327 fileName=05_LavaProvider_05 lines=16327
Dec  9 16:03:24 DBG success writing to file bytesWritten=8248 fileName=01_stakeLava lines=8248
Dec  9 16:03:24 DBG success writing to file bytesWritten=23509 fileName=03_EthProvider_01 lines=23509
Dec  9 16:03:24 DBG success writing to file bytesWritten=8248 fileName=01_stakeLava lines=8248
Dec  9 16:03:24 DBG success writing to file bytesWritten=482629 fileName=00_StartLava lines=482629
Dec  9 16:03:24 DBG success writing to file bytesWritten=23509 fileName=03_EthProvider_01 lines=23509
Dec  9 16:03:24 DBG success writing to file bytesWritten=7566 fileName=03_EthProvider_03 lines=7566
ERROR:  Dec  9 16:03:03 ERR Failed To Connect to cache at address error="context deadline exceeded" address=127.0.0.1:2778
ERROR:  Dec  9 16:02:57 ERR Failed To Connect to cache at address error="context deadline exceeded" address=127.0.0.1:2777
ERROR:  Dec  9 16:02:29 ERR Failed To Connect to cache at address error="context deadline exceeded" address=127.0.0.1:2777
ERROR:  Dec  9 16:02:29 ERR Failed To Connect to cache at address error="context deadline exceeded" address=127.0.0.1:2777
ERROR:  Dec  9 16:02:29 ERR Failed To Connect to cache at address error="context deadline exceeded" address=127.0.0.1:2777
ERROR:  Dec  9 16:02:57 ERR Failed To Connect to cache at address error="context deadline exceeded" address=127.0.0.1:2777
ERROR:  Dec  9 16:02:57 ERR Failed To Connect to cache at address error="context deadline exceeded" address=127.0.0.1:2777
ERROR:  Dec  9 16:02:57 ERR Failed To Connect to cache at address error="context deadline exceeded" address=127.0.0.1:2777
ERROR:  Dec  9 16:02:53 ERR Failed To Connect to cache at address error="context deadline exceeded" address=127.0.0.1:2778
Dec  9 16:03:10 ERR failed getting responses from providers error="No pairings available." GUID=11572787078923455918 endpoint=ETH1jsonrpc relayProcessor="relayProcessor {resultsManager {success 0, nodeErrors:0, protocolErrors:0}, unwantedAddresses: ,currentlyUsedAddresses:}" userIp=127.0.0.1
Dec  9 16:03:10 ERR UniqueGuidResponseForError error="No pairings available." msgSeed=11572787078923455918
Dec  9 16:03:10 ERR jsonrpc http error="No pairings available." GUID=11572787078923455918 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\":\"11572787078923455918\",\"Error\":\"No pairings available.\"}" timeTaken=17.334096ms
Dec  9 16:03:14 ERR failed getting responses from providers error="No pairings available." GUID=2213265938751017033 endpoint=ETH1jsonrpc relayProcessor="relayProcessor {resultsManager {success 0, nodeErrors:0, protocolErrors:0}, unwantedAddresses: ,currentlyUsedAddresses:}" userIp=127.0.0.1
ERROR:  Dec  9 16:02:57 ERR Failed To Connect to cache at address error="context deadline exceeded" address=127.0.0.1:2777
ERROR:  Dec  9 16:02:29 ERR Failed To Connect to cache at address error="context deadline exceeded" address=127.0.0.1:2777
ERROR:  Dec  9 16:02:29 ERR Failed To Connect to cache at address error="context deadline exceeded" address=127.0.0.1:2777
Panic occurred: Error found in logs on ./testutil/e2e/protocolLogs/05_LavaProvider_02, 05_LavaProvider_01, 03_EthProvider_01, 03_EthProvider_05, 04_jsonConsumer_01, 05_LavaProvider_05, 03_EthProvider_02, 03_EthProvider_03, 03_EthProvider_04, 05_LavaProvider_03, 05_LavaProvider_04, 06_RPCConsumer_01
Dec  9 16:03:24 INF Restarting goroutine for startJSONRPCProvider. Remaining retries:  1

Dec  9 16:03:24 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
panic: context canceled

goroutine 297 [running]:
github.com/lavanet/lava/v4/testutil/e2e.(*lavaTest).execCommandWithRetry(0xc0001dde00, {0x3116ab0?, 0xc000ec5560}, {0xc000ad41b0, 0x22}, {0xc000eba5b8, 0x11}, {0xc000a8c180, 0xba})
	/home/runner/work/lava/lava/testutil/e2e/protocolE2E.go:108 +0x413
created by github.com/lavanet/lava/v4/testutil/e2e.(*lavaTest).execCommandWithRetry.func1.1
	/home/runner/work/lava/lava/testutil/e2e/protocolE2E.go:123 +0x2aa

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 10s]
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:58:37 INF setting log level loglevel=debug
Dec  9 15:58:37 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:35745, addons: (websocket), internal-path: ]} ||:{NetworkAddress:{Address: KeyPem: CertPem: DisableTLS:false} ChainID:LAV1 ApiInterface:tendermintrpc Geolocation:1 NodeUrls:[http://127.0.0.1:45617]}]"
Dec  9 15:58:37 INF Finished adding Clients Asynchronously free clients=1 url=http://127.0.0.1:45617
Dec  9 15:58:37 DBG router keys chainProxyRouter="map[|websocket|:[{ChainProxy:0xc007ea9320 addonsSupported:map[:{}] methodsRouted:map[]}] ||:[{ChainProxy:0xc007ea90e0 addonsSupported:map[:{}] methodsRouted:map[]}]]"
Dec  9 15:58:37 DBG chainRouter created chainProxyRouter="map[|websocket|:[{ChainProxy:0xc007ea9320 addonsSupported:map[:{}] methodsRouted:map[]}] ||:[{ChainProxy:0xc007ea90e0 addonsSupported:map[:{}] methodsRouted:map[]}]]"
Dec  9 15:58:37 INF Finished adding Clients Asynchronously free clients=1 url="ws://127.0.0.1:35745, addons: (websocket), internal-path: "
badger 2024/12/09 15:58:37 INFO: Set nextTxnTs to 0
Dec  9 15:58:37 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:58:37 DBG ChainTracker fetched init data successfully
Dec  9 15:58:37 WRN Warning: Using Self signed certificate is not recommended, this will not allow https connections to be established
Dec  9 15:58:37 INF [++] Provider Listening on Address Address="{Address:localhost:1027 KeyPem: CertPem: DisableTLS:false}" apiInterface=tendermintrpc chainID=LAV1
Dec  9 15:58:37 INF New provider listener active address="{Address:localhost:1027 KeyPem: CertPem: DisableTLS:false}"
Dec  9 15:58:37 INF setting log level loglevel=debug
Dec  9 15:58:37 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:34837, addons: (websocket), internal-path: ]} ||:{NetworkAddress:{Address: KeyPem: CertPem: DisableTLS:false} ChainID:LAV1 ApiInterface:tendermintrpc Geolocation:1 NodeUrls:[http://127.0.0.1:40077]}]"
Dec  9 15:58:37 INF Finished adding Clients Asynchronously free clients=1 url=http://127.0.0.1:40077
Dec  9 15:58:37 DBG router keys chainProxyRouter="map[|websocket|:[{ChainProxy:0xc007ea97a0 addonsSupported:map[:{}] methodsRouted:map[]}] ||:[{ChainProxy:0xc007ea9560 addonsSupported:map[:{}] methodsRouted:map[]}]]"
Dec  9 15:58:37 DBG chainRouter created chainProxyRouter="map[|websocket|:[{ChainProxy:0xc007ea97a0 addonsSupported:map[:{}] methodsRouted:map[]}] ||:[{ChainProxy:0xc007ea9560 addonsSupported:map[:{}] methodsRouted:map[]}]]"
Dec  9 15:58:37 INF Finished adding Clients Asynchronously free clients=1 url="ws://127.0.0.1:34837, addons: (websocket), internal-path: "
badger 2024/12/09 15:58:37 INFO: Set nextTxnTs to 0
Dec  9 15:58:37 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:58:37 DBG ChainTracker fetched init data successfully
Dec  9 15:58:37 WRN Warning: Using Self signed certificate is not recommended, this will not allow https connections to be established
Dec  9 15:58:38 INF New provider listener active address="{Address:localhost:1028 KeyPem: CertPem: DisableTLS:false}"
Dec  9 15:58:38 INF [++] Provider Listening on Address Address="{Address:localhost:1028 KeyPem: CertPem: DisableTLS:false}" apiInterface=tendermintrpc chainID=LAV1
Dec  9 15:58:38 INF setting log level loglevel=debug
Dec  9 15:58:38 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:33681, addons: (websocket), internal-path: ]} ||:{NetworkAddress:{Address: KeyPem: CertPem: DisableTLS:false} ChainID:LAV1 ApiInterface:tendermintrpc Geolocation:1 NodeUrls:[http://127.0.0.1:39323]}]"
Dec  9 15:58:38 INF Finished adding Clients Asynchronously free clients=1 url=http://127.0.0.1:39323
Dec  9 15:58:38 DBG router keys chainProxyRouter="map[|websocket|:[{ChainProxy:0xc001b465a0 addonsSupported:map[:{}] methodsRouted:map[]}] ||:[{ChainProxy:0xc001b46240 addonsSupported:map[:{}] methodsRouted:map[]}]]"
Dec  9 15:58:38 INF Finished adding Clients Asynchronously free clients=1 url="ws://127.0.0.1:33681, addons: (websocket), internal-path: "
Dec  9 15:58:38 DBG chainRouter created chainProxyRouter="map[|websocket|:[{ChainProxy:0xc001b465a0 addonsSupported:map[:{}] methodsRouted:map[]}] ||:[{ChainProxy:0xc001b46240 addonsSupported:map[:{}] methodsRouted:map[]}]]"
badger 2024/12/09 15:58:38 INFO: Set nextTxnTs to 0
Dec  9 15:58:38 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:58:38 DBG ChainTracker fetched init data successfully
Dec  9 15:58:38 WRN Warning: Using Self signed certificate is not recommended, this will not allow https connections to be established
Dec  9 15:58:38 INF [++] Provider Listening on Address Address="{Address:localhost:1029 KeyPem: CertPem: DisableTLS:false}" apiInterface=tendermintrpc chainID=LAV1
Dec  9 15:58:38 INF New provider listener active address="{Address:localhost:1029 KeyPem: CertPem: DisableTLS:false}"
Dec  9 15:58:38 INF setting log level loglevel=debug
Dec  9 15:58:38 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:39051, addons: (websocket), internal-path: ]} ||:{NetworkAddress:{Address: KeyPem: CertPem: DisableTLS:false} ChainID:LAV1 ApiInterface:tendermintrpc Geolocation:1 NodeUrls:[http://127.0.0.1:39381]}]"
Dec  9 15:58:38 INF Finished adding Clients Asynchronously free clients=1 url=http://127.0.0.1:39381
Dec  9 15:58:38 DBG router keys chainProxyRouter="map[|websocket|:[{ChainProxy:0xc0017fa360 addonsSupported:map[:{}] methodsRouted:map[]}] ||:[{ChainProxy:0xc0017fa000 addonsSupported:map[:{}] methodsRouted:map[]}]]"
Dec  9 15:58:38 DBG chainRouter created chainProxyRouter="map[|websocket|:[{ChainProxy:0xc0017fa360 addonsSupported:map[:{}] methodsRouted:map[]}] ||:[{ChainProxy:0xc0017fa000 addonsSupported:map[:{}] methodsRouted:map[]}]]"
Dec  9 15:58:38 INF Finished adding Clients Asynchronously free clients=1 url="ws://127.0.0.1:39051, addons: (websocket), internal-path: "
badger 2024/12/09 15:58:38 INFO: Set nextTxnTs to 0
Dec  9 15:58:38 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:58:38 DBG ChainTracker fetched init data successfully
Dec  9 15:58:38 WRN Warning: Using Self signed certificate is not recommended, this will not allow https connections to be established
Dec  9 15:58:38 INF [++] Provider Listening on Address Address="{Address:localhost:1030 KeyPem: CertPem: DisableTLS:false}" apiInterface=tendermintrpc chainID=LAV1
Dec  9 15:58:38 INF New provider listener active address="{Address:localhost:1030 KeyPem: CertPem: DisableTLS:false}"
Dec  9 15:58:38 INF setting log level loglevel=debug
Dec  9 15:58:38 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:41149, addons: (websocket), internal-path: ]} ||:{NetworkAddress:{Address: KeyPem: CertPem: DisableTLS:false} ChainID:LAV1 ApiInterface:tendermintrpc Geolocation:1 NodeUrls:[http://127.0.0.1:39889]}]"
Dec  9 15:58:38 INF Finished adding Clients Asynchronously free clients=1 url=http://127.0.0.1:39889
Dec  9 15:58:38 DBG router keys chainProxyRouter="map[|websocket|:[{ChainProxy:0xc0011758c0 addonsSupported:map[:{}] methodsRouted:map[]}] ||:[{ChainProxy:0xc001175680 addonsSupported:map[:{}] methodsRouted:map[]}]]"
Dec  9 15:58:38 DBG chainRouter created chainProxyRouter="map[|websocket|:[{ChainProxy:0xc0011758c0 addonsSupported:map[:{}] methodsRouted:map[]}] ||:[{ChainProxy:0xc001175680 addonsSupported:map[:{}] methodsRouted:map[]}]]"
Dec  9 15:58:38 INF Finished adding Clients Asynchronously free clients=1 url="ws://127.0.0.1:41149, addons: (websocket), internal-path: "
badger 2024/12/09 15:58:38 INFO: Set nextTxnTs to 0
Dec  9 15:58:38 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:58:38 DBG ChainTracker fetched init data successfully
Dec  9 15:58:38 WRN Warning: Using Self signed certificate is not recommended, this will not allow https connections to be established
Dec  9 15:58:38 INF [++] Provider Listening on Address Address="{Address:localhost:1031 KeyPem: CertPem: DisableTLS:false}" apiInterface=tendermintrpc chainID=LAV1
Dec  9 15:58:38 INF New provider listener active address="{Address:localhost:1031 KeyPem: CertPem: DisableTLS:false}"
Dec  9 15:58:38 INF setting log level loglevel=debug
Dec  9 15:58:38 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:37295, addons: (websocket), internal-path: ]} ||:{NetworkAddress:{Address: KeyPem: CertPem: DisableTLS:false} ChainID:LAV1 ApiInterface:tendermintrpc Geolocation:1 NodeUrls:[http://127.0.0.1:34559]}]"
Dec  9 15:58:38 INF Finished adding Clients Asynchronously free clients=1 url=http://127.0.0.1:34559
Dec  9 15:58:38 DBG router keys chainProxyRouter="map[|websocket|:[{ChainProxy:0xc001974120 addonsSupported:map[:{}] methodsRouted:map[]}] ||:[{ChainProxy:0xc0012145a0 addonsSupported:map[:{}] methodsRouted:map[]}]]"
Dec  9 15:58:38 DBG chainRouter created chainProxyRouter="map[|websocket|:[{ChainProxy:0xc001974120 addonsSupported:map[:{}] methodsRouted:map[]}] ||:[{ChainProxy:0xc0012145a0 addonsSupported:map[:{}] methodsRouted:map[]}]]"
Dec  9 15:58:38 INF Finished adding Clients Asynchronously free clients=1 url="ws://127.0.0.1:37295, addons: (websocket), internal-path: "
Dec  9 15:58:38 DBG updated providers epoch=100 spec=LAV1tendermintrpc
Dec  9 15:58:39 INF New relic missing environment file
Dec  9 15:58:39 DBG Sending crafted relays apiInterface=tendermintrpc chainId=LAV1
Dec  9 15:58:39 WRN provider data not found, using default address=cosmos1hh0x6lt3l60u3r3ukcdepjxdrmu04uaknd8m7s
Dec  9 15:58:39 WRN provider data not found, using default address=cosmos14zv7k7zfzutu4ur08yz2hc8zxj5an5p546zh7s
Dec  9 15:58:39 WRN provider data not found, using default address=cosmos1a8t33fymt6th9qystrv7xsu5w5vnzwd2j0cp8a
Dec  9 15:58:39 WRN provider data not found, using default address=cosmos10y2mhrx9v2dne2xtz30u9gpxs0g9jpqdp6q8ur
Dec  9 15:58:39 WRN provider data not found, using default address=cosmos1ygyz9t24nwgurv9m59vmz7rad57r4hp3yguamg

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

Dec  9 15:58:39 WRN provider data not found, using default address=cosmos1ygyz9t24nwgurv9m59vmz7rad57r4hp3yguamg
Dec  9 15:58:39 DBG Provider got relay request GUID=10890933453773573898 relay addon= relay extensions= relay_timeout=29.999876149s request.SessionId=6340293329416606866 request.cu=10 request.relayNumber=1 requestBlock=-2 seenBlock=0
Dec  9 15:58:39 DBG provider registered consumer consumer=cosmos1u3zu5v775q37yp3002g9fln5wvucqlxn33pzrd epoch=100
Dec  9 15:58:39 DBG Provider creating new sessionID SessionID=6340293329416606866 epoch=100
Dec  9 15:58:39 DBG Before Update Normal PrepareSessionForUsage GUID=10890933453773573898 relayNum=0 relayRequestTotalCU=10 sps.CuSum=10 sps.LatestRelayCu=10 sps.sessionId=6340293329416606866
Dec  9 15:58:39 ERR failed to unmarshal id from response error="invalid character '\x00' looking for beginning of value" id=
Dec  9 15:58:39 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:58:39 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=10890933453773573898 attempt=0 specID=LAV1
Dec  9 15:58:39 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:10890933453773573898,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=10890933453773573898 request.SessionId=6340293329416606866 request.userAddr=cosmos1u3zu5v775q37yp3002g9fln5wvucqlxn33pzrd timed_out=false
Dec  9 15:58:39 DBG Provider returned a relay response GUID=10890933453773573898 relay_timeout=29.994095152s request.SessionId=6340293329416606866 request.cu=10 request.relayNumber=1 timeTaken=5.783151ms
Dec  9 15:58:39 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:10890933453773573898,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:10890933453773573898,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:6340293329416606866,request.userAddr:cosmos1u3zu5v775q37yp3002g9fln5wvucqlxn33pzrd,GUID:10890933453773573898,timed_out:false}" GUID=10890933453773573898 provider=cosmos1ygyz9t24nwgurv9m59vmz7rad57r4hp3yguamg
Dec  9 15:58:39 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:10890933453773573898,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:10890933453773573898,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:6340293329416606866,request.userAddr:cosmos1u3zu5v775q37yp3002g9fln5wvucqlxn33pzrd,GUID:10890933453773573898,timed_out:false}"
Dec  9 15:58:39 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:10890933453773573898,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:10890933453773573898,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:6340293329416606866,request.userAddr:cosmos1u3zu5v775q37yp3002g9fln5wvucqlxn33pzrd,GUID:10890933453773573898,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:10890933453773573898,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:10890933453773573898,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:6340293329416606866,request.userAddr:cosmos1u3zu5v775q37yp3002g9fln5wvucqlxn33pzrd,GUID:10890933453773573898,timed_out:false}" APIInterface=tendermintrpc chainID=LAV1 relayProcessor="relayProcessor {resultsManager {success 0, nodeErrors:0, protocolErrors:1}, unwantedAddresses: cosmos1ygyz9t24nwgurv9m59vmz7rad57r4hp3yguamg,currentlyUsedAddresses:}"
Dec  9 15:58:39 DBG Provider got relay request GUID=10890933453773573898 relay addon= relay extensions= relay_timeout=29.999920833s request.SessionId=3909966237964545019 request.cu=10 request.relayNumber=1 requestBlock=-2 seenBlock=0
Dec  9 15:58:39 DBG provider registered consumer consumer=cosmos1u3zu5v775q37yp3002g9fln5wvucqlxn33pzrd epoch=100
Dec  9 15:58:39 DBG Provider creating new sessionID SessionID=3909966237964545019 epoch=100
Dec  9 15:58:39 DBG Before Update Normal PrepareSessionForUsage GUID=10890933453773573898 relayNum=0 relayRequestTotalCU=10 sps.CuSum=10 sps.LatestRelayCu=10 sps.sessionId=3909966237964545019
Dec  9 15:58:39 ERR failed to unmarshal id from response error="invalid character '\x00' looking for beginning of value" id=
Dec  9 15:58:39 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:58:39 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=10890933453773573898 attempt=0 specID=LAV1
Dec  9 15:58:39 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:10890933453773573898,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=10890933453773573898 request.SessionId=3909966237964545019 request.userAddr=cosmos1u3zu5v775q37yp3002g9fln5wvucqlxn33pzrd timed_out=false
Dec  9 15:58:39 DBG Provider returned a relay response GUID=10890933453773573898 relay_timeout=29.998686207s request.SessionId=3909966237964545019 request.cu=10 request.relayNumber=1 timeTaken=1.239165ms
Dec  9 15:58:39 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:10890933453773573898,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:10890933453773573898,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:3909966237964545019,request.userAddr:cosmos1u3zu5v775q37yp3002g9fln5wvucqlxn33pzrd,GUID:10890933453773573898,timed_out:false}" GUID=10890933453773573898 provider=cosmos14zv7k7zfzutu4ur08yz2hc8zxj5an5p546zh7s
Dec  9 15:58:39 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:10890933453773573898,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:10890933453773573898,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:6340293329416606866,request.userAddr:cosmos1u3zu5v775q37yp3002g9fln5wvucqlxn33pzrd,GUID:10890933453773573898,timed_out:false}"
Dec  9 15:58:39 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:10890933453773573898,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:10890933453773573898,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:6340293329416606866,request.userAddr:cosmos1u3zu5v775q37yp3002g9fln5wvucqlxn33pzrd,GUID:10890933453773573898,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:10890933453773573898,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:10890933453773573898,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:6340293329416606866,request.userAddr:cosmos1u3zu5v775q37yp3002g9fln5wvucqlxn33pzrd,GUID:10890933453773573898,timed_out:false}" APIInterface=tendermintrpc chainID=LAV1 relayProcessor="relayProcessor {resultsManager {success 0, nodeErrors:0, protocolErrors:2}, unwantedAddresses: cosmos1ygyz9t24nwgurv9m59vmz7rad57r4hp3yguamg;cosmos14zv7k7zfzutu4ur08yz2hc8zx…769707363567923306 request.cu=5110 request.relayNumber=512 timeTaken=3.930591ms
Dec  9 15:58:45 DBG saving rewards snapshot to the DB proofs=1
Dec  9 15:58:45 INF Saved rewards snapshot to the DB successfully proofs=1
Dec  9 15:58:45 DBG QoS Availability report Availability=0.980468750000000000 down percent=0.001953125000000000
Dec  9 15:58:45 DBG tendermint http in/out GUID=18361292599519184313 HasError=false method=GET path=http://localhost:1026/status request= response="{\"reply\": 2}" timeTaken=5.526257ms
Dec  9 15:58:45 DBG urirpc in <<< GUID=16953656858050377774 _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:58:45 DBG Provider got relay request GUID=16953656858050377774 relay addon= relay extensions= relay_timeout=29.999928879s request.SessionId=5059797745928753848 request.cu=4670 request.relayNumber=468 requestBlock=-2 seenBlock=1000
Dec  9 15:58:45 DBG Before Update Normal PrepareSessionForUsage GUID=16953656858050377774 relayNum=467 relayRequestTotalCU=4670 sps.CuSum=4670 sps.LatestRelayCu=10 sps.sessionId=5059797745928753848
Dec  9 15:58:45 DBG Provider Finished Relay Successfully GUID=16953656858050377774 method=status replyBlock=1000 request.SessionId=5059797745928753848 request.relayNumber=468 requestedBlock=1000
Dec  9 15:58:45 DBG Provider returned a relay response GUID=16953656858050377774 relay_timeout=29.996027863s request.SessionId=5059797745928753848 request.cu=4670 request.relayNumber=468 timeTaken=3.90318ms
Dec  9 15:58:45 DBG saving rewards snapshot to the DB proofs=1
Dec  9 15:58:45 INF Saved rewards snapshot to the DB successfully proofs=1
Dec  9 15:58:45 DBG QoS Availability report Availability=0.978632478632478630 down percent=0.002136752136752137
Dec  9 15:58:45 DBG tendermint http in/out GUID=16953656858050377774 HasError=false method=GET path=http://localhost:1026/status request= response="{\"reply\": 4}" timeTaken=5.269814ms
Dec  9 15:58:45 DBG urirpc in <<< GUID=58471634284871413 _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:58:45 DBG Provider got relay request GUID=58471634284871413 relay addon= relay extensions= relay_timeout=29.999917263s request.SessionId=5059797745928753848 request.cu=4680 request.relayNumber=469 requestBlock=-2 seenBlock=1000
Dec  9 15:58:45 DBG Before Update Normal PrepareSessionForUsage GUID=58471634284871413 relayNum=468 relayRequestTotalCU=4680 sps.CuSum=4680 sps.LatestRelayCu=10 sps.sessionId=5059797745928753848
Dec  9 15:58:45 DBG Provider Finished Relay Successfully GUID=58471634284871413 method=status replyBlock=1000 request.SessionId=5059797745928753848 request.relayNumber=469 requestedBlock=1000
Dec  9 15:58:45 DBG Provider returned a relay response GUID=58471634284871413 relay_timeout=29.995946043s request.SessionId=5059797745928753848 request.cu=4680 request.relayNumber=469 timeTaken=3.973404ms
Dec  9 15:58:45 DBG saving rewards snapshot to the DB proofs=1
Dec  9 15:58:45 INF Saved rewards snapshot to the DB successfully proofs=1
Dec  9 15:58:45 DBG QoS Availability report Availability=0.978678038379530920 down percent=0.002132196162046908
Dec  9 15:58:45 DBG tendermint http in/out GUID=58471634284871413 HasError=false method=GET path=http://localhost:1026/status request= response="{\"reply\": 4}" timeTaken=5.407815ms
Dec  9 15:58:45 DBG urirpc in <<< GUID=9478971046675185200 _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:58:45 DBG Provider got relay request GUID=9478971046675185200 relay addon= relay extensions= relay_timeout=29.999926897s request.SessionId=5059797745928753848 request.cu=4690 request.relayNumber=470 requestBlock=-2 seenBlock=1000
Dec  9 15:58:45 DBG Before Update Normal PrepareSessionForUsage GUID=9478971046675185200 relayNum=469 relayRequestTotalCU=4690 sps.CuSum=4690 sps.LatestRelayCu=10 sps.sessionId=5059797745928753848
Dec  9 15:58:45 DBG Provider Finished Relay Successfully GUID=9478971046675185200 method=status replyBlock=1000 request.SessionId=5059797745928753848 request.relayNumber=470 requestedBlock=1000
Dec  9 15:58:45 DBG Provider returned a relay response GUID=9478971046675185200 relay_timeout=29.995937499s request.SessionId=5059797745928753848 request.cu=4690 request.relayNumber=470 timeTaken=3.991572ms
Dec  9 15:58:45 DBG saving rewards snapshot to the DB proofs=1
Dec  9 15:58:45 INF Saved rewards snapshot to the DB successfully proofs=1
Dec  9 15:58:45 DBG QoS Availability report Availability=0.978723404255319150 down percent=0.002127659574468085
Dec  9 15:58:45 DBG tendermint http in/out GUID=9478971046675185200 HasError=false method=GET path=http://localhost:1026/status request= response="{\"reply\": 4}" timeTaken=5.381656ms
Dec  9 15:58:45 DBG urirpc in <<< GUID=6362402416648090054 _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:58:45 DBG Provider got relay request GUID=6362402416648090054 relay addon= relay extensions= relay_timeout=29.999916061s request.SessionId=7769707363567923306 request.cu=5120 request.relayNumber=513 requestBlock=-2 seenBlock=1000
Dec  9 15:58:45 DBG Before Update Normal PrepareSessionForUsage GUID=6362402416648090054 relayNum=512 relayRequestTotalCU=5120 sps.CuSum=5120 sps.LatestRelayCu=10 sps.sessionId=7769707363567923306
Dec  9 15:58:45 DBG Provider Finished Relay Successfully GUID=6362402416648090054 method=status replyBlock=1000 request.SessionId=7769707363567923306 request.relayNumber=513 requestedBlock=1000
Dec  9 15:58:45 DBG Provider returned a relay response GUID=6362402416648090054 relay_timeout=29.99573858s request.SessionId=7769707363567923306 request.cu=5120 request.relayNumber=513 timeTaken=4.179965ms
Dec  9 15:58:45 DBG saving rewards snapshot to the DB proofs=1
Dec  9 15:58:45 INF Saved rewards snapshot to the DB successfully proofs=1
Dec  9 15:58:45 DBG QoS Availability report Availability=0.980506822612085770 down percent=0.001949317738791423
Dec  9 15:58:45 DBG tendermint http in/out GUID=6362402416648090054 HasError=false method=GET path=http://localhost:1026/status request= response="{\"reply\": 2}" timeTaken=5.707922ms
Dec  9 15:58:45 DBG urirpc in <<< GUID=16849236608939073594 _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:58:45 DBG Provider got relay request GUID=16849236608939073594 relay addon= relay extensions= relay_timeout=29.999904111s request.SessionId=7769707363567923306 request.cu=5130 request.relayNumber=514 requestBlock=-2 seenBlock=1000
Dec  9 15:58:45 DBG Before Update Normal PrepareSessionForUsage GUID=16849236608939073594 relayNum=513 relayRequestTotalCU=5130 sps.CuSum=5130 sps.LatestRelayCu=10 sps.sessionId=7769707363567923306
Dec  9 15:58:45 DBG Provider Finished Relay Successfully GUID=16849236608939073594 method=status replyBlock=1000 request.SessionId=7769707363567923306 request.relayNumber=514 requestedBlock=1000
Dec  9 15:58:45 DBG Provider returned a relay response GUID=16849236608939073594 relay_timeout=29.995851027s request.SessionId=7769707363567923306 request.cu=5130 request.relayNumber=514 timeTaken=4.055929ms
Dec  9 15:58:45 DBG saving rewards snapshot to the DB proofs=1
Dec  9 15:58:45 INF Saved rewards snapshot to the DB successfully proofs=1
Dec  9 15:58:45 DBG QoS Availability report Availability=0.980544747081712060 down percent=0.001945525291828794
Dec  9 15:58:45 DBG tendermint http in/out GUID=16849236608939073594 HasError=false method=GET path=http://localhost:1026/status request= response="{\"reply\": 2}" timeTaken=5.669783ms
Dec  9 15:58:45 DBG urirpc in <<< GUID=9667333277573483387 _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:58:45 DBG Provider got relay request GUID=9667333277573483387 relay addon= relay extensions= relay_timeout=29.999889659s request.SessionId=5059797745928753848 request.cu=4700 request.relayNumber=471 requestBlock=-2 seenBlock=1000
Dec  9 15:58:45 DBG Before Update Normal PrepareSessionForUsage GUID=9667333277573483387 relayNum=470 relayRequestTotalCU=4700 sps.CuSum=4700 sps.LatestRelayCu=10 sps.sessionId=5059797745928753848
Dec  9 15:58:45 DBG Provider Finished Relay Successfully GUID=9667333277573483387 method=status replyBlock=1000 request.SessionId=5059797745928753848 request.relayNumber=471 requestedBlock=1000
Dec  9 15:58:45 DBG Provider returned a relay response GUID=9667333277573483387 relay_timeout=29.995879633s request.SessionId=5059797745928753848 request.cu=4700 request.relayNumber=471 timeTaken=4.01229ms
Dec  9 15:58:45 DBG saving rewards snapshot to the DB proofs=1
Dec  9 15:58:45 INF Saved rewards snapshot to the DB successfully proofs=1
Dec  9 15:58:45 DBG QoS Availability report Availability=0.978768577494692140 down percent=0.002123142250530786
Dec  9 15:58:45 DBG tendermint http in/out GUID=9667333277573483387 HasError=false method=GET path=http://localhost:1026/status request= response="{\"reply\": 4}" timeTaken=5.568567ms
Dec  9 15:58:45 DBG urirpc in <<< GUID=809045102663110161 _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:58:45 DBG Provider got relay request GUID=809045102663110161 relay addon= relay extensions= relay_timeout=29.999923108s request.SessionId=7769707363567923306 request.cu=5140 request.relayNumber=515 requestBlock=-2 seenBlock=1000
Dec  9 15:58:45 DBG Before Update Normal PrepareSessionForUsage GUID=809045102663110161 relayNum=514 relayRequestTotalCU=5140 sps.CuSum=5140 sps.LatestRelayCu=10 sps.sessionId=7769707363567923306
Dec  9 15:58:45 DBG Provider Finished Relay Successfully GUID=809045102663110161 method=status replyBlock=1000 request.SessionId=7769707363567923306 request.relayNumber=515 requestedBlock=1000
Dec  9 15:58:45 DBG Provider returned a relay response GUID=809045102663110161 relay_timeout=29.995928324s request.SessionId=7769707363567923306 request.cu=5140 request.relayNumber=515 timeTaken=3.997079ms
Dec  9 15:58:45 DBG saving rewards snapshot to the DB proofs=1
Dec  9 15:58:45 INF Saved rewards snapshot to the DB successfully proofs=1
Dec  9 15:58:45 DBG QoS Availability report Availability=0.980582524271844660 down percent=0.001941747572815534
Dec  9 15:58:45 DBG tendermint http in/out GUID=809045102663110161 HasError=false method=GET path=http://localhost:1026/status request= response="{\"reply\": 2}" timeTaken=5.537843ms
Dec  9 15:58:45 DBG urirpc in <<< GUID=9101031047292941150 _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:58:45 DBG Provider got relay request GUID=9101031047292941150 relay addon= relay extensions= relay_timeout=29.999919802s request.SessionId=7769707363567923306 request.cu=5150 request.relayNumber=516 requestBlock=-2 seenBlock=1000
Dec  9 15:58:45 DBG Before Update Normal PrepareSessionForUsage GUID=9101031047292941150 relayNum=515 relayRequestTotalCU=5150 sps.CuSum=5150 sps.LatestRelayCu=10 sps.sessionId=7769707363567923306
Dec  9 15:58:45 DBG Provider Finished Relay Successfully GUID=9101031047292941150 method=status replyBlock=1000 request.SessionId=7769707363567923306 request.relayNumber=516 requestedBlock=1000
Dec  9 15:58:45 DBG Provider returned a relay response GUID=9101031047292941150 relay_timeout=29.995955548s request.SessionId=7769707363567923306 request.cu=5150 request.relayNumber=516 timeTaken=3.968061ms
Dec  9 15:58:45 DBG saving rewards snapshot to the DB proofs=1
Dec  9 15:58:45 INF Saved rewards snapshot to the DB successfully proofs=1
Dec  9 15:58:45 DBG QoS Availability report Availability=0.980620155038759690 down percent=0.001937984496124031
Dec  9 15:58:45 DBG tendermint http in/out GUID=9101031047292941150 HasError=false method=GET path=http://localhost:1026/status request= response="{\"reply\": 2}" timeTaken=5.379933ms
Dec  9 15:58:45 DBG urirpc in <<< GUID=16657170416976794445 _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:58:45 DBG Provider got relay request GUID=16657170416976794445 relay addon= relay extensions= relay_timeout=29.999923114s request.SessionId=7769707363567923306 request.cu=5160 request.relayNumber=517 requestBlock=-2 seenBlock=1000
Dec  9 15:58:45 DBG Before Update Normal PrepareSessionForUsage GUID=16657170416976794445 relayNum=516 relayRequestTotalCU=5160 sps.CuSum=5160 sps.LatestRelayCu=10 sps.sessionId=7769707363567923306
Dec  9 15:58:45 DBG Provider Finished Relay Successfully GUID=16657170416976794445 method=status replyBlock=1000 request.SessionId=7769707363567923306 request.relayNumber=517 requestedBlock=1000
Dec  9 15:58:45 DBG Provider returned a relay response GUID=16657170416976794445 relay_timeout=29.995818009s request.SessionId=7769707363567923306 request.cu=5160 request.relayNumber=517 timeTaken=4.108612ms
Dec  9 15:58:45 DBG saving rewards snapshot to the DB proofs=1
Dec  9 15:58:45 INF Saved rewards snapshot to the DB successfully proofs=1
Dec  9 15:58:45 DBG QoS Availability report Availability=0.980657640232108320 down percent=0.001934235976789168
Dec  9 15:58:45 DBG tendermint http in/out GUID=16657170416976794445 HasError=false method=GET path=http://localhost:1026/status request= response="{\"reply\": 2}" timeTaken=5.648313ms
Dec  9 15:58:45 DBG urirpc in <<< GUID=16420663909217939288 _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:58:45 DBG Provider got relay request GUID=16420663909217939288 relay addon= relay extensions= relay_timeout=29.999924282s request.SessionId=7769707363567923306 request.cu=5170 request.relayNumber=518 requestBlock=-2 seenBlock=1000
Dec  9 15:58:45 DBG Before Update Normal PrepareSessionForUsage GUID=16420663909217939288 relayNum=517 relayRequestTotalCU=5170 sps.CuSum=5170 sps.LatestRelayCu=10 sps.sessionId=7769707363567923306
Dec  9 15:58:45 DBG Provider Finished Relay Successfully GUID=16420663909217939288 method=status replyBlock=1000 request.SessionId=7769707363567923306 request.relayNumber=518 requestedBlock=1000
Dec  9 15:58:45 DBG saving rewards snapshot to the DB proofs=1
Dec  9 15:58:45 INF Saved rewards snapshot to the DB successfully proofs=1
Dec  9 15:58:45 DBG Provider returned a relay response GUID=16420663909217939288 relay_timeout=29.995831214s request.SessionId=7769707363567923306 request.cu=5170 request.relayNumber=518 timeTaken=4.095343ms
Dec  9 15:58:45 DBG QoS Availability report Availability=0.980694980694980690 down percent=0.001930501930501931
Dec  9 15:58:45 DBG tendermint http in/out GUID=16420663909217939288 HasError=false method=GET path=http://localhost:1026/status request= response="{\"reply\": 2}" timeTaken=5.511203ms
Dec  9 15:58:45 DBG urirpc in <<< GUID=5293167882714385165 _msg=status dappID=DefaultDappID headers="[{Name:User-Agent Value:Go-http-client/1.1} {Name:Accept-Encoding Value:gzip} {Name:Host Value:localhost:1026}]"
Dec  9 15:58:45 DBG Provider got relay request GUID=5293167882714385165 relay addon= relay extensions= relay_timeout=29.999920564s request.SessionId=5059797745928753848 request.cu=4710 request.relayNumber=472 requestBlock=-2 seenBlock=1000
Dec  9 15:58:45 DBG Before Update Normal PrepareSessionForUsage GUID=5293167882714385165 relayNum=471 relayRequestTotalCU=4710 sps.CuSum=4710 sps.LatestRelayCu=10 sps.sessionId=5059797745928753848
Dec  9 15:58:45 DBG Provider Finished Relay Successfully GUID=5293167882714385165 method=status replyBlock=1000 request.SessionId=5059797745928753848 request.relayNumber=472 requestedBlock=1000
Dec  9 15:58:45 DBG Provider returned a relay response GUID=5293167882714385165 relay_timeout=29.995768524s request.SessionId=5059797745928753848 request.cu=4710 request.relayNumber=472 timeTaken=4.154314ms
Dec  9 15:58:45 DBG saving rewards snapshot to the DB proofs=1
Dec  9 15:58:45 INF Saved rewards snapshot to the DB successfully proofs=1
Dec  9 15:58:45 DBG QoS Availability report Availability=0.978813559322033900 down percent=0.002118644067796610
Dec  9 15:58:45 DBG tendermint http in/out GUID=5293167882714385165 HasError=false method=GET path=http://localhost:1026/status request= response="{\"reply\": 4}" timeTaken=5.738589ms
Dec  9 15:58:45 DBG urirpc in <<< GUID=8162429533250345458 _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:58:45 DBG Provider got relay request GUID=8162429533250345458 relay addon= relay extensions= relay_timeout=29.99991392s request.SessionId=5059797745928753848 request.cu=4720 request.relayNumber=473 requestBlock=-2 seenBlock=1000
Dec  9 15:58:45 DBG Before Update Normal PrepareSessionForUsage GUID=8162429533250345458 relayNum=472 relayRequestTotalCU=4720 sps.CuSum=4720 sps.LatestRelayCu=10 sps.sessionId=5059797745928753848
Dec  9 15:58:45 DBG Provider Finished Relay Successfully GUID=8162429533250345458 method=status replyBlock=1000 request.SessionId=5059797745928753848 request.relayNumber=473 requestedBlock=1000
Dec  9 15:58:45 DBG Provider returned a relay response GUID=8162429533250345458 relay_timeout=29.995766105s request.SessionId=5059797745928753848 request.cu=4720 request.relayNumber=473 timeTaken=4.15059ms
Dec  9 15:58:45 DBG saving rewards snapshot to the DB proofs=1
Dec  9 15:58:45 INF Saved rewards snapshot to the DB successfully proofs=1
Dec  9 15:58:45 DBG QoS Availability report Availability=0.978858350951374210 down percent=0.002114164904862579
Dec  9 15:58:45 DBG tendermint http in/out GUID=8162429533250345458 HasError=false method=GET path=http://localhost:1026/status request= response="{\"reply\": 4}" timeTaken=5.640419ms
Dec  9 15:58:45 DBG urirpc in <<< GUID=15442489102185654976 _msg=status dappID=DefaultDappID headers="[{Name:User-Agent Value:Go-http-client/1.1} {Name:Accept-Encoding Value:gzip} {Name:Host Value:localhost:1026}]"
Dec  9 15:58:45 DBG Provider got relay request GUID=15442489102185654976 relay addon= relay extensions= relay_timeout=29.99990979s request.SessionId=7769707363567923306 request.cu=5180 request.relayNumber=519 requestBlock=-2 seenBlock=1000
Dec  9 15:58:45 DBG Before Update Normal PrepareSessionForUsage GUID=15442489102185654976 relayNum=518 relayRequestTotalCU=5180 sps.CuSum=5180 sps.LatestRelayCu=10 sps.sessionId=7769707363567923306
Dec  9 15:58:45 DBG Provider Finished Relay Successfully GUID=15442489102185654976 method=status replyBlock=1000 request.SessionId=7769707363567923306 request.relayNumber=519 requestedBlock=1000
Dec  9 15:58:45 DBG saving rewards snapshot to the DB proofs=1
Dec  9 15:58:45 INF Saved rewards snapshot to the DB successfully proofs=1
Dec  9 15:58:45 DBG Provider returned a relay response GUID=15442489102185654976 relay_timeout=29.996007893s request.SessionId=7769707363567923306 request.cu=5180 request.relayNumber=519 timeTaken=3.904241ms
Dec  9 15:58:45 DBG QoS Availability report Availability=0.980732177263969170 down percent=0.001926782273603083
Dec  9 15:58:45 DBG tendermint http in/out GUID=15442489102185654976 HasError=false method=GET path=http://localhost:1026/status request= response="{\"reply\": 2}" timeTaken=5.464812ms
Dec  9 15:58:45 DBG urirpc in <<< GUID=13346770644985554415 _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:58:45 DBG Provider got relay request GUID=13346770644985554415 relay addon= relay extensions= relay_timeout=29.999907708s request.SessionId=7769707363567923306 request.cu=5190 request.relayNumber=520 requestBlock=-2 seenBlock=1000
Dec  9 15:58:45 DBG Before Update Normal PrepareSessionForUsage GUID=13346770644985554415 relayNum=519 relayRequestTotalCU=5190 sps.CuSum=5190 sps.LatestRelayCu=10 sps.sessionId=7769707363567923306
Dec  9 15:58:45 DBG Provider Finished Relay Successfully GUID=13346770644985554415 method=status replyBlock=1000 request.SessionId=7769707363567923306 request.relayNumber=520 requestedBlock=1000
Dec  9 15:58:45 DBG saving rewards snapshot to the DB proofs=1
Dec  9 15:58:45 INF Saved rewards snapshot to the DB successfully proofs=1
Dec  9 15:58:45 DBG Provider returned a relay response GUID=13346770644985554415 relay_timeout=29.99592552s request.SessionId=7769707363567923306 request.cu=5190 request.relayNumber=520 timeTaken=3.985394ms
Dec  9 15:58:45 DBG QoS Availability report Availability=0.980769230769230770 down percent=0.001923076923076923
Dec  9 15:58:45 DBG tendermint http in/out GUID=13346770644985554415 HasError=false method=GET path=http://localhost:1026/status request= response="{\"reply\": 2}" timeTaken=5.492374ms
Dec  9 15:58:45 DBG urirpc in <<< GUID=10236761353282942208 _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:58:45 DBG Provider got relay request GUID=10236761353282942208 relay addon= relay extensions= relay_timeout=29.999914843s request.SessionId=7769707363567923306 request.cu=5200 request.relayNumber=521 requestBlock=-2 seenBlock=1000
Dec  9 15:58:45 DBG Before Update Normal PrepareSessionForUsage GUID=10236761353282942208 relayNum=520 relayRequestTotalCU=5200 sps.CuSum=5200 sps.LatestRelayCu=10 sps.sessionId=7769707363567923306
Dec  9 15:58:45 DBG Provider Finished Relay Successfully GUID=10236761353282942208 method=status replyBlock=1000 request.SessionId=7769707363567923306 request.relayNumber=521 requestedBlock=1000
Dec  9 15:58:45 DBG Provider returned a relay response GUID=10236761353282942208 relay_timeout=29.995982549s request.SessionId=7769707363567923306 request.cu=5200 request.relayNumber=521 timeTaken=3.9351ms
Dec  9 15:58:45 DBG saving rewards snapshot to the DB proofs=1
Dec  9 15:58:45 INF Saved rewards snapshot to the DB successfully proofs=1
Dec  9 15:58:45 DBG QoS Availability report Availability=0.980806142034548940 down percent=0.001919385796545106
Dec  9 15:58:45 DBG tendermint http in/out GUID=10236761353282942208 HasError=false method=GET path=http://localhost:1026/status request= response="{\"reply\": 2}" timeTaken=5.446398ms
Dec  9 15:58:45 DBG urirpc in <<< GUID=204573842871830375 _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:58:45 DBG Provider got relay request GUID=204573842871830375 relay addon= relay extensions= relay_timeout=29.999923662s request.SessionId=7769707363567923306 request.cu=5210 request.relayNumber=522 requestBlock=-2 seenBlock=1000
Dec  9 15:58:45 DBG Before Update Normal PrepareSessionForUsage GUID=204573842871830375 relayNum=521 relayRequestTotalCU=5210 sps.CuSum=5210 sps.LatestRelayCu=10 sps.sessionId=7769707363567923306
Dec  9 15:58:45 DBG Provider Finished Relay Successfully GUID=204573842871830375 method=status replyBlock=1000 request.SessionId=7769707363567923306 request.relayNumber=522 requestedBlock=1000
Dec  9 15:58:45 DBG Provider returned a relay response GUID=204573842871830375 relay_timeout=29.995816102s request.SessionId=7769707363567923306 request.cu=5210 request.relayNumber=522 timeTaken=4.109823ms
Dec  9 15:58:45 DBG saving rewards snapshot to the DB proofs=1
Dec  9 15:58:45 INF Saved rewards snapshot to the DB successfully proofs=1
Dec  9 15:58:45 DBG QoS Availability report Availability=0.980842911877394640 down percent=0.001915708812260536
Dec  9 15:58:45 DBG tendermint http in/out GUID=204573842871830375 HasError=false method=GET path=http://localhost:1026/status request= response="{\"reply\": 2}" timeTaken=5.666798ms
Dec  9 15:58:45 DBG urirpc in <<< GUID=2178444465176519086 _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:58:45 DBG Provider got relay request GUID=2178444465176519086 relay addon= relay extensions= relay_timeout=29.99992089s request.SessionId=5059797745928753848 request.cu=4730 request.relayNumber=474 requestBlock=-2 seenBlock=1000
Dec  9 15:58:45 DBG Before Update Normal PrepareSessionForUsage GUID=2178444465176519086 relayNum=473 relayRequestTotalCU=4730 sps.CuSum=4730 sps.LatestRelayCu=10 sps.sessionId=5059797745928753848
Dec  9 15:58:45 DBG Provider Finished Relay Successfully GUID=2178444465176519086 method=status replyBlock=1000 request.SessionId=5059797745928753848 request.relayNumber=474 requestedBlock=1000
Dec  9 15:58:45 DBG Provider returned a relay response GUID=2178444465176519086 relay_timeout=29.995792474s request.SessionId=5059797745928753848 request.cu=4730 request.relayNumber=474 timeTaken=4.13075ms
Dec  9 15:58:45 DBG saving rewards snapshot to the DB proofs=1
Dec  9 15:58:45 INF Saved rewards snapshot to the DB successfully proofs=1
Dec  9 15:58:45 DBG QoS Availability report Availability=0.978902953586497890 down percent=0.002109704641350211
Dec  9 15:58:45 DBG tendermint http in/out GUID=2178444465176519086 HasError=false method=GET path=http://localhost:1026/status request= response="{\"reply\": 4}" timeTaken=5.591524ms
Dec  9 15:58:45 DBG urirpc in <<< GUID=6107649854995649807 _msg=status dappID=DefaultDappID headers="[{Name:User-Agent Value:Go-http-client/1.1} {Name:Accept-Encoding Value:gzip} {Name:Host Value:localhost:1026}]"
Dec  9 15:58:45 DBG Provider got relay request GUID=6107649854995649807 relay addon= relay extensions= relay_timeout=29.999919387s request.SessionId=7769707363567923306 request.cu=5220 request.relayNumber=523 requestBlock=-2 seenBlock=1000
Dec  9 15:58:45 DBG Before Update Normal PrepareSessionForUsage GUID=6107649854995649807 relayNum=522 relayRequestTotalCU=5220 sps.CuSum=5220 sps.LatestRelayCu=10 sps.sessionId=7769707363567923306
Dec  9 15:58:45 DBG Provider Finished Relay Successfully GUID=6107649854995649807 method=status replyBlock=1000 request.SessionId=7769707363567923306 request.relayNumber=523 requestedBlock=1000
Dec  9 15:58:45 DBG Provider returned a relay response GUID=6107649854995649807 relay_timeout=29.995937203s request.SessionId=7769707363567923306 request.cu=5220 request.relayNumber=523 timeTaken=3.984478ms
Dec  9 15:58:45 DBG saving rewards snapshot to the DB proofs=1
Dec  9 15:58:45 INF Saved rewards snapshot to the DB successfully proofs=1
Dec  9 15:58:45 DBG QoS Availability report Availability=0.980879541108986620 down percent=0.001912045889101338
Dec  9 15:58:45 DBG tendermint http in/out GUID=6107649854995649807 HasError=false method=GET path=http://localhost:1026/status request= response="{\"reply\": 2}" timeTaken=5.574331ms
Dec  9 15:58:45 DBG urirpc in <<< GUID=4484654353952062544 _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:58:45 DBG Provider got relay request GUID=4484654353952062544 relay addon= relay extensions= relay_timeout=29.999915162s request.SessionId=5059797745928753848 request.cu=4740 request.relayNumber=475 requestBlock=-2 seenBlock=1000
Dec  9 15:58:45 DBG Before Update Normal PrepareSessionForUsage GUID=4484654353952062544 relayNum=474 relayRequestTotalCU=4740 sps.CuSum=4740 sps.LatestRelayCu=10 sps.sessionId=5059797745928753848
Dec  9 15:58:45 DBG Provider Finished Relay Successfully GUID=4484654353952062544 method=status replyBlock=1000 request.SessionId=5059797745928753848 request.relayNumber=475 requestedBlock=1000
Dec  9 15:58:45 DBG saving rewards snapshot to the DB proofs=1
Dec  9 15:58:45 DBG Provider returned a relay response GUID=4484654353952062544 relay_timeout=29.995939637s request.SessionId=5059797745928753848 request.cu=4740 request.relayNumber=475 timeTaken=3.977819ms
Dec  9 15:58:45 INF Saved rewards snapshot to the DB successfully proofs=1
Dec  9 15:58:45 DBG QoS Availability report Availability=0.978947368421052630 down percent=0.002105263157894737
Dec  9 15:58:45 DBG tendermint http in/out GUID=4484654353952062544 HasError=false method=GET path=http://localhost:1026/status request= response="{\"reply\": 4}" timeTaken=5.484028ms
Dec  9 15:58:45 DBG urirpc in <<< GUID=3601004802120093307 _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:58:45 DBG Provider got relay request GUID=3601004802120093307 relay addon= relay extensions= relay_timeout=29.999923544s request.SessionId=5059797745928753848 request.cu=4750 request.relayNumber=476 requestBlock=-2 seenBlock=1000
Dec  9 15:58:45 DBG Before Update Normal PrepareSessionForUsage GUID=3601004802120093307 relayNum=475 relayRequestTotalCU=4750 sps.CuSum=4750 sps.LatestRelayCu=10 sps.sessionId=5059797745928753848
Dec  9 15:58:45 DBG Provider Finished Relay Successfully GUID=3601004802120093307 method=status replyBlock=1000 request.SessionId=5059797745928753848 request.relayNumber=476 requestedBlock=1000
Dec  9 15:58:45 DBG Provider returned a relay response GUID=3601004802120093307 relay_timeout=29.995816926s request.SessionId=5059797745928753848 request.cu=4750 request.relayNumber=476 timeTaken=4.108762ms
Dec  9 15:58:45 DBG saving rewards snapshot to the DB proofs=1
Dec  9 15:58:45 DBG QoS Availability report Availability=0.978991596638655460 down percent=0.002100840336134454
Dec  9 15:58:45 INF Saved rewards snapshot to the DB successfully proofs=1
Dec  9 15:58:45 DBG tendermint http in/out GUID=3601004802120093307 HasError=false method=GET path=http://localhost:1026/status request= response="{\"reply\": 4}" timeTaken=5.635773ms
Dec  9 15:58:45 DBG urirpc in <<< GUID=15308582839411337059 _msg=status dappID=DefaultDappID headers="[{Name:User-Agent Value:Go-http-client/1.1} {Name:Accept-Encoding Value:gzip} {Name:Host Value:localhost:1026}]"
Dec  9 15:58:45 DBG Provider got relay request GUID=15308582839411337059 relay addon= relay extensions= relay_timeout=29.999924013s request.SessionId=7769707363567923306 request.cu=5230 request.relayNumber=524 requestBlock=-2 seenBlock=1000
Dec  9 15:58:45 DBG Before Update Normal PrepareSessionForUsage GUID=15308582839411337059 relayNum=523 relayRequestTotalCU=5230 sps.CuSum=5230 sps.LatestRelayCu=10 sps.sessionId=7769707363567923306
Dec  9 15:58:45 DBG Provider Finished Relay Successfully GUID=15308582839411337059 method=status replyBlock=1000 request.SessionId=7769707363567923306 request.relayNumber=524 requestedBlock=1000
Dec  9 15:58:45 DBG saving rewards snapshot to the DB proofs=1
Dec  9 15:58:45 DBG Provider returned a relay response GUID=15308582839411337059 relay_timeout=29.995873975s request.SessionId=7769707363567923306 request.cu=5230 request.relayNumber=524 timeTaken=4.052382ms
Dec  9 15:58:45 INF Saved rewards snapshot to the DB successfully proofs=1
Dec  9 15:58:45 DBG QoS Availability report Availability=0.980916030534351150 down percent=0.001908396946564885
Dec  9 15:58:45 DBG tendermint http in/out GUID=15308582839411337059 HasError=false method=GET path=http://localhost:1026/status request= response="{\"reply\": 2}" timeTaken=5.55261ms
    protocol_test.go:626: 
        	Error Trace:	/home/runner/work/lava/lava/protocol/integration/protocol_test.go:626
        	Error:      	"map[2:523 4:475 5:3]" should have 5 item(s), but has 3
        	Test:       	TestConsumerProviderWithProviders/basic-success
View more details on GitHub Actions