Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

'tokio-runtime-worker' panicked at 'Externalities not allowed to fail within runtime: "Trie lookup error: Database missing expected key: #6547

Open
2 tasks done
Anastasiia-Khab opened this issue Nov 19, 2024 · 33 comments
Labels
I2-bug The node fails to follow expected behavior. I10-unconfirmed Issue might be valid, but it's not yet known.

Comments

@Anastasiia-Khab
Copy link

Anastasiia-Khab commented Nov 19, 2024

Is there an existing issue?

  • I have searched the existing issues

Experiencing problems? Have you tried our Stack Exchange first?

  • This is not a support question.

Description of bug

Two nodes failed at Kusama network in the same time with a Bug report:

2024-11-19 15:33:49 🏆 Imported #25848324 (0x690e…3b63 → 0x80af…0f8f)
2024-11-19 15:33:51 💤 Idle (14 peers), best: #25848324 (0x80af…0f8f), finalized #25848321 (0x7923…8f04), ⬇ 3.1MiB/s ⬆ 2.1MiB/s
2024-11-19 15:33:52 🏆 Imported #25848325 (0x80af…0f8f → 0xebee…38a9)
====================
Version: 1.16.2
    0: sp_panic_handler::set::{{closure}}
    1: std::panicking::rust_panic_with_hook
    2: std::panicking::begin_panic_handler::{{closure}}
    3: std::sys::backtrace::__rust_end_short_backtrace
    4: rust_begin_unwind
    5: core::panicking::panic_fmt
    6: core::result::unwrap_failed
    7: <sp_state_machine::ext::Ext<H,B> as sp_externalities::Externalities>::storage
    8: sp_io::storage::ExtStorageGetVersion1::call
    9: <F as wasmtime::func::IntoFunc<T,(wasmtime::func::Caller<T>,A1),R>>::into_func::wasm_to_host_shim
   10: <unknown>
   11: <unknown>
   12: <unknown>
   13: wasmtime_runtime::traphandlers::catch_traps::call_closure
   14: wasmtime_setjmp
   15: sc_executor_wasmtime::runtime::WasmtimeInstance::call_impl
   16: sc_executor_common::wasm_runtime::WasmInstance::call_export
   17: sc_executor::executor::WasmExecutor<H>::with_instance::{{closure}}
   18: <sc_executor::executor::WasmExecutor<H> as sp_core::traits::CodeExecutor>::call
   19: sp_state_machine::execution::StateMachine<B,H,Exec>::execute
   20: <sc_service::client::client::Client<B,E,Block,RA> as sp_api::CallApiAt<Block>>::call_api_at
   21: <polkadot_service::fake_runtime_api::RuntimeApiImpl<__SrApiBlock__,RuntimeApiImplCall> as sp_api::Core<__SrApiBlock__>>::__runtime_api_internal_call_api_at::{{closure}}
   22: <polkadot_service::fake_runtime_api::RuntimeApiImpl<__SrApiBlock__,RuntimeApiImplCall> as sp_api::Core<__SrApiBlock__>>::__runtime_api_internal_call_api_at
   23: sc_transaction_pool::api::validate_transaction_blocking
   24: <sc_transaction_pool::api::FullChainApi<Client,Block> as sc_transaction_pool::graph::pool::ChainApi>::validate_transaction::{{closure}}::{{closure}}
   25: sc_transaction_pool::api::spawn_validation_pool_task::{{closure}}
   26: <futures_util::future::future::Map<Fut,F> as core::future::future::Future>::poll
   27: <tracing_futures::Instrumented<T> as core::future::future::Future>::poll
   28: tokio::runtime::task::raw::poll
   29: std::sys::backtrace::__rust_begin_short_backtrace
   30: core::ops::function::FnOnce::call_once{{vtable.shim}}
   31: std::sys::pal::unix::thread::Thread::new::thread_start
   32: start_thread
   33: __GI___clone3
Thread 'tokio-runtime-worker' panicked at 'Externalities not allowed to fail within runtime: "Trie lookup error: Database missing expected key: [KEY]"', /builds/substrate/primitives/state-machine/src/ext.rs:174
This is a bug. Please report it at:
https://github.com/paritytech/polkadot-sdk/issues/new

Happened just once.

Steps to reproduce

No response

@Anastasiia-Khab Anastasiia-Khab added I10-unconfirmed Issue might be valid, but it's not yet known. I2-bug The node fails to follow expected behavior. labels Nov 19, 2024
@bkchr
Copy link
Member

bkchr commented Nov 20, 2024

This means you have some storage corruption. Not that much we can do there.

@bkchr bkchr closed this as completed Nov 20, 2024
@f33r0
Copy link

f33r0 commented Nov 21, 2024

Something similar happened to us today.
The problem is definitely not global?

[Relaychain] ⚙️ Preparing 0.0 bps, target=#25875211 (17 peers), best: #25875205 (0x7e68…0363), finalized #25875203 (0x8312…b031)

@Anastasiia-Khab
Copy link
Author

It keeps failing from time to time today on my side as well

There was a similar issue a year ago #663

@lexnv
Copy link
Contributor

lexnv commented Nov 21, 2024

@Anastasiia-Khab are you using the default network backed (libp2p)?

@f33r0
Copy link

f33r0 commented Nov 21, 2024

No problems with relaychains today?

@Anastasiia-Khab
Copy link
Author

Anastasiia-Khab commented Nov 21, 2024

@lexnv I was using the default network configuration, but the port is not 30333
How can check if I am using libp2p?

In logs I also have: Running libp2p network backend, so I suppose the answer is Yes

@bkchr
Copy link
Member

bkchr commented Nov 21, 2024

It keeps failing from time to time today on my side as well

And restarting the node fixes it?

@bkchr bkchr reopened this Nov 21, 2024
@bkchr
Copy link
Member

bkchr commented Nov 21, 2024

If yes, please provide more logs before the issue is happening, 10min should be enough.

@Anastasiia-Khab
Copy link
Author

It keeps failing from time to time today on my side as well

And restarting the node fixes it?

It does fixes it for a while, but then with the time fails again

@bkchr
Copy link
Member

bkchr commented Nov 21, 2024

@Anastasiia-Khab ty, then please provide logs. Did this started to appear with 1.16.2? What version did you used before? Are you using paritydb?

@Anastasiia-Khab
Copy link
Author

Started to appear yesterday. Updated to 1.16.2 on the day of release. Before was using 1.16.1

@Anastasiia-Khab ty, then please provide logs. Did this started to appear with 1.16.2? What version did you used before? Are you using paritydb?

@Anastasiia-Khab
Copy link
Author

Anastasiia-Khab commented Nov 21, 2024

@Anastasiia-Khab ty, then please provide logs. Did this started to appear with 1.16.2? What version did you used before? Are you using paritydb?

