Block producer doesn't produce

acangros · 10 June 2022 16:08

Hi everyone

I have installed 3 servers (2 relays, 1 BP) throught docker v1.34.1.
I have created the keys and submited the registration
testPool: BGPt BoardGamePool test v0007 - Cardanoscan

and the relay (94.250.203.16) appears here
https://explorer.cardano-testnet.iohkdev.io/relays/topology.json

The servers are syncronize, grafana is working. Topology is fine


Relay 1: 185.205.244.180
Relay 2: 94.250.203.16
BP: 154.53.60.163

Relay 1
{
  "Producers": [
    {
      "addr": "relays-new.cardano-testnet.iohkdev.io",
      "port": 3001,
      "valency": 2
    },
	{
      "addr": "154.53.60.163",
      "port": 3001,
      "valency": 1
    },
	{
      "addr": "94.250.203.16",
      "port": 3001,
      "valency": 1
    }
  ]
}

Relay 2
{
  "Producers": [
    {
      "addr": "relays-new.cardano-testnet.iohkdev.io",
      "port": 3001,
      "valency": 2
    },
	{
      "addr": "154.53.60.163",
      "port": 3001,
      "valency": 1
    },
	{
      "addr": "185.205.244.180",
      "port": 3001,
      "valency": 1
    }
  ]
}

BP:
{
  "Producers": [
    {
      "addr": "185.205.244.180",
      "port": 3001,
      "valency": 1
    },
	{
      "addr": "94.250.203.16",
      "port": 3001,
      "valency": 1
    }
  ]
}

sudo nsenter -t $(docker inspect -f '{{.State.Pid}}' cardano-node) -n netstat
nestat docker relay1
Proto Recv-Q Send-Q Local Address           Foreign Address         State      
tcp        0      0 172.20.0.5:12798        172.20.0.2:50264        ESTABLISHED
tcp        0      0 172.20.0.5:3001         154.53.60.163:36385     ESTABLISHED
tcp        0      0 172.20.0.5:40077        13.41.9.54:3001         ESTABLISHED
tcp        0      0 172.20.0.5:42103        154.53.60.163:3001      ESTABLISHED
tcp        0      0 172.20.0.5:3001         154.53.60.163:36713     ESTABLISHED
tcp        0      0 172.20.0.5:3001         154.53.60.163:36871     ESTABLISHED
tcp        0      0 172.20.0.5:46673        3.72.26.163:3001        ESTABLISHED
tcp        0      0 172.20.0.5:3001         154.53.60.163:39247     ESTABLISHED
tcp        0      0 172.20.0.5:3001         154.53.60.163:40097     ESTABLISHED
tcp        0      0 172.20.0.5:12798        172.20.0.1:56576        ESTABLISHED
tcp        0      0 172.20.0.5:3001         154.53.60.163:45513     ESTABLISHED
tcp        0      0 172.20.0.5:3001         94.250.203.16:34405     ESTABLISHED
tcp        0      0 172.20.0.5:3001         154.53.60.163:36697     ESTABLISHED
tcp        0      0 172.20.0.5:3001         154.53.60.163:42709     ESTABLISHED

nestat docker relay2
Proto Recv-Q Send-Q Local Address           Foreign Address         State      
tcp        0      0 172.18.0.4:3001         161.97.170.151:3001     ESTABLISHED
tcp        0      0 172.18.0.4:3001         154.53.60.163:35075     ESTABLISHED
tcp        0      0 172.18.0.4:3001         52.74.94.66:3001        ESTABLISHED
tcp        0      0 172.18.0.4:3001         13.244.68.168:7777      ESTABLISHED
tcp        0      0 172.18.0.4:40689        3.72.137.52:3001        ESTABLISHED
tcp        0      0 172.18.0.4:45397        13.41.9.54:3001         ESTABLISHED
tcp        0      0 172.18.0.4:3001         207.148.84.75:x11       ESTABLISHED
tcp        0      0 172.18.0.4:45007        154.53.60.163:3001      ESTABLISHED
tcp        0      0 172.18.0.4:12798        185.205.244.180:46200   ESTABLISHED
tcp        0      0 172.18.0.4:3001         161.97.170.151:3002     ESTABLISHED
tcp        0      0 172.18.0.4:34405        185.205.244.180:3001    ESTABLISHED
tcp        0      0 172.18.0.4:3001         3.72.26.163:40823       ESTABLISHED
tcp        0      0 172.18.0.4:3001         47.200.165.160:x11-1    ESTABLISHED
tcp        0      0 172.18.0.4:3001         23.115.121.201:27177    ESTABLISHED
tcp        0      0 172.18.0.4:3001         51.159.14.219:2930      ESTABLISHED

nestat docker BP
Proto Recv-Q Send-Q Local Address           Foreign Address         State      
tcp        0      0 172.18.0.4:35075        94.250.203.16:3001      ESTABLISHED
tcp        0      0 172.18.0.4:3001         185.205.244.180:42103   ESTABLISHED
tcp        0      0 172.18.0.4:39247        185.205.244.180:3001    ESTABLISHED
tcp        0      0 172.18.0.4:3001         94.250.203.16:45007     ESTABLISHED

