din-payments-stack fails with geth 1.14 fixturenet #11

New Issue

dboreham · 2024-05-22T12:31:34Z

dboreham commented

2024-05-22 12:31:34 +00:00

Testing against #7

Ledger channel creation never completes.

In logs for go-nitro-bob:

go-nitro-bob-1  | {"time":"2024-05-22T12:22:48.761292551Z","level":"DEBUG","msg":"Sending notification","address":"0xe22AD83A0dE117bA0d03d5E94Eb4E0d80a69C62a","method":"ledger_channel_updated","payload":{"ID":"0xf51231020dea3e86e76c108ac8b22d89eb746c787edd946ceee74716b112a3ec","Status":"Proposed","Balance":{"AssetAddress":"0x0000000000000000000000000000000000000000","Me":"0xe22ad83a0de117ba0d03d5e94eb4e0d80a69c62a","Them":"0xe6ce22afe802caf5ff7d3845cec8c736ecc8d61f","MyBalance":"0x186a0","TheirBalance":"0x186a0"}}}
go-nitro-bob-1  | {"time":"2024-05-22T12:22:48.761360541Z","level":"WARN","msg":"did not find scAddr in local peers map, fetching from DHT","address":"0xe22AD83A0dE117bA0d03d5E94Eb4E0d80a69C62a","scAddr":"0xe6CE22afe802CAf5fF7d3845cec8c736ecc8d61F"}
go-nitro-bob-1  | {"time":"2024-05-22T12:22:48.762445583Z","level":"DEBUG","msg":"found address in dht","address":"0xe22AD83A0dE117bA0d03d5E94Eb4E0d80a69C62a","scaddr":"0xe6CE22afe802CAf5fF7d3845cec8c736ecc8d61F","peerId":"16Uiu2HAm9QfRxbyTHpmZnn1nPmwYEkY3grene7E32btoN4Y1PQZM"}
go-nitro-bob-1  | {"time":"2024-05-22T12:22:48.762545769Z","level":"DEBUG","msg":"Sent message","address":"0xe22AD83A0dE117bA0d03d5E94Eb4E0d80a69C62a","msg":{"To":"0xe6CE22","From":"0xe22AD8","PayloadSummaries":[{"ObjectiveId":"DirectFunding-0xf51231020dea3e86e76c108ac8b22d89eb746c787edd946ceee74716b112a3ec","Type":"SignedStatePayload","PayloadDataSize":830}],"ProposalSummaries":[],"Payments":[],"RejectedObjectives":[]}}
go-nitro-bob-1  | {"time":"2024-05-22T12:22:51.061399971Z","level":"DEBUG","msg":"queueing new chainEvent","address":"0xe22AD83A0dE117bA0d03d5E94Eb4E0d80a69C62a","block-num":109}
go-nitro-bob-1  | {"time":"2024-05-22T12:22:51.061426073Z","level":"DEBUG","msg":"event added to queue","address":"0xe22AD83A0dE117bA0d03d5E94Eb4E0d80a69C62a","updated-queue-length":1}
go-nitro-bob-1  | {"time":"2024-05-22T12:22:57.071862451Z","level":"DEBUG","msg":"event popped from queue","address":"0xe22AD83A0dE117bA0d03d5E94Eb4E0d80a69C62a","updated-queue-length":0}
go-nitro-bob-1  | {"time":"2024-05-22T12:22:57.072564469Z","level":"WARN","msg":"dropping event because its block is no longer in the chain (possible re-org)","address":"0xe22AD83A0dE117bA0d03d5E94Eb4E0d80a69C62a","blockNumber":109,"blockHash":"0xc828ad2c5c6213bb4d2077a92e8db2be9ead1cbfe259e54f65c75672a5529940"}

