search

ergoplatform / oracle-core

Core off-chain component of Oracle Pools
Apache License 2.0
61 stars 37 forks source link

Failed to found my own oracle box in the filtered posted oracle boxes #318

Open mgpai22 opened 1 year ago

mgpai22 commented 1 year ago

image

Get this issue running the oracle. I have made some custom modifications:

greenhat commented 1 year ago

Are there any other messages in the log. Could you post the log from ~1 hour running the oracle.

greenhat commented 1 year ago

Does the error go away if you return default values?

mgpai22 commented 1 year ago

Will need to try. Will let you know.

spireblockchain commented 1 year ago

Same problem with similar sleep time. I also noticed my claimable GORT didn't increase for a few hours while the error was occurring. I don't know the code/logic well, but perhaps the datapoint isn't being picked up, so not eligible to post refresh or claim any GORT for the period(s) in question? The explorer shows tx (see image). Only running 1 active oracle, so the posting frequency seems a bit strange.

Screenshot 2023-10-17 203324

spireblockchain commented 1 year ago

The error is thrown when trying to post the refresh tx. It then showed the following and posted two datapoint tx about the same time. Maybe once it moves into this error state the same process repeats and it can't self correct without intervention.

... 2023-10-18T01:42:41.301939843+00:00 ERROR oracle_core - error: error on building RefreshAction: failed to found my own oracle box in the filtered posted oracle boxes

Caused by: failed to found my own oracle box in the filtered posted oracle boxes 2023-10-18T01:42:42.485560504+00:00 INFO oracle_core::actions - Datapoint tx published. Check status: https://ergo-explorer.tosidrop.io/en/transactions/c13c2c9de6cf7bd47a5867074c9d67909fba0cba27f02de6ca6f04ef3bad538b 2023-10-18T01:48:53.355043795+00:00 INFO oracle_core::actions - Datapoint tx published. Check status: https://ergo-explorer.tosidrop.io/en/transactions/1905c3ec905d40973d3a99a41af85e1281c28bd6a7d96fdc1a80265d77545707

spireblockchain commented 1 year ago

Oracle box height (collected) stalls out during error.

image

code-for-uss commented 1 year ago

image

Get this issue running the oracle. I have made some custom modifications:

  • Set sleep time to one
  • Set explorer sleep time to five

I have the same issue with the default image!

mgpai22 commented 1 year ago

@greenhat I can confirm this happens with default values as well

greenhat commented 1 year ago

Could anyone please set the log level to DEBUG in oracle config and post the log excerpt before, during, and after the error?

Alesfatalis commented 1 year ago

@greenhat here the logs with DEBUG enabled. I removed duplicated/repeating entries for readability:

2023-11-08T14:30:58.640140801+01:00 DEBUG oracle_core::state - Height 1130145. Live epoch state: LiveEpochState { pool_box_epoch_id: EpochCounter(981), local_datapoint_box_state: Some(Posted { epoch_id: EpochCounter(981), height: BlockHeight(1130118) }), latest_pool_datapoint: Rate(61853158298751), latest_pool_box_height: BlockHeight(1130115) }
2023-11-08T14:30:58.700413375+01:00 DEBUG oracle_core::state - Height 1130146. Live epoch state: LiveEpochState { pool_box_epoch_id: EpochCounter(981), local_datapoint_box_state: Some(Posted { epoch_id: EpochCounter(981), height: BlockHeight(1130118) }), latest_pool_datapoint: Rate(61853158298751), latest_pool_box_height: BlockHeight(1130115) }
2023-11-08T14:30:58.700437942+01:00 DEBUG oracle_core - Height 1130146. Building action for command: Refresh
2023-11-08T14:30:58.878241993+01:00 DEBUG oracle_core::actions - Node rejected tx with error: Double spending attempt
2023-11-08T14:30:58.926800943+01:00 DEBUG oracle_core::state - Height 1130146. Live epoch state: LiveEpochState { pool_box_epoch_id: EpochCounter(981), local_datapoint_box_state: Some(Posted { epoch_id: EpochCounter(981), height: BlockHeight(1130118) }), latest_pool_datapoint: Rate(61853158298751), latest_pool_box_height: BlockHeight(1130115) }
2023-11-08T14:30:58.926823334+01:00 DEBUG oracle_core - Height 1130146. Building action for command: Refresh
2023-11-08T14:30:59.089643466+01:00 DEBUG oracle_core::actions - Node rejected tx with error: Double spending attempt
2023-11-08T14:33:46.839020627+01:00 ERROR oracle_core - error: node error: Node error: The node rejected the request you provided.
Node Response: Malformed transaction: Scripts of all transaction inputs should pass verification. transactionIdRetracted: #1 => Success((false,1233))

Caused by:
    0: Node error: The node rejected the request you provided.
       Node Response: Malformed transaction: Scripts of all transaction inputs should pass verification. transactionIdRetracted: #1 => Success((false,1233))
    1: The node rejected the request you provided.
       Node Response: Malformed transaction: Scripts of all transaction inputs should pass verification. transactionIdRetracted: #1 => Success((false,1233))