the hash of the VRF is fine
the KES is regenerated three days ago

But my BP doesn’t produce. What else can I check??

thanks

os11k · 10 June 2022 16:26

Have you got any slots assigned to you? It might happened that you didn’t get any, highly unluckily, but still possible.

acangros · 10 June 2022 17:19

yes, good point xDD !

I have 3 or 4 slots assigned per epoch, and I miss all !

os11k · 10 June 2022 18:01

Hi!

What is in logs? You should see something like this on your BP on exact time when block is assigned.

ChainSyncClient:Info:3940] [2022-06-10 15:48:53.27 UTC] fromList [("blockNo",Number 3620271.0),("peer",Object (fromList [("local",Object (fromList [("addr",String "192.168.1.210"),("port",String "41163")])),("remote",Object (fromList [("addr",String "192.168.1.210"),("port",String "6002")]))])),("kind",String "ChainSyncClientEvent.TraceDownloadedHeader"),("slot",Number 6.0506917e7),("block",String "93c3b32ccc0e66e8cd184f25cacb2292c86a4e5cdaf1865c4db7dda14d6d3120")]
ChainSyncClient:Info:3963] [.22 UTC] fromList [("blockNo",Number 3620271.0),("peer",Object (fromList [("local",Object (fromList [("addr",String "192.168.1.210"),("port",String "37429")])),("remote",Object (fromList [("addr",String "192.168.1.210"),("port",String "6001")]))])),("kind",String "ChainSyncClientEvent.TraceDownloadedHeader"),("slot",Number 6.0506917e7),("block",String "93c3b32ccc0e66e8cd184f25cacb2292c86a4e5cdaf1865c4db7dda14d6d3120")]
BlockFetchServer:Info:3903] [.16 UTC] [String "TraceBlockFetchServerSendBlock",String "93c3b32ccc0e66e8cd184f25cacb2292c86a4e5cdaf1865c4db7dda14d6d3120"]
BlockFetchServer:Info:5109] [.15 UTC] [String "TraceBlockFetchServerSendBlock",String "93c3b32ccc0e66e8cd184f25cacb2292c86a4e5cdaf1865c4db7dda14d6d3120"]
Mempool:Info:262] [.10 UTC] fromList [("kind",String "TraceMempoolRemoveTxs"),("txs",Array [Object (fromList [("txid",String "504cdf0e")]),Object (fromList [("txid",String "4e4516c9")]),Object (fromList [("txid",String "7b127af4")]),Object (fromList [("txid",String "663aba9e")]),Object (fromList [("txid",String "8633eb90")]),Object (fromList [("txid",String "b6978eef")]),Object (fromList [("txid",String "7f21c794")]),Object (fromList [("txid",String "b9bdf297")])]),("mempoolSize",Object (fromList [("bytes",Number 0.0),("numTxs",Number 0.0)]))]
ChainSyncHeaderServer:Info:3902] [.10 UTC] fromList [("kind",String "ChainSyncServerEvent.TraceChainSyncRollForward"),("point",Object (fromList [("headerHash",String "93c3b32ccc0e66e8cd184f25cacb2292c86a4e5cdaf1865c4db7dda14d6d3120"),("kind",String "BlockPoint"),("slot",Number 6.0506917e7)]))]
ChainSyncHeaderServer:Info:3902] [.10 UTC] fromList [("blockNo",Number 3620271.0),("kind",String "ChainSyncServerEvent.TraceChainSyncServerReadBlocked.AddBlock"),("slot",Number 6.0506917e7),("block",String "93c3b32ccc0e66e8cd184f25cacb2292c86a4e5cdaf1865c4db7dda14d6d3120")]
ChainSyncHeaderServer:Info:5108] [.10 UTC] fromList [("kind",String "ChainSyncServerEvent.TraceChainSyncRollForward"),("point",Object (fromList [("headerHash",String "93c3b32ccc0e66e8cd184f25cacb2292c86a4e5cdaf1865c4db7dda14d6d3120"),("kind",String "BlockPoint"),("slot",Number 6.0506917e7)]))]
ChainSyncHeaderServer:Info:5108] [.10 UTC] fromList [("blockNo",Number 3620271.0),("kind",String "ChainSyncServerEvent.TraceChainSyncServerReadBlocked.AddBlock"),("slot",Number 6.0506917e7),("block",String "93c3b32ccc0e66e8cd184f25cacb2292c86a4e5cdaf1865c4db7dda14d6d3120")]
Forge:Info:264] [.10 UTC] fromList [("val",Object (fromList [("blockHash",String "93c3b32ccc0e66e8cd184f25cacb2292c86a4e5cdaf1865c4db7dda14d6d3120"),("blockSize",Number 9452.0),("kind",String "TraceAdoptedBlock"),("slot",Number 6.0506917e7)])),("credentials",String "Cardano")]
ChainDB:Notice:254] [.10 UTC] Chain extended, new tip: 93c3b32ccc0e66e8cd184f25cacb2292c86a4e5cdaf1865c4db7dda14d6d3120 at slot 60506917
Forge:Info:264] [.06 UTC] fromList [("val",Object (fromList [("block",String "93c3b32ccc0e66e8cd184f25cacb2292c86a4e5cdaf1865c4db7dda14d6d3120"),("blockNo",Number 3620271.0),("blockPrev",String "f766c83d5af12f2fd5d9db733fe6b5256936b356202a25bc46ff7235aeae46d0"),("kind",String "TraceForgedBlock"),("slot",Number 6.0506917e7)])),("credentials",String "Cardano")]
Forge:Info:264] [.01 UTC] fromList [("val",Object (fromList [("kind",String "TraceNodeIsLeader"),("slot",Number 6.0506917e7)])),("credentials",String "Cardano")]
LeadershipCheck:Info:264] [.01 UTC] {"utxoSize":2493416,"kind":"TraceStartLeadershipCheck","delegMapSize":86086,"credentials":"Cardano","slot":60506917,"chainDensity":3.1852115e-2}

