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

[mgpai22]

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

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

[greenhat]

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

[greenhat]

Does the error go away if you return default values?

[mgpai22]

Will need to try. Will let you know.

[spireblockchain]

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.

[spireblockchain]

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]

Oracle box height (collected) stalls out during error.

[code-for-uss]

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]

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

[greenhat]

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

[Alesfatalis]

@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