2023-11-08T14:33:46.892305256+01:00 DEBUG oracle_core::state - Height 1130148. Live epoch state: LiveEpochState { pool_box_epoch_id: EpochCounter(981), local_datapoint_box_state: Some(Posted { epoch_id: EpochCounter(981), height: BlockHeight(1130118) }), latest_pool_datapoint: Rate(61853158298751), latest_pool_box_height: BlockHeight(1130115) }
2023-11-08T14:33:46.892329633+01:00 DEBUG oracle_core - Height 1130148. Building action for command: Refresh
2023-11-08T14:33:46.991998500+01:00 ERROR oracle_core - error: error on building RefreshAction: failed to found my own oracle box in the filtered posted oracle boxes

Caused by:
    failed to found my own oracle box in the filtered posted oracle boxes
2023-11-08T14:33:47.043105327+01:00 DEBUG oracle_core::state - Height 1130148. Live epoch state: LiveEpochState { pool_box_epoch_id: EpochCounter(981), local_datapoint_box_state: Some(Posted { epoch_id: EpochCounter(981), height: BlockHeight(1130118) }), latest_pool_datapoint: Rate(61853158298751), latest_pool_box_height: BlockHeight(1130115) }
2023-11-08T14:33:47.043129134+01:00 DEBUG oracle_core - Height 1130148. Building action for command: Refresh
2023-11-08T14:33:47.140149444+01:00 ERROR oracle_core - error: error on building RefreshAction: failed to found my own oracle box in the filtered posted oracle boxes

Caused by:
    failed to found my own oracle box in the filtered posted oracle boxes
2023-11-08T14:33:47.192807636+01:00 DEBUG oracle_core::state - Height 1130148. Live epoch state: LiveEpochState { pool_box_epoch_id: EpochCounter(981), local_datapoint_box_state: Some(Posted { epoch_id: EpochCounter(981), height: BlockHeight(1130118) }), latest_pool_datapoint: Rate(61853158298751), latest_pool_box_height: BlockHeight(1130115) }
2023-11-08T14:33:47.192832373+01:00 DEBUG oracle_core - Height 1130148. Building action for command: Refresh
2023-11-08T14:33:47.288948937+01:00 ERROR oracle_core - error: error on building RefreshAction: failed to found my own oracle box in the filtered posted oracle boxes

Caused by:
    failed to found my own oracle box in the filtered posted oracle boxes
2023-11-08T14:34:37.592752892+01:00 DEBUG oracle_core::state - Height 1130149. Live epoch state: LiveEpochState { pool_box_epoch_id: EpochCounter(981), local_datapoint_box_state: Some(Posted { epoch_id: EpochCounter(981), height: BlockHeight(1130118) }), latest_pool_datapoint: Rate(61853158298751), latest_pool_box_height: BlockHeight(1130115) }
2023-11-08T14:34:37.592777321+01:00 DEBUG oracle_core - Height 1130149. Building action for command: PublishSubsequentDataPoint { republish: true }
2023-11-08T14:34:37.912342716+01:00 INFO oracle_core::actions - Datapoint tx published. Check status: https://explorer.ergoplatform.com/en/transactions/transactionIdRetracted
2023-11-08T14:34:37.969571954+01:00 DEBUG oracle_core::state - Height 1130149. Live epoch state: LiveEpochState { pool_box_epoch_id: EpochCounter(981), local_datapoint_box_state: Some(Posted { epoch_id: EpochCounter(981), height: BlockHeight(1130118) }), latest_pool_datapoint: Rate(61853158298751), latest_pool_box_height: BlockHeight(1130115) }
2023-11-08T14:34:37.969595866+01:00 DEBUG oracle_core - Height 1130149. Building action for command: PublishSubsequentDataPoint { republish: true }
2023-11-08T14:34:38.312233695+01:00 DEBUG oracle_core::actions - Node rejected tx with error: Double spending attempt
2023-11-08T14:34:38.359691822+01:00 DEBUG oracle_core::state - Height 1130149. Live epoch state: LiveEpochState { pool_box_epoch_id: EpochCounter(981), local_datapoint_box_state: Some(Posted { epoch_id: EpochCounter(981), height: BlockHeight(1130118) }), latest_pool_datapoint: Rate(61853158298751), latest_pool_box_height: BlockHeight(1130115) }
2023-11-08T14:34:38.359714333+01:00 DEBUG oracle_core - Height 1130149. Building action for command: PublishSubsequentDataPoint { republish: true }
2023-11-08T14:34:38.711312112+01:00 DEBUG oracle_core::actions - Node rejected tx with error: Double spending attempt
2023-11-08T14:34:38.758336509+01:00 DEBUG oracle_core::state - Height 1130149. Live epoch state: LiveEpochState { pool_box_epoch_id: EpochCounter(981), local_datapoint_box_state: Some(Posted { epoch_id: EpochCounter(981), height: BlockHeight(1130118) }), latest_pool_datapoint: Rate(61853158298751), latest_pool_box_height: BlockHeight(1130115) }
2023-11-08T14:34:38.758362625+01:00 DEBUG oracle_core - Height 1130149. Building action for command: PublishSubsequentDataPoint { republish: true }
2023-11-08T14:34:39.176603629+01:00 DEBUG oracle_core::actions - Node rejected tx with error: Double spending attempt