Keep attention to TraceNodeIsLeader and TraceBlockFetchServerSendBlock

On relay something like this:

ChainSyncClient:Info:109801] [2022-06-10 15:48:53.83 UTC] fromList [("blockNo",Number 3620271.0),("peer",Object (fromList [("local",Object (fromList [("addr",String "192.168.1.210"),("port",String "6001")])),("remote",Object (fromList [("addr",String "34.222.196.75"),("port",String "3001")]))])),("kind",String "ChainSyncClientEvent.TraceDownloadedHeader"),("slot",Number 6.0506917e7),("block",String "93c3b32ccc0e66e8cd184f25cacb2292c86a4e5cdaf1865c4db7dda14d6d3120")]
ChainSyncClient:Info:97546] [2022-06-10 15:48:53.70 UTC] fromList [("blockNo",Number 3620271.0),("peer",Object (fromList [("local",Object (fromList [("addr",String "192.168.1.210"),("port",String "6001")])),("remote",Object (fromList [("addr",String "162.250.9.203"),("port",String "6000")]))])),("kind",String "ChainSyncClientEvent.TraceDownloadedHeader"),("slot",Number 6.0506917e7),("block",String "93c3b32ccc0e66e8cd184f25cacb2292c86a4e5cdaf1865c4db7dda14d6d3120")]
ChainSyncClient:Info:125459] [2022-06-10 15:48:53.68 UTC] fromList [("blockNo",Number 3620271.0),("peer",Object (fromList [("local",Object (fromList [("addr",String "192.168.1.210"),("port",String "6001")])),("remote",Object (fromList [("addr",String "50.208.182.12"),("port",String "3001")]))])),("kind",String "ChainSyncClientEvent.TraceDownloadedHeader"),("slot",Number 6.0506917e7),("block",String "93c3b32ccc0e66e8cd184f25cacb2292c86a4e5cdaf1865c4db7dda14d6d3120")]
ChainSyncClient:Info:118618] [2022-06-10 15:48:53.67 UTC] fromList [("blockNo",Number 3620271.0),("peer",Object (fromList [("local",Object (fromList [("addr",String "192.168.1.210"),("port",String "6001")])),("remote",Object (fromList [("addr",String "108.236.80.74"),("port",String "4565")]))])),("kind",String "ChainSyncClientEvent.TraceDownloadedHeader"),("slot",Number 6.0506917e7),("block",String "93c3b32ccc0e66e8cd184f25cacb2292c86a4e5cdaf1865c4db7dda14d6d3120")]
ChainSyncClient:Info:120092] [2022-06-10 15:48:53.65 UTC] fromList [("blockNo",Number 3620271.0),("peer",Object (fromList [("local",Object (fromList [("addr",String "192.168.1.210"),("port",String "6001")])),("remote",Object (fromList [("addr",String "146.166.116.161"),("port",String "6161")]))])),("kind",String "ChainSyncClientEvent.TraceDownloadedHeader"),("slot",Number 6.0506917e7),("block",String "93c3b32ccc0e66e8cd184f25cacb2292c86a4e5cdaf1865c4db7dda14d6d3120")]
ChainSyncClient:Info:124235] [2022-06-10 15:48:53.63 UTC] fromList [("blockNo",Number 3620271.0),("peer",Object (fromList [("local",Object (fromList [("addr",String "192.168.1.210"),("port",String "6001")])),("remote",Object (fromList [("addr",String "47.200.165.160"),("port",String "6001")]))])),("kind",String "ChainSyncClientEvent.TraceDownloadedHeader"),("slot",Number 6.0506917e7),("block",String "93c3b32ccc0e66e8cd184f25cacb2292c86a4e5cdaf1865c4db7dda14d6d3120")]
ChainSyncClient:Info:125259] [2022-06-10 15:48:53.60 UTC] fromList [("blockNo",Number 3620271.0),("peer",Object (fromList [("local",Object (fromList [("addr",String "192.168.1.210"),("port",String "6001")])),("remote",Object (fromList [("addr",String "108.31.195.89"),("port",String "4001")]))])),("kind",String "ChainSyncClientEvent.TraceDownloadedHeader"),("slot",Number 6.0506917e7),("block",String "93c3b32ccc0e66e8cd184f25cacb2292c86a4e5cdaf1865c4db7dda14d6d3120")]
ChainSyncClient:Info:109864] [2022-06-10 15:48:53.59 UTC] fromList [("blockNo",Number 3620271.0),("peer",Object (fromList [("local",Object (fromList [("addr",String "192.168.1.210"),("port",String "6001")])),("remote",Object (fromList [("addr",String "3.131.32.242"),("port",String "3001")]))])),("kind",String "ChainSyncClientEvent.TraceDownloadedHeader"),("slot",Number 6.0506917e7),("block",String "93c3b32ccc0e66e8cd184f25cacb2292c86a4e5cdaf1865c4db7dda14d6d3120")]
ChainSyncClient:Info:121870] [2022-06-10 15:48:53.54 UTC] fromList [("blockNo",Number 3620271.0),("peer",Object (fromList [("local",Object (fromList [("addr",String "192.168.1.210"),("port",String "6001")])),("remote",Object (fromList [("addr",String "158.101.114.246"),("port",String "6000")]))])),("kind",String "ChainSyncClientEvent.TraceDownloadedHeader"),("slot",Number 6.0506917e7),("block",String "93c3b32ccc0e66e8cd184f25cacb2292c86a4e5cdaf1865c4db7dda14d6d3120")]
ChainSyncClient:Info:123462] [2022-06-10 15:48:53.49 UTC] fromList [("blockNo",Number 3620271.0),("peer",Object (fromList [("local",Object (fromList [("addr",String "192.168.1.210"),("port",String "6001")])),("remote",Object (fromList [("addr",String "213.158.48.219"),("port",String "7002")]))])),("kind",String "ChainSyncClientEvent.TraceDownloadedHeader"),("slot",Number 6.0506917e7),("block",String "93c3b32ccc0e66e8cd184f25cacb2292c86a4e5cdaf1865c4db7dda14d6d3120")]
ChainSyncClient:Info:125198] [2022-06-10 15:48:53.47 UTC] fromList [("blockNo",Number 3620271.0),("peer",Object (fromList [("local",Object (fromList [("addr",String "192.168.1.210"),("port",String "6001")])),("remote",Object (fromList [("addr",String "52.56.210.10"),("port",String "3001")]))])),("kind",String "ChainSyncClientEvent.TraceDownloadedHeader"),("slot",Number 6.0506917e7),("block",String "93c3b32ccc0e66e8cd184f25cacb2292c86a4e5cdaf1865c4db7dda14d6d3120")]
ChainSyncClient:Info:97508] [2022-06-10 15:48:53.46 UTC] fromList [("blockNo",Number 3620271.0),("peer",Object (fromList [("local",Object (fromList [("addr",String "192.168.1.210"),("port",String "6001")])),("remote",Object (fromList [("addr",String "3.72.26.163"),("port",String "3001")]))])),("kind",String "ChainSyncClientEvent.TraceDownloadedHeader"),("slot",Number 6.0506917e7),("block",String "93c3b32ccc0e66e8cd184f25cacb2292c86a4e5cdaf1865c4db7dda14d6d3120")]
ChainSyncClient:Info:97442] [2022-06-10 15:48:53.44 UTC] fromList [("blockNo",Number 3620271.0),("peer",Object (fromList [("local",Object (fromList [("addr",String "192.168.1.210"),("port",String "6001")])),("remote",Object (fromList [("addr",String "13.41.9.54"),("port",String "3001")]))])),("kind",String "ChainSyncClientEvent.TraceDownloadedHeader"),("slot",Number 6.0506917e7),("block",String "93c3b32ccc0e66e8cd184f25cacb2292c86a4e5cdaf1865c4db7dda14d6d3120")]
ChainSyncClient:Info:122403] [2022-06-10 15:48:53.43 UTC] fromList [("blockNo",Number 3620271.0),("peer",Object (fromList [("local",Object (fromList [("addr",String "192.168.1.210"),("port",String "6001")])),("remote",Object (fromList [("addr",String "83.167.247.29"),("port",String "6000")]))])),("kind",String "ChainSyncClientEvent.TraceDownloadedHeader"),("slot",Number 6.0506917e7),("block",String "93c3b32ccc0e66e8cd184f25cacb2292c86a4e5cdaf1865c4db7dda14d6d3120")]
ChainSyncClient:Info:125247] [2022-06-10 15:48:53.42 UTC] fromList [("blockNo",Number 3620271.0),("peer",Object (fromList [("local",Object (fromList [("addr",String "192.168.1.210"),("port",String "6001")])),("remote",Object (fromList [("addr",String "20.107.184.103"),("port",String "15654")]))])),("kind",String "ChainSyncClientEvent.TraceDownloadedHeader"),("slot",Number 6.0506917e7),("block",String "93c3b32ccc0e66e8cd184f25cacb2292c86a4e5cdaf1865c4db7dda14d6d3120")]
ChainSyncClient:Info:123453] [2022-06-10 15:48:53.40 UTC] fromList [("blockNo",Number 3620271.0),("peer",Object (fromList [("local",Object (fromList [("addr",String "192.168.1.210"),("port",String "6001")])),("remote",Object (fromList [("addr",String "185.161.193.105"),("port",String "6021")]))])),("kind",String "ChainSyncClientEvent.TraceDownloadedHeader"),("slot",Number 6.0506917e7),("block",String "93c3b32ccc0e66e8cd184f25cacb2292c86a4e5cdaf1865c4db7dda14d6d3120")]
ChainSyncClient:Info:103038] [2022-06-10 15:48:53.39 UTC] fromList [("blockNo",Number 3620271.0),("peer",Object (fromList [("local",Object (fromList [("addr",String "192.168.1.210"),("port",String "6001")])),("remote",Object (fromList [("addr",String "185.161.193.92"),("port",String "1025")]))])),("kind",String "ChainSyncClientEvent.TraceDownloadedHeader"),("slot",Number 6.0506917e7),("block",String "93c3b32ccc0e66e8cd184f25cacb2292c86a4e5cdaf1865c4db7dda14d6d3120")]
ChainSyncClient:Info:120535] [2022-06-10 15:48:53.37 UTC] fromList [("blockNo",Number 3620271.0),("peer",Object (fromList [("local",Object (fromList [("addr",String "192.168.1.210"),("port",String "6001")])),("remote",Object (fromList [("addr",String "185.164.6.221"),("port",String "54329")]))])),("kind",String "ChainSyncClientEvent.TraceDownloadedHeader"),("slot",Number 6.0506917e7),("block",String "93c3b32ccc0e66e8cd184f25cacb2292c86a4e5cdaf1865c4db7dda14d6d3120")]
ChainSyncClient:Info:123059] [2022-06-10 15:48:53.36 UTC] fromList [("blockNo",Number 3620271.0),("peer",Object (fromList [("local",Object (fromList [("addr",String "192.168.1.210"),("port",String "6001")])),("remote",Object (fromList [("addr",String "3.72.137.52"),("port",String "3001")]))])),("kind",String "ChainSyncClientEvent.TraceDownloadedHeader"),("slot",Number 6.0506917e7),("block",String "93c3b32ccc0e66e8cd184f25cacb2292c86a4e5cdaf1865c4db7dda14d6d3120")]
BlockFetchServer:Info:123160] [2022-06-10 15:48:53.25 UTC] [String "TraceBlockFetchServerSendBlock",String "93c3b32ccc0e66e8cd184f25cacb2292c86a4e5cdaf1865c4db7dda14d6d3120"]
Mempool:Info:195] [2022-06-10 15:48:53.25 UTC] fromList [("kind",String "TraceMempoolRemoveTxs"),("txs",Array [Object (fromList [("txid",String "504cdf0e")]),Object (fromList [("txid",String "4e4516c9")]),Object (fromList [("txid",String "7b127af4")]),Object (fromList [("txid",String "663aba9e")]),Object (fromList [("txid",String "8633eb90")]),Object (fromList [("txid",String "b6978eef")]),Object (fromList [("txid",String "7f21c794")]),Object (fromList [("txid",String "b9bdf297")])]),("mempoolSize",Object (fromList [("bytes",Number 0.0),("numTxs",Number 0.0)]))]
ChainSyncHeaderServer:Info:111145] [2022-06-10 15:48:53.22 UTC] fromList [("kind",String "ChainSyncServerEvent.TraceChainSyncRollForward"),("point",Object (fromList [("headerHash",String "93c3b32ccc0e66e8cd184f25cacb2292c86a4e5cdaf1865c4db7dda14d6d3120"),("kind",String "BlockPoint"),("slot",Number 6.0506917e7)]))]
ChainSyncHeaderServer:Info:111145] [2022-06-10 15:48:53.20 UTC] fromList [("blockNo",Number 3620271.0),("kind",String "ChainSyncServerEvent.TraceChainSyncServerReadBlocked.AddBlock"),("slot",Number 6.0506917e7),("block",String "93c3b32ccc0e66e8cd184f25cacb2292c86a4e5cdaf1865c4db7dda14d6d3120")]
ChainSyncHeaderServer:Info:122373] [2022-06-10 15:48:53.20 UTC] fromList [("kind",String "ChainSyncServerEvent.TraceChainSyncRollForward"),("point",Object (fromList [("headerHash",String "93c3b32ccc0e66e8cd184f25cacb2292c86a4e5cdaf1865c4db7dda14d6d3120"),("kind",String "BlockPoint"),("slot",Number 6.0506917e7)]))]
ChainSyncHeaderServer:Info:122373] [2022-06-10 15:48:53.20 UTC] fromList [("blockNo",Number 3620271.0),("kind",String "ChainSyncServerEvent.TraceChainSyncServerReadBlocked.AddBlock"),("slot",Number 6.0506917e7),("block",String "93c3b32ccc0e66e8cd184f25cacb2292c86a4e5cdaf1865c4db7dda14d6d3120")]
ChainSyncHeaderServer:Info:98007] [2022-06-10 15:48:53.20 UTC] fromList [("kind",String "ChainSyncServerEvent.TraceChainSyncRollForward"),("point",Object (fromList [("headerHash",String "93c3b32ccc0e66e8cd184f25cacb2292c86a4e5cdaf1865c4db7dda14d6d3120"),("kind",String "BlockPoint"),("slot",Number 6.0506917e7)]))]
ChainSyncHeaderServer:Info:98007] [2022-06-10 15:48:53.20 UTC] fromList [("blockNo",Number 3620271.0),("kind",String "ChainSyncServerEvent.TraceChainSyncServerReadBlocked.AddBlock"),("slot",Number 6.0506917e7),("block",String "93c3b32ccc0e66e8cd184f25cacb2292c86a4e5cdaf1865c4db7dda14d6d3120")]
ChainSyncHeaderServer:Info:96759] [2022-06-10 15:48:53.20 UTC] fromList [("kind",String "ChainSyncServerEvent.TraceChainSyncRollForward"),("point",Object (fromList [("headerHash",String "93c3b32ccc0e66e8cd184f25cacb2292c86a4e5cdaf1865c4db7dda14d6d3120"),("kind",String "BlockPoint"),("slot",Number 6.0506917e7)]))]
ChainSyncHeaderServer:Info:96759] [2022-06-10 15:48:53.20 UTC] fromList [("blockNo",Number 3620271.0),("kind",String "ChainSyncServerEvent.TraceChainSyncServerReadBlocked.AddBlock"),("slot",Number 6.0506917e7),("block",String "93c3b32ccc0e66e8cd184f25cacb2292c86a4e5cdaf1865c4db7dda14d6d3120")]
ChainDB:Notice:187] [2022-06-10 15:48:53.20 UTC] Chain extended, new tip: 93c3b32ccc0e66e8cd184f25cacb2292c86a4e5cdaf1865c4db7dda14d6d3120 at slot 60506917
BlockFetchClient:Info:102944] [2022-06-10 15:48:53.17 UTC] fromList [("peer",Object (fromList [("local",Object (fromList [("addr",String "127.0.0.1"),("port",String "6001")])),("remote",Object (fromList [("addr",String "127.0.0.1"),("port",String "6000")]))])),("kind",String "CompletedFetchBatch")]
BlockFetchClient:Info:102944] [2022-06-10 15:48:53.17 UTC] fromList [("delay",Number 0.165988135),("peer",Object (fromList [("local",Object (fromList [("addr",String "127.0.0.1"),("port",String "6001")])),("remote",Object (fromList [("addr",String "127.0.0.1"),("port",String "6000")]))])),("kind",String "CompletedBlockFetch"),("size",Number 9452.0),("block",String "93c3b32ccc0e66e8cd184f25cacb2292c86a4e5cdaf1865c4db7dda14d6d3120")]
BlockFetchClient:Info:102944] [2022-06-10 15:48:53.16 UTC] fromList [("peer",Object (fromList [("local",Object (fromList [("addr",String "127.0.0.1"),("port",String "6001")])),("remote",Object (fromList [("addr",String "127.0.0.1"),("port",String "6000")]))])),("kind",String "StartedFetchBatch")]
BlockFetchClient:Info:102945] [2022-06-10 15:48:53.12 UTC] fromList [("peer",Object (fromList [("local",Object (fromList [("addr",String "127.0.0.1"),("port",String "6001")])),("remote",Object (fromList [("addr",String "127.0.0.1"),("port",String "6000")]))])),("kind",String "SendFetchRequest"),("length",Number 1.0),("head",String "93c3b32ccc0e66e8cd184f25cacb2292c86a4e5cdaf1865c4db7dda14d6d3120")]
BlockFetchClient:Info:102945] [2022-06-10 15:48:53.12 UTC] fromList [("peer",Object (fromList [("local",Object (fromList [("addr",String "127.0.0.1"),("port",String "6001")])),("remote",Object (fromList [("addr",String "127.0.0.1"),("port",String "6000")]))])),("kind",String "AcknowledgedFetchRequest")]
BlockFetchClient:Info:197] [2022-06-10 15:48:53.12 UTC] fromList [("peer",Object (fromList [("local",Object (fromList [("addr",String "127.0.0.1"),("port",String "6001")])),("remote",Object (fromList [("addr",String "127.0.0.1"),("port",String "6000")]))])),("kind",String "AddedFetchRequest")]
BlockFetchDecision:Info:197] [2022-06-10 15:48:53.12 UTC] [TraceLabelPeer (ConnectionId {localAddress = 127.0.0.1:6001, remoteAddress = 127.0.0.1:6000}) (Right [At (Block {blockPointSlot = SlotNo 60506917, blockPointHash = 93c3b32ccc0e66e8cd184f25cacb2292c86a4e5cdaf1865c4db7dda14d6d3120})]),TraceLabelPeer (ConnectionId {localAddress = 192.168.1.210:6001, remoteAddress = 213.158.48.219:7002}) (Left FetchDeclineChainNotPlausible),TraceLabelPeer (ConnectionId {localAddress = 192.168.1.210:6001, remoteAddress = 3.72.137.52:3001}) (Left FetchDeclineChainNotPlausible),TraceLabelPeer (ConnectionId {localAddress = 192.168.1.210:6001, remoteAddress = 3.72.26.163:3001}) (Left FetchDeclineChainNotPlausible),TraceLabelPeer (ConnectionId {localAddress = 192.168.1.210:6001, remoteAddress = 185.161.193.92:1025}) (Left FetchDeclineChainNotPlausible),TraceLabelPeer (ConnectionId {localAddress = 192.168.1.210:6001, remoteAddress = 185.164.6.221:54329}) (Left FetchDeclineChainNotPlausible),TraceLabelPeer (ConnectionId {localAddress = 192.168.1.210:6001, remoteAddress = 185.161.193.105:6021}) (Left FetchDeclineChainNotPlausible),TraceLabelPeer (ConnectionId {localAddress = 192.168.1.210:6001, remoteAddress = 83.167.247.29:6000}) (Left FetchDeclineChainNotPlausible),TraceLabelPeer (ConnectionId {localAddress = 192.168.1.210:6001, remoteAddress = 13.41.9.54:3001}) (Left FetchDeclineChainNotPlausible),TraceLabelPeer (ConnectionId {localAddress = 192.168.1.210:6001, remoteAddress = 52.56.210.10:3001}) (Left FetchDeclineChainNotPlausible),TraceLabelPeer (ConnectionId {localAddress = 192.168.1.210:6001, remoteAddress = 20.107.184.103:15654}) (Left FetchDeclineChainNotPlausible),TraceLabelPeer (ConnectionId {localAddress = 192.168.1.210:6001, remoteAddress = 158.101.114.246:6000}) (Left FetchDeclineChainNotPlausible),TraceLabelPeer (ConnectionId {localAddress = 192.168.1.210:6001, remoteAddress = 3.131.32.242:3001}) (Left FetchDeclineChainNotPlausible),TraceLabelPeer (ConnectionId {localAddress = 192.168.1.210:6001, remoteAddress = 162.250.9.203:6000}) (Left FetchDeclineChainNotPlausible),TraceLabelPeer (ConnectionId {localAddress = 192.168.1.210:6001, remoteAddress = 108.31.195.89:4001}) (Left FetchDeclineChainNotPlausible),TraceLabelPeer (ConnectionId {localAddress = 192.168.1.210:6001, remoteAddress = 50.208.182.12:3001}) (Left FetchDeclineChainNotPlausible),TraceLabelPeer (ConnectionId {localAddress = 192.168.1.210:6001, remoteAddress = 108.236.80.74:4565}) (Left FetchDeclineChainNotPlausible),TraceLabelPeer (ConnectionId {localAddress = 192.168.1.210:6001, remoteAddress = 47.200.165.160:6001}) (Left FetchDeclineChainNotPlausible),TraceLabelPeer (ConnectionId {localAddress = 192.168.1.210:6001, remoteAddress = 146.166.116.161:6161}) (Left FetchDeclineChainNotPlausible),TraceLabelPeer (ConnectionId {localAddress = 192.168.1.210:6001, remoteAddress = 34.222.196.75:3001}) (Left FetchDeclineChainNotPlausible)]
ChainSyncClient:Info:102943] [2022-06-10 15:48:53.11 UTC] fromList [("blockNo",Number 3620271.0),("peer",Object (fromList [("local",Object (fromList [("addr",String "127.0.0.1"),("port",String "6001")])),("remote",Object (fromList [("addr",String "127.0.0.1"),("port",String "6000")]))])),("kind",String "ChainSyncClientEvent.TraceDownloadedHeader"),("slot",Number 6.0506917e7),("block",String "93c3b32ccc0e66e8cd184f25cacb2292c86a4e5cdaf1865c4db7dda14d6d3120")]
TxOutbound:Info:120087] [2022-06-10 15:48:53.02 UTC] fromList [("peer",Object (fromList [("local",Object (fromList [("addr",String "192.168.1.210"),("port",String "6001")])),("remote",Object (fromList [("addr",String "146.166.116.161"),("port",String "6161")]))])),("kind",String "TxSubmissionOutboundSendMsgReplyTxs")]
TxOutbound:Info:120087] [2022-06-10 15:48:53.02 UTC] fromList [("peer",Object (fromList [("local",Object (fromList [("addr",String "192.168.1.210"),("port",String "6001")])),("remote",Object (fromList [("addr",String "146.166.116.161"),("port",String "6161")]))])),("kind",String "TxSubmissionOutboundRecvMsgRequestTxs")]
TxOutbound:Info:124231] [2022-06-10 15:48:53.00 UTC] fromList [("peer",Object (fromList [("local",Object (fromList [("addr",String "192.168.1.210"),("port",String "6001")])),("remote",Object (fromList [("addr",String "47.200.165.160"),("port",String "6001")]))])),("kind",String "TxSubmissionOutboundSendMsgReplyTxs")]
TxOutbound:Info:124231] [2022-06-10 15:48:53.00 UTC] fromList [("peer",Object (fromList [("local",Object (fromList [("addr",String "192.168.1.210"),("port",String "6001")])),("remote",Object (fromList [("addr",String "47.200.165.160"),("port",String "6001")]))])),("kind",String "TxSubmissionOutboundRecvMsgRequestTxs")]