30:01 :2024-11-21 15:30:01 💤 Idle (4 peers), best: #25875905 (0x83ba…b3c9), finalized #25875903 (0xa97a…1456), ⬇ 2.5MiB/s ⬆ 646.0kiB/s
30:04 :2024-11-21 15:30:04 🆕 Imported #25875905 (0x61a7…798f → 0x442e…0568)
30:05 :2024-11-21 15:30:05 🏆 Imported #25875906 (0x83ba…b3c9 → 0xbeb5…0a51)
30:06 :2024-11-21 15:30:06 💤 Idle (4 peers), best: #25875906 (0xbeb5…0a51), finalized #25875903 (0xa97a…1456), ⬇ 2.2MiB/s ⬆ 443.5kiB/s
30:09 :2024-11-21 15:30:09 🏆 Imported #25875907 (0xbeb5…0a51 → 0x9e74…7c11)
30:11 :2024-11-21 15:30:11 💤 Idle (5 peers), best: #25875907 (0x9e74…7c11), finalized #25875904 (0x61a7…798f), ⬇ 2.4MiB/s ⬆ 487.6kiB/s
30:12 :2024-11-21 15:30:12 🆕 Imported #25875906 (0x83ba…b3c9 → 0x0aed…2aad)
30:16 :2024-11-21 15:30:16 💤 Idle (3 peers), best: #25875907 (0x9e74…7c11), finalized #25875905 (0x83ba…b3c9), ⬇ 2.4MiB/s ⬆ 3.2MiB/s
30:17 :2024-11-21 15:30:17 🏆 Imported #25875908 (0x9e74…7c11 → 0xf0a1…d36f)
30:21 :2024-11-21 15:30:21 💤 Idle (3 peers), best: #25875908 (0xf0a1…d36f), finalized #25875905 (0x83ba…b3c9), ⬇ 2.9MiB/s ⬆ 4.0MiB/s
30:26 :2024-11-21 15:30:26 💤 Idle (3 peers), best: #25875908 (0xf0a1…d36f), finalized #25875905 (0x83ba…b3c9), ⬇ 1.9MiB/s ⬆ 317.1kiB/s
30:31 :2024-11-21 15:30:31 💤 Idle (5 peers), best: #25875908 (0xf0a1…d36f), finalized #25875905 (0x83ba…b3c9), ⬇ 1.6MiB/s ⬆ 283.9kiB/s
30:32 :2024-11-21 15:30:32 🏆 Imported #25875909 (0xf0a1…d36f → 0x85d4…7b1c)
30:33 :2024-11-21 15:30:33 ♻️  Reorg on #25875909,0x85d4…7b1c to #25875909,0xd9fc…ea20, common ancestor #25875908,0xf0a1…d36f
30:33 :2024-11-21 15:30:33 🏆 Imported #25875909 (0xf0a1…d36f → 0xd9fc…ea20)
30:36 :2024-11-21 15:30:36 💤 Idle (4 peers), best: #25875909 (0xd9fc…ea20), finalized #25875906 (0xbeb5…0a51), ⬇ 1.8MiB/s ⬆ 548.3kiB/s
30:38 :2024-11-21 15:30:38 [0xacf1d9e13...] Trying to notify an import but the channel is full
30:38 :2024-11-21 15:30:38 [0xd899c2b82...] Trying to notify an import but the channel is full\
...
... ~900 more messages like this
...
30:39 :2024-11-21 15:30:39 [0x700f8286...] Trying to notify an import but the channel is full
30:39 :2024-11-21 15:30:39 [0xa9b45a15...] Trying to notify an import but the channel is full
30:41 :2024-11-21 15:30:41 💤 Idle (5 peers), best: #25875909 (0xd9fc…ea20), finalized #25875906 (0xbeb5…0a51), ⬇ 1.8MiB/s ⬆ 264.4kiB/s
30:46 :2024-11-21 15:30:46 💤 Idle (3 peers), best: #25875909 (0xd9fc…ea20), finalized #25875908 (0xf0a1…d36f), ⬇ 1.6MiB/s ⬆ 217.7kiB/s
30:51 :2024-11-21 15:30:51 💤 Idle (4 peers), best: #25875909 (0xd9fc…ea20), finalized #25875908 (0xf0a1…d36f), ⬇ 1.5MiB/s ⬆ 291.0kiB/s
30:56 :2024-11-21 15:30:56 💤 Idle (5 peers), best: #25875909 (0xd9fc…ea20), finalized #25875908 (0xf0a1…d36f), ⬇ 1.7MiB/s ⬆ 145.5kiB/s
30:57 :2024-11-21 15:30:57 🏆 Imported #25875910 (0xd9fc…ea20 → 0x43de…9c79)
30:58 :2024-11-21 15:30:58 🏆 Imported #25875911 (0x43de…9c79 → 0xe8f0…53b6)
31:01 :2024-11-21 15:31:01 💤 Idle (5 peers), best: #25875911 (0xe8f0…53b6), finalized #25875908 (0xf0a1…d36f), ⬇ 1.4MiB/s ⬆ 474.9kiB/s
31:06 :2024-11-21 15:31:06 💤 Idle (4 peers), best: #25875911 (0xe8f0…53b6), finalized #25875908 (0xf0a1…d36f), ⬇ 1.2MiB/s ⬆ 160.3kiB/s
31:11 :2024-11-21 15:31:11 💤 Idle (3 peers), best: #25875911 (0xe8f0…53b6), finalized #25875908 (0xf0a1…d36f), ⬇ 1.4MiB/s ⬆ 128.7kiB/s
31:16 :2024-11-21 15:31:16 💤 Idle (3 peers), best: #25875911 (0xe8f0…53b6), finalized #25875908 (0xf0a1…d36f), ⬇ 1.3MiB/s ⬆ 2.5MiB/s
31:21 :2024-11-21 15:31:21 💤 Idle (2 peers), best: #25875911 (0xe8f0…53b6), finalized #25875908 (0xf0a1…d36f), ⬇ 1.3MiB/s ⬆ 131.5kiB/s
31:26 :2024-11-21 15:31:26 💤 Idle (3 peers), best: #25875911 (0xe8f0…53b6), finalized #25875911 (0xe8f0…53b6), ⬇ 1.7MiB/s ⬆ 263.2kiB/s
31:27 :2024-11-21 15:31:27 🏆 Imported #25875912 (0xe8f0…53b6 → 0x0837…9fbd)
31:28 :2024-11-21 15:31:28 🏆 Imported #25875913 (0x0837…9fbd → 0xd581…9809)
31:31 :2024-11-21 15:31:31 💤 Idle (2 peers), best: #25875913 (0xd581…9809), finalized #25875911 (0xe8f0…53b6), ⬇ 1.2MiB/s ⬆ 133.9kiB/s
31:36 :2024-11-21 15:31:36 💤 Idle (2 peers), best: #25875913 (0xd581…9809), finalized #25875911 (0xe8f0…53b6), ⬇ 1.3MiB/s ⬆ 109.7kiB/s
31:41 :2024-11-21 15:31:41 💤 Idle (2 peers), best: #25875913 (0xd581…9809), finalized #25875911 (0xe8f0…53b6), ⬇ 1.3MiB/s ⬆ 134.6kiB/s
31:46 :2024-11-21 15:31:46 💤 Idle (2 peers), best: #25875913 (0xd581…9809), finalized #25875911 (0xe8f0…53b6), ⬇ 1.2MiB/s ⬆ 123.4kiB/s
31:51 :2024-11-21 15:31:51 💤 Idle (2 peers), best: #25875913 (0xd581…9809), finalized #25875911 (0xe8f0…53b6), ⬇ 2.0MiB/s ⬆ 502.9kiB/s
31:56 :2024-11-21 15:31:56 💤 Idle (2 peers), best: #25875913 (0xd581…9809), finalized #25875911 (0xe8f0…53b6), ⬇ 1.7MiB/s ⬆ 187.5kiB/s
32:01 :2024-11-21 15:32:01 💤 Idle (2 peers), best: #25875913 (0xd581…9809), finalized #25875911 (0xe8f0…53b6), ⬇ 1.2MiB/s ⬆ 169.4kiB/s
32:06 :2024-11-21 15:32:06 💤 Idle (2 peers), best: #25875913 (0xd581…9809), finalized #25875911 (0xe8f0…53b6), ⬇ 1.0MiB/s ⬆ 156.5kiB/s
32:11 :2024-11-21 15:32:11 💤 Idle (2 peers), best: #25875913 (0xd581…9809), finalized #25875911 (0xe8f0…53b6), ⬇ 1.2MiB/s ⬆ 165.8kiB/s
32:16 :2024-11-21 15:32:16 💤 Idle (2 peers), best: #25875913 (0xd581…9809), finalized #25875911 (0xe8f0…53b6), ⬇ 938.2kiB/s ⬆ 2.8MiB/s
32:21 :2024-11-21 15:32:21 💤 Idle (2 peers), best: #25875913 (0xd581…9809), finalized #25875911 (0xe8f0…53b6), ⬇ 1.1MiB/s ⬆ 170.9kiB/s
32:26 :2024-11-21 15:32:26 💤 Idle (2 peers), best: #25875913 (0xd581…9809), finalized #25875911 (0xe8f0…53b6), ⬇ 1.0MiB/s ⬆ 142.5kiB/s
32:31 :2024-11-21 15:32:31 💤 Idle (2 peers), best: #25875913 (0xd581…9809), finalized #25875911 (0xe8f0…53b6), ⬇ 972.8kiB/s ⬆ 134.1kiB/s
32:36 :2024-11-21 15:32:36 💤 Idle (2 peers), best: #25875913 (0xd581…9809), finalized #25875911 (0xe8f0…53b6), ⬇ 981.2kiB/s ⬆ 133.5kiB/s
32:41 :2024-11-21 15:32:41 💤 Idle (2 peers), best: #25875913 (0xd581…9809), finalized #25875911 (0xe8f0…53b6), ⬇ 1019.7kiB/s ⬆ 131.1kiB/s
32:46 :2024-11-21 15:32:46 💤 Idle (2 peers), best: #25875913 (0xd581…9809), finalized #25875911 (0xe8f0…53b6), ⬇ 859.8kiB/s ⬆ 114.9kiB/s
32:51 :2024-11-21 15:32:51 💤 Idle (2 peers), best: #25875913 (0xd581…9809), finalized #25875911 (0xe8f0…53b6), ⬇ 982.4kiB/s ⬆ 132.8kiB/s
32:54 :2024-11-21 15:32:54 🏆 Imported #25875914 (0xd581…9809 → 0x034d…de50)
32:55 :2024-11-21 15:32:55 🏆 Imported #25875915 (0x034d…de50 → 0x2f5a…a61c)
32:56 :2024-11-21 15:32:56 💤 Idle (2 peers), best: #25875915 (0x2f5a…a61c), finalized #25875911 (0xe8f0…53b6), ⬇ 2.4MiB/s ⬆ 186.5kiB/s
32:57 :2024-11-21 15:32:57 🏆 Imported #25875916 (0x2f5a…a61c → 0xc1b0…f3ec)
32:58 :2024-11-21 15:32:58 🏆 Imported #25875917 (0xc1b0…f3ec → 0x32d1…dd21)
32:59 :2024-11-21 15:32:59 🏆 Imported #25875918 (0x32d1…dd21 → 0x6dcf…3ef9)
33:00 :2024-11-21 15:33:00 🏆 Imported #25875919 (0x6dcf…3ef9 → 0xebec…eeae)
33:01 :2024-11-21 15:33:01 💤 Idle (2 peers), best: #25875919 (0xebec…eeae), finalized #25875911 (0xe8f0…53b6), ⬇ 1.3MiB/s ⬆ 226.7kiB/s
33:02 :2024-11-21 15:33:02 🏆 Imported #25875920 (0xebec…eeae → 0xd543…7497)
33:03 :2024-11-21 15:33:03 🏆 Imported #25875921 (0xd543…7497 → 0xb297…5318)
33:04 :2024-11-21 15:33:04 🏆 Imported #25875922 (0xb297…5318 → 0xdc61…c32c)
33:06 :2024-11-21 15:33:06 🆕 Imported #25875922 (0xb297…5318 → 0xe483…2a2c)
33:06 :2024-11-21 15:33:06 💤 Idle (3 peers), best: #25875922 (0xdc61…c32c), finalized #25875920 (0xd543…7497), ⬇ 1.3MiB/s ⬆ 1.7MiB/s
33:07 :2024-11-21 15:33:07 🏆 Imported #25875923 (0xdc61…c32c → 0x8e55…0cae)
33:11 :2024-11-21 15:33:11 💤 Idle (3 peers), best: #25875923 (0x8e55…0cae), finalized #25875921 (0xb297…5318), ⬇ 2.9MiB/s ⬆ 2.6MiB/s
33:11 :2024-11-21 15:33:11 🆕 Imported #25875923 (0xdc61…c32c → 0x9f39…7ef6)
33:16 :2024-11-21 15:33:16 💤 Idle (4 peers), best: #25875923 (0x8e55…0cae), finalized #25875921 (0xb297…5318), ⬇ 2.5MiB/s ⬆ 3.2MiB/s
33:17 :2024-11-21 15:33:17 🏆 Imported #25875924 (0x8e55…0cae → 0x2bfc…a346)
33:21 :2024-11-21 15:33:21 💤 Idle (4 peers), best: #25875924 (0x2bfc…a346), finalized #25875921 (0xb297…5318), ⬇ 1.8MiB/s ⬆ 1.9MiB/s
33:26 :2024-11-21 15:33:25 🆕 Imported #25875924 (0x8e55…0cae → 0xefab…31ef)
33:26 :2024-11-21 15:33:26 💤 Idle (2 peers), best: #25875924 (0x2bfc…a346), finalized #25875921 (0xb297…5318), ⬇ 1.5MiB/s ⬆ 2.1MiB/s
33:31 :2024-11-21 15:33:31 💤 Idle (3 peers), best: #25875924 (0x2bfc…a346), finalized #25875921 (0xb297…5318), ⬇ 1.2MiB/s ⬆ 1.6MiB/s
33:36 :2024-11-21 15:33:36 💤 Idle (3 peers), best: #25875924 (0x2bfc…a346), finalized #25875921 (0xb297…5318), ⬇ 1.3MiB/s ⬆ 2.0MiB/s
33:41 :2024-11-21 15:33:41 💤 Idle (4 peers), best: #25875924 (0x2bfc…a346), finalized #25875923 (0x8e55…0cae), ⬇ 1.5MiB/s ⬆ 1.7MiB/s
33:42 :2024-11-21 15:33:42 🏆 Imported #25875925 (0x2bfc…a346 → 0x80ad…b0b8)
33:46 :2024-11-21 15:33:46 💤 Idle (3 peers), best: #25875925 (0x80ad…b0b8), finalized #25875923 (0x8e55…0cae), ⬇ 1.5MiB/s ⬆ 2.2MiB/s
33:51 :2024-11-21 15:33:51 💤 Idle (2 peers), best: #25875925 (0x80ad…b0b8), finalized #25875924 (0x2bfc…a346), ⬇ 1.4MiB/s ⬆ 2.5MiB/s
33:56 :2024-11-21 15:33:56 💤 Idle (2 peers), best: #25875925 (0x80ad…b0b8), finalized #25875924 (0x2bfc…a346), ⬇ 1.3MiB/s ⬆ 1.5MiB/s
33:58 :2024-11-21 15:33:58 🏆 Imported #25875926 (0x80ad…b0b8 → 0x8dbe…b833)
34:01 :2024-11-21 15:34:01 💤 Idle (2 peers), best: #25875926 (0x8dbe…b833), finalized #25875924 (0x2bfc…a346), ⬇ 1.6MiB/s ⬆ 1.9MiB/s
34:06 :2024-11-21 15:34:06 💤 Idle (3 peers), best: #25875926 (0x8dbe…b833), finalized #25875924 (0x2bfc…a346), ⬇ 1.4MiB/s ⬆ 1.6MiB/s
34:07 :2024-11-21 15:34:07 🏆 Imported #25875927 (0x8dbe…b833 → 0x8f98…3c97)
34:11 :2024-11-21 15:34:11 💤 Idle (5 peers), best: #25875927 (0x8f98…3c97), finalized #25875924 (0x2bfc…a346), ⬇ 1.6MiB/s ⬆ 2.1MiB/s
34:14 :2024-11-21 15:34:14 🆕 Imported #25875927 (0x8dbe…b833 → 0xb407…9a89)
34:15 :2024-11-21 15:34:15 🆕 Imported #25875928 (0xb407…9a89 → 0x369e…e0cd)
34:16 :2024-11-21 15:34:16 💤 Idle (3 peers), best: #25875927 (0x8f98…3c97), finalized #25875925 (0x80ad…b0b8), ⬇ 2.0MiB/s ⬆ 2.9MiB/s
34:21 :2024-11-21 15:34:21 💤 Idle (3 peers), best: #25875927 (0x8f98…3c97), finalized #25875925 (0x80ad…b0b8), ⬇ 1.4MiB/s ⬆ 2.1MiB/s
34:26 :2024-11-21 15:34:26 💤 Idle (2 peers), best: #25875927 (0x8f98…3c97), finalized #25875926 (0x8dbe…b833), ⬇ 1.3MiB/s ⬆ 1.6MiB/s
34:31 :2024-11-21 15:34:31 💤 Idle (2 peers), best: #25875927 (0x8f98…3c97), finalized #25875926 (0x8dbe…b833), ⬇ 1.2MiB/s ⬆ 2.0MiB/s
34:36 :2024-11-21 15:34:36 💤 Idle (2 peers), best: #25875927 (0x8f98…3c97), finalized #25875926 (0x8dbe…b833), ⬇ 1.3MiB/s ⬆ 1.5MiB/s
34:41 :2024-11-21 15:34:41 💤 Idle (2 peers), best: #25875927 (0x8f98…3c97), finalized #25875926 (0x8dbe…b833), ⬇ 1.2MiB/s ⬆ 1.9MiB/s
34:46 :2024-11-21 15:34:46 💤 Idle (4 peers), best: #25875927 (0x8f98…3c97), finalized #25875926 (0x8dbe…b833), ⬇ 1.2MiB/s ⬆ 2.5MiB/s
34:51 :2024-11-21 15:34:51 💤 Idle (4 peers), best: #25875927 (0x8f98…3c97), finalized #25875926 (0x8dbe…b833), ⬇ 4.8MiB/s ⬆ 4.0MiB/s
34:54 :2024-11-21 15:34:54 🏆 Imported #25875928 (0x8f98…3c97 → 0xbc16…83ac)
34:55 :2024-11-21 15:34:55 🏆 Imported #25875929 (0xbc16…83ac → 0x9f9b…a5cb)
34:56 :2024-11-21 15:34:56 💤 Idle (3 peers), best: #25875929 (0x9f9b…a5cb), finalized #25875927 (0x8f98…3c97), ⬇ 6.1MiB/s ⬆ 3.7MiB/s
34:56 :2024-11-21 15:34:56 🏆 Imported #25875930 (0x9f9b…a5cb → 0xd35d…c787)
34:57 :2024-11-21 15:34:57 Report 12D3KooWCaXyaqB2bUbNthAoSZYhHY1Nxw4sqpbrrqBhuK4xqznE: -2147483648 to -2147483648. Reason: Same block request multiple times. Banned, disconnecting.
35:01 :2024-11-21 15:35:01 💤 Idle (4 peers), best: #25875930 (0xd35d…c787), finalized #25875928 (0xbc16…83ac), ⬇ 3.9MiB/s ⬆ 2.6MiB/s
35:06 :2024-11-21 15:35:06 💤 Idle (3 peers), best: #25875930 (0xd35d…c787), finalized #25875928 (0xbc16…83ac), ⬇ 1.6MiB/s ⬆ 2.2MiB/s
35:07 :2024-11-21 15:35:07 🏆 Imported #25875931 (0xd35d…c787 → 0xae5a…22a0)
35:08 :2024-11-21 15:35:08 🆕 Imported #25875931 (0xd35d…c787 → 0xf2a0…b400)
35:11 :2024-11-21 15:35:11 💤 Idle (4 peers), best: #25875931 (0xae5a…22a0), finalized #25875929 (0x9f9b…a5cb), ⬇ 3.0MiB/s ⬆ 2.3MiB/s
35:16 :2024-11-21 15:35:16 💤 Idle (4 peers), best: #25875931 (0xae5a…22a0), finalized #25875929 (0x9f9b…a5cb), ⬇ 1.8MiB/s ⬆ 4.5MiB/s
35:21 :2024-11-21 15:35:21 💤 Idle (3 peers), best: #25875931 (0xae5a…22a0), finalized #25875929 (0x9f9b…a5cb), ⬇ 1.5MiB/s ⬆ 1.7MiB/s
35:26 :2024-11-21 15:35:26 💤 Idle (3 peers), best: #25875931 (0xae5a…22a0), finalized #25875929 (0x9f9b…a5cb), ⬇ 1.6MiB/s ⬆ 2.3MiB/s
35:30 :2024-11-21 15:35:30 ♻️  Reorg on #25875931,0xae5a…22a0 to #25875931,0xf8f3…8cd3, common ancestor #25875930,0xd35d…c787
35:30 :2024-11-21 15:35:30 🏆 Imported #25875931 (0xd35d…c787 → 0xf8f3…8cd3)
35:31 :2024-11-21 15:35:31 💤 Idle (3 peers), best: #25875931 (0xf8f3…8cd3), finalized #25875929 (0x9f9b…a5cb), ⬇ 1.7MiB/s ⬆ 1.9MiB/s
35:31 :2024-11-21 15:35:31 🏆 Imported #25875932 (0xf8f3…8cd3 → 0x3af8…b619)
35:35 :2024-11-21 15:35:35 🏆 Imported #25875933 (0x3af8…b619 → 0xf106…0a9a)
35:36 :2024-11-21 15:35:36 💤 Idle (4 peers), best: #25875933 (0xf106…0a9a), finalized #25875930 (0xd35d…c787), ⬇ 1.8MiB/s ⬆ 2.4MiB/s
35:38 :2024-11-21 15:35:38 ♻️  Reorg on #25875933,0xf106…0a9a to #25875933,0x6114…a41a, common ancestor #25875932,0x3af8…b619
35:38 :2024-11-21 15:35:38 🏆 Imported #25875933 (0x3af8…b619 → 0x6114…a41a)
35:41 :2024-11-21 15:35:41 💤 Idle (3 peers), best: #25875933 (0x6114…a41a), finalized #25875930 (0xd35d…c787), ⬇ 1.4MiB/s ⬆ 2.2MiB/s
35:41 :2024-11-21 15:35:41 [0xcc8ce0ba08653...] Trying to notify an import but the channel is full
35:41 :2024-11-21 15:35:41 [0x69413385ef06b...] Trying to notify an import but the channel is full
...
... ~1400 messages like this
...
35:41 :2024-11-21 15:35:41 [0x056333bb3023...] Trying to notify an import but the channel is full
35:41 :2024-11-21 15:35:41 [0x5503e10f783a...] Trying to notify an import but the channel is full
35:41 :2024-11-21 15:35:41 [0x95517efc8b2e...] Trying to notify an import but the channel is full
35:46 :2024-11-21 15:35:46 💤 Idle (4 peers), best: #25875933 (0x6114…a41a), finalized #25875930 (0xd35d…c787), ⬇ 1.2MiB/s ⬆ 1.8MiB/s
35:49 :2024-11-21 15:35:49 ♻️  Reorg on #25875933,0x6114…a41a to #25875934,0x7937…531c, common ancestor #25875932,0x3af8…b619
35:49 :2024-11-21 15:35:49 🏆 Imported #25875934 (0xf106…0a9a → 0x7937…531c)
35:51 :2024-11-21 15:35:51 💤 Idle (5 peers), best: #25875934 (0x7937…531c), finalized #25875932 (0x3af8…b619), ⬇ 2.2MiB/s ⬆ 2.5MiB/s
35:54 :2024-11-21 15:35:54 🆕 Imported #25875934 (0x6114…a41a → 0x1893…83f6)
35:56 :2024-11-21 15:35:56 💤 Idle (4 peers), best: #25875934 (0x7937…531c), finalized #25875932 (0x3af8…b619), ⬇ 1.7MiB/s ⬆ 1.8MiB/s
35:58 :2024-11-21 15:35:58 Block import error: Potential long-range attack: block not in finalized chain.
35:58 :2024-11-21 15:35:58 💔 Error importing block 0x4525ff4fb6b18878ffd92c7e1cb28042c1f6ec623bc7ea2146149baf06a02b50: consensus error: Import failed: Import failed: Potential long-range attack: block not in finalized chain.
36:01 :2024-11-21 15:36:01 💤 Idle (4 peers), best: #25875934 (0x7937…531c), finalized #25875933 (0xf106…0a9a), ⬇ 1.7MiB/s ⬆ 2.1MiB/s
36:01 :2024-11-21 15:36:01 🏆 Imported #25875935 (0x7937…531c → 0x3aaa…2bc4)
36:05 :2024-11-21 15:36:05 🆕 Imported #25875935 (0x7937…531c → 0x92b6…9261)
36:06 :2024-11-21 15:36:06 💤 Idle (4 peers), best: #25875935 (0x3aaa…2bc4), finalized #25875933 (0xf106…0a9a), ⬇ 1.6MiB/s ⬆ 1.7MiB/s
36:08 :2024-11-21 15:36:08 🏆 Imported #25875936 (0x3aaa…2bc4 → 0xf88d…6d77)
36:11 :2024-11-21 15:36:11 💤 Idle (2 peers), best: #25875936 (0xf88d…6d77), finalized #25875934 (0x7937…531c), ⬇ 1.8MiB/s ⬆ 2.2MiB/s
36:16 :2024-11-21 15:36:16 💤 Idle (4 peers), best: #25875936 (0xf88d…6d77), finalized #25875934 (0x7937…531c), ⬇ 1.3MiB/s ⬆ 3.1MiB/s
36:21 :2024-11-21 15:36:21 💤 Idle (4 peers), best: #25875936 (0xf88d…6d77), finalized #25875934 (0x7937…531c), ⬇ 1.4MiB/s ⬆ 2.2MiB/s
36:26 :2024-11-21 15:36:26 💤 Idle (4 peers), best: #25875936 (0xf88d…6d77), finalized #25875934 (0x7937…531c), ⬇ 1.4MiB/s ⬆ 1.7MiB/s
36:27 :2024-11-21 15:36:27 🏆 Imported #25875937 (0xf88d…6d77 → 0x91b3…ad87)
36:28 :2024-11-21 15:36:28 🏆 Imported #25875938 (0x91b3…ad87 → 0x62b0…84ed)
36:31 :2024-11-21 15:36:31 💤 Idle (3 peers), best: #25875938 (0x62b0…84ed), finalized #25875936 (0xf88d…6d77), ⬇ 1.6MiB/s ⬆ 2.1MiB/s
36:36 :2024-11-21 15:36:36 💤 Idle (3 peers), best: #25875938 (0x62b0…84ed), finalized #25875936 (0xf88d…6d77), ⬇ 1.4MiB/s ⬆ 2.1MiB/s
36:41 :2024-11-21 15:36:41 💤 Idle (4 peers), best: #25875938 (0x62b0…84ed), finalized #25875936 (0xf88d…6d77), ⬇ 2.4MiB/s ⬆ 2.0MiB/s
36:46 :2024-11-21 15:36:46 💤 Idle (4 peers), best: #25875938 (0x62b0…84ed), finalized #25875936 (0xf88d…6d77), ⬇ 1.3MiB/s ⬆ 2.1MiB/s
36:51 :2024-11-21 15:36:51 💤 Idle (3 peers), best: #25875938 (0x62b0…84ed), finalized #25875936 (0xf88d…6d77), ⬇ 1007.6kiB/s ⬆ 1.7MiB/s
36:56 :2024-11-21 15:36:56 💤 Idle (2 peers), best: #25875938 (0x62b0…84ed), finalized #25875936 (0xf88d…6d77), ⬇ 1.2MiB/s ⬆ 2.0MiB/s
37:01 :2024-11-21 15:37:01 💤 Idle (2 peers), best: #25875938 (0x62b0…84ed), finalized #25875936 (0xf88d…6d77), ⬇ 957.4kiB/s ⬆ 1.6MiB/s
37:06 :2024-11-21 15:37:06 💤 Idle (2 peers), best: #25875938 (0x62b0…84ed), finalized #25875936 (0xf88d…6d77), ⬇ 1.1MiB/s ⬆ 2.0MiB/s
37:11 :2024-11-21 15:37:11 💤 Idle (2 peers), best: #25875938 (0x62b0…84ed), finalized #25875936 (0xf88d…6d77), ⬇ 1.0MiB/s ⬆ 1.7MiB/s
37:16 :2024-11-21 15:37:16 💤 Idle (3 peers), best: #25875938 (0x62b0…84ed), finalized #25875936 (0xf88d…6d77), ⬇ 1.5MiB/s ⬆ 3.9MiB/s
37:21 :2024-11-21 15:37:21 💤 Idle (3 peers), best: #25875938 (0x62b0…84ed), finalized #25875936 (0xf88d…6d77), ⬇ 1.5MiB/s ⬆ 1.8MiB/s
37:23 :2024-11-21 15:37:23 🏆 Imported #25875939 (0x62b0…84ed → 0x5464…7353)
37:25 :2024-11-21 15:37:25 🏆 Imported #25875940 (0x5464…7353 → 0x4fac…4bfa)
37:26 :2024-11-21 15:37:26 💤 Idle (4 peers), best: #25875940 (0x4fac…4bfa), finalized #25875940 (0x4fac…4bfa), ⬇ 2.0MiB/s ⬆ 2.4MiB/s
37:26 :2024-11-21 15:37:26 🏆 Imported #25875941 (0x4fac…4bfa → 0x4cc9…3b1d)
37:27 :2024-11-21 15:37:27 🏆 Imported #25875942 (0x4cc9…3b1d → 0x2477…92a2)
37:28 :2024-11-21 15:37:28 🏆 Imported #25875943 (0x2477…92a2 → 0xeaa7…e917)
37:29 :2024-11-21 15:37:29 🆕 Imported #25875942 (0x4cc9…3b1d → 0xe419…fe19)
37:31 :2024-11-21 15:37:31 💤 Idle (3 peers), best: #25875943 (0xeaa7…e917), finalized #25875940 (0x4fac…4bfa), ⬇ 1.1MiB/s ⬆ 166.7kiB/s
37:36 :2024-11-21 15:37:36 💤 Idle (4 peers), best: #25875943 (0xeaa7…e917), finalized #25875941 (0x4cc9…3b1d), ⬇ 1.2MiB/s ⬆ 186.0kiB/s
37:41 :2024-11-21 15:37:41 💤 Idle (4 peers), best: #25875943 (0xeaa7…e917), finalized #25875941 (0x4cc9…3b1d), ⬇ 1.1MiB/s ⬆ 253.5kiB/s
37:44 :2024-11-21 15:37:44 🏆 Imported #25875944 (0xeaa7…e917 → 0xcd71…0296)
37:46 :2024-11-21 15:37:46 💤 Idle (6 peers), best: #25875944 (0xcd71…0296), finalized #25875942 (0x2477…92a2), ⬇ 1.6MiB/s ⬆ 562.8kiB/s
37:46 :2024-11-21 15:37:46 🆕 Imported #25875943 (0x2477…92a2 → 0x194b…0706)
37:51 :2024-11-21 15:37:51 💤 Idle (2 peers), best: #25875944 (0xcd71…0296), finalized #25875942 (0x2477…92a2), ⬇ 1.4MiB/s ⬆ 788.1kiB/s
37:53 :2024-11-21 15:37:53 🏆 Imported #25875945 (0xcd71…0296 → 0x89a9…c524)
37:55 :2024-11-21 15:37:55 🆕 Imported #25875944 (0xeaa7…e917 → 0x8371…bcc9)
37:56 :2024-11-21 15:37:56 💤 Idle (4 peers), best: #25875945 (0x89a9…c524), finalized #25875943 (0xeaa7…e917), ⬇ 1.7MiB/s ⬆ 266.0kiB/s
38:01 :2024-11-21 15:38:01 💤 Idle (3 peers), best: #25875945 (0x89a9…c524), finalized #25875943 (0xeaa7…e917), ⬇ 1.2MiB/s ⬆ 306.6kiB/s
38:06 :2024-11-21 15:38:06 💤 Idle (3 peers), best: #25875945 (0x89a9…c524), finalized #25875943 (0xeaa7…e917), ⬇ 1.1MiB/s ⬆ 397.9kiB/s
38:11 :2024-11-21 15:38:11 💤 Idle (3 peers), best: #25875945 (0x89a9…c524), finalized #25875943 (0xeaa7…e917), ⬇ 1.0MiB/s ⬆ 415.1kiB/s
38:15 :2024-11-21 15:38:15 🏆 Imported #25875946 (0x89a9…c524 → 0x3cda…c4c1)
38:16 :2024-11-21 15:38:16 🏆 Imported #25875947 (0x3cda…c4c1 → 0x5f75…6475)
38:16 :2024-11-21 15:38:16 💤 Idle (3 peers), best: #25875947 (0x5f75…6475), finalized #25875943 (0xeaa7…e917), ⬇ 1.6MiB/s ⬆ 182.5kiB/s
38:21 :2024-11-21 15:38:21 💤 Idle (5 peers), best: #25875947 (0x5f75…6475), finalized #25875945 (0x89a9…c524), ⬇ 2.4MiB/s ⬆ 4.9MiB/s
38:26 :2024-11-21 15:38:26 💤 Idle (5 peers), best: #25875947 (0x5f75…6475), finalized #25875946 (0x3cda…c4c1), ⬇ 2.0MiB/s ⬆ 2.3MiB/s
38:31 :2024-11-21 15:38:31 💤 Idle (2 peers), best: #25875947 (0x5f75…6475), finalized #25875946 (0x3cda…c4c1), ⬇ 1.5MiB/s ⬆ 1.8MiB/s
38:36 :2024-11-21 15:38:36 💤 Idle (3 peers), best: #25875947 (0x5f75…6475), finalized #25875946 (0x3cda…c4c1), ⬇ 1.5MiB/s ⬆ 2.1MiB/s
38:41 :2024-11-21 15:38:41 💤 Idle (3 peers), best: #25875947 (0x5f75…6475), finalized #25875946 (0x3cda…c4c1), ⬇ 1.6MiB/s ⬆ 1.7MiB/s
38:46 :2024-11-21 15:38:46 💤 Idle (3 peers), best: #25875947 (0x5f75…6475), finalized #25875946 (0x3cda…c4c1), ⬇ 1.5MiB/s ⬆ 2.0MiB/s
38:51 :2024-11-21 15:38:51 💤 Idle (2 peers), best: #25875947 (0x5f75…6475), finalized #25875946 (0x3cda…c4c1), ⬇ 1.4MiB/s ⬆ 1.7MiB/s
38:56 :2024-11-21 15:38:56 💤 Idle (4 peers), best: #25875947 (0x5f75…6475), finalized #25875946 (0x3cda…c4c1), ⬇ 1.9MiB/s ⬆ 2.3MiB/s
38:58 :2024-11-21 15:38:58 🏆 Imported #25875948 (0x5f75…6475 → 0x2892…f70b)
38:59 :2024-11-21 15:38:59 🏆 Imported #25875949 (0x2892…f70b → 0xd5c8…a67b)
39:01 :2024-11-21 15:39:01 🏆 Imported #25875950 (0xd5c8…a67b → 0xf774…70b4)
39:01 :2024-11-21 15:39:01 💤 Idle (3 peers), best: #25875950 (0xf774…70b4), finalized #25875949 (0xd5c8…a67b), ⬇ 2.1MiB/s ⬆ 1.8MiB/s
39:01 :2024-11-21 15:39:01 🏆 Imported #25875951 (0xf774…70b4 → 0x3194…5fb3)
39:02 :2024-11-21 15:39:02 🏆 Imported #25875952 (0x3194…5fb3 → 0x0002…037a)
39:06 :2024-11-21 15:39:06 💤 Idle (2 peers), best: #25875952 (0x0002…037a), finalized #25875950 (0xf774…70b4), ⬇ 1.5MiB/s ⬆ 2.0MiB/s
39:10 :2024-11-21 15:39:10 🏆 Imported #25875953 (0x0002…037a → 0x9c48…2ed7)
39:11 :2024-11-21 15:39:11 💤 Idle (4 peers), best: #25875953 (0x9c48…2ed7), finalized #25875951 (0x3194…5fb3), ⬇ 1.8MiB/s ⬆ 1.8MiB/s
39:12 :2024-11-21 15:39:12 🆕 Imported #25875952 (0x3194…5fb3 → 0x7694…4fbd)
39:13 :2024-11-21 15:39:13 🆕 Imported #25875953 (0x0002…037a → 0x0c3d…6234)
39:16 :2024-11-21 15:39:16 💤 Idle (3 peers), best: #25875953 (0x9c48…2ed7), finalized #25875951 (0x3194…5fb3), ⬇ 1.7MiB/s ⬆ 2.1MiB/s
39:21 :2024-11-21 15:39:21 💤 Idle (5 peers), best: #25875953 (0x9c48…2ed7), finalized #25875951 (0x3194…5fb3), ⬇ 1.6MiB/s ⬆ 3.2MiB/s
39:26 :2024-11-21 15:39:26 💤 Idle (5 peers), best: #25875953 (0x9c48…2ed7), finalized #25875951 (0x3194…5fb3), ⬇ 1.7MiB/s ⬆ 1.9MiB/s
39:31 :2024-11-21 15:39:31 💤 Idle (5 peers), best: #25875953 (0x9c48…2ed7), finalized #25875951 (0x3194…5fb3), ⬇ 2.8MiB/s ⬆ 2.5MiB/s
39:36 :2024-11-21 15:39:36 💤 Idle (4 peers), best: #25875953 (0x9c48…2ed7), finalized #25875952 (0x0002…037a), ⬇ 1.9MiB/s ⬆ 2.2MiB/s
39:41 :2024-11-21 15:39:41 💤 Idle (6 peers), best: #25875953 (0x9c48…2ed7), finalized #25875952 (0x0002…037a), ⬇ 2.0MiB/s ⬆ 2.3MiB/s
39:46 :2024-11-21 15:39:46 💤 Idle (6 peers), best: #25875953 (0x9c48…2ed7), finalized #25875952 (0x0002…037a), ⬇ 1.8MiB/s ⬆ 1.8MiB/s
39:51 :2024-11-21 15:39:51 💤 Idle (7 peers), best: #25875953 (0x9c48…2ed7), finalized #25875953 (0x9c48…2ed7), ⬇ 2.0MiB/s ⬆ 1.7MiB/s
39:56 :2024-11-21 15:39:56 💤 Idle (7 peers), best: #25875953 (0x9c48…2ed7), finalized #25875953 (0x9c48…2ed7), ⬇ 1.8MiB/s ⬆ 279.0kiB/s
40:01 :2024-11-21 15:40:01 💤 Idle (5 peers), best: #25875953 (0x9c48…2ed7), finalized #25875953 (0x9c48…2ed7), ⬇ 1.8MiB/s ⬆ 236.9kiB/s
40:06 :2024-11-21 15:40:06 💤 Idle (5 peers), best: #25875953 (0x9c48…2ed7), finalized #25875953 (0x9c48…2ed7), ⬇ 1.9MiB/s ⬆ 219.8kiB/s
40:11 :2024-11-21 15:40:11 💤 Idle (5 peers), best: #25875953 (0x9c48…2ed7), finalized #25875953 (0x9c48…2ed7), ⬇ 2.0MiB/s ⬆ 215.9kiB/s
40:13 :2024-11-21 15:40:13 🏆 Imported #25875954 (0x9c48…2ed7 → 0x9148…7b86)
40:14 :2024-11-21 15:40:14 🏆 Imported #25875955 (0x9148…7b86 → 0x6170…2947)
40:15 :2024-11-21 15:40:15 🏆 Imported #25875956 (0x6170…2947 → 0xbead…c7bd)
40:16 :2024-11-21 15:40:16 💤 Idle (5 peers), best: #25875956 (0xbead…c7bd), finalized #25875954 (0x9148…7b86), ⬇ 2.3MiB/s ⬆ 201.4kiB/s
40:16 :2024-11-21 15:40:16 ♻️  Reorg on #25875956,0xbead…c7bd to #25875955,0xf54d…f29c, common ancestor #25875954,0x9148…7b86
40:16 :2024-11-21 15:40:16 🏆 Imported #25875955 (0x9148…7b86 → 0xf54d…f29c)
40:21 :2024-11-21 15:40:21 💤 Idle (4 peers), best: #25875955 (0xf54d…f29c), finalized #25875955 (0xf54d…f29c), ⬇ 2.1MiB/s ⬆ 2.3MiB/s
40:21 :====================
40:21 :Version: 1.16.2
40:21 :   0: sp_panic_handler::set::{{closure}}
40:21 :   1: std::panicking::rust_panic_with_hook
40:21 :   2: std::panicking::begin_panic_handler::{{closure}}
40:21 :   3: std::sys::backtrace::__rust_end_short_backtrace
40:21 :   4: rust_begin_unwind
40:21 :   5: core::panicking::panic_fmt
40:21 :   6: core::result::unwrap_failed
40:21 :   7: <sp_state_machine::ext::Ext<H,B> as sp_externalities::Externalities>::storage
40:21 :   8: sp_io::storage::ExtStorageGetVersion1::call
40:21 :   9: <F as wasmtime::func::IntoFunc<T,(wasmtime::func::Caller<T>,A1),R>>::into_func::wasm_to_host_shim
40:21 :  10: <unknown>
40:21 :  11: <unknown>
40:21 :  12: <unknown>
40:21 :  13: wasmtime_runtime::traphandlers::catch_traps::call_closure
40:21 :  14: wasmtime_setjmp
40:21 :  15: sc_executor_wasmtime::runtime::WasmtimeInstance::call_impl
40:21 :  16: sc_executor_common::wasm_runtime::WasmInstance::call_export
40:21 :  17: sc_executor::executor::WasmExecutor<H>::with_instance::{{closure}}
40:21 :  18: <sc_executor::executor::WasmExecutor<H> as sp_core::traits::CodeExecutor>::call
40:21 :  19: sp_state_machine::execution::StateMachine<B,H,Exec>::execute
40:21 :  20: <sc_service::client::client::Client<B,E,Block,RA> as sp_api::CallApiAt<Block>>::call_api_at
40:21 :  21: <polkadot_service::fake_runtime_api::RuntimeApiImpl<__SrApiBlock__,RuntimeApiImplCall> as sp_api::Core<__SrApiBlock__>>::__runtime_api_internal_call_api_at::{{closure}}
40:21 :  22: <polkadot_service::fake_runtime_api::RuntimeApiImpl<__SrApiBlock__,RuntimeApiImplCall> as sp_api::Core<__SrApiBlock__>>::__runtime_api_internal_call_api_at
40:21 :  23: sc_transaction_pool::api::validate_transaction_blocking
40:21 :  24: <sc_transaction_pool::api::FullChainApi<Client,Block> as sc_transaction_pool::graph::pool::ChainApi>::validate_transaction::{{closure}}::{{closure}}
40:21 :  25: sc_transaction_pool::api::spawn_validation_pool_task::{{closure}}
40:21 :  26: <futures_util::future::future::Map<Fut,F> as core::future::future::Future>::poll
40:21 :  27: <tracing_futures::Instrumented<T> as core::future::future::Future>::poll
40:21 :  28: tokio::runtime::task::raw::poll
40:21 :  29: std::sys::backtrace::__rust_begin_short_backtrace
40:21 :  30: core::ops::function::FnOnce::call_once{{vtable.shim}}
40:21 :  31: std::sys::pal::unix::thread::Thread::new::thread_start
40:21 :  32: start_thread
40:21 :  33: __GI___clone3
40:21 :Thread 'tokio-runtime-worker' panicked at 'Externalities not allowed to fail within runtime: "Trie lookup error: Database missing expected key: [key]"', /builds/substrate/primitives/state-machine/src/ext.rs:174
40:21 :This is a bug. Please report it at:
40:21 :        https://github.com/paritytech/polkadot-sdk/issues/new

