Race in TestAwaitUtilCancelTx caused by the test design #1274

Open
opened 2025-12-28 17:15:51 +00:00 by sami · 6 comments
Owner

Originally created by @AnnaShaleva on GitHub (Mar 19, 2024).

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
Originally created by @AnnaShaleva on GitHub (Mar 19, 2024). `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 ```
Author
Owner

@AnnaShaleva commented on GitHub (Apr 1, 2024):

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
@AnnaShaleva commented on GitHub (Apr 1, 2024): 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 ```
Author
Owner

@AnnaShaleva commented on GitHub (Apr 3, 2024):

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
@AnnaShaleva commented on GitHub (Apr 3, 2024): 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 ```
Author
Owner

@AnnaShaleva commented on GitHub (May 13, 2024):

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
@AnnaShaleva commented on GitHub (May 13, 2024): 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 ```
Author
Owner

@AliceInHunterland commented on GitHub (Aug 9, 2024):

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
@AliceInHunterland commented on GitHub (Aug 9, 2024): 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 ```
Author
Owner
@roman-khimov commented on GitHub (Aug 27, 2024): https://github.com/nspcc-dev/neo-go/actions/runs/10572280163/job/29289709040?pr=3563
Author
Owner

@AnnaShaleva commented on GitHub (Nov 15, 2024):

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)
@AnnaShaleva commented on GitHub (Nov 15, 2024): ``` 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) ```
Sign in to join this conversation.
No milestone
No project
No assignees
1 participant
Notifications
Due date
The due date is invalid or out of range. Please use the format "yyyy-mm-dd".

No due date set.

Dependencies

No dependencies set.

Reference
nspcc-dev/neo-go#1274
No description provided.