Testing against https://git.vdb.to/cerc-io/fixturenet-eth-stacks/pulls/7 Ledger channel creation never completes. In logs for go-nitro-bob: ``` go-nitro-bob-1 | {"time":"2024-05-22T12:22:48.761292551Z","level":"DEBUG","msg":"Sending notification","address":"0xe22AD83A0dE117bA0d03d5E94Eb4E0d80a69C62a","method":"ledger_channel_updated","payload":{"ID":"0xf51231020dea3e86e76c108ac8b22d89eb746c787edd946ceee74716b112a3ec","Status":"Proposed","Balance":{"AssetAddress":"0x0000000000000000000000000000000000000000","Me":"0xe22ad83a0de117ba0d03d5e94eb4e0d80a69c62a","Them":"0xe6ce22afe802caf5ff7d3845cec8c736ecc8d61f","MyBalance":"0x186a0","TheirBalance":"0x186a0"}}} go-nitro-bob-1 | {"time":"2024-05-22T12:22:48.761360541Z","level":"WARN","msg":"did not find scAddr in local peers map, fetching from DHT","address":"0xe22AD83A0dE117bA0d03d5E94Eb4E0d80a69C62a","scAddr":"0xe6CE22afe802CAf5fF7d3845cec8c736ecc8d61F"} go-nitro-bob-1 | {"time":"2024-05-22T12:22:48.762445583Z","level":"DEBUG","msg":"found address in dht","address":"0xe22AD83A0dE117bA0d03d5E94Eb4E0d80a69C62a","scaddr":"0xe6CE22afe802CAf5fF7d3845cec8c736ecc8d61F","peerId":"16Uiu2HAm9QfRxbyTHpmZnn1nPmwYEkY3grene7E32btoN4Y1PQZM"} go-nitro-bob-1 | {"time":"2024-05-22T12:22:48.762545769Z","level":"DEBUG","msg":"Sent message","address":"0xe22AD83A0dE117bA0d03d5E94Eb4E0d80a69C62a","msg":{"To":"0xe6CE22","From":"0xe22AD8","PayloadSummaries":[{"ObjectiveId":"DirectFunding-0xf51231020dea3e86e76c108ac8b22d89eb746c787edd946ceee74716b112a3ec","Type":"SignedStatePayload","PayloadDataSize":830}],"ProposalSummaries":[],"Payments":[],"RejectedObjectives":[]}} go-nitro-bob-1 | {"time":"2024-05-22T12:22:51.061399971Z","level":"DEBUG","msg":"queueing new chainEvent","address":"0xe22AD83A0dE117bA0d03d5E94Eb4E0d80a69C62a","block-num":109} go-nitro-bob-1 | {"time":"2024-05-22T12:22:51.061426073Z","level":"DEBUG","msg":"event added to queue","address":"0xe22AD83A0dE117bA0d03d5E94Eb4E0d80a69C62a","updated-queue-length":1} go-nitro-bob-1 | {"time":"2024-05-22T12:22:57.071862451Z","level":"DEBUG","msg":"event popped from queue","address":"0xe22AD83A0dE117bA0d03d5E94Eb4E0d80a69C62a","updated-queue-length":0} go-nitro-bob-1 | {"time":"2024-05-22T12:22:57.072564469Z","level":"WARN","msg":"dropping event because its block is no longer in the chain (possible re-org)","address":"0xe22AD83A0dE117bA0d03d5E94Eb4E0d80a69C62a","blockNumber":109,"blockHash":"0xc828ad2c5c6213bb4d2077a92e8db2be9ead1cbfe259e54f65c75672a5529940"} ```

dboreham self-assigned this 2024-05-22 12:31:39 +00:00

dboreham commented

2024-05-22 12:42:26 +00:00

Inspected a bit later, the block cited as not being on chain is present, and has the same hash as the log message above:

laconic-so deployment --dir nitro-net-deployment exec foundry "cast block 109"


baseFeePerGas        508
difficulty           0
extraData            0xd883010e03846765746888676f312e32322e33856c696e7578
gasLimit             27806313
gasUsed              46520
hash                 0xc828ad2c5c6213bb4d2077a92e8db2be9ead1cbfe259e54f65c75672a5529940
logsBloom            0x00000000000000000000000000000020000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000200000000000000000000000000000002000000000000000000000000000000000000080000000400000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000002000000000000000000000000000000000000000000000000000000000000002000000008000000800000000000000000000000000000000000000000000000000000000000000000000000000000000000
miner                0xe6CE22afe802CAf5fF7d3845cec8c736ecc8d61F
mixHash              0xdb39dd5dc905f8b5e3bd057584acfa018641fc9d7f74067f1ea987b4517a4937
nonce                0x0000000000000000
number               109
parentHash           0x49c8f0d50541fa664162961ab566740b2e272f60dc7f39721b769af8f7440c95
transactionsRoot     0xe8e864f7b1240c68561b38e3ef4f4a82253ff1e8fc5d1b1561cdbd675eebe223
receiptsRoot         0xe334dc2c407ae8e962352a97de3479891e09444f691d0e35762e1e5c3dc868ac
sha3Uncles           0x1dcc4de8dec75d7aab85b567b6ccd41ad312451b948a7413f0a142fd40d49347
size                 858
stateRoot            0xa89cbfc0f30cf6ebc1c78979f1e31981041380816e5aff5663e1464688742b77
timestamp            1716380571
withdrawalsRoot      0x56e81f171bcc55a6ff8345e692c0f86e5b48e01b996cadc001622fb5e363b421
totalDifficulty      1
transactions:        [
        0x86a5ef2aadd2bc1470128b3fec26a34502bef21e06394a39bcd2878871c10bdc
]