@infrachris
Copy link

infrachris commented Nov 21, 2024

I had similar issue occur on one Kusama node today. ParityDB, running Polkadot 1.16.2 since the day it was released (downloaded from releases). Baremetal, 64GB RAM, i9-13900.

Nov 21 03:39:55 host03 polkadot[490816]: Thread 'tokio-runtime-worker' panicked at 'Externalities not allowed to fail within runtime: "Trie lookup error: Database missing expected key: 0x0a85f21a1b28e101d3b47a0b285724881390336e47d8a962b395c04121c4e576"', /builds/substrate/primitives/state-machine/src/ext.rs:174
Nov 21 03:39:55 host03 polkadot[490816]: This is a bug. Please report it at:
Nov 21 03:39:55 host03 polkadot[490816]:         https://github.com/paritytech/polkadot-sdk/issues/new
Nov 21 03:39:56 host03 systemd[1]: polkadot.service: Failed with result 'exit-code'.

Polkadot service restarted and ran successfully for several hours, then node became non-operational several hours later but in a peculiar way:

  • polkadot service still running
  • no errors in logs
  • node not appearing in telemetry
  • Prometheus detected polkadot as down

Attempting stop/start of polkadot service with 180 second wait. Service stayed failed. Restarted service again by restarting server, and service came back up on the second attempt, with numerous errors like this:

Nov 21 08:23:52 host03 polkadot[1439]: 2024-11-21 08:23:52 [0x5259525a7b069135556f50fda88baa1f7a0908a8a423c6a5fa6c39f9df7b6f9b] Trying to notify an import but the channel is full
Nov 21 08:23:52 host03 polkadot[1439]: 2024-11-21 08:23:52 [0x1a8002ae3ba85a77cb7eba490cc81735ae0bf16b8295aa6677b9c43dc8506ec6] Trying to notify an import but the channel is full

Those may be expected as the 'transactions in queue' in telemetry was quite high as the node caught up, and transactions in queue went to zero. No 'trying to notify' errors for last eight minutes.

@lexnv
Copy link
Contributor

lexnv commented Nov 21, 2024

@Anastasiia-Khab @infrachris Could you provide all warnings and errors you got from running the node?

Are there other types of warnings except for the "transaction in queue"? (probably best to "cat logs.txt | grep 'WARN|ERROR'")

@infrachris
Copy link

infrachris commented Nov 21, 2024

Here are the immediate prior 100 logs of warn or above:

Nov 21 03:39:48 host03 polkadot[490816]: 2024-11-21 03:39:48 [0xf45becf5298046ba0d087ff2d2600632f958d112cdccd44ab7450515ff04b403] Trying to notify an import but the channel is full
Nov 21 03:39:48 host03 polkadot[490816]: 2024-11-21 03:39:48 [0x5c4da357d9f5aa4d3a01e94df3056194667d9c1d38ee37dc22ba7b8856f35273] Trying to notify an import but the channel is full
Nov 21 03:39:48 host03 polkadot[490816]: 2024-11-21 03:39:48 [0xc5864aafb97ae9e9d8147f78d3cdea916d229ae1d6211ba158522f3c17aea7e4] Trying to notify an import but the channel is full
Nov 21 03:39:48 host03 polkadot[490816]: 2024-11-21 03:39:48 [0x4ccaa47ff6e5768ca3f8bfb903ef9b598929c67375ddbd2bc3cec7272c3ea07d] Trying to notify an import but the channel is full
Nov 21 03:39:48 host03 polkadot[490816]: 2024-11-21 03:39:48 [0xae784877a598eacf74c4560879d2888678ad8ea9e6ad1b09d4acab342d4b2a52] Trying to notify an import but the channel is full
Nov 21 03:39:48 host03 polkadot[490816]: 2024-11-21 03:39:48 [0xeab0d686fbd6661f5344053c191ac9fe8059cca54227daa3372c88af8b695ca7] Trying to notify an import but the channel is full
Nov 21 03:39:48 host03 polkadot[490816]: 2024-11-21 03:39:48 [0x8017681aede839e588874d17f39fd52936e8cdbd79586bbd16dccf011de96823] Trying to notify an import but the channel is full
Nov 21 03:39:48 host03 polkadot[490816]: 2024-11-21 03:39:48 [0x0eba71dbbf0022cf304401bdfb63621d20311b85c787c161c7d0491b746828fa] Trying to notify an import but the channel is full
Nov 21 03:39:48 host03 polkadot[490816]: 2024-11-21 03:39:48 [0x574d8d2e851d6f6b9f2678d2a746fb30ec7eeca6553a0f36fcd7226abe331954] Trying to notify an import but the channel is full
Nov 21 03:39:48 host03 polkadot[490816]: 2024-11-21 03:39:48 [0x1d067af12ede93c08b6fca7ba4b0cb348204442fa70ebf298e669433c42aba94] Trying to notify an import but the channel is full
Nov 21 03:39:48 host03 polkadot[490816]: 2024-11-21 03:39:48 [0xd0448a7d940c9ca960ded44a7d2303841962012f39fe7a7007a81fbc75f03913] Trying to notify an import but the channel is full
Nov 21 03:39:48 host03 polkadot[490816]: 2024-11-21 03:39:48 [0xa6beb89ee525313223fef5628927a744c19b9a2a6bb80ee30fc131164b6a88d2] Trying to notify an import but the channel is full
Nov 21 03:39:48 host03 polkadot[490816]: 2024-11-21 03:39:48 [0xea41f7dca36115a6f66e2d2eb3b759f8912bd0680e4ee5027a4a7854818ae7a1] Trying to notify an import but the channel is full
Nov 21 03:39:48 host03 polkadot[490816]: 2024-11-21 03:39:48 [0xdd97c38e40771c342660427b96f701d7e36e32e3e949967b9eb56c2c299f4286] Trying to notify an import but the channel is full
Nov 21 03:39:48 host03 polkadot[490816]: 2024-11-21 03:39:48 [0x09a8ae3d821c885f2fe2586aad86358a3ea5f97357cb7e01e5eac9da48dc9fa0] Trying to notify an import but the channel is full
Nov 21 03:39:48 host03 polkadot[490816]: 2024-11-21 03:39:48 [0xee740ffe5cb2701f8ca12319202db17358850bf14281409801957449f2e2283d] Trying to notify an import but the channel is full
Nov 21 03:39:48 host03 polkadot[490816]: 2024-11-21 03:39:48 [0xea790d7604f227332ba31443737289ab1d41e9be854fddb609c9c06411bf3c06] Trying to notify an import but the channel is full
Nov 21 03:39:48 host03 polkadot[490816]: 2024-11-21 03:39:48 [0x35c9c5aabcc87ef9bce022208513e7448726506b6994195f7a0d0d124d60168c] Trying to notify an import but the channel is full
Nov 21 03:39:48 host03 polkadot[490816]: 2024-11-21 03:39:48 [0xce675dc18aa6de32ac01f194daa3815e41ce1c81d9f385ec464deb8e0156cd57] Trying to notify an import but the channel is full
Nov 21 03:39:48 host03 polkadot[490816]: 2024-11-21 03:39:48 [0x5cb6aba6af56551b72ed7bf4bdef2bfe08c2f174e4e7765f681decfed8f55ee7] Trying to notify an import but the channel is full
Nov 21 03:39:48 host03 polkadot[490816]: 2024-11-21 03:39:48 [0x718945f948836c59cbacdf4f602ab757a4605a28fc32e301d754635f8f3f533c] Trying to notify an import but the channel is full
Nov 21 03:39:48 host03 polkadot[490816]: 2024-11-21 03:39:48 [0xb86f34a4be254ad5ee5e35ab790d653abd3ae245683b35cdbb86e9bf5e3d1d27] Trying to notify an import but the channel is full
Nov 21 03:39:48 host03 polkadot[490816]: 2024-11-21 03:39:48 [0x36b16c161d84c4e05e8f448890905a88b32980e12da297c24b10602deec11747] Trying to notify an import but the channel is full
Nov 21 03:39:48 host03 polkadot[490816]: 2024-11-21 03:39:48 [0x9b24c1df3f4408e0c31a8d86bc9743dd7cb1fbd4691e8d1f222f8ce4bb922c12] Trying to notify an import but the channel is full
Nov 21 03:39:48 host03 polkadot[490816]: 2024-11-21 03:39:48 [0xbdae34cf32102c7988cb3426b2d5405fae99105dd243c95f4aefd07eb672e258] Trying to notify an import but the channel is full
Nov 21 03:39:48 host03 polkadot[490816]: 2024-11-21 03:39:48 [0x8d189552ae100acef78fa3d4bac8151283e578c1ecf3b08f3ebf8e2292d967c9] Trying to notify an import but the channel is full
Nov 21 03:39:48 host03 polkadot[490816]: 2024-11-21 03:39:48 [0x726ad3da71b4853d33bfebf5b157d6241309505d9d40b4257e2325972761f577] Trying to notify an import but the channel is full
Nov 21 03:39:48 host03 polkadot[490816]: 2024-11-21 03:39:48 [0x1232b931531c2dd040b3a35acac8c6e2c23ce961e901fe7d09e2a1a831d9be46] Trying to notify an import but the channel is full
Nov 21 03:39:48 host03 polkadot[490816]: 2024-11-21 03:39:48 [0x10976b81ae38f5cf170dd302b34fedc2428b8a7b8d5256e5b0b7882073d1ce25] Trying to notify an import but the channel is full
Nov 21 03:39:48 host03 polkadot[490816]: 2024-11-21 03:39:48 [0x0b15fab64fb7c60bafa0e66fff8355ea3ec45161837466c942e2991acb6c0179] Trying to notify an import but the channel is full
Nov 21 03:39:48 host03 polkadot[490816]: 2024-11-21 03:39:48 [0x851a165f18b36f3ed2282221f21c5e43f796463dde7c92e3ff7310382f07e6da] Trying to notify an import but the channel is full
Nov 21 03:39:48 host03 polkadot[490816]: 2024-11-21 03:39:48 [0x180e8a643085f14510bec50f7e996768356a0cb0d564ca367eb9e5e9992fb6bf] Trying to notify an import but the channel is full
Nov 21 03:39:48 host03 polkadot[490816]: 2024-11-21 03:39:48 [0xf6683a64c0239ef60222b3e58e3cdf52023314455a8bad722e24398fdd79c3de] Trying to notify an import but the channel is full
Nov 21 03:39:48 host03 polkadot[490816]: 2024-11-21 03:39:48 [0x17b66b2f51d12bdbd65291ec3f690252966736f322463512ae9d5cbd56649db1] Trying to notify an import but the channel is full
Nov 21 03:39:48 host03 polkadot[490816]: 2024-11-21 03:39:48 [0xf738d3ab8bd2ffcbbe583e54cf1216a252718266743edafdf1583fa0eba6af0e] Trying to notify an import but the channel is full
Nov 21 03:39:48 host03 polkadot[490816]: 2024-11-21 03:39:48 [0x16283fda8db0cdce12fc6a9a55bfca55cf3bae9527316d3034aa6c07a7437b8e] Trying to notify an import but the channel is full
Nov 21 03:39:48 host03 polkadot[490816]: 2024-11-21 03:39:48 [0x7d06cd11ba8ac0809adc5b24e88146bbbd7a9d9d073b61eb443b3ee169ce1071] Trying to notify an import but the channel is full
Nov 21 03:39:48 host03 polkadot[490816]: 2024-11-21 03:39:48 [0x426f13b312e0d19a1ce1477068154b07529f1cc5a5ed9f07df46a686112e3c4c] Trying to notify an import but the channel is full
Nov 21 03:39:48 host03 polkadot[490816]: 2024-11-21 03:39:48 [0xa8d18e70b2313f2aea925444b624e17930f8c3640ffc6151d03db5c2473335f7] Trying to notify an import but the channel is full
Nov 21 03:39:48 host03 polkadot[490816]: 2024-11-21 03:39:48 [0x9731cd0629684df40152daa62c41c40afe2cc2f78d52b721d5d8decef16765b3] Trying to notify an import but the channel is full
Nov 21 03:39:48 host03 polkadot[490816]: 2024-11-21 03:39:48 [0x652c2a21e5bde1e6cedd03e9a23ec6320d12fdbcd2c947f36fb978a2f8129dd6] Trying to notify an import but the channel is full
Nov 21 03:39:48 host03 polkadot[490816]: 2024-11-21 03:39:48 [0x37698c0d980836dd0ea66860564939efcf3808ffcd294065ed38bda6e759b7b4] Trying to notify an import but the channel is full
Nov 21 03:39:48 host03 polkadot[490816]: 2024-11-21 03:39:48 [0x0f4d047115dcb220086c448815e143d10c1beef752cac061c40b95f586169773] Trying to notify an import but the channel is full
Nov 21 03:39:48 host03 polkadot[490816]: 2024-11-21 03:39:48 [0xed4fde9ec866c9214f0c4a4251e28bf608f7494febde98a2e325242cf0e68bb3] Trying to notify an import but the channel is full
Nov 21 03:39:48 host03 polkadot[490816]: 2024-11-21 03:39:48 [0x4a83937ec811a45ec89ef2393fd62ce69df0789ba1a33e5cf54ec16764c7ff9b] Trying to notify an import but the channel is full
Nov 21 03:39:48 host03 polkadot[490816]: 2024-11-21 03:39:48 [0x748864f8f4eb3d65656980d3ee3174da9192c16161dc2b723bad31f98b300991] Trying to notify an import but the channel is full
Nov 21 03:39:48 host03 polkadot[490816]: 2024-11-21 03:39:48 [0x391580ea159b4eab40eecd27fcac8470f2468b80bc03a242cfdd488015d71364] Trying to notify an import but the channel is full
Nov 21 03:39:48 host03 polkadot[490816]: 2024-11-21 03:39:48 [0xe7098933850944ea98dce356429f213057434b25a5d388d4eed5dc4118c02b34] Trying to notify an import but the channel is full
Nov 21 03:39:48 host03 polkadot[490816]: 2024-11-21 03:39:48 [0xeba9eaa3fe9b0165f3d6b6b0cf62ab5ffbf373d38ef67e23f3c446bcff5aa94a] Trying to notify an import but the channel is full
Nov 21 03:39:48 host03 polkadot[490816]: 2024-11-21 03:39:48 [0x098c12e8be3aa7521e44ca22df5b2f2a0d4d714bfcb5197830869b542e4a3eef] Trying to notify an import but the channel is full
Nov 21 03:39:48 host03 polkadot[490816]: 2024-11-21 03:39:48 [0xe2a90f5715a5d7cb4f406caf3c4d6b699a4f6c1bd45626d6abc797eefa4f06b4] Trying to notify an import but the channel is full
Nov 21 03:39:48 host03 polkadot[490816]: 2024-11-21 03:39:48 [0x637626831682dee5f77b5791f92d5f739b49b15e1f3cd5bd6fb31a0698029a35] Trying to notify an import but the channel is full
Nov 21 03:39:48 host03 polkadot[490816]: 2024-11-21 03:39:48 [0x6ca077f89a12327e21657ded678e363bc7ea701fc5fbb74ae61b32ea29b69886] Trying to notify an import but the channel is full
Nov 21 03:39:48 host03 polkadot[490816]: 2024-11-21 03:39:48 [0x22f999afde54ce8aa9411995fc39c4f564e151c3bdbb662b685019ab2f6d72da] Trying to notify an import but the channel is full
Nov 21 03:39:48 host03 polkadot[490816]: 2024-11-21 03:39:48 [0x8ad7636440f739e64082fb6da8ba74deec12ed74b371e4f032f4b11b9404d00d] Trying to notify an import but the channel is full
Nov 21 03:39:48 host03 polkadot[490816]: 2024-11-21 03:39:48 [0xffdf265c456c9925e1fb8336ab235e0d2825bf1857090a9aa549f7547f57f262] Trying to notify an import but the channel is full
Nov 21 03:39:48 host03 polkadot[490816]: 2024-11-21 03:39:48 [0x67e13d92a6a3d7982c4d9be16bd0346dadabb66914356f51341245341f8a76f9] Trying to notify an import but the channel is full
Nov 21 03:39:48 host03 polkadot[490816]: 2024-11-21 03:39:48 [0x31f82ec9cf4e1f70bde701ab4263175f038ed758dce4397134926ebb8915d1ff] Trying to notify an import but the channel is full
Nov 21 03:39:48 host03 polkadot[490816]: 2024-11-21 03:39:48 [0x7034fe6f33b09c1157fb53d346b180cf540240f10f2c365fe75d0effcb570ecd] Trying to notify an import but the channel is full
Nov 21 03:39:48 host03 polkadot[490816]: 2024-11-21 03:39:48 [0xb50c1c2d4970ef8f3e684c49e4336abaf3669937b48e0c888aaa75796a2894ff] Trying to notify an import but the channel is full
Nov 21 03:39:48 host03 polkadot[490816]: 2024-11-21 03:39:48 [0x373617674277d03090801d897716f9989451c351f65d6a39f73cfe22705d3c50] Trying to notify an import but the channel is full
Nov 21 03:39:48 host03 polkadot[490816]: 2024-11-21 03:39:48 [0x1443faf529cb5b45dfa517a57833d48c5a769f1beff051d01f4cdc7e37a588ae] Trying to notify an import but the channel is full
Nov 21 03:39:48 host03 polkadot[490816]: 2024-11-21 03:39:48 [0x08c6bdd24a939fcda8ee4c9e89200b37b98d5756e6bbe09536eb210ab5977ba2] Trying to notify an import but the channel is full
Nov 21 03:39:48 host03 polkadot[490816]: 2024-11-21 03:39:48 [0xd774c2d9da48d64331e8be73193d4362021d657b92411524a657bd2f60df816b] Trying to notify an import but the channel is full
Nov 21 03:39:55 host03 polkadot[490816]: ====================
Nov 21 03:39:55 host03 polkadot[490816]: Version: 1.16.2-dba2dd59101
Nov 21 03:39:55 host03 polkadot[490816]:    0: sp_panic_handler::set::{{closure}}
Nov 21 03:39:55 host03 polkadot[490816]:    1: std::panicking::rust_panic_with_hook
Nov 21 03:39:55 host03 polkadot[490816]:    2: std::panicking::begin_panic_handler::{{closure}}
Nov 21 03:39:55 host03 polkadot[490816]:    3: std::sys::backtrace::__rust_end_short_backtrace
Nov 21 03:39:55 host03 polkadot[490816]:    4: rust_begin_unwind
Nov 21 03:39:55 host03 polkadot[490816]:    5: core::panicking::panic_fmt
Nov 21 03:39:55 host03 polkadot[490816]:    6: core::result::unwrap_failed
Nov 21 03:39:55 host03 polkadot[490816]:    7: <sp_state_machine::ext::Ext<H,B> as sp_externalities::Externalities>::storage
Nov 21 03:39:55 host03 polkadot[490816]:    8: sp_io::storage::ExtStorageGetVersion1::call
Nov 21 03:39:55 host03 polkadot[490816]:    9: <F as wasmtime::func::IntoFunc<T,(wasmtime::func::Caller<T>,A1),R>>::into_func::wasm_to_host_shim
Nov 21 03:39:55 host03 polkadot[490816]:   10: <unknown>
Nov 21 03:39:55 host03 polkadot[490816]:   11: <unknown>
Nov 21 03:39:55 host03 polkadot[490816]:   12: <unknown>
Nov 21 03:39:55 host03 polkadot[490816]:   13: wasmtime_runtime::traphandlers::catch_traps::call_closure
Nov 21 03:39:55 host03 polkadot[490816]:   14: wasmtime_setjmp
Nov 21 03:39:55 host03 polkadot[490816]:   15: sc_executor_wasmtime::runtime::WasmtimeInstance::call_impl
Nov 21 03:39:55 host03 polkadot[490816]:   16: sc_executor_common::wasm_runtime::WasmInstance::call_export
Nov 21 03:39:55 host03 polkadot[490816]:   17: sc_executor::executor::WasmExecutor<H>::with_instance::{{closure}}
Nov 21 03:39:55 host03 polkadot[490816]:   18: <sc_executor::executor::WasmExecutor<H> as sp_core::traits::CodeExecutor>::call
Nov 21 03:39:55 host03 polkadot[490816]:   19: sp_state_machine::execution::StateMachine<B,H,Exec>::execute
Nov 21 03:39:55 host03 polkadot[490816]:   20: <sc_service::client::client::Client<B,E,Block,RA> as sp_api::CallApiAt<Block>>::call_api_at
Nov 21 03:39:55 host03 polkadot[490816]:   21: <polkadot_service::fake_runtime_api::RuntimeApiImpl<__SrApiBlock__,RuntimeApiImplCall> as sp_api::Core<__SrApiBlock__>>::__runtime_api_internal_call_api_at::{{closure}}
Nov 21 03:39:55 host03 polkadot[490816]:   22: <polkadot_service::fake_runtime_api::RuntimeApiImpl<__SrApiBlock__,RuntimeApiImplCall> as sp_api::Core<__SrApiBlock__>>::__runtime_api_internal_call_api_at
Nov 21 03:39:55 host03 polkadot[490816]:   23: sc_transaction_pool::api::validate_transaction_blocking
Nov 21 03:39:55 host03 polkadot[490816]:   24: <sc_transaction_pool::api::FullChainApi<Client,Block> as sc_transaction_pool::graph::pool::ChainApi>::validate_transaction::{{closure}}::{{closure}}
Nov 21 03:39:55 host03 polkadot[490816]:   25: sc_transaction_pool::api::spawn_validation_pool_task::{{closure}}
Nov 21 03:39:55 host03 polkadot[490816]:   26: <futures_util::future::future::Map<Fut,F> as core::future::future::Future>::poll
Nov 21 03:39:55 host03 polkadot[490816]:   27: <tracing_futures::Instrumented<T> as core::future::future::Future>::poll
Nov 21 03:39:55 host03 polkadot[490816]:   28: tokio::runtime::task::raw::poll
Nov 21 03:39:55 host03 polkadot[490816]:   29: std::sys::backtrace::__rust_begin_short_backtrace
Nov 21 03:39:55 host03 polkadot[490816]:   30: core::ops::function::FnOnce::call_once{{vtable.shim}}
Nov 21 03:39:55 host03 polkadot[490816]:   31: std::sys::pal::unix::thread::Thread::new::thread_start
Nov 21 03:39:55 host03 polkadot[490816]:   32: <unknown>
Nov 21 03:39:55 host03 polkadot[490816]:   33: clone
Nov 21 03:39:55 host03 polkadot[490816]: Thread 'tokio-runtime-worker' panicked at 'Externalities not allowed to fail within runtime: "Trie lookup error: Database missing expected key: 0x0a85f21a1b28e101d3b47a0b285724881390336e47d8a962b395c04121c4e576"', /builds/substrate/primitives/state-machine/src/ext.rs:174