As you can see from relay block were fetched too in my example.

jeremyisme · 10 June 2022 18:08

Is your BP processing transactions?

acangros · 10 June 2022 18:10

yes, manually I can submit UTXO and the registration transaction itself

acangros · 10 June 2022 18:11

I just check it from log the exact hh:mm:ss of the assigned slot and I can see this error:


{"host":"cardano-","pid":"1","loc":null,"at":"2022-06-10T12:37:17.55Z","ns":["cardano.node.ChainDB"],"sev":"Error","env":"1.34.1:73f9a","data":{"kind":"TraceAddBlockEvent.AddBlockValidation.InvalidBlock","error":"ExtValidationErrorHeader (HeaderProtocolError (HardForkValidationErrFromEra S (S (S (S (Z (WrapValidationErr {unwrapValidationErr = ChainTransitionError [OverlayFailure (VRFKeyWrongVRFKey (KeyHash \"585a358862e7c0cae80ec28c957b0a58c63d337deeefe69d69998780\") \"e93d309a3179365b981b8fd8b4dc8045cc4edf1998f520ff484125245678c2fc\" \"6de5d5e319b9d09a22a41025e2b248138ed2f084f87089a268caee3761af4291\")]})))))))","block":{"hash":"f461f6573ff6b35573a68ecb8eda59aa25c3479ee375ccb58cf825d29905904d","kind":"Point","slot":60495421}},"msg":"","thread":"35","app":[]}


