more tracing for av-store (#2604)

* more tracing for av-store

* Update node/core/av-store/src/lib.rs

Co-authored-by: Bastian Köcher <bkchr@users.noreply.github.com>

* Update node/core/av-store/src/lib.rs

Co-authored-by: Bastian Köcher <bkchr@users.noreply.github.com>

* Update node/core/av-store/src/lib.rs

Co-authored-by: Bastian Köcher <bkchr@users.noreply.github.com>

* Update tracing everywhere

* Fix build

* More fixes

* Push cargo.lock

* Update

Co-authored-by: Bastian Köcher <bkchr@users.noreply.github.com>
Co-authored-by: Bastian Köcher <info@kchr.de>

polkadot/node/core/approval-voting/Cargo.toml

@@ -8,8 +8,7 @@ edition = "2018"
 futures = "0.3.8"
 futures-timer = "3.0.2"
 parity-scale-codec = { version = "2.0.0", default-features = false, features = ["bit-vec", "derive"] }
-tracing = "0.1.22"
-tracing-futures = "0.2.4"
+tracing = "0.1.25"
 bitvec = { version = "0.20.1", default-features = false, features = ["alloc"] }
 merlin = "2.0"
 schnorrkel = "0.9.1"

polkadot/node/core/av-store/Cargo.toml

@@ -10,8 +10,7 @@ futures-timer = "3.0.2"
 kvdb = "0.9.0"
 kvdb-rocksdb = "0.11.0"
 thiserror = "1.0.23"
-tracing = "0.1.22"
-tracing-futures = "0.2.4"
+tracing = "0.1.25"
 bitvec = "0.20.1"
 
 parity-scale-codec = { version = "2.0.0", features = ["derive"] }

polkadot/node/core/av-store/src/lib.rs

@@ -679,6 +679,12 @@ fn note_block_backed(
 ) -> Result<(), Error> {
 	let candidate_hash = candidate.hash();
 
+	tracing::trace!(
+		target: LOG_TARGET,
+		?candidate_hash,
+		"Candidate backed",
+	);
+
 	if load_meta(db, &candidate_hash)?.is_none() {
 		let meta = CandidateMeta {
 			state: State::Unavailable(now.into()),
@@ -717,6 +723,12 @@ fn note_block_included(
 		Some(mut meta) => {
 			let be_block = (BEBlockNumber(block.0), block.1);
 
+			tracing::trace!(
+				target: LOG_TARGET,
+				?candidate_hash,
+				"Candidate included",
+			);
+
 			meta.state = match meta.state {
 				State::Unavailable(at) => {
 					let at_d: Duration = at.into();
@@ -1045,6 +1057,13 @@ fn store_chunk(
 		None => return Ok(false), // out of bounds.
 	}
 
+	tracing::debug!(
+		target: LOG_TARGET,
+		?candidate_hash,
+		chunk_index = %chunk.index.0,
+		"Stored chunk index for candidate.",
+	);
+
 	db.write(tx)?;
 	Ok(true)
 }

polkadot/node/core/backing/Cargo.toml

@@ -14,8 +14,7 @@ polkadot-node-subsystem-util = { path = "../../subsystem-util" }
 erasure-coding = { package = "polkadot-erasure-coding", path = "../../../erasure-coding" }
 statement-table = { package = "polkadot-statement-table", path = "../../../statement-table" }
 bitvec = { version = "0.20.1", default-features = false, features = ["alloc"] }
-tracing = "0.1.22"
-tracing-futures = "0.2.4"
+tracing = "0.1.25"
 thiserror = "1.0.23"
 
 [dev-dependencies]

polkadot/node/core/bitfield-signing/Cargo.toml

@@ -6,8 +6,7 @@ edition = "2018"
 
 [dependencies]
 futures = "0.3.12"
-tracing = "0.1.22"
-tracing-futures = "0.2.4"
+tracing = "0.1.25"
 polkadot-primitives = { path = "../../../primitives" }
 polkadot-node-subsystem = { path = "../../subsystem" }
 polkadot-node-subsystem-util = { path = "../../subsystem-util" }

polkadot/node/core/candidate-selection/Cargo.toml

@@ -6,8 +6,7 @@ edition = "2018"
 
 [dependencies]
 futures = "0.3.12"
-tracing = "0.1.22"
-tracing-futures = "0.2.4"
+tracing = "0.1.25"
 thiserror = "1.0.23"
 
 sp-keystore = { git = "https://github.com/paritytech/substrate", branch = "master" }

polkadot/node/core/candidate-validation/Cargo.toml

@@ -6,8 +6,7 @@ edition = "2018"
 
 [dependencies]
 futures = "0.3.12"
-tracing = "0.1.22"
-tracing-futures = "0.2.4"
+tracing = "0.1.25"
 
 sp-core = { package = "sp-core", git = "https://github.com/paritytech/substrate", branch = "master" }
 parity-scale-codec = { version = "2.0.0", default-features = false, features = ["bit-vec", "derive"] }

polkadot/node/core/chain-api/Cargo.toml

@@ -6,8 +6,7 @@ edition = "2018"
 
 [dependencies]
 futures = "0.3.12"
-tracing = "0.1.22"
-tracing-futures = "0.2.4"
+tracing = "0.1.25"
 sp-blockchain = { git = "https://github.com/paritytech/substrate", branch = "master" }
 polkadot-primitives = { path = "../../../primitives" }
 polkadot-subsystem = { package = "polkadot-node-subsystem", path = "../../subsystem" }

polkadot/node/core/proposer/Cargo.toml

@@ -7,7 +7,7 @@ edition = "2018"
 [dependencies]
 futures = "0.3.12"
 futures-timer = "3.0.2"
-tracing = "0.1.22"
+tracing = "0.1.25"
 polkadot-node-subsystem = { path = "../../subsystem" }
 polkadot-overseer = { path = "../../overseer" }
 polkadot-primitives = { path = "../../../primitives" }

polkadot/node/core/provisioner/Cargo.toml

@@ -7,8 +7,7 @@ edition = "2018"
 [dependencies]
 bitvec = { version = "0.20.1", default-features = false, features = ["alloc"] }
 futures = "0.3.12"
-tracing = "0.1.22"
-tracing-futures = "0.2.4"
+tracing = "0.1.25"
 thiserror = "1.0.23"
 polkadot-primitives = { path = "../../../primitives" }
 polkadot-node-subsystem = { path = "../../subsystem" }

polkadot/node/core/runtime-api/Cargo.toml

@@ -6,8 +6,7 @@ edition = "2018"
 
 [dependencies]
 futures = "0.3.12"
-tracing = "0.1.22"
-tracing-futures = "0.2.4"
+tracing = "0.1.25"
 memory-lru = "0.1.0"
 parity-util-mem = { version = "0.9.0", default-features = false }