Joinsplit in progress message for one hour

Greetings. I've had a node fail to successfully load to the end of the blockchain a couple of times, requireing me to start over. Now the node has the proper block height, finally, and is validating transactions, but I have the message "Mining paused (a joinsplit may be in progress) for one hour.

I believe this represents an error, as my other nodes have never had this message and a transaction with joinsplit should process much quicker than this, am I correct?

This node has 16 gigs of ram of 8 cpu cores.

What could be the cause of this error and How do I clear this and proceed with mining?

Below are the contents of my debug.log since restarted the node a few minutes ago thinking that might clear the state (it didn't).

2017-06-17 12:56:06 Zcash version v1.0.9 (2017-05-24 14:21:43 -0700)
2017-06-17 12:56:06 Using OpenSSL version OpenSSL 1.1.0d 26 Jan 2017
2017-06-17 12:56:06 Using BerkeleyDB version Berkeley DB 6.2.23: (March 28, 2016)
2017-06-17 12:56:06 Default data directory /home/greg/.zcash
2017-06-17 12:56:06 Using data directory /home/greg/.zcash
2017-06-17 12:56:06 Using config file /home/greg/.zcash/zcash.conf
2017-06-17 12:56:06 Using at most 125 connections (1024 file descriptors available)
2017-06-17 12:56:06 Using 8 threads for script verification
2017-06-17 12:56:06 scheduler thread start
2017-06-17 12:56:06 Loading verifying key from /home/greg/.zcash-params/sprout-verifying.key
2017-06-17 12:56:06 Loaded verifying key in 0.013471s seconds.
2017-06-17 12:56:06 Binding RPC on address ::1 port 8232 failed.
2017-06-17 12:56:06 HTTP: creating work queue of depth 16
2017-06-17 12:56:06 HTTP: starting 4 worker threads
2017-06-17 12:56:06 Using wallet wallet.dat
2017-06-17 12:56:06 CDBEnv::Open: LogDir=/home/greg/.zcash/database ErrorFile=/home/greg/.zcash/db.log
2017-06-17 12:56:06 Bound to [::]:8233
2017-06-17 12:56:06 Bound to 0.0.0.0:8233
2017-06-17 12:56:06 Cache configuration:
2017-06-17 12:56:06 * Using 2.0MiB for block index database
2017-06-17 12:56:06 * Using 32.5MiB for chain state database
2017-06-17 12:56:06 * Using 65.5MiB for in-memory UTXO set
2017-06-17 12:56:06 Opening LevelDB in /home/greg/.zcash/blocks/index
2017-06-17 12:56:06 Opened LevelDB successfully
2017-06-17 12:56:06 Opening LevelDB in /home/greg/.zcash/chainstate
2017-06-17 12:56:07 Opened LevelDB successfully
2017-06-17 12:56:17 LoadBlockIndexDB: last block file = 28
2017-06-17 12:56:17 LoadBlockIndexDB: last block file info: CBlockFileInfo(blocks=3827, size=106456260, heights=129624...133464, time=2017-06-10...2017-06-17)
2017-06-17 12:56:17 Checking all blk files are present...
2017-06-17 12:56:17 LoadBlockIndexDB: transaction index disabled
2017-06-17 12:56:17 LoadBlockIndexDB: hashBestChain=000000002bb9b70137180ca0a41a5986c534641ade5c623e577ea4168f8dd3c2 height=133464 date=2017-06-17 12:55:14 progress=0.999996
2017-06-17 12:56:17 Verifying last 288 blocks at level 3
2017-06-17 12:56:25 No coin database inconsistencies in last 132 blocks (1373 transactions)
2017-06-17 12:56:25 block index 18852ms
2017-06-17 12:56:25 nFileVersion = 1000950
2017-06-17 12:56:25 Keys: 102 plaintext, 0 encrypted, 102 w/ metadata, 102 total
2017-06-17 12:56:25 ZKeys: 0 plaintext, 0 encrypted, 0 w/metadata, 0 total
2017-06-17 12:56:25 wallet 48ms
2017-06-17 12:56:25 Rescanning last 26741 blocks (from block 106723)...
2017-06-17 12:56:33 rescan 7939ms
2017-06-17 12:56:33 SetBestChain(): Failed to write nWitnessCacheSize, aborting atomic write
2017-06-17 12:56:33 mapBlockIndex.size() = 133465
2017-06-17 12:56:33 nBestHeight = 133464
2017-06-17 12:56:33 setKeyPool.size() = 100
2017-06-17 12:56:33 mapWallet.size() = 0
2017-06-17 12:56:33 mapAddressBook.size() = 1
2017-06-17 12:56:33 torcontrol thread start
2017-06-17 12:56:33 Loaded 17521 addresses from peers.dat 181ms
2017-06-17 12:56:33 dnsseed thread start
2017-06-17 12:56:33 net thread start
2017-06-17 12:56:33 addcon thread start
2017-06-17 12:56:33 opencon thread start
2017-06-17 12:56:33 msghand thread start
2017-06-17 12:56:33 ZcashMiner started
2017-06-17 12:56:34 socket recv error Connection reset by peer (104)
2017-06-17 12:56:34 connect() to [2a02:8108:4a40:7874:b08a:622e:319b:c139]:8233 failed: Network is unreachable (101)
2017-06-17 12:56:35 ProcessMessages: advertizing address 71.171.87.73:8233
2017-06-17 12:56:35 receive version message: /MagicBean:1.0.81/: version 170002, blocks=133465, us=71.171.87.73:52287, peer=2
2017-06-17 12:56:35 Added time data, samples 2, offset -1 (+0 minutes)
2017-06-17 12:56:35 AdvertizeLocal: advertizing address 71.171.87.73:8233
2017-06-17 12:56:35 ZcashMiner runtime error: TopUpKeyPool(): writing generated key failed
2017-06-17 12:56:36 UpdateTip: new best=000000001207c056486e9a8f889d743e98c04a1d01c33853e1a064b963d79ae3 height=133465 log2_work=50.049163 tx=736872 date=2017-06-17 12:55:49 progress=0.999997 cache=0.5MiB(1tx)
2017-06-17 12:56:36 ProcessMessages: advertizing address 71.171.87.73:8233
2017-06-17 12:56:36 receive version message: /MagicBean:1.0.81/: version 170002, blocks=133465, us=71.171.87.73:42399, peer=3
2017-06-17 12:56:36 Added time data, samples 3, offset -1 (+0 minutes)
2017-06-17 12:56:36 connect() to [2001:0:9d38:90d7:2cc4:df66:2d0f:6367]:8233 failed: Network is unreachable (101)
2017-06-17 12:56:37 ProcessMessages: advertizing address 71.171.87.73:8233
2017-06-17 12:56:37 receive version message: /MagicBean:1.0.9/: version 170002, blocks=133465, us=71.171.87.73:52226, peer=4
2017-06-17 12:56:37 Added time data, samples 4, offset -1 (+0 minutes)
2017-06-17 12:56:43 connect() to [2001:0:9d38:90d7:3cea:3362:7212:c1d0]:8233 failed: Network is unreachable (101)
2017-06-17 12:56:43 connect() to [2001:0:9d38:953c:105b:16c4:b464:b577]:8233 failed: Network is unreachable (101)
2017-06-17 12:56:44 ProcessMessages: advertizing address 71.171.87.73:8233
2017-06-17 12:56:44 receive version message: /MagicBean:1.0.9/: version 170002, blocks=133465, us=71.171.87.73:37562, peer=5
2017-06-17 12:56:44 Added time data, samples 5, offset -18 (+0 minutes)
2017-06-17 12:56:44 nTimeOffset = -1 (+0 minutes)
2017-06-17 12:56:44 P2P peers available. Skipped DNS seeding.
2017-06-17 12:56:44 dnsseed thread exit
2017-06-17 12:56:44 ProcessMessages: advertizing address 71.171.87.73:8233
2017-06-17 12:56:44 receive version message: /MagicBean:1.0.81/: version 170002, blocks=133465, us=71.171.87.73:35728, peer=6
2017-06-17 12:56:44 Added time data, samples 6, offset +32 (+0 minutes)
2017-06-17 12:56:51 ProcessMessages: advertizing address 71.171.87.73:8233
2017-06-17 12:56:51 receive version message: /MagicBean:1.0.3/: version 170002, blocks=133465, us=71.171.87.73:59968, peer=7
2017-06-17 12:56:51 Added time data, samples 7, offset +0 (+0 minutes)
2017-06-17 12:56:51 nTimeOffset = -1 (+0 minutes)
2017-06-17 12:56:52 ProcessMessages: advertizing address 71.171.87.73:8233
2017-06-17 12:56:52 receive version message: /MagicBean:1.0.9/: version 170002, blocks=133465, us=71.171.87.73:46978, peer=8
2017-06-17 12:56:52 Added time data, samples 8, offset +0 (+0 minutes)
2017-06-17 12:56:53 connect() to [2001:0:9d38:90d7:1456:9e4b:b603:6cb8]:8233 failed: Network is unreachable (101)
2017-06-17 12:56:54 ProcessMessages: advertizing address 71.171.87.73:8233
2017-06-17 12:56:54 receive version message: /MagicBean:1.0.9/: version 170002, blocks=133465, us=71.171.87.73:46218, peer=9
2017-06-17 12:56:54 Added time data, samples 9, offset +0 (+0 minutes)
2017-06-17 12:56:54 nTimeOffset = +0 (+0 minutes)
2017-06-17 12:59:15 UpdateTip: new best=000000002c0eb14d3313b9eeb78d9e40f23841d1a3af23058aca1ef2a58cc298 height=133466 log2_work=50.049189 tx=736894 date=2017-06-17 12:59:05 progress=0.999999 cache=8.9MiB(203tx)
2017-06-17 13:00:19 UpdateTip: new best=0000000015013bbd8843a291c8ee427a6a0680b1f43f7e1338b9008954ddb931 height=133467 log2_work=50.049215 tx=736895 date=2017-06-17 12:59:14 progress=0.999996 cache=9.0MiB(208tx)
2017-06-17 13:00:41 UpdateTip: new best=00000000090198ffbdadc37119d32327282aa5faa889c083e58744f2acd119d6 height=133468 log2_work=50.049242 tx=736900 date=2017-06-17 13:00:19 progress=0.999999 cache=9.0MiB(211tx)
2017-06-17 13:00:47 Pre-allocating up to position 0xa00000 in rev00028.dat
2017-06-17 13:00:47 UpdateTip: new best=0000000003dfc2b0e8b99851de7d2a85d274a3248c85bc0a436e0b9fd28d0a7e height=133469 log2_work=50.049269 tx=736906 date=2017-06-17 13:00:40 progress=1.000000 cache=10.7MiB(269tx)
2017-06-17 13:00:52 UpdateTip: new best=000000002b443c0988750f18c935cd9ff88f7988a0f5ce4b771ef706ef0138bf height=133470 log2_work=50.049295 tx=736907 date=2017-06-17 13:01:19 progress=1.000002 cache=10.7MiB(270tx)
2017-06-17 13:01:22 ERROR: AcceptToMemoryPool: inputs already spent
2017-06-17 13:03:03 UpdateTip: new best=000000001aa7de765ddfde6788634a1024de18ed65e3aaa4500a82121e018d73 height=133471 log2_work=50.049321 tx=736919 date=2017-06-17 13:02:53 progress=0.999999 cache=11.5MiB(307tx)
2017-06-17 13:03:22 ERROR: AcceptToMemoryPool: inputs already spent
2017-06-17 13:05:28 UpdateTip: new best=0000000003bcfe7e21c5193ee5164eb5d6aec8c2bd764e39a54e86530d9aad27 height=133472 log2_work=50.049348 tx=736921 date=2017-06-17 13:03:03 progress=0.999990 cache=14.7MiB(377tx)
2017-06-17 13:05:50 UpdateTip: new best=000000000d1747e8e503e64116b325863e2c7ed0efd7093cb6a367f3040e81d3 height=133473 log2_work=50.049376 tx=736930 date=2017-06-17 13:05:28 progress=0.999999 cache=14.8MiB(385tx)
2017-06-17 13:06:05 UpdateTip: new best=000000000a2e1c8e8ca76da171ff6512af2e60efbda47fe31f95bb4d9f5a0b25 height=133474 log2_work=50.049405 tx=736933 date=2017-06-17 13:06:00 progress=1.000000 cache=14.8MiB(391tx)
2017-06-17 13:07:18 UpdateTip: new best=0000000009cd208fe7cec665909e1352cf01457c7a755455d6e0ed981c7a55c6 height=133475 log2_work=50.049434 tx=736936 date=2017-06-17 13:06:05 progress=0.999995 cache=30.2MiB(755tx)
2017-06-17 13:08:03 ERROR: AcceptToMemoryPool: inputs already spent
2017-06-17 13:08:15 UpdateTip: new best=0000000013ccc6588c745dbd3b1b469c79db541c5820c624787df6b9562c8b89 height=133476 log2_work=50.049463 tx=736939 date=2017-06-17 13:07:39 progress=0.999998 cache=30.2MiB(766tx)
2017-06-17 13:10:03 ERROR: AcceptToMemoryPool: inputs already spent
2017-06-17 13:13:40 UpdateTip: new best=0000000023bd11a9df6be718adb6c37e6e4555d7d684c89b4ba038ce23bd600f height=133477 log2_work=50.049493 tx=736950 date=2017-06-17 13:13:28 progress=0.999999 cache=31.9MiB(840tx)
2017-06-17 13:14:03 ERROR: AcceptToMemoryPool: inputs already spent
2017-06-17 13:14:52 UpdateTip: new best=00000000251e5163bdd0083203dcc33932c9f1ac1fec7763f4169f7f8370e0c6 height=133478 log2_work=50.049522 tx=736963 date=2017-06-17 13:14:41 progress=0.999999 cache=31.9MiB(858tx)
2017-06-17 13:15:51 UpdateTip: new best=0000000021606b0f27c1f35e4f5ea65f50a4b96a5ad81d95790e065a43664c95 height=133479 log2_work=50.049551 tx=736970 date=2017-06-17 13:14:51 progress=0.999996 cache=32.1MiB(866tx)
2017-06-17 13:15:51 UpdateTip: new best=0000000002237e984074108aa4c7defc52765a55b56a530380335a43cf6adb65 height=133480 log2_work=50.049581 tx=736975 date=2017-06-17 13:15:48 progress=1.000000 cache=32.1MiB(870tx)

Hi,
im with the same error message of you. Did you fix your problem ? Can you give a clue of how could i do ?
Thanks in advance, Estruc

Have you tried with latest Zcash ?

Zcash 1.0.10-1

No, I’ve invested my time in another cryptocurrency.

Maybe one day I’ll come back and try it again.