Test Failure: sync_call_restart #1853
Description
Need to see why the test takes so long.
https://github.com/AntelopeIO/spring/actions/runs/17267148663/job/49002895936
debug 2025-08-27T13:04:32.382 nodeos producer_plugin.cpp:2522 log_trx_results ] [TRX_TRACE] Speculative execution is REJECTING tx: 271bdb59c71da6301c5af33bcf2e934908bf475dae48aa4c1e873504e1e7dc52, auth: caller : action: caller:nestedcalls, transaction 271bdb59c71da6301c5af33bcf2e934908bf475dae48aa4c1e873504e1e7dc52 was executing for too long 375066us reached on chain max_transaction_cpu_usage 375000us
debug 2025-08-27T13:04:32.383 nodeos producer_plugin.cpp:2527 log_trx_results ] [TRX_TRACE] Speculative execution is REJECTING tx: {"expiration":"2025-08-27T13:05:01","ref_block_num":208,"ref_block_prefix":432702678,"max_net_usage_words":0,"max_cpu_usage_ms":0,"delay_sec":0,"context_free_actions":[],"actions":[{"account":"caller","name":"nestedcalls","authorization":[{"actor":"caller","permission":"active"}],"data":{"size":0,"hex":""},"hex_data":{"size":0,"hex":""}}]}
debug 2025-08-27T13:04:32.383 nodeos producer_plugin.cpp:2529 log_trx_results ] [TRX_TRACE] Speculative execution is REJECTING tx: {"id":"271bdb59c71da6301c5af33bcf2e934908bf475dae48aa4c1e873504e1e7dc52","block_num":211,"block_time":"2025-08-27T13:04:32.500","producer_block_id":null,"receipt":null,"elapsed":375144,"net_usage":92,"scheduled":false,"action_traces":[{"action_ordinal":1,"creator_action_ordinal":0,"closest_unnotified_ancestor_action_ordinal":0,"receipt":null,"receiver":"caller","act":{"account":"caller","name":"nestedcalls","authorization":[{"actor":"caller","permission":"active"}],"data":{"size":0,"hex":""},"hex_data":{"size":0,"hex":""}},"context_free":false,"elapsed":375058,"console":"","trx_id":"271bdb59c71da6301c5af33bcf2e934908bf475dae48aa4c1e873504e1e7dc52","block_num":211,"block_time":"2025-08-27T13:04:32.500","producer_block_id":null,"account_ram_deltas":[],"except":{"code":3080004,"name":"tx_cpu_usage_exceeded","message":"Transaction exceeded the current CPU usage limit imposed on the transaction","stack":[{"context":{"level":"error","file":"transaction_context.cpp","line":530,"method":"checktime","hostname":"","thread_name":"nodeos","timestamp":"2025-08-27T13:04:32.382"},"format":"transaction ${id} was executing for too long ${billing_timer}us reached on chain max_transaction_cpu_usage ${limit}us","data":{"id":"271bdb59c71da6301c5af33bcf2e934908bf475dae48aa4c1e873504e1e7dc52","billing_timer":375066,"subjective":0,"limit":375000}},{"context":{"level":"warn","file":"apply_context.cpp","line":131,"method":"exec_one","hostname":"","thread_name":"nodeos","timestamp":"2025-08-27T13:04:32.382"},"format":"${receiver} <= ${account}::${action} console output: ${console}","data":{"console":"","account":"caller","action":"nestedcalls","receiver":"caller"}}]},"error_code":"10000000000000000000","return_value_hex_data":"","call_traces":[],"console_markers":[]}],"account_ram_delta":null,"except":{"code":3080004,"name":"tx_cpu_usage_exceeded","message":"Transaction exceeded the current CPU usage limit imposed on the transaction","stack":[{"context":{"level":"error","file":"transaction_context.cpp","line":530,"method":"checktime","hostname":"","thread_name":"nodeos","timestamp":"2025-08-27T13:04:32.382"},"format":"transaction ${id} was executing for too long ${billing_timer}us reached on chain max_transaction_cpu_usage ${limit}us","data":{"id":"271bdb59c71da6301c5af33bcf2e934908bf475dae48aa4c1e873504e1e7dc52","billing_timer":375066,"subjective":0,"limit":375000}},{"context":{"level":"warn","file":"apply_context.cpp","line":131,"method":"exec_one","hostname":"","thread_name":"nodeos","timestamp":"2025-08-27T13:04:32.382"},"format":"${receiver} <= ${account}::${action} console output: ${console}","data":{"console":"","account":"caller","action":"nestedcalls","receiver":"caller"}}]},"error_code":"10000000000000000000"}