{"host":"cardano-","pid":"1","loc":null,"at":"2022-06-10T12:37:17.55Z","ns":["cardano.node.ChainDB"],"sev":"Info","env":"1.34.1:73f9a","data":{"kind":"TraceAddBlockEvent.AddBlockValidation.ValidCandidate","block":"4fe387622b4b48ba056929dcc535760a24a6add340ba1515f26d52348b65e07e@60495344"},"msg":"","thread":"35","app":[]}
{"host":"cardano-","pid":"1","loc":null,"at":"2022-06-10T12:37:17.55Z","ns":["cardano.node.Forge"],"sev":"Error","env":"1.34.1:73f9a","data":{"val":{"kind":"TraceForgedInvalidBlock","reason":{"kind":"ValidationError","error":{"kind":"HeaderProtocolError","error":{"kind":"ChainTransitionError","failures":[{"registeredVRFKeHash":"\"e93d309a3179365b981b8fd8b4dc8045cc4edf1998f520ff484125245678c2fc\"","kind":"**VRFKeyWrongVRFKeyOVERLAY**","poolHash":"KeyHash \"585a358862e7c0cae80ec28c957b0a58c63d337deeefe69d69998780\"","unregisteredVRFKeyHash":"\"6de5d5e319b9d09a22a41025e2b248138ed2f084f87089a268caee3761af4291\""}]}}},"slot":60495421},"credentials":"Cardano"},"msg":"","thread":"45","app":[]}