@bkchr
Copy link
Member

bkchr commented Nov 21, 2024

@Anastasiia-Khab ty! Can you also answer my other questions?

@bkchr
Copy link
Member

bkchr commented Nov 22, 2024

panick.txt
tie-error.txt

I got two more logs. All of them are using paritydb and it failed while calling validate_transaction.

@arkpar @cheme does there exists any kind of in memory cache in parityb?

@arkpar
Copy link
Member

arkpar commented Nov 22, 2024

@arkpar @cheme does there exists any kind of in memory cache in parityb?

Yes, writing to the DB is asynchronous, so the stuff that's not yet commited to disk is kept in memory.
We should setup an instance and try and reproduce this locally. It sounds like there might be some kind of deadlock (node running, but not responding and not processing blocks). If this can be reproduced we'd need stack traces and parity-db logs.

@MattHalpinParity could you look into it?

@bkchr
Copy link
Member

bkchr commented Nov 22, 2024

This was happening during the spamming of Kusama, so a lot of data modifications were happening around this time.

@f33r0
Copy link

f33r0 commented Nov 22, 2024

This was happening during the spamming of Kusama, so a lot of data modifications were happening around this times.

seems like yesterday was loadtests.
1.5k transactions in queue at once.

@LukeWheeldon
Copy link

