Block height fallback problem report

[MatrixStorage]

Checklist

• [X] This is not a security-related bug/issue. If it is, please follow please follow the security policy.
• [X] This is not a question or a support request. If you have any lotus related questions, please ask in the lotus forum.
• [X] This is not a new feature request. If it is, please file a feature request instead.
• [X] This is not an enhancement request. If it is, please file a improvement suggestion instead.
• [X] I have searched on the issue tracker and the lotus forum, and there is no existing related issue or discussion.
• [X] I am running the Latest release, or the most recent RC(release canadiate) for the upcoming release or the dev branch(master), or have an issue updating to any of these.
• [ ] I did not make any code changes to lotus.

Lotus component

• [X] lotus daemon - chain sync
• [ ] lotus miner - mining and block production
• [ ] lotus miner/worker - sealing
• [ ] lotus miner - proving(WindowPoSt)
• [ ] lotus miner/market - storage deal
• [ ] lotus miner/market - retrieval deal
• [ ] lotus miner/market - data transfer
• [X] lotus client
• [X] lotus JSON-RPC API
• [ ] lotus message management (mpool)
• [ ] Other

Lotus Version

Daemon:  1.13.0+mainnet+git.7a55e8e89.dirty+api1.4.0
Local: lotus version 1.13.0+mainnet+git.7a55e8e89.dirty

Describe the Bug

Error：

failed to set up called handler: called check error (h: 1256818): failed to look up deal on chain: deal 2711201 not found - deal may not have completed sealing before deal proposal start epoch, or deal may have been slashed

Add a block height log to chain/events/events_called.go # 122 to print the block height to be applied: 2021-11-02T17:16:00.919Z INFO events events/events_called.go:123 启动events callled apply,from 1252231 to 1252232 2021-11-02T17:16:00.920Z INFO events events/events_called.go:161 启动events callled revert,from 1252232 to 1252231 2021-11-02T17:16:00.920Z INFO events events/events_called.go:123 启动events callled apply,from 1252231 to 1252232 2021-11-02T17:16:30.637Z INFO events events/events_called.go:123 启动events callled apply,from 1252232 to 1252233 2021-11-02T17:16:30.637Z INFO events events/events_called.go:161 启动events callled revert,from 1252233 to 1252232 2021-11-02T17:16:30.637Z INFO events events/events_called.go:123 启动events callled apply,from 1252232 to 1252233 2021-11-02T17:16:30.637Z INFO events events/events_called.go:161 启动events callled revert,from 1252233 to 1252232 2021-11-02T17:16:30.637Z INFO events events/events_called.go:123 启动events callled apply,from 1252232 to 1252233 2021-11-03T01:32:21.950Z INFO events events/events_called.go:123 启动events callled apply,from 1252231 to 1252232 2021-11-03T01:32:21.950Z INFO events events/events_called.go:161 启动events callled revert,from 1252232 to 1252231 2021-11-03T01:32:36.738Z INFO events events/events_called.go:123 启动events callled apply,from 1252231 to 1252232 2021-11-03T01:32:51.569Z INFO events events/events_called.go:123 启动events callled apply,from 1252232 to 1252233 2021-11-03T01:32:51.569Z INFO events events/events_called.go:161 启动events callled revert,from 1252233 to 1252232 2021-11-03T01:33:06.098Z INFO events events/events_called.go:123 启动events callled apply,from 1252232 to 1252233 2021-11-03T01:33:06.098Z INFO events events/events_called.go:161 启动events callled revert,from 1252233 to 1252232 2021-11-03T01:33:20.606Z INFO events events/events_called.go:123 启动events callled apply,from 1252232 to 1252233

This causes the block height at the current time to go back in time, and when Lotus Client checks whether the deal is consistent, it uses the block height of the past to query the deal, and the deal cannot be found

Logging Information

fil@CH-6:~$ lotus log list
splitstore
beacon
p2p-config
cli
autonat
retrieval-discovery
peerstore/ds
lock
dht.pb
tracing
data-transfer
chunk
boguskey
bs:sprmgr
fsm
partialfile
metrics
paramfetch
miner
data_transfer_network
repo
messagesigner
p2pnode
storageadapter
bs:peermgr
cmds
statemgr
addrutil
wallet
preseal
gs_request_executor
gs-asyncloader
statetree
messagepool
genesis
main
cmds/http
fsjournal
retrievaladapter
node
payment-channel-settler
sectoraccessor
swarm2
reuseport-transport
ffi-wrapper
disputer
nat
pathresolv
gs-traversal
stores
chainstore
providers
alerting
evtsm
mocknet
graphsync_allocator
auth
badger
stream-upgrader
graphsync_network
chain
bs:sess
gs-unverifiedbs
piecestore
rpc
build
storagemrkt
pubsub
dt-chanmon
actors
peermgr
ping
net/conngater
connmgr
modules
importmgr
vm
blockstore
blankhost
dht
dht/RtRefreshManager
net/identify
market_adapter
bitswap
storagemarket_network
fsutil
sectors
providerstates
client
ulimit
conngater
storagemarket_impl
tarutil
ipns
gs-notifications
builder
backupds
relay
filestore
gen
storedask
ffiwrapper
autorelay
tcp-tpt
routedhost
rpcenc
peerstore
types
cliutil
system
hello
watchdog
eventlog
blockservice
dt_graphsync
unixfs
sub
rand
fil-consensus
badgerbs
chainxchg
events
test-logger
discovery
dagstore/upgrader
dt-impl
retrieval_network
incrt
mplex
wallet-ledger
engine
drand
diversityFilter
quic-transport
markets
metrics-prometheus
dagstore
markets-rtvl
markets-rtvl-reval
amt
panic-reporter
storageminer
routing/record
fullnode
table
bitswap_network
paych
basichost
retrievalmarket_impl
retrieval-fsm
retrieval
graphsync
cborrrpc
advmgr

Repo Steps

1. Run '...'
2. Do '...'
3. See error '...' ...

[jennijuju]

What do you mean by This causes the block height at the current time to go back in time, what’s this? the log shown doesn’t seems like to be in default lotus so gonna transfer this to discussion. Also, the error message seems clear to me on why the deal cannot be found.