{"host":"cardano-","pid":"1","loc":null,"at":"2022-06-10T12:37:17.55Z","ns":["cardano.node.Mempool"],"sev":"Info","env":"1.34.1:73f9a","data":{"txsInvalidated":[],"kind":"TraceMempoolManuallyRemovedTxs","txsRemoved":["dd17788a","14474c2e","a239e2dc","d62ea174","287edd94","8617c5e3","e0c7b319","0c3bbb75","80b58714","211ac0c8","5ff767c3","0f0dd31b","815d9a29","a5bfee06","45d7b86b","763e0c05","e2e5b00a","20252793","792ffc4a","be2e831c","54a8b872","c95c7c26","ea872932","e1318443","182b7b75","b1347cab","07e08a23","69e02558","5aa84c84"],"mempoolSize":{"bytes":0,"numTxs":0}},"msg":"","thread":"45","app":[]}

os11k · 10 June 2022 18:13

Well, seems you have problem with a key.

acangros · 10 June 2022 18:16

great! at least I have sth to go on time to work. thanks very much, I will report !!

jeremyisme · 10 June 2022 19:08

Make sure your transaction count is going up once you’ve fixed the key. In both the relays and the BP.

acangros · 30 June 2022 06:25

I have produce my first block

What was it wrong? I can assume that something with the keys. I deleted my previous Pool (I learnt how to deregister a Pool) and build a new Pool step by step. And voilà, we have a block in the first oportunity.

Thanks to all, because with your comments I have learnt a lot checking things

Topic		Replies	Views
NOT A BLOCK PRODUCER! RELAY ONLY! Setup a Stake Pool	2	660	6 March 2021
Block Producing/Relay Node Issues Operate a Stake Pool	4	983	2 September 2020
Connection issues between relay and producer Setup a Stake Pool mainnet	22	1972	16 March 2021
Test-Setup for Relay and Producer with Docker Setup a Stake Pool docker	10	1077	25 February 2021
Issue start block producer/relay node Setup a Stake Pool	21	1296	7 January 2022

Block producer doesn't produce

Related Topics