Inspected a bit later, the block cited as not being on chain is present, and has the same hash as the log message above: ``` laconic-so deployment --dir nitro-net-deployment exec foundry "cast block 109" baseFeePerGas 508 difficulty 0 extraData 0xd883010e03846765746888676f312e32322e33856c696e7578 gasLimit 27806313 gasUsed 46520 hash 0xc828ad2c5c6213bb4d2077a92e8db2be9ead1cbfe259e54f65c75672a5529940 logsBloom 0x00000000000000000000000000000020000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000200000000000000000000000000000002000000000000000000000000000000000000080000000400000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000002000000000000000000000000000000000000000000000000000000000000002000000008000000800000000000000000000000000000000000000000000000000000000000000000000000000000000000 miner 0xe6CE22afe802CAf5fF7d3845cec8c736ecc8d61F mixHash 0xdb39dd5dc905f8b5e3bd057584acfa018641fc9d7f74067f1ea987b4517a4937 nonce 0x0000000000000000 number 109 parentHash 0x49c8f0d50541fa664162961ab566740b2e272f60dc7f39721b769af8f7440c95 transactionsRoot 0xe8e864f7b1240c68561b38e3ef4f4a82253ff1e8fc5d1b1561cdbd675eebe223 receiptsRoot 0xe334dc2c407ae8e962352a97de3479891e09444f691d0e35762e1e5c3dc868ac sha3Uncles 0x1dcc4de8dec75d7aab85b567b6ccd41ad312451b948a7413f0a142fd40d49347 size 858 stateRoot 0xa89cbfc0f30cf6ebc1c78979f1e31981041380816e5aff5663e1464688742b77 timestamp 1716380571 withdrawalsRoot 0x56e81f171bcc55a6ff8345e692c0f86e5b48e01b996cadc001622fb5e363b421 totalDifficulty 1 transactions: [ 0x86a5ef2aadd2bc1470128b3fec26a34502bef21e06394a39bcd2878871c10bdc ] ```

dboreham commented

2024-05-22 13:07:41 +00:00

Added extra logging to see the hash it expected:

go-nitro-bob-1  | {"time":"2024-05-22T13:06:22.083515468Z","level":"WARN","msg":"dropping event because its block is no longer in the chain (possible re-org)","address":"0xe22AD83A0dE117bA0d03d5E94Eb4E0d80a69C62a","blockNumber":35,"blockHash":"0x35ebfb7e637fe796e433d010cc1e160b75336ac86306b2f296d9bbfb8da1e0ca","wrongHash":"0xa5985c2bad2048bde18fc5071b19783e319db3cf28691b2ae44473e38abd6f9e"}

Added extra logging to see the hash it expected: ``` go-nitro-bob-1 | {"time":"2024-05-22T13:06:22.083515468Z","level":"WARN","msg":"dropping event because its block is no longer in the chain (possible re-org)","address":"0xe22AD83A0dE117bA0d03d5E94Eb4E0d80a69C62a","blockNumber":35,"blockHash":"0x35ebfb7e637fe796e433d010cc1e160b75336ac86306b2f296d9bbfb8da1e0ca","wrongHash":"0xa5985c2bad2048bde18fc5071b19783e319db3cf28691b2ae44473e38abd6f9e"} ```

dboreham commented

2024-05-22 13:08:36 +00:00

Queried the block subsequently and it has the expected hash:

$ laconic-so deployment --dir nitro-net-deployment exec foundry "cast block 35"


