neo-go
neo-go copied to clipboard
nspcc-dev
Race in `TestAwaitUtilCancelTx` caused by the test design
TestAwaitUtilCancelTx is failing sometimes because of the fact that original transaction is being accepted instead of conflicting one. It happens because chain executor's chain continiousely produces blocks, so occasionally the block is being accepted right after main transaction submission. Go 1.22, macOS, firstly discovered at https://github.com/nspcc-dev/neo-go/actions/runs/8328012697/job/22787046121?pr=3357.
The possible solution is to add two branches into this test. One branch should handle the case of original transaction acceptance, the other branch should handle the conflicting transaction acceptance. This situation is very natural, so let the test follow both cases (similar to the way how TestWalletClaimGas works.
2024-03-18T14:12:38.1281770Z === RUN TestAwaitUtilCancelTx
2024-03-18T14:12:38.1284550Z logger.go:146: 2024-03-18T14:11:11.985Z INFO initial gas supply is not set or wrong, setting default value {"InitialGASSupply": "52000000"}
2024-03-18T14:12:38.1289250Z logger.go:146: 2024-03-18T14:11:11.985Z INFO P2PNotaryRequestPayloadPool size is not set or wrong, setting default value {"P2PNotaryRequestPayloadPoolSize": 1000}
2024-03-18T14:12:38.1293660Z logger.go:146: 2024-03-18T14:11:11.985Z INFO MaxBlockSize is not set or wrong, setting default value {"MaxBlockSize": 262144}
2024-03-18T14:12:38.1297780Z logger.go:146: 2024-03-18T14:11:11.985Z INFO MaxBlockSystemFee is not set or wrong, setting default value {"MaxBlockSystemFee": 900000000000}
2024-03-18T14:12:38.1302150Z logger.go:146: 2024-03-18T14:11:11.985Z INFO MaxTransactionsPerBlock is not set or wrong, using default value {"MaxTransactionsPerBlock": 512}
2024-03-18T14:12:38.1306370Z logger.go:146: 2024-03-18T14:11:11.986Z INFO MaxValidUntilBlockIncrement is not set or wrong, using default value {"MaxValidUntilBlockIncrement": 864000}
2024-03-18T14:12:38.1310270Z logger.go:146: 2024-03-18T14:11:11.986Z INFO no storage version found! creating genesis block
2024-03-18T14:12:38.1312860Z logger.go:146: 2024-03-18T14:11:12.032Z INFO MaxIteratorResultItems is not set or wrong, setting default value {"MaxIteratorResultItems": 100}
2024-03-18T14:12:38.1317080Z logger.go:146: 2024-03-18T14:11:12.033Z INFO MaxFindResultItems is not set or wrong, setting default value {"MaxFindResultItems": 100}
2024-03-18T14:12:38.1321560Z logger.go:146: 2024-03-18T14:11:12.033Z INFO MaxFindStorageResultItems is not set or wrong, setting default value {"MaxFindStorageResultItems": 50}
2024-03-18T14:12:38.1325620Z logger.go:146: 2024-03-18T14:11:12.033Z INFO MaxNEP11Tokens is not set or wrong, setting default value {"MaxNEP11Tokens": 100}
2024-03-18T14:12:38.1329240Z logger.go:146: 2024-03-18T14:11:12.033Z INFO MaxRequestBodyBytes is not set or wong, setting default value {"MaxRequestBodyBytes": 5242880}
2024-03-18T14:12:38.1332860Z logger.go:146: 2024-03-18T14:11:12.033Z DEBUG done processing headers {"headerIndex": 1, "blockHeight": 0, "took": "634.803µs"}
2024-03-18T14:12:38.1335730Z logger.go:146: 2024-03-18T14:11:12.033Z INFO MaxRequestHeaderBytes is not set or wong, setting default value {"MaxRequestHeaderBytes": 1048576}
2024-03-18T14:12:38.1338540Z logger.go:146: 2024-03-18T14:11:12.034Z INFO MaxWebSocketClients is not set or wrong, setting default value {"MaxWebSocketClients": 64}
2024-03-18T14:12:38.1341380Z logger.go:146: 2024-03-18T14:11:12.034Z INFO starting rpc-server {"endpoint": "127.0.0.1:0"}
2024-03-18T14:12:38.1344790Z logger.go:146: 2024-03-18T14:11:12.040Z DEBUG processing rpc request {"method": "getversion", "params": "[]"}
2024-03-18T14:12:38.1354560Z logger.go:146: 2024-03-18T14:11:12.042Z DEBUG processing rpc request {"method": "getnativecontracts", "params": "[]"}
2024-03-18T14:12:38.1356870Z logger.go:146: 2024-03-18T14:11:12.081Z DEBUG processing rpc request {"method": "getversion", "params": "[]"}
2024-03-18T14:12:38.1361330Z logger.go:146: 2024-03-18T14:11:12.083Z DEBUG processing rpc request {"method": "getnep17balances", "params": "[56c989e76f9a2ca05bb5caa6c96f524d905accd8]"}
2024-03-18T14:12:38.1373300Z logger.go:146: 2024-03-18T14:11:12.087Z DEBUG processing rpc request {"method": "invokescript", "params": "[CxEMFNwk6EPkEi4hj1BzCuuZPYVDDVeBDBTYzFqQTVJvyabKtVugLJpv54nJVhTAHwwIdHJhbnNmZXIMFPVj6kC8KD1NDgXEjqMFs/Kgc0DvQWJ9W1I5 ]"}
2024-03-18T14:12:38.1376930Z logger.go:146: 2024-03-18T14:11:12.091Z DEBUG processing rpc request {"method": "getblockcount", "params": "[]"}
2024-03-18T14:12:38.1384980Z logger.go:146: 2024-03-18T14:11:12.092Z DEBUG processing rpc request {"method": "calculatenetworkfee", "params": "[AL+xcc+0P5gAAAAAAAAAAAAAAAAABAAAAAHYzFqQTVJvyabKtVugLJpv54nJVgEAVwsRDBTcJOhD5BIuIY9QcwrrmT2FQw1XgQwU2MxakE1Sb8mmyrVboCyab+eJyVYUwB8MCHRyYW5zZmVyDBT1Y+pAvCg9TQ4FxI6jBbPyoHNA70FifVtSOQEAKhEMIQKzYiv0AXvf4xfFiu1fTHU/IGt9uJYEb6fXdLvEv3+NwhFBntDcOg==]"}
2024-03-18T14:12:38.1400300Z logger.go:146: 2024-03-18T14:11:12.093Z DEBUG processing rpc request {"method": "sendrawtransaction", "params": "[AL+xcc+0P5gAAAAAAPTGEgAAAAAABAAAAAHYzFqQTVJvyabKtVugLJpv54nJVgEAVwsRDBTcJOhD5BIuIY9QcwrrmT2FQw1XgQwU2MxakE1Sb8mmyrVboCyab+eJyVYUwB8MCHRyYW5zZmVyDBT1Y+pAvCg9TQ4FxI6jBbPyoHNA70FifVtSOQFCDEA51mOBl0nGji0ZR/T1FTGuRIeBphZ6hut5HtxYtGBhkA55np/tFBSpeLAJdL0zKwtUualypxdPDnaxVBL3/m/IKhEMIQKzYiv0AXvf4xfFiu1fTHU/IGt9uJYEb6fXdLvEv3+NwhFBntDcOg==]"}
2024-03-18T14:12:38.1408640Z logger.go:146: 2024-03-18T14:11:12.097Z DEBUG processing rpc request {"method": "getversion", "params": "[]"}
2024-03-18T14:12:38.1411190Z logger.go:146: 2024-03-18T14:11:12.098Z DEBUG processing rpc request {"method": "getnativecontracts", "params": "[]"}
2024-03-18T14:12:38.1413360Z logger.go:146: 2024-03-18T14:11:12.114Z DEBUG processing rpc request {"method": "getversion", "params": "[]"}
2024-03-18T14:12:38.1417410Z logger.go:146: 2024-03-18T14:11:12.118Z DEBUG processing rpc request {"method": "getrawtransaction", "params": "[b1b2ba3bc8fbb340cb12841306fc54b8c8fe4477a98956b02fb8f447c578a46b 1]"}
2024-03-18T14:12:38.1427580Z logger.go:146: 2024-03-18T14:11:12.135Z DEBUG done processing headers {"headerIndex": 2, "blockHeight": 1, "took": "242.594µs"}
2024-03-18T14:12:38.1431180Z logger.go:146: 2024-03-18T14:11:12.163Z DEBUG processing rpc request {"method": "invokescript", "params": "[QA== ]"}
2024-03-18T14:12:38.1433370Z logger.go:146: 2024-03-18T14:11:12.167Z DEBUG processing rpc request {"method": "getblockcount", "params": "[]"}
2024-03-18T14:12:38.1439410Z logger.go:146: 2024-03-18T14:11:12.171Z DEBUG processing rpc request {"method": "calculatenetworkfee", "params": "[AEK492AAAAAAAAAAAAAAAAAAAAAABQAAAAHYzFqQTVJvyabKtVugLJpv54nJVgEBIWukeMVH9LgvsFaJqXdE/si4VPwGE4QSy0Cz+8g7urKxAUABACoRDCECs2Ir9AF73+MXxYrtX0x1PyBrfbiWBG+n13S7xL9/jcIRQZ7Q3Do=]"}
2024-03-18T14:12:38.1447070Z logger.go:146: 2024-03-18T14:11:12.172Z DEBUG processing rpc request {"method": "sendrawtransaction", "params": "[AEK492AAAAAAAAAAAPXGEgAAAAAABAAAAAHYzFqQTVJvyabKtVugLJpv54nJVgEBIWukeMVH9LgvsFaJqXdE/si4VPwGE4QSy0Cz+8g7urKxAUABQgxAKMFykB4BdUMOcwyVKq8OpiTLkCxy0miTzoJZSpWghqf04VXVHNBS0RYZ35suMn4plDa7N8Ky7Dpq5C78J29KPCoRDCECs2Ir9AF73+MXxYrtX0x1PyBrfbiWBG+n13S7xL9/jcIRQZ7Q3Do=]"}
2024-03-18T14:12:38.1461860Z logger.go:146: 2024-03-18T14:11:12.173Z INFO Error encountered with rpc request {"code": -507, "cause": "invalid attribute: conflicting transaction b1b2ba3bc8fbb340cb12841306fc54b8c8fe4477a98956b02fb8f447c578a46b is already on chain", "method": "sendrawtransaction", "params": "[AEK492AAAAAAAAAAAPXGEgAAAAAABAAAAAHYzFqQTVJvyabKtVugLJpv54nJVgEBIWukeMVH9LgvsFaJqXdE/si4VPwGE4QSy0Cz+8g7urKxAUABQgxAKMFykB4BdUMOcwyVKq8OpiTLkCxy0miTzoJZSpWghqf04VXVHNBS0RYZ35suMn4plDa7N8Ky7Dpq5C78J29KPCoRDCECs2Ir9AF73+MXxYrtX0x1PyBrfbiWBG+n13S7xL9/jcIRQZ7Q3Do=]"}
2024-03-18T14:12:38.1477430Z failed to send conflicting transaction: Invalid transaction attribute (-507) - invalid attribute: conflicting transaction b1b2ba3bc8fbb340cb12841306fc54b8c8fe4477a98956b02fb8f447c578a46b is already on chain
2024-03-18T14:12:38.1482300Z executor.go:286:
2024-03-18T14:12:38.1484120Z Error Trace: /Users/runner/work/neo-go/neo-go/internal/testcli/executor.go:286
2024-03-18T14:12:38.1487030Z /Users/runner/work/neo-go/neo-go/cli/util/util_test.go:171
2024-03-18T14:12:38.1488560Z Error: Received unexpected error:
2024-03-18T14:12:38.1494180Z failed to send conflicting transaction: Invalid transaction attribute (-507) - invalid attribute: conflicting transaction b1b2ba3bc8fbb340cb12841306fc54b8c8fe4477a98956b02fb8f447c578a46b is already on chain
2024-03-18T14:12:38.1500160Z Test: TestAwaitUtilCancelTx
2024-03-18T14:12:38.1502210Z logger.go:146: 2024-03-18T14:11:12.196Z INFO shutting down RPC server {"endpoint": "127.0.0.1:49239"}
2024-03-18T14:12:38.1505990Z logger.go:146: 2024-03-18T14:11:12.197Z INFO persisted to disk {"blocks": 2, "keys": 166, "headerHeight": 2, "blockHeight": 2, "took": "121.248µs"}
2024-03-18T14:12:38.1508430Z --- FAIL: TestAwaitUtilCancelTx (0.21s)
2024-03-18T14:12:38.1509280Z FAIL
2024-03-18T14:12:38.1510140Z FAIL github.com/nspcc-dev/neo-go/cli/util 0.463s
Not fixed, failure discovered in #3387 rebased onto fresh master. The error is:
2024-04-01T16:07:03.8231740Z === RUN TestAwaitUtilCancelTx
2024-04-01T16:07:03.8234450Z logger.go:146: 2024-04-01T16:05:21.609Z INFO initial gas supply is not set or wrong, setting default value {"InitialGASSupply": "52000000"}
2024-04-01T16:07:03.8238700Z logger.go:146: 2024-04-01T16:05:21.609Z INFO P2PNotaryRequestPayloadPool size is not set or wrong, setting default value {"P2PNotaryRequestPayloadPoolSize": 1000}
2024-04-01T16:07:03.8243030Z logger.go:146: 2024-04-01T16:05:21.609Z INFO MaxBlockSize is not set or wrong, setting default value {"MaxBlockSize": 262144}
2024-04-01T16:07:03.8247200Z logger.go:146: 2024-04-01T16:05:21.609Z INFO MaxBlockSystemFee is not set or wrong, setting default value {"MaxBlockSystemFee": 900000000000}
2024-04-01T16:07:03.8250370Z logger.go:146: 2024-04-01T16:05:21.609Z INFO MaxTransactionsPerBlock is not set or wrong, using default value {"MaxTransactionsPerBlock": 512}
2024-04-01T16:07:03.8253420Z logger.go:146: 2024-04-01T16:05:21.609Z INFO MaxValidUntilBlockIncrement is not set or wrong, using default value {"MaxValidUntilBlockIncrement": 864000}
2024-04-01T16:07:03.8257500Z logger.go:146: 2024-04-01T16:05:21.611Z INFO no storage version found! creating genesis block
2024-04-01T16:07:03.8260110Z logger.go:146: 2024-04-01T16:05:21.617Z INFO MaxIteratorResultItems is not set or wrong, setting default value {"MaxIteratorResultItems": 100}
2024-04-01T16:07:03.8262910Z logger.go:146: 2024-04-01T16:05:21.617Z INFO MaxFindResultItems is not set or wrong, setting default value {"MaxFindResultItems": 100}
2024-04-01T16:07:03.8265780Z logger.go:146: 2024-04-01T16:05:21.618Z INFO MaxFindStorageResultItems is not set or wrong, setting default value {"MaxFindStorageResultItems": 50}
2024-04-01T16:07:03.8268540Z logger.go:146: 2024-04-01T16:05:21.618Z INFO MaxNEP11Tokens is not set or wrong, setting default value {"MaxNEP11Tokens": 100}
2024-04-01T16:07:03.8271190Z logger.go:146: 2024-04-01T16:05:21.618Z INFO MaxRequestBodyBytes is not set or wong, setting default value {"MaxRequestBodyBytes": 5242880}
2024-04-01T16:07:03.8274050Z logger.go:146: 2024-04-01T16:05:21.618Z INFO MaxRequestHeaderBytes is not set or wong, setting default value {"MaxRequestHeaderBytes": 1048576}
2024-04-01T16:07:03.8276850Z logger.go:146: 2024-04-01T16:05:21.618Z INFO MaxWebSocketClients is not set or wrong, setting default value {"MaxWebSocketClients": 64}
2024-04-01T16:07:03.8279090Z logger.go:146: 2024-04-01T16:05:21.618Z INFO starting rpc-server {"endpoint": "127.0.0.1:0"}
2024-04-01T16:07:03.8281280Z logger.go:146: 2024-04-01T16:05:21.618Z DEBUG done processing headers {"headerIndex": 1, "blockHeight": 0, "took": "393.88µs"}
2024-04-01T16:07:03.8283580Z logger.go:146: 2024-04-01T16:05:21.620Z DEBUG processing rpc request {"method": "getversion", "params": "[]"}
2024-04-01T16:07:03.8285830Z logger.go:146: 2024-04-01T16:05:21.622Z DEBUG processing rpc request {"method": "getnativecontracts", "params": "[]"}
2024-04-01T16:07:03.8288040Z logger.go:146: 2024-04-01T16:05:21.699Z DEBUG processing rpc request {"method": "getversion", "params": "[]"}
2024-04-01T16:07:03.8291090Z logger.go:146: 2024-04-01T16:05:21.702Z DEBUG processing rpc request {"method": "getnep17balances", "params": "[56c989e76f9a2ca05bb5caa6c96f524d905accd8]"}
2024-04-01T16:07:03.8295410Z logger.go:146: 2024-04-01T16:05:21.704Z DEBUG processing rpc request {"method": "invokescript", "params": "[CxEMFNwk6EPkEi4hj1BzCuuZPYVDDVeBDBTYzFqQTVJvyabKtVugLJpv54nJVhTAHwwIdHJhbnNmZXIMFPVj6kC8KD1NDgXEjqMFs/Kgc0DvQWJ9W1I5 ]"}
2024-04-01T16:07:03.8298990Z logger.go:146: 2024-04-01T16:05:21.707Z DEBUG processing rpc request {"method": "getblockcount", "params": "[]"}
2024-04-01T16:07:03.8305210Z logger.go:146: 2024-04-01T16:05:21.708Z DEBUG processing rpc request {"method": "calculatenetworkfee", "params": "[AJ+O0U60P5gAAAAAAAAAAAAAAAAABAAAAAHYzFqQTVJvyabKtVugLJpv54nJVgEAVwsRDBTcJOhD5BIuIY9QcwrrmT2FQw1XgQwU2MxakE1Sb8mmyrVboCyab+eJyVYUwB8MCHRyYW5zZmVyDBT1Y+pAvCg9TQ4FxI6jBbPyoHNA70FifVtSOQEAKhEMIQKzYiv0AXvf4xfFiu1fTHU/IGt9uJYEb6fXdLvEv3+NwhFBntDcOg==]"}
2024-04-01T16:07:03.8316120Z logger.go:146: 2024-04-01T16:05:21.710Z DEBUG processing rpc request {"method": "sendrawtransaction", "params": "[AJ+O0U60P5gAAAAAAPTGEgAAAAAABAAAAAHYzFqQTVJvyabKtVugLJpv54nJVgEAVwsRDBTcJOhD5BIuIY9QcwrrmT2FQw1XgQwU2MxakE1Sb8mmyrVboCyab+eJyVYUwB8MCHRyYW5zZmVyDBT1Y+pAvCg9TQ4FxI6jBbPyoHNA70FifVtSOQFCDEBHS+LsAsy6vRhMf1zAZVKPVci5JESEGGbldIGIjSv/YHOSILofo06298twgq6Rdt4l0N88wO9b4APNkT2oRvzqKhEMIQKzYiv0AXvf4xfFiu1fTHU/IGt9uJYEb6fXdLvEv3+NwhFBntDcOg==]"}
2024-04-01T16:07:03.8322910Z logger.go:146: 2024-04-01T16:05:21.714Z DEBUG processing rpc request {"method": "getversion", "params": "[]"}
2024-04-01T16:07:03.8325170Z logger.go:146: 2024-04-01T16:05:21.716Z DEBUG processing rpc request {"method": "getnativecontracts", "params": "[]"}
2024-04-01T16:07:03.8327610Z logger.go:146: 2024-04-01T16:05:21.720Z DEBUG done processing headers {"headerIndex": 2, "blockHeight": 1, "took": "355.844µs"}
2024-04-01T16:07:03.8329930Z logger.go:146: 2024-04-01T16:05:21.731Z DEBUG processing rpc request {"method": "getversion", "params": "[]"}
2024-04-01T16:07:03.8332840Z logger.go:146: 2024-04-01T16:05:21.732Z DEBUG processing rpc request {"method": "getrawtransaction", "params": "[5723243dc959a7443cd5b78a2947554515deb0cd8e410b5f20e08f4831b65914 1]"}
2024-04-01T16:07:03.8336030Z transaction 1459b631488fe0205f0b418ecdb0de15455547298ab7d53c44a759c93d242357 is already accepted at block b9b5d74844bc84f4644562b4606dbef666c3cebdf8462d4a71513e3b5749fbec
2024-04-01T16:07:03.8338000Z executor.go:286:
2024-04-01T16:07:03.8339200Z Error Trace: /Users/runner/work/neo-go/neo-go/internal/testcli/executor.go:286
2024-04-01T16:07:03.8341080Z /Users/runner/work/neo-go/neo-go/cli/util/util_test.go:172
2024-04-01T16:07:03.8342100Z Error: Received unexpected error:
2024-04-01T16:07:03.8344920Z transaction 1459b631488fe0205f0b418ecdb0de15455547298ab7d53c44a759c93d242357 is already accepted at block b9b5d74844bc84f4644562b4606dbef666c3cebdf8462d4a71513e3b5749fbec
2024-04-01T16:07:03.8346960Z Test: TestAwaitUtilCancelTx
2024-04-01T16:07:03.8348420Z logger.go:146: 2024-04-01T16:05:21.733Z INFO shutting down RPC server {"endpoint": "127.0.0.1:49234"}
2024-04-01T16:07:03.8350860Z logger.go:146: 2024-04-01T16:05:21.736Z INFO persisted to disk {"blocks": 2, "keys": 166, "headerHeight": 2, "blockHeight": 2, "took": "177.485µs"}
2024-04-01T16:07:03.8352530Z --- FAIL: TestAwaitUtilCancelTx (0.13s)
2024-04-01T16:07:03.8353140Z FAIL
2024-04-01T16:07:03.8353710Z FAIL github.com/nspcc-dev/neo-go/cli/util 0.457s
Fails with another error case after the last fix. Discovered in https://github.com/nspcc-dev/neo-go/pull/3385.
2024-04-02T17:00:47.8155910Z === RUN TestAwaitUtilCancelTx
2024-04-02T17:00:47.8157740Z logger.go:146: 2024-04-02T16:59:13.596Z INFO initial gas supply is not set or wrong, setting default value {"InitialGASSupply": "52000000"}
2024-04-02T17:00:47.8160860Z logger.go:146: 2024-04-02T16:59:13.596Z INFO P2PNotaryRequestPayloadPool size is not set or wrong, setting default value {"P2PNotaryRequestPayloadPoolSize": 1000}
2024-04-02T17:00:47.8164360Z logger.go:146: 2024-04-02T16:59:13.596Z INFO MaxBlockSize is not set or wrong, setting default value {"MaxBlockSize": 262144}
2024-04-02T17:00:47.8167270Z logger.go:146: 2024-04-02T16:59:13.597Z INFO MaxBlockSystemFee is not set or wrong, setting default value {"MaxBlockSystemFee": 900000000000}
2024-04-02T17:00:47.8170180Z logger.go:146: 2024-04-02T16:59:13.598Z INFO MaxTransactionsPerBlock is not set or wrong, using default value {"MaxTransactionsPerBlock": 512}
2024-04-02T17:00:47.8173250Z logger.go:146: 2024-04-02T16:59:13.598Z INFO MaxValidUntilBlockIncrement is not set or wrong, using default value {"MaxValidUntilBlockIncrement": 864000}
2024-04-02T17:00:47.8176290Z logger.go:146: 2024-04-02T16:59:13.599Z INFO no storage version found! creating genesis block
2024-04-02T17:00:47.8178890Z logger.go:146: 2024-04-02T16:59:13.607Z INFO MaxIteratorResultItems is not set or wrong, setting default value {"MaxIteratorResultItems": 100}
2024-04-02T17:00:47.8181730Z logger.go:146: 2024-04-02T16:59:13.607Z INFO MaxFindResultItems is not set or wrong, setting default value {"MaxFindResultItems": 100}
2024-04-02T17:00:47.8185010Z logger.go:146: 2024-04-02T16:59:13.607Z INFO MaxFindStorageResultItems is not set or wrong, setting default value {"MaxFindStorageResultItems": 50}
2024-04-02T17:00:47.8188000Z logger.go:146: 2024-04-02T16:59:13.608Z INFO MaxNEP11Tokens is not set or wrong, setting default value {"MaxNEP11Tokens": 100}
2024-04-02T17:00:47.8191550Z logger.go:146: 2024-04-02T16:59:13.608Z INFO MaxRequestBodyBytes is not set or wong, setting default value {"MaxRequestBodyBytes": 5242880}
2024-04-02T17:00:47.8194620Z logger.go:146: 2024-04-02T16:59:13.608Z INFO MaxRequestHeaderBytes is not set or wong, setting default value {"MaxRequestHeaderBytes": 1048576}
2024-04-02T17:00:47.8197470Z logger.go:146: 2024-04-02T16:59:13.608Z INFO MaxWebSocketClients is not set or wrong, setting default value {"MaxWebSocketClients": 64}
2024-04-02T17:00:47.8288470Z logger.go:146: 2024-04-02T16:59:13.608Z INFO starting rpc-server {"endpoint": "127.0.0.1:0"}
2024-04-02T17:00:47.8293630Z logger.go:146: 2024-04-02T16:59:13.612Z DEBUG done processing headers {"headerIndex": 1, "blockHeight": 0, "took": "284.826µs"}
2024-04-02T17:00:47.8296060Z logger.go:146: 2024-04-02T16:59:13.614Z DEBUG processing rpc request {"method": "getversion", "params": "[]"}
2024-04-02T17:00:47.8298340Z logger.go:146: 2024-04-02T16:59:13.615Z DEBUG processing rpc request {"method": "getnativecontracts", "params": "[]"}
2024-04-02T17:00:47.8300590Z logger.go:146: 2024-04-02T16:59:13.686Z DEBUG processing rpc request {"method": "getversion", "params": "[]"}
2024-04-02T17:00:47.8303250Z logger.go:146: 2024-04-02T16:59:13.687Z DEBUG processing rpc request {"method": "getnep17balances", "params": "[56c989e76f9a2ca05bb5caa6c96f524d905accd8]"}
2024-04-02T17:00:47.8309500Z logger.go:146: 2024-04-02T16:59:13.689Z DEBUG processing rpc request {"method": "invokescript", "params": "[CxEMFNwk6EPkEi4hj1BzCuuZPYVDDVeBDBTYzFqQTVJvyabKtVugLJpv54nJVhTAHwwIdHJhbnNmZXIMFPVj6kC8KD1NDgXEjqMFs/Kgc0DvQWJ9W1I5 ]"}
2024-04-02T17:00:47.8319730Z logger.go:146: 2024-04-02T16:59:13.691Z DEBUG processing rpc request {"method": "getblockcount", "params": "[]"}
2024-04-02T17:00:47.9706210Z logger.go:146: 2024-04-02T16:59:13.692Z DEBUG processing rpc request {"method": "calculatenetworkfee", "params": "[AO5z34q0P5gAAAAAAAAAAAAAAAAABAAAAAHYzFqQTVJvyabKtVugLJpv54nJVgEAVwsRDBTcJOhD5BIuIY9QcwrrmT2FQw1XgQwU2MxakE1Sb8mmyrVboCyab+eJyVYUwB8MCHRyYW5zZmVyDBT1Y+pAvCg9TQ4FxI6jBbPyoHNA70FifVtSOQEAKhEMIQKzYiv0AXvf4xfFiu1fTHU/IGt9uJYEb6fXdLvEv3+NwhFBntDcOg==]"}
2024-04-02T17:00:47.9724900Z logger.go:146: 2024-04-02T16:59:13.693Z DEBUG processing rpc request {"method": "sendrawtransaction", "params": "[AO5z34q0P5gAAAAAAPTGEgAAAAAABAAAAAHYzFqQTVJvyabKtVugLJpv54nJVgEAVwsRDBTcJOhD5BIuIY9QcwrrmT2FQw1XgQwU2MxakE1Sb8mmyrVboCyab+eJyVYUwB8MCHRyYW5zZmVyDBT1Y+pAvCg9TQ4FxI6jBbPyoHNA70FifVtSOQFCDEAKEsfNd+KxQLZ6JyXoN8elaug0fN7xY4P1c2GJcRbGCJIhdgUpl+JvOegb3j/eKb2mydhkUOAnNrdWw7DWw3sqKhEMIQKzYiv0AXvf4xfFiu1fTHU/IGt9uJYEb6fXdLvEv3+NwhFBntDcOg==]"}
2024-04-02T17:00:47.9732150Z logger.go:146: 2024-04-02T16:59:13.696Z DEBUG processing rpc request {"method": "getversion", "params": "[]"}
2024-04-02T17:00:47.9734460Z logger.go:146: 2024-04-02T16:59:13.698Z DEBUG processing rpc request {"method": "getnativecontracts", "params": "[]"}
2024-04-02T17:00:47.9736710Z logger.go:146: 2024-04-02T16:59:13.711Z DEBUG processing rpc request {"method": "getversion", "params": "[]"}
2024-04-02T17:00:47.9739650Z logger.go:146: 2024-04-02T16:59:13.712Z DEBUG processing rpc request {"method": "getrawtransaction", "params": "[2645a6d6e5b0eb5729f04d83c657f877b06c8389407c1afb4cf67e37001a5f35 1]"}
2024-04-02T17:00:47.9742690Z logger.go:146: 2024-04-02T16:59:13.712Z DEBUG done processing headers {"headerIndex": 2, "blockHeight": 1, "took": "240.459µs"}
2024-04-02T17:00:47.9745830Z logger.go:146: 2024-04-02T16:59:13.714Z DEBUG processing rpc request {"method": "invokescript", "params": "[QA== ]"}
2024-04-02T17:00:47.9748780Z logger.go:146: 2024-04-02T16:59:13.715Z DEBUG processing rpc request {"method": "getblockcount", "params": "[]"}
2024-04-02T17:00:47.9753940Z logger.go:146: 2024-04-02T16:59:13.716Z DEBUG processing rpc request {"method": "calculatenetworkfee", "params": "[AEMSQwwAAAAAAAAAAAAAAAAAAAAABQAAAAHYzFqQTVJvyabKtVugLJpv54nJVgEBITVfGgA3fvZM+xp8QImDbLB3+FfGg03wKVfrsOXWpkUmAUABACoRDCECs2Ir9AF73+MXxYrtX0x1PyBrfbiWBG+n13S7xL9/jcIRQZ7Q3Do=]"}
2024-04-02T17:00:47.9762310Z logger.go:146: 2024-04-02T16:59:13.717Z DEBUG processing rpc request {"method": "sendrawtransaction", "params": "[AEMSQwwAAAAAAAAAAPXGEgAAAAAABAAAAAHYzFqQTVJvyabKtVugLJpv54nJVgEBITVfGgA3fvZM+xp8QImDbLB3+FfGg03wKVfrsOXWpkUmAUABQgxAEEahFINIcl6HfDfXIsvX7d/XDDaqvLwEafR3N9yFWrSHNtrZTnoVl2eqcg9iwFZUa8NcaSpohszGYvNhMFN87CoRDCECs2Ir9AF73+MXxYrtX0x1PyBrfbiWBG+n13S7xL9/jcIRQZ7Q3Do=]"}
2024-04-02T17:00:47.9795110Z logger.go:146: 2024-04-02T16:59:13.717Z INFO Error encountered with rpc request {"code": -507, "cause": "invalid attribute: conflicting transaction 2645a6d6e5b0eb5729f04d83c657f877b06c8389407c1afb4cf67e37001a5f35 is already on chain", "method": "sendrawtransaction", "params": "[AEMSQwwAAAAAAAAAAPXGEgAAAAAABAAAAAHYzFqQTVJvyabKtVugLJpv54nJVgEBITVfGgA3fvZM+xp8QImDbLB3+FfGg03wKVfrsOXWpkUmAUABQgxAEEahFINIcl6HfDfXIsvX7d/XDDaqvLwEafR3N9yFWrSHNtrZTnoVl2eqcg9iwFZUa8NcaSpohszGYvNhMFN87CoRDCECs2Ir9AF73+MXxYrtX0x1PyBrfbiWBG+n13S7xL9/jcIRQZ7Q3Do=]"}
2024-04-02T17:00:47.9805980Z failed to send conflicting transaction: Invalid transaction attribute (-507) - invalid attribute: conflicting transaction 2645a6d6e5b0eb5729f04d83c657f877b06c8389407c1afb4cf67e37001a5f35 is already on chain
2024-04-02T17:00:47.9808310Z executor.go:295:
2024-04-02T17:00:47.9809530Z Error Trace: /Users/runner/work/neo-go/neo-go/internal/testcli/executor.go:295
2024-04-02T17:00:47.9812120Z /Users/runner/work/neo-go/neo-go/cli/util/util_test.go:173
2024-04-02T17:00:47.9813230Z Error: Should be true
2024-04-02T17:00:47.9813970Z Test: TestAwaitUtilCancelTx
2024-04-02T17:00:47.9815640Z logger.go:146: 2024-04-02T16:59:13.718Z INFO shutting down RPC server {"endpoint": "127.0.0.1:49228"}
2024-04-02T17:00:47.9818170Z logger.go:146: 2024-04-02T16:59:13.719Z INFO persisted to disk {"blocks": 2, "keys": 166, "headerHeight": 2, "blockHeight": 2, "took": "122.243µs"}
2024-04-02T17:00:47.9819870Z --- FAIL: TestAwaitUtilCancelTx (0.13s)
2024-04-02T17:00:47.9820480Z FAIL
2024-04-02T17:00:47.9821040Z FAIL github.com/nspcc-dev/neo-go/cli/util
https://github.com/nspcc-dev/neo-go/actions/runs/8999180144/job/24720829287?pr=3431
2024-05-08T09:08:22.2302570Z === RUN TestAwaitUtilCancelTx
2024-05-08T09:08:22.2303839Z logger.go:146: 2024-05-08T09:07:47.584Z INFO initial gas supply is not set or wrong, setting default value {"InitialGASSupply": "52000000"}
2024-05-08T09:08:22.2305426Z logger.go:146: 2024-05-08T09:07:47.584Z INFO P2PNotaryRequestPayloadPool size is not set or wrong, setting default value {"P2PNotaryRequestPayloadPoolSize": 1000}
2024-05-08T09:08:22.2306828Z logger.go:146: 2024-05-08T09:07:47.584Z INFO MaxBlockSize is not set or wrong, setting default value {"MaxBlockSize": 262144}
2024-05-08T09:08:22.2308154Z logger.go:146: 2024-05-08T09:07:47.584Z INFO MaxBlockSystemFee is not set or wrong, setting default value {"MaxBlockSystemFee": 900000000000}
2024-05-08T09:08:22.2309490Z logger.go:146: 2024-05-08T09:07:47.584Z INFO MaxTransactionsPerBlock is not set or wrong, using default value {"MaxTransactionsPerBlock": 512}
2024-05-08T09:08:22.2310947Z logger.go:146: 2024-05-08T09:07:47.584Z INFO MaxValidUntilBlockIncrement is not set or wrong, using default value {"MaxValidUntilBlockIncrement": 864000}
2024-05-08T09:08:22.2311808Z logger.go:146: 2024-05-08T09:07:47.584Z INFO no storage version found! creating genesis block
2024-05-08T09:08:22.2313319Z logger.go:146: 2024-05-08T09:07:47.589Z INFO MaxIteratorResultItems is not set or wrong, setting default value {"MaxIteratorResultItems": 100}
2024-05-08T09:08:22.2314568Z logger.go:146: 2024-05-08T09:07:47.589Z INFO MaxFindResultItems is not set or wrong, setting default value {"MaxFindResultItems": 100}
2024-05-08T09:08:22.2316019Z logger.go:146: 2024-05-08T09:07:47.589Z INFO MaxFindStorageResultItems is not set or wrong, setting default value {"MaxFindStorageResultItems": 50}
2024-05-08T09:08:22.2317230Z logger.go:146: 2024-05-08T09:07:47.589Z INFO MaxNEP11Tokens is not set or wrong, setting default value {"MaxNEP11Tokens": 100}
2024-05-08T09:08:22.2318540Z logger.go:146: 2024-05-08T09:07:47.589Z INFO MaxRequestBodyBytes is not set or wong, setting default value {"MaxRequestBodyBytes": 5242880}
2024-05-08T09:08:22.2319895Z logger.go:146: 2024-05-08T09:07:47.589Z INFO MaxRequestHeaderBytes is not set or wong, setting default value {"MaxRequestHeaderBytes": 1048576}
2024-05-08T09:08:22.2321200Z logger.go:146: 2024-05-08T09:07:47.589Z INFO MaxWebSocketClients is not set or wrong, setting default value {"MaxWebSocketClients": 64}
2024-05-08T09:08:22.2322061Z logger.go:146: 2024-05-08T09:07:47.589Z INFO starting rpc-server {"endpoint": "127.0.0.1:0"}
2024-05-08T09:08:22.2323160Z logger.go:146: 2024-05-08T09:07:47.594Z DEBUG done processing headers {"headerIndex": 1, "blockHeight": 0, "took": "0s"}
2024-05-08T09:08:22.2324169Z logger.go:146: 2024-05-08T09:07:47.603Z DEBUG processing rpc request {"method": "getversion", "params": "[]"}
2024-05-08T09:08:22.2325248Z logger.go:146: 2024-05-08T09:07:47.605Z DEBUG processing rpc request {"method": "getnativecontracts", "params": "[]"}
2024-05-08T09:08:22.2326265Z logger.go:146: 2024-05-08T09:07:47.632Z DEBUG processing rpc request {"method": "getversion", "params": "[]"}
2024-05-08T09:08:22.2327698Z logger.go:146: 2024-05-08T09:07:47.633Z DEBUG processing rpc request {"method": "getnep17balances", "params": "[56c989e76f9a2ca05bb5caa6c96f524d905accd8]"}
2024-05-08T09:08:22.2330088Z logger.go:146: 2024-05-08T09:07:47.633Z DEBUG processing rpc request {"method": "invokescript", "params": "[CxEMFNwk6EPkEi4hj1BzCuuZPYVDDVeBDBTYzFqQTVJvyabKtVugLJpv54nJVhTAHwwIdHJhbnNmZXIMFPVj6kC8KD1NDgXEjqMFs/Kgc0DvQWJ9W1I5 ]"}
2024-05-08T09:08:22.2331122Z logger.go:146: 2024-05-08T09:07:47.633Z DEBUG processing rpc request {"method": "getblockcount", "params": "[]"}
2024-05-08T09:08:22.2335986Z logger.go:146: 2024-05-08T09:07:47.633Z DEBUG processing rpc request {"method": "calculatenetworkfee", "params": "[AD4acEq0P5gAAAAAAAAAAAAAAAAABAAAAAHYzFqQTVJvyabKtVugLJpv54nJVgEAVwsRDBTcJOhD5BIuIY9QcwrrmT2FQw1XgQwU2MxakE1Sb8mmyrVboCyab+eJyVYUwB8MCHRyYW5zZmVyDBT1Y+pAvCg9TQ4FxI6jBbPyoHNA70FifVtSOQEAKhEMIQKzYiv0AXvf4xfFiu1fTHU/IGt9uJYEb6fXdLvEv3+NwhFBntDcOg==]"}
2024-05-08T09:08:22.2341614Z logger.go:146: 2024-05-08T09:07:47.633Z DEBUG processing rpc request {"method": "sendrawtransaction", "params": "[AD4acEq0P5gAAAAAAPTGEgAAAAAABAAAAAHYzFqQTVJvyabKtVugLJpv54nJVgEAVwsRDBTcJOhD5BIuIY9QcwrrmT2FQw1XgQwU2MxakE1Sb8mmyrVboCyab+eJyVYUwB8MCHRyYW5zZmVyDBT1Y+pAvCg9TQ4FxI6jBbPyoHNA70FifVtSOQFCDEBrQnkAx5HxqAGepLektz7lxOwSRPwDAF/wyED97Y/B020sWCDBJobg5Tz0PC7gZb8m/o2mNzTu8tAK3qGEW1QBKhEMIQKzYiv0AXvf4xfFiu1fTHU/IGt9uJYEb6fXdLvEv3+NwhFBntDcOg==]"}
2024-05-08T09:08:22.2342973Z logger.go:146: 2024-05-08T09:07:47.671Z DEBUG processing rpc request {"method": "getversion", "params": "[]"}
2024-05-08T09:08:22.2344073Z logger.go:146: 2024-05-08T09:07:47.671Z DEBUG processing rpc request {"method": "getnativecontracts", "params": "[]"}
2024-05-08T09:08:22.2345095Z logger.go:146: 2024-05-08T09:07:47.680Z DEBUG processing rpc request {"method": "getversion", "params": "[]"}
2024-05-08T09:08:22.2346755Z logger.go:146: 2024-05-08T09:07:47.688Z DEBUG processing rpc request {"method": "getrawtransaction", "params": "[9f553994099d9369947f1ac8eb691c850be9ef57757a4b72933a27f9ff17b520 1]"}
2024-05-08T09:08:22.2348018Z logger.go:146: 2024-05-08T09:07:47.689Z DEBUG processing rpc request {"method": "invokescript", "params": "[QA== ]"}
2024-05-08T09:08:22.2349105Z logger.go:146: 2024-05-08T09:07:47.689Z DEBUG processing rpc request {"method": "getblockcount", "params": "[]"}
2024-05-08T09:08:22.2350250Z logger.go:146: 2024-05-08T09:07:47.689Z DEBUG done processing headers {"headerIndex": 2, "blockHeight": 1, "took": "0s"}
2024-05-08T09:08:22.2353244Z logger.go:146: 2024-05-08T09:07:47.689Z DEBUG processing rpc request {"method": "calculatenetworkfee", "params": "[AP/qdYsAAAAAAAAAAAAAAAAAAAAABAAAAAHYzFqQTVJvyabKtVugLJpv54nJVgEBISC1F//5JzqTckt6dVfv6QuFHGnryBp/lGmTnQmUOVWfAUABACoRDCECs2Ir9AF73+MXxYrtX0x1PyBrfbiWBG+n13S7xL9/jcIRQZ7Q3Do=]"}
2024-05-08T09:08:22.2357305Z logger.go:146: 2024-05-08T09:07:47.689Z DEBUG processing rpc request {"method": "sendrawtransaction", "params": "[AP/qdYsAAAAAAAAAAPXGEgAAAAAABAAAAAHYzFqQTVJvyabKtVugLJpv54nJVgEBISC1F//5JzqTckt6dVfv6QuFHGnryBp/lGmTnQmUOVWfAUABQgxAbTeKQfsJttEma5wY1AtY7flmJ5WVPtyyLOoPkS/eH6tH6jnJCph9kiVyQmw4ocZsg7k6GLEHYaJy0JuRniezHSoRDCECs2Ir9AF73+MXxYrtX0x1PyBrfbiWBG+n13S7xL9/jcIRQZ7Q3Do=]"}
2024-05-08T09:08:22.2362840Z logger.go:146: 2024-05-08T09:07:47.693Z INFO Error encountered with rpc request {"code": -507, "cause": "invalid attribute: conflicting transaction 9f553994099d9369947f1ac8eb691c850be9ef57757a4b72933a27f9ff17b520 is already on chain", "method": "sendrawtransaction", "params": "[AP/qdYsAAAAAAAAAAPXGEgAAAAAABAAAAAHYzFqQTVJvyabKtVugLJpv54nJVgEBISC1F//5JzqTckt6dVfv6QuFHGnryBp/lGmTnQmUOVWfAUABQgxAbTeKQfsJttEma5wY1AtY7flmJ5WVPtyyLOoPkS/eH6tH6jnJCph9kiVyQmw4ocZsg7k6GLEHYaJy0JuRniezHSoRDCECs2Ir9AF73+MXxYrtX0x1PyBrfbiWBG+n13S7xL9/jcIRQZ7Q3Do=]"}
2024-05-08T09:08:22.2364530Z failed to send conflicting transaction: Invalid transaction attribute (-507) - invalid attribute: conflicting transaction 9f553994099d9369947f1ac8eb691c850be9ef57757a4b72933a27f9ff17b520 is already on chain
2024-05-08T09:08:22.2366831Z util_test.go:190: unexpected error: failed to send conflicting transaction: Invalid transaction attribute (-507) - invalid attribute: conflicting transaction 9f553994099d9369947f1ac8eb691c850be9ef57757a4b72933a27f9ff17b520 is already on chain
2024-05-08T09:08:22.2368084Z logger.go:146: 2024-05-08T09:07:47.694Z INFO shutting down RPC server {"endpoint": "127.0.0.1:49789"}
2024-05-08T09:08:22.2369385Z logger.go:146: 2024-05-08T09:07:47.696Z INFO persisted to disk {"blocks": 2, "keys": 166, "headerHeight": 2, "blockHeight": 2, "took": "0s"}
2024-05-08T09:08:22.2369695Z --- FAIL: TestAwaitUtilCancelTx (0.11s)
2024-05-08T09:08:22.2369812Z FAIL
2024-05-08T09:08:22.2370165Z FAIL github.com/nspcc-dev/neo-go/cli/util 0.598s
another case https://github.com/nspcc-dev/neo-go/actions/runs/10307310424/job/28532327039?pr=3540
2024-08-08T18:19:00.8208350Z === RUN TestAwaitUtilCancelTx
2024-08-08T18:19:00.8212600Z logger.go:146: 2024-08-08T18:17:10.521Z INFO initial gas supply is not set or wrong, setting default value {"InitialGASSupply": "52000000"}
2024-08-08T18:19:00.8229150Z logger.go:146: 2024-08-08T18:17:10.521Z INFO P2PNotaryRequestPayloadPool size is not set or wrong, setting default value {"P2PNotaryRequestPayloadPoolSize": 1000}
2024-08-08T18:19:00.8235180Z logger.go:146: 2024-08-08T18:17:10.522Z INFO MaxBlockSize is not set or wrong, setting default value {"MaxBlockSize": 262144}
2024-08-08T18:19:00.8241480Z logger.go:146: 2024-08-08T18:17:10.522Z INFO MaxBlockSystemFee is not set or wrong, setting default value {"MaxBlockSystemFee": 900000000000}
2024-08-08T18:19:00.8247110Z logger.go:146: 2024-08-08T18:17:10.522Z INFO MaxTransactionsPerBlock is not set or wrong, using default value {"MaxTransactionsPerBlock": 512}
2024-08-08T18:19:00.8256090Z logger.go:146: 2024-08-08T18:17:10.522Z INFO MaxValidUntilBlockIncrement is not set or wrong, using default value {"MaxValidUntilBlockIncrement": 864000}
2024-08-08T18:19:00.8261930Z logger.go:146: 2024-08-08T18:17:10.524Z INFO no storage version found! creating genesis block
2024-08-08T18:19:00.8267840Z logger.go:146: 2024-08-08T18:17:10.535Z INFO MaxIteratorResultItems is not set or wrong, setting default value {"MaxIteratorResultItems": 100}
2024-08-08T18:19:00.8273140Z logger.go:146: 2024-08-08T18:17:10.535Z INFO MaxFindResultItems is not set or wrong, setting default value {"MaxFindResultItems": 100}
2024-08-08T18:19:00.8277590Z logger.go:146: 2024-08-08T18:17:10.535Z INFO MaxFindStorageResultItems is not set or wrong, setting default value {"MaxFindStorageResultItems": 50}
2024-08-08T18:19:00.8281420Z logger.go:146: 2024-08-08T18:17:10.535Z INFO MaxNEP11Tokens is not set or wrong, setting default value {"MaxNEP11Tokens": 100}
2024-08-08T18:19:00.8288680Z logger.go:146: 2024-08-08T18:17:10.536Z INFO MaxRequestBodyBytes is not set or wong, setting default value {"MaxRequestBodyBytes": 5242880}
2024-08-08T18:19:00.8293490Z logger.go:146: 2024-08-08T18:17:10.536Z INFO MaxRequestHeaderBytes is not set or wong, setting default value {"MaxRequestHeaderBytes": 1048576}
2024-08-08T18:19:00.8298130Z logger.go:146: 2024-08-08T18:17:10.536Z INFO MaxWebSocketClients is not set or wrong, setting default value {"MaxWebSocketClients": 64}
2024-08-08T18:19:00.8301870Z logger.go:146: 2024-08-08T18:17:10.536Z INFO starting rpc-server {"endpoint": "127.0.0.1:0"}
2024-08-08T18:19:00.8305040Z logger.go:146: 2024-08-08T18:17:10.539Z DEBUG done processing headers {"headerIndex": 1, "blockHeight": 0, "took": "2.154739ms"}
2024-08-08T18:19:00.8308640Z logger.go:146: 2024-08-08T18:17:10.550Z DEBUG processing rpc request {"method": "getversion", "params": "[]"}
2024-08-08T18:19:00.8314000Z logger.go:146: 2024-08-08T18:17:10.553Z DEBUG processing rpc request {"method": "getnativecontracts", "params": "[]"}
2024-08-08T18:19:00.8318240Z logger.go:146: 2024-08-08T18:17:10.580Z DEBUG processing rpc request {"method": "getversion", "params": "[]"}
2024-08-08T18:19:00.8327050Z logger.go:146: 2024-08-08T18:17:10.584Z DEBUG processing rpc request {"method": "getnep17balances", "params": "[56c989e76f9a2ca05bb5caa6c96f524d905accd8]"}
2024-08-08T18:19:00.8335110Z logger.go:146: 2024-08-08T18:17:10.591Z DEBUG processing rpc request {"method": "invokescript", "params": "[CxEMFNwk6EPkEi4hj1BzCuuZPYVDDVeBDBTYzFqQTVJvyabKtVugLJpv54nJVhTAHwwIdHJhbnNmZXIMFPVj6kC8KD1NDgXEjqMFs/Kgc0DvQWJ9W1I5 ]"}
2024-08-08T18:19:00.8339630Z logger.go:146: 2024-08-08T18:17:10.595Z DEBUG processing rpc request {"method": "getblockcount", "params": "[]"}
2024-08-08T18:19:00.8348750Z logger.go:146: 2024-08-08T18:17:10.597Z DEBUG processing rpc request {"method": "calculatenetworkfee", "params": "[ACOrONu0P5gAAAAAAAAAAAAAAAAABAAAAAHYzFqQTVJvyabKtVugLJpv54nJVgEAVwsRDBTcJOhD5BIuIY9QcwrrmT2FQw1XgQwU2MxakE1Sb8mmyrVboCyab+eJyVYUwB8MCHRyYW5zZmVyDBT1Y+pAvCg9TQ4FxI6jBbPyoHNA70FifVtSOQEAKhEMIQKzYiv0AXvf4xfFiu1fTHU/IGt9uJYEb6fXdLvEv3+NwhFBntDcOg==]"}
2024-08-08T18:19:00.8369430Z logger.go:146: 2024-08-08T18:17:10.599Z DEBUG processing rpc request {"method": "sendrawtransaction", "params": "[ACOrONu0P5gAAAAAAPTGEgAAAAAABAAAAAHYzFqQTVJvyabKtVugLJpv54nJVgEAVwsRDBTcJOhD5BIuIY9QcwrrmT2FQw1XgQwU2MxakE1Sb8mmyrVboCyab+eJyVYUwB8MCHRyYW5zZmVyDBT1Y+pAvCg9TQ4FxI6jBbPyoHNA70FifVtSOQFCDEC1+o1zrRu3umEVFbAdPcbX+PaWE0aIWxhNPhuaLToN9yaj07ahcTsFYRY/ktqiFkeux/717j7u1UH4ez5KLU6fKhEMIQKzYiv0AXvf4xfFiu1fTHU/IGt9uJYEb6fXdLvEv3+NwhFBntDcOg==]"}
2024-08-08T18:19:00.8380770Z logger.go:146: 2024-08-08T18:17:10.613Z DEBUG processing rpc request {"method": "getversion", "params": "[]"}
2024-08-08T18:19:00.8384640Z logger.go:146: 2024-08-08T18:17:10.616Z DEBUG processing rpc request {"method": "getnativecontracts", "params": "[]"}
2024-08-08T18:19:00.8388820Z logger.go:146: 2024-08-08T18:17:10.641Z DEBUG processing rpc request {"method": "getversion", "params": "[]"}
2024-08-08T18:19:00.8394090Z logger.go:146: 2024-08-08T18:17:10.643Z DEBUG done processing headers {"headerIndex": 2, "blockHeight": 1, "took": "423.528µs"}
2024-08-08T18:19:00.8410120Z logger.go:146: 2024-08-08T18:17:10.644Z DEBUG processing rpc request {"method": "getrawtransaction", "params": "[3643e7cf105af1b495dd0ed493f5552b218e7c903b57f6e8bb530d9bdaf50d63 1]"}
2024-08-08T18:19:00.8413960Z logger.go:146: 2024-08-08T18:17:10.647Z DEBUG processing rpc request {"method": "invokescript", "params": "[QA== ]"}
2024-08-08T18:19:00.8418560Z logger.go:146: 2024-08-08T18:17:10.649Z DEBUG processing rpc request {"method": "getblockcount", "params": "[]"}
2024-08-08T18:19:00.8429140Z logger.go:146: 2024-08-08T18:17:10.650Z DEBUG processing rpc request {"method": "calculatenetworkfee", "params": "[AIMR4EYAAAAAAAAAAAAAAAAAAAAABQAAAAHYzFqQTVJvyabKtVugLJpv54nJVgEBIWMN9dqbDVO76PZXO5B8jiErVfWT1A7dlbTxWhDP50M2AUABACoRDCECs2Ir9AF73+MXxYrtX0x1PyBrfbiWBG+n13S7xL9/jcIRQZ7Q3Do=]"}
2024-08-08T18:19:00.8442270Z logger.go:146: 2024-08-08T18:17:10.653Z DEBUG processing rpc request {"method": "sendrawtransaction", "params": "[AIMR4EYAAAAAAAAAAPXGEgAAAAAABAAAAAHYzFqQTVJvyabKtVugLJpv54nJVgEBIWMN9dqbDVO76PZXO5B8jiErVfWT1A7dlbTxWhDP50M2AUABQgxAaVR9dcllz/JrKOdJYBHowv9BHGGWkS4efWZYkRGqDCGQMjrciRxYBXbM3rudJ/eqzlgylL9pDvTL08umau2KxioRDCECs2Ir9AF73+MXxYrtX0x1PyBrfbiWBG+n13S7xL9/jcIRQZ7Q3Do=]"}
2024-08-08T18:19:00.8461900Z logger.go:146: 2024-08-08T18:17:10.654Z INFO Error encountered with rpc request {"code": -507, "cause": "invalid attribute: conflicting transaction 3643e7cf105af1b495dd0ed493f5552b218e7c903b57f6e8bb530d9bdaf50d63 is already on chain", "method": "sendrawtransaction", "params": "[AIMR4EYAAAAAAAAAAPXGEgAAAAAABAAAAAHYzFqQTVJvyabKtVugLJpv54nJVgEBIWMN9dqbDVO76PZXO5B8jiErVfWT1A7dlbTxWhDP50M2AUABQgxAaVR9dcllz/JrKOdJYBHowv9BHGGWkS4efWZYkRGqDCGQMjrciRxYBXbM3rudJ/eqzlgylL9pDvTL08umau2KxioRDCECs2Ir9AF73+MXxYrtX0x1PyBrfbiWBG+n13S7xL9/jcIRQZ7Q3Do=]"}
2024-08-08T18:19:00.8481270Z failed to send conflicting transaction: Invalid transaction attribute (-507) - invalid attribute: conflicting transaction 3643e7cf105af1b495dd0ed493f5552b218e7c903b57f6e8bb530d9bdaf50d63 is already on chain
2024-08-08T18:19:00.8523420Z util_test.go:190: unexpected error: failed to send conflicting transaction: Invalid transaction attribute (-507) - invalid attribute: conflicting transaction 3643e7cf105af1b495dd0ed493f5552b218e7c903b57f6e8bb530d9bdaf50d63 is already on chain
2024-08-08T18:19:00.8534590Z logger.go:146: 2024-08-08T18:17:10.655Z INFO shutting down RPC server {"endpoint": "127.0.0.1:49268"}
2024-08-08T18:19:00.8544660Z logger.go:146: 2024-08-08T18:17:10.656Z INFO persisted to disk {"blocks": 2, "keys": 166, "headerHeight": 2, "blockHeight": 2, "took": "151.347µs"}
2024-08-08T18:19:00.8548850Z --- FAIL: TestAwaitUtilCancelTx (0.14s)
2024-08-08T18:19:00.8551280Z FAIL
2024-08-08T18:19:00.8552360Z FAIL github.com/nspcc-dev/neo-go/cli/util 1.010s
https://github.com/nspcc-dev/neo-go/actions/runs/10572280163/job/29289709040?pr=3563
2024-11-15T12:03:28.8462286Z === RUN TestAwaitUtilCancelTx
2024-11-15T12:03:28.8464208Z logger.go:146: 2024-11-15T12:02:58.898Z INFO initial gas supply is not set or wrong, setting default value {"InitialGASSupply": "52000000"}
2024-11-15T12:03:28.8467076Z logger.go:146: 2024-11-15T12:02:58.898Z INFO P2PNotaryRequestPayloadPool size is not set or wrong, setting default value {"P2PNotaryRequestPayloadPoolSize": 1000}
2024-11-15T12:03:28.8469714Z logger.go:146: 2024-11-15T12:02:58.898Z INFO MaxBlockSize is not set or wrong, setting default value {"MaxBlockSize": 262144}
2024-11-15T12:03:28.8472267Z logger.go:146: 2024-11-15T12:02:58.898Z INFO MaxBlockSystemFee is not set or wrong, setting default value {"MaxBlockSystemFee": 900000000000}
2024-11-15T12:03:28.8474981Z logger.go:146: 2024-11-15T12:02:58.898Z INFO MaxTransactionsPerBlock is not set or wrong, using default value {"MaxTransactionsPerBlock": 512}
2024-11-15T12:03:28.8477759Z logger.go:146: 2024-11-15T12:02:58.898Z INFO MaxValidUntilBlockIncrement is not set or wrong, using default value {"MaxValidUntilBlockIncrement": 864000}
2024-11-15T12:03:28.8480468Z logger.go:146: 2024-11-15T12:02:58.899Z INFO no storage version found! creating genesis block
2024-11-15T12:03:28.8483226Z logger.go:146: 2024-11-15T12:02:58.907Z INFO MaxIteratorResultItems is not set or wrong, setting default value {"MaxIteratorResultItems": 100}
2024-11-15T12:03:28.8486910Z logger.go:146: 2024-11-15T12:02:58.907Z INFO MaxFindResultItems is not set or wrong, setting default value {"MaxFindResultItems": 100}
2024-11-15T12:03:28.8490285Z logger.go:146: 2024-11-15T12:02:58.907Z INFO MaxFindStorageResultItems is not set or wrong, setting default value {"MaxFindStorageResultItems": 50}
2024-11-15T12:03:28.8493361Z logger.go:146: 2024-11-15T12:02:58.907Z INFO MaxNEP11Tokens is not set or wrong, setting default value {"MaxNEP11Tokens": 100}
2024-11-15T12:03:28.8496815Z logger.go:146: 2024-11-15T12:02:58.907Z INFO MaxRequestBodyBytes is not set or wong, setting default value {"MaxRequestBodyBytes": 5242880}
2024-11-15T12:03:28.8500076Z logger.go:146: 2024-11-15T12:02:58.907Z INFO MaxRequestHeaderBytes is not set or wong, setting default value {"MaxRequestHeaderBytes": 1048576}
2024-11-15T12:03:28.8503205Z logger.go:146: 2024-11-15T12:02:58.907Z INFO MaxWebSocketClients is not set or wrong, setting default value {"MaxWebSocketClients": 64}
2024-11-15T12:03:28.8505888Z logger.go:146: 2024-11-15T12:02:58.908Z INFO starting rpc-server {"endpoint": "127.0.0.1:0"}
2024-11-15T12:03:28.8508476Z logger.go:146: 2024-11-15T12:02:58.909Z DEBUG done processing headers {"headerIndex": 1, "blockHeight": 0, "took": "379.161µs"}
2024-11-15T12:03:28.8511059Z logger.go:146: 2024-11-15T12:02:58.944Z DEBUG processing rpc request {"method": "getversion", "params": "[]"}
2024-11-15T12:03:28.8513861Z logger.go:146: 2024-11-15T12:02:58.945Z DEBUG processing rpc request {"method": "getnativecontracts", "params": "[]"}
2024-11-15T12:03:28.8516404Z logger.go:146: 2024-11-15T12:02:58.958Z DEBUG processing rpc request {"method": "getversion", "params": "[]"}
2024-11-15T12:03:28.8519445Z logger.go:146: 2024-11-15T12:02:58.966Z DEBUG processing rpc request {"method": "getnep17balances", "params": "[56c989e76f9a2ca05bb5caa6c96f524d905accd8]"}
2024-11-15T12:03:28.8524582Z logger.go:146: 2024-11-15T12:02:58.968Z DEBUG processing rpc request {"method": "invokescript", "params": "[CxEMFNwk6EPkEi4hj1BzCuuZPYVDDVeBDBTYzFqQTVJvyabKtVugLJpv54nJVhTAHwwIdHJhbnNmZXIMFPVj6kC8KD1NDgXEjqMFs/Kgc0DvQWJ9W1I5 ]"}
2024-11-15T12:03:28.8527920Z logger.go:146: 2024-11-15T12:02:58.970Z DEBUG processing rpc request {"method": "getblockcount", "params": "[]"}
2024-11-15T12:03:28.8534222Z logger.go:146: 2024-11-15T12:02:58.971Z DEBUG processing rpc request {"method": "calculatenetworkfee", "params": "[AHyTdXm0P5gAAAAAAAAAAAAAAAAABAAAAAHYzFqQTVJvyabKtVugLJpv54nJVgEAVwsRDBTcJOhD5BIuIY9QcwrrmT2FQw1XgQwU2MxakE1Sb8mmyrVboCyab+eJyVYUwB8MCHRyYW5zZmVyDBT1Y+pAvCg9TQ4FxI6jBbPyoHNA70FifVtSOQEAKhEMIQKzYiv0AXvf4xfFiu1fTHU/IGt9uJYEb6fXdLvEv3+NwhFBntDcOg==]"}
2024-11-15T12:03:28.8546139Z logger.go:146: 2024-11-15T12:02:58.973Z DEBUG processing rpc request {"method": "sendrawtransaction", "params": "[AHyTdXm0P5gAAAAAAPTGEgAAAAAABAAAAAHYzFqQTVJvyabKtVugLJpv54nJVgEAVwsRDBTcJOhD5BIuIY9QcwrrmT2FQw1XgQwU2MxakE1Sb8mmyrVboCyab+eJyVYUwB8MCHRyYW5zZmVyDBT1Y+pAvCg9TQ4FxI6jBbPyoHNA70FifVtSOQFCDEBm4q1lv2+lm0zurhn/vEwQO3HSGKu8MRxOqsX1qCbrmDUMAiI1lGe71nveRyCi6OBnMgSU7WN3SE1ntdssNrnTKhEMIQKzYiv0AXvf4xfFiu1fTHU/IGt9uJYEb6fXdLvEv3+NwhFBntDcOg==]"}
2024-11-15T12:03:28.8562552Z logger.go:146: 2024-11-15T12:02:58.984Z DEBUG processing rpc request {"method": "getversion", "params": "[]"}
2024-11-15T12:03:28.8564894Z logger.go:146: 2024-11-15T12:02:58.987Z DEBUG processing rpc request {"method": "getnativecontracts", "params": "[]"}
2024-11-15T12:03:28.8567029Z logger.go:146: 2024-11-15T12:02:59.001Z DEBUG processing rpc request {"method": "getversion", "params": "[]"}
2024-11-15T12:03:28.8569817Z logger.go:146: 2024-11-15T12:02:59.002Z DEBUG processing rpc request {"method": "getrawtransaction", "params": "[7bfcffe12e94cd6516f06f9aef251d2d746e70ef792fd83a5fc1db4c1255b2de 1]"}
2024-11-15T12:03:28.8572544Z logger.go:146: 2024-11-15T12:02:59.004Z DEBUG processing rpc request {"method": "invokescript", "params": "[QA== ]"}
2024-11-15T12:03:28.8574755Z logger.go:146: 2024-11-15T12:02:59.005Z DEBUG processing rpc request {"method": "getblockcount", "params": "[]"}
2024-11-15T12:03:28.8579134Z logger.go:146: 2024-11-15T12:02:59.006Z DEBUG processing rpc request {"method": "calculatenetworkfee", "params": "[AEkx3owAAAAAAAAAAAAAAAAAAAAABAAAAAHYzFqQTVJvyabKtVugLJpv54nJVgEBId6yVRJM28FfOtgvee9wbnQtHSXvmm/wFmXNlC7h//x7AUABACoRDCECs2Ir9AF73+MXxYrtX0x1PyBrfbiWBG+n13S7xL9/jcIRQZ7Q3Do=]"}
2024-11-15T12:03:28.8583165Z logger.go:146: 2024-11-15T12:02:59.008Z DEBUG done processing headers {"headerIndex": 2, "blockHeight": 1, "took": "300.975µs"}
2024-11-15T12:03:28.8588856Z logger.go:146: 2024-11-15T12:02:59.010Z DEBUG processing rpc request {"method": "sendrawtransaction", "params": "[AEkx3owAAAAAAAAAAPXGEgAAAAAABAAAAAHYzFqQTVJvyabKtVugLJpv54nJVgEBId6yVRJM28FfOtgvee9wbnQtHSXvmm/wFmXNlC7h//x7AUABQgxAJ/uJSVCZLUaq39rQLRDGmpKZWZnG+urCCwXAEy6IPYPVp4xOK03HTKBgGmGBhYkd8dOS//Dm1bY9fZTbonIduioRDCECs2Ir9AF73+MXxYrtX0x1PyBrfbiWBG+n13S7xL9/jcIRQZ7Q3Do=]"}
2024-11-15T12:03:28.8598463Z logger.go:146: 2024-11-15T12:02:59.011Z INFO Error encountered with rpc request {"code": -507, "cause": "invalid attribute: conflicting transaction 7bfcffe12e94cd6516f06f9aef251d2d746e70ef792fd83a5fc1db4c1255b2de is already on chain", "method": "sendrawtransaction", "params": "[AEkx3owAAAAAAAAAAPXGEgAAAAAABAAAAAHYzFqQTVJvyabKtVugLJpv54nJVgEBId6yVRJM28FfOtgvee9wbnQtHSXvmm/wFmXNlC7h//x7AUABQgxAJ/uJSVCZLUaq39rQLRDGmpKZWZnG+urCCwXAEy6IPYPVp4xOK03HTKBgGmGBhYkd8dOS//Dm1bY9fZTbonIduioRDCECs2Ir9AF73+MXxYrtX0x1PyBrfbiWBG+n13S7xL9/jcIRQZ7Q3Do=]"}
2024-11-15T12:03:28.8605149Z failed to send conflicting transaction: Invalid transaction attribute (-507) - invalid attribute: conflicting transaction 7bfcffe12e94cd6516f06f9aef251d2d746e70ef792fd83a5fc1db4c1255b2de is already on chain
2024-11-15T12:03:28.8609354Z util_test.go:190: unexpected error: failed to send conflicting transaction: Invalid transaction attribute (-507) - invalid attribute: conflicting transaction 7bfcffe12e94cd6516f06f9aef251d2d746e70ef792fd83a5fc1db4c1255b2de is already on chain
2024-11-15T12:03:28.8612441Z logger.go:146: 2024-11-15T12:02:59.011Z INFO shutting down RPC server {"endpoint": "127.0.0.1:39475"}
2024-11-15T12:03:28.8614745Z logger.go:146: 2024-11-15T12:02:59.012Z INFO persisted to disk {"blocks": 2, "keys": 166, "headerHeight": 2, "blockHeight": 2, "took": "92.503µs"}
2024-11-15T12:03:28.8616197Z --- FAIL: TestAwaitUtilCancelTx (0.12s)