This means you have some storage corruption. Not that much we can do there.

Same here on Polkadot. I guess somehow we have all synchronized our storage corruption.

@f33r0
Copy link

f33r0 commented Nov 23, 2024

This means you have some storage corruption. Not that much we can do there.

Same here on Polkadot. I guess somehow we have all synchronized our storage corruption.

related with:
https://x.com/Polkadot/status/1859971941660348469

try to restart your nodes.

@bkchr
Copy link
Member

bkchr commented Nov 24, 2024

@LukeWheeldon did you also use paritydb?

@LukeWheeldon
Copy link

@bkchr nope, default settings on that side, rocksdb I believe.

@bkchr
Copy link
Member

bkchr commented Nov 24, 2024

@LukeWheeldon but you have seen the same logs? Can you post your logs please.

@LukeWheeldon
Copy link

@bkchr unfortunately I didn't have logging enabled when this happened. system has been stable since this has happened.

@bkchr
Copy link
Member

bkchr commented Nov 24, 2024

But you are 100% sure it was the same failure? How do you know if you didn't had logging enabled? 😅 (I just want to make sure this was not something different and would otherwise invalidate the assumptions here)

@LukeWheeldon
Copy link

@bkchr apologies, I should at least kept a screenshot of the error when it has crashed. At this point all I can say is that I feel pretty sure I have seen the same error as seen above:

Thread 'tokio-runtime-worker' panicked at 'Externalities not allowed to fail within runtime: "Trie lookup error: Database missing expected key: [KEY]"', /builds/substrate/primitives/state-machine/src/ext.rs:174

I'll report with at least the crash logs next time.

@infrachris
Copy link

@bkchr apologies, I should at least kept a screenshot of the error when it has crashed. At this point all I can say is that I feel pretty sure I have seen the same error as seen above:

Thread 'tokio-runtime-worker' panicked at 'Externalities not allowed to fail within runtime: "Trie lookup error: Database missing expected key: [KEY]"', /builds/substrate/primitives/state-machine/src/ext.rs:174

I'll report with at least the crash logs next time.

I also don't understand how you could see error messages if you had logs 'disabled'. Can you share how you believe you disabled logs?

Please try these commands one by one:

journalctl -u tokio-runtime-worker
journalctl -u panicked
journalctl -u polkadot

@Anastasiia-Khab
Copy link
Author

Anastasiia-Khab commented Nov 24, 2024

@Anastasiia-Khab @infrachris Could you provide all warnings and errors you got from running the node?

Are there other types of warnings except for the "transaction in queue"? (probably best to "cat logs.txt | grep 'WARN|ERROR'")

  1. There were no warnings, just errors:
2024-11-21 15:35:58 Block import error: Potential long-range attack: block not in finalized chain.
2024-11-21 15:35:58 💔 Error importing block 0x4525ff4fb6b18878ffd92c7e1cb28042c1f6ec623bc7ea2146149baf06a02b50: consensus error: Import failed: Import failed: Potential long-range attack: block not in finalized chain.
2024-11-21 15:40:21 : Thread 'tokio-runtime-worker' panicked at 'Externalities not allowed to fail within runtime: "Trie lookup error: Database missing expected key: [KEY]"', /builds/substrate/primitives/state-machine/src/ext.rs:174
2024-11-21 15:40:21 : Thread 'tokio-runtime-worker' panicked at 'Externalities not allowed to fail within runtime: "Trie lookup error: Database missing expected key: [KEY]"', /builds/substrate/primitives/state-machine/src/ext.rs:174
2024-11-21 15:44:07 💔 Error importing block 0xd13b6d8ab18f839b7318ce63a5d21140adf2e934d51c30c403469ef184ab32a3: block has an unknown parent
2024-11-21 15:44:07 💔 Error importing block 0x45fa422204fdca3c97661efae4b492c43627b2ea444bbad1212b5db94956ee21: block has an unknown parent
2024-11-21 15:44:07 💔 Error importing block 0x8d2d4d0d182192d91d00d802620a50b6dc3b177795438ec333d323a03db5201e: block has an unknown parent
2024-11-21 15:44:07 💔 Error importing block 0x8746c294a08ecccd671180232f3a7e2036a607374526393b63c5cd7d7f725369: block has an unknown parent
2024-11-21 15:44:07 💔 Error importing block 0xa2dbfcfd798f8717039d0f3366d1a7c8b84a8670dcd0ede4e34f430683b962eb: block has an unknown parent
2024-11-21 15:45:57 : Thread 'tokio-runtime-worker' panicked at 'Externalities not allowed to fail within runtime: "Trie lookup error: Database missing expected key: [KEY]"', /builds/substrate/primitives/state-machine/src/ext.rs:174
2024-11-21 15:45:57 : Thread 'tokio-runtime-worker' panicked at 'Externalities not allowed to fail within runtime: "Trie lookup error: Database missing expected key: [KEY]"', /builds/substrate/primitives/state-machine/src/ext.rs:174
  1. Also noticed same error on Polkadot on 22 of November, there was no messages like 💔 Error importing block... or Trying to notify an import but the channel is full prior to it. Even though they happened after restarting.
22:10:20 : 2024-11-22 22:10:20 💤 Idle (27 peers), best: #23527793 (0x8071…3009), finalized #23527791 (0xd0bb…8a10), ⬇ 5.9MiB/s ⬆ 6.2MiB/s
22:10:21 : 2024-11-22 22:10:21 🏆 Imported #23527794 (0x8071…3009 → 0x97fc…036e)
22:10:24 : ====================
22:10:24 : ====================
22:10:24 : Version: 1.16.2
22:10:24 :    0: sp_panic_handler::set::{{closure}}
22:10:24 :    1: std::panicking::rust_panic_with_hook
22:10:24 :    2: std::panicking::begin_panic_handler::{{closure}}
22:10:24 :    3: std::sys::backtrace::__rust_end_short_backtrace
22:10:24 :    4: rust_begin_unwind
22:10:24 :    5: core::panicking::panic_fmt
22:10:24 :    6: core::result::unwrap_failed
22:10:24 :    7: <sp_state_machine::ext::Ext<H,B> as sp_externalities::Externalities>::storage
22:10:24 :    8: sp_io::storage::ExtStorageGetVersion1::call
22:10:24 :    9: <F as wasmtime::func::IntoFunc<T,(wasmtime::func::Caller<T>,A1),R>>::into_func::wasm_to_host_shim
22:10:24 :   10: <unknown>
22:10:24 :   11: <unknown>
22:10:24 :   12: <unknown>
22:10:24 :   13: wasmtime_runtime::traphandlers::catch_traps::call_closure
22:10:24 :   14: wasmtime_setjmp
22:10:24 :   15: sc_executor_wasmtime::runtime::WasmtimeInstance::call_impl
22:10:24 :   16: sc_executor_common::wasm_runtime::WasmInstance::call_export
22:10:24 :   17: sc_executor::executor::WasmExecutor<H>::with_instance::{{closure}}
22:10:24 :   18: <sc_executor::executor::WasmExecutor<H> as sp_core::traits::CodeExecutor>::call
22:10:24 :   19: sp_state_machine::execution::StateMachine<B,H,Exec>::execute
22:10:24 :   20: <sc_service::client::client::Client<B,E,Block,RA> as sp_api::CallApiAt<Block>>::call_api_at
22:10:24 :   21: <polkadot_service::fake_runtime_api::RuntimeApiImpl<__SrApiBlock__,RuntimeApiImplCall> as sp_api::Core<__SrApiBlock__>>::__runtime_api_internal_call_api_at::{{closure}}
22:10:24 :   22: <polkadot_service::fake_runtime_api::RuntimeApiImpl<__SrApiBlock__,RuntimeApiImplCall> as sp_api::Core<__SrApiBlock__>>::__runtime_api_internal_call_api_at
22:10:24 :   23: sc_transaction_pool::api::validate_transaction_blocking
22:10:24 :   24: <sc_transaction_pool::api::FullChainApi<Client,Block> as sc_transaction_pool::graph::pool::ChainApi>::validate_transaction::{{closure}}::{{closure}}
22:10:24 :   25: sc_transaction_pool::api::spawn_validation_pool_task::{{closure}}
22:10:24 :   26: <futures_util::future::future::Map<Fut,F> as core::future::future::Future>::poll
22:10:24 :   27: <tracing_futures::Instrumented<T> as core::future::future::Future>::poll
22:10:24 :   28: tokio::runtime::task::raw::poll
22:10:24 :   29: std::sys::backtrace::__rust_begin_short_backtrace
22:10:24 :   30: core::ops::function::FnOnce::call_once{{vtable.shim}}
22:10:24 :   31: std::sys::pal::unix::thread::Thread::new::thread_start
22:10:24 :   32: start_thread
22:10:24 :   33: __GI___clone3
22:10:24 : Thread 'tokio-runtime-worker' panicked at 'Externalities not allowed to fail within runtime: "Trie lookup error: Database missing expected key: [KEY]"', /builds/substrate/primitives/state-machine/src/ext.rs:174
  1. These errors didn't happen to me after 22nd of November neither on Kusama nor on Polkadot.

@Anastasiia-Khab
Copy link
Author

Anastasiia-Khab commented Nov 24, 2024

@Anastasiia-Khab ty! Can you also answer my other questions?

I think it's here: #6547 (comment)

Not using Paritydb, using Rocksdb

@LukeWheeldon
Copy link

I also don't understand how you could see error messages if you had logs 'disabled'.

I have not elected to run polkadot as a service; prometheus informs me as soon as there is something wrong and I act on it if it is under my control. When the binary crashes, I generally do get some level of details for why it has crashed. What I meant is that I run polkadot with the default level of verbosity.

@lexnv
Copy link
Contributor

lexnv commented Nov 25, 2024

Might be related, I saw this in versi-net while testing something authority-disc improvements:

https://github.com/paritytech/polkadot-sdk/ | 5          | warn       | Timeout while trying to acquire a write lock for the shared trie cache

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
I2-bug The node fails to follow expected behavior. I10-unconfirmed Issue might be valid, but it's not yet known.
Projects
None yet
Development

No branches or pull requests

7 participants