baseFeePerGas        9882480
difficulty           0
extraData            0xd883010e03846765746888676f312e32322e33856c696e7578
gasLimit             25868777
gasUsed              46520
hash                 0x35ebfb7e637fe796e433d010cc1e160b75336ac86306b2f296d9bbfb8da1e0ca
logsBloom            0x00000000000000000000000000000020000000000000010000000000000000000000020000000000000000000000000000000000000000000000020000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000400000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000002000000000000000000000000000000000000000000000000000000000000002000000008000000800000000000000000000000000000000000000000000000000000000000000000000000000000000000
miner                0xe6CE22afe802CAf5fF7d3845cec8c736ecc8d61F
mixHash              0xf68f9e20369fa2e3f8b15d94efd2e28c33a6e825ec2910c9b43c6f6bc5f99cee
nonce                0x0000000000000000
number               35
parentHash           0xf76b3ac088b0153fbc0581ef7cff7fd0226135551d8b70f13114af9d190e2b71
transactionsRoot     0x2f8d70dce227cd0bf7ba05e7c6ef10d10eff3f5f78c3fe8babbe0eed8f2c2b0a
receiptsRoot         0x35f3a17b3469d87fce8346abede9e1574f5a6b173a5062f5d934270593baf7c4
sha3Uncles           0x1dcc4de8dec75d7aab85b567b6ccd41ad312451b948a7413f0a142fd40d49347
size                 859
stateRoot            0x9c47fd209fe553f4e6d6b887334f33b5dd0a7dc6617613c97fded430eb4d54c4
timestamp            1716383176
withdrawalsRoot      0x56e81f171bcc55a6ff8345e692c0f86e5b48e01b996cadc001622fb5e363b421
totalDifficulty      1
transactions:        [
        0x3fda11a83aa9626fb65d13bda765aba0270f2d6dc99f71ae70663044b6d37969
]

Queried the block subsequently and it has the expected hash: ``` $ laconic-so deployment --dir nitro-net-deployment exec foundry "cast block 35" baseFeePerGas 9882480 difficulty 0 extraData 0xd883010e03846765746888676f312e32322e33856c696e7578 gasLimit 25868777 gasUsed 46520 hash 0x35ebfb7e637fe796e433d010cc1e160b75336ac86306b2f296d9bbfb8da1e0ca logsBloom 0x00000000000000000000000000000020000000000000010000000000000000000000020000000000000000000000000000000000000000000000020000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000400000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000002000000000000000000000000000000000000000000000000000000000000002000000008000000800000000000000000000000000000000000000000000000000000000000000000000000000000000000 miner 0xe6CE22afe802CAf5fF7d3845cec8c736ecc8d61F mixHash 0xf68f9e20369fa2e3f8b15d94efd2e28c33a6e825ec2910c9b43c6f6bc5f99cee nonce 0x0000000000000000 number 35 parentHash 0xf76b3ac088b0153fbc0581ef7cff7fd0226135551d8b70f13114af9d190e2b71 transactionsRoot 0x2f8d70dce227cd0bf7ba05e7c6ef10d10eff3f5f78c3fe8babbe0eed8f2c2b0a receiptsRoot 0x35f3a17b3469d87fce8346abede9e1574f5a6b173a5062f5d934270593baf7c4 sha3Uncles 0x1dcc4de8dec75d7aab85b567b6ccd41ad312451b948a7413f0a142fd40d49347 size 859 stateRoot 0x9c47fd209fe553f4e6d6b887334f33b5dd0a7dc6617613c97fded430eb4d54c4 timestamp 1716383176 withdrawalsRoot 0x56e81f171bcc55a6ff8345e692c0f86e5b48e01b996cadc001622fb5e363b421 totalDifficulty 1 transactions: [ 0x3fda11a83aa9626fb65d13bda765aba0270f2d6dc99f71ae70663044b6d37969 ] ```

dboreham commented

2024-05-31 16:18:10 +00:00

This turned out to be some wire format incompatibility between new geth and old go-eth library.
Updating go-nitro to use the latest client library made the problem go away.

This turned out to be some wire format incompatibility between new geth and old go-eth library. Updating go-nitro to use the latest client library made the problem go away.

dboreham closed this issue

2024-05-31 16:18:10 +00:00

Sign in to join this conversation.