[INFO] fetching crate ockam 0.40.0... [INFO] testing ockam-0.40.0 against master#62efba8a050c64249dab942951bb28f710208bc8 for pr-90799 [INFO] extracting crate ockam 0.40.0 into /workspace/builds/worker-4/source [INFO] validating manifest of crates.io crate ockam 0.40.0 on toolchain 62efba8a050c64249dab942951bb28f710208bc8 [INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+62efba8a050c64249dab942951bb28f710208bc8" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }` [INFO] started tweaking crates.io crate ockam 0.40.0 [INFO] finished tweaking crates.io crate ockam 0.40.0 [INFO] tweaked toml for crates.io crate ockam 0.40.0 written to /workspace/builds/worker-4/source/Cargo.toml [INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+62efba8a050c64249dab942951bb28f710208bc8" "generate-lockfile" "--manifest-path" "Cargo.toml" "-Zno-index-update", kill_on_drop: false }` [INFO] [stderr] Blocking waiting for file lock on package cache [INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+62efba8a050c64249dab942951bb28f710208bc8" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] [stderr] Blocking waiting for file lock on package cache [INFO] [stderr] Blocking waiting for file lock on package cache [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:e8397a04204aaf8b9f284182a6f9e27a3c2e67044d0943b4415b160bfc957681" "/opt/rustwide/cargo-home/bin/cargo" "+62efba8a050c64249dab942951bb28f710208bc8" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] 6e91fe3357937cce05e544b00c80e371696c07db79e4984a87a6dd7c3dc74453 [INFO] running `Command { std: "docker" "start" "-a" "6e91fe3357937cce05e544b00c80e371696c07db79e4984a87a6dd7c3dc74453", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "6e91fe3357937cce05e544b00c80e371696c07db79e4984a87a6dd7c3dc74453", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "6e91fe3357937cce05e544b00c80e371696c07db79e4984a87a6dd7c3dc74453", kill_on_drop: false }` [INFO] [stdout] 6e91fe3357937cce05e544b00c80e371696c07db79e4984a87a6dd7c3dc74453 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:e8397a04204aaf8b9f284182a6f9e27a3c2e67044d0943b4415b160bfc957681" "/opt/rustwide/cargo-home/bin/cargo" "+62efba8a050c64249dab942951bb28f710208bc8" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] fcd4ebdb087ec5067648a9a8148ded041bb26d20d910fc0419c2fed8308c10ab [INFO] running `Command { std: "docker" "start" "-a" "fcd4ebdb087ec5067648a9a8148ded041bb26d20d910fc0419c2fed8308c10ab", kill_on_drop: false }` [INFO] [stderr] Blocking waiting for file lock on package cache [INFO] [stderr] Blocking waiting for file lock on package cache [INFO] [stderr] Compiling syn v1.0.82 [INFO] [stderr] Compiling version_check v0.9.3 [INFO] [stderr] Compiling typenum v1.14.0 [INFO] [stderr] Compiling pin-project-lite v0.2.7 [INFO] [stderr] Compiling futures-core v0.3.18 [INFO] [stderr] Compiling byteorder v1.4.3 [INFO] [stderr] Compiling futures-channel v0.3.18 [INFO] [stderr] Compiling futures-task v0.3.18 [INFO] [stderr] Compiling serde v1.0.130 [INFO] [stderr] Compiling ppv-lite86 v0.2.15 [INFO] [stderr] Compiling once_cell v1.8.0 [INFO] [stderr] Compiling futures-sink v0.3.18 [INFO] [stderr] Compiling subtle v2.4.1 [INFO] [stderr] Compiling futures-util v0.3.18 [INFO] [stderr] Compiling pin-utils v0.1.0 [INFO] [stderr] Compiling spin v0.5.2 [INFO] [stderr] Compiling futures-io v0.3.18 [INFO] [stderr] Compiling async-trait v0.1.51 [INFO] [stderr] Compiling heapless v0.7.8 [INFO] [stderr] Compiling getrandom v0.1.16 [INFO] [stderr] Compiling stable_deref_trait v1.2.0 [INFO] [stderr] Compiling hex v0.4.3 [INFO] [stderr] Compiling log v0.4.14 [INFO] [stderr] Compiling opaque-debug v0.3.0 [INFO] [stderr] Compiling cpufeatures v0.2.1 [INFO] [stderr] Compiling crossbeam-utils v0.8.5 [INFO] [stderr] Compiling crossbeam-queue v0.3.2 [INFO] [stderr] Compiling woke v0.0.2 [INFO] [stderr] Compiling signature v1.4.0 [INFO] [stderr] Compiling bytes v1.1.0 [INFO] [stderr] Compiling smallvec v1.7.0 [INFO] [stderr] Compiling ansi_term v0.12.1 [INFO] [stderr] Compiling arrayref v0.3.6 [INFO] [stderr] Compiling ahash v0.4.7 [INFO] [stderr] Compiling dyn-clone v1.0.4 [INFO] [stderr] Compiling lock_api v0.4.5 [INFO] [stderr] Compiling tokio v1.14.0 [INFO] [stderr] Compiling regex-automata v0.1.10 [INFO] [stderr] Compiling regex v1.5.4 [INFO] [stderr] Compiling getrandom v0.2.3 [INFO] [stderr] Compiling core2 v0.3.2 [INFO] [stderr] Compiling lazy_static v1.4.0 [INFO] [stderr] Compiling ed25519 v1.3.0 [INFO] [stderr] Compiling thread_local v1.1.3 [INFO] [stderr] Compiling hash32 v0.2.1 [INFO] [stderr] Compiling tracing-core v0.1.21 [INFO] [stderr] Compiling sharded-slab v0.1.4 [INFO] [stderr] Compiling hashbrown v0.9.1 [INFO] [stderr] Compiling rand_core v0.6.3 [INFO] [stderr] Compiling spin v0.9.2 [INFO] [stderr] Compiling generic-array v0.14.4 [INFO] [stderr] Compiling ahash v0.7.6 [INFO] [stderr] Compiling ff v0.10.1 [INFO] [stderr] Compiling rand_chacha v0.3.1 [INFO] [stderr] Compiling rand_core v0.5.1 [INFO] [stderr] Compiling group v0.10.0 [INFO] [stderr] Compiling tracing v0.1.29 [INFO] [stderr] Compiling tracing-log v0.1.2 [INFO] [stderr] Compiling mio v0.7.14 [INFO] [stderr] Compiling rand_chacha v0.2.2 [INFO] [stderr] Compiling matchers v0.1.0 [INFO] [stderr] Compiling rand v0.8.4 [INFO] [stderr] Compiling rand v0.7.3 [INFO] [stderr] Compiling tracing-subscriber v0.3.3 [INFO] [stderr] Compiling digest v0.9.0 [INFO] [stderr] Compiling universal-hash v0.4.1 [INFO] [stderr] Compiling cipher v0.3.0 [INFO] [stderr] Compiling block-buffer v0.9.0 [INFO] [stderr] Compiling crypto-mac v0.11.1 [INFO] [stderr] Compiling aead v0.4.3 [INFO] [stderr] Compiling sha2 v0.9.8 [INFO] [stderr] Compiling polyval v0.5.3 [INFO] [stderr] Compiling hmac v0.11.0 [INFO] [stderr] Compiling aes v0.7.5 [INFO] [stderr] Compiling ctr v0.8.0 [INFO] [stderr] Compiling hkdf v0.11.0 [INFO] [stderr] Compiling ghash v0.4.4 [INFO] [stderr] Compiling aes-gcm v0.9.4 [INFO] [stderr] Compiling synstructure v0.12.6 [INFO] [stderr] Compiling serde_derive v1.0.130 [INFO] [stderr] Compiling futures-macro v0.3.18 [INFO] [stderr] Compiling ockam_message_derive v0.1.0 [INFO] [stderr] Compiling ockam_macro v0.1.0 [INFO] [stderr] Compiling zeroize_derive v1.2.2 [INFO] [stderr] Compiling executor-macros v0.1.1 [INFO] [stderr] Compiling tokio-macros v1.6.0 [INFO] [stderr] Compiling ockam_node_test_attribute v0.5.0 [INFO] [stderr] Compiling ockam_node_attribute v0.27.0 [INFO] [stderr] Compiling executor v0.7.0 [INFO] [stderr] Compiling ockam_node_no_std v0.12.0 [INFO] [stderr] Compiling zeroize v1.4.3 [INFO] [stderr] Compiling curve25519-dalek v3.2.0 [INFO] [stderr] Compiling x25519-dalek v1.1.1 [INFO] [stderr] Compiling ed25519-dalek v1.0.1 [INFO] [stderr] Compiling futures-executor v0.3.18 [INFO] [stderr] Compiling futures v0.3.18 [INFO] [stderr] Compiling hashbrown v0.11.2 [INFO] [stderr] Compiling serde_bare v0.5.0 [INFO] [stderr] Compiling serde-big-array v0.3.2 [INFO] [stderr] Compiling ockam_core v0.40.0 [INFO] [stderr] Compiling ockam_vault_core v0.34.0 [INFO] [stderr] Compiling ockam_executor v0.9.0 [INFO] [stderr] Compiling ockam_transport_core v0.14.0 [INFO] [stderr] Compiling ockam_vault v0.34.0 [INFO] [stderr] Compiling ockam_key_exchange_core v0.32.0 [INFO] [stderr] Compiling ockam_node v0.39.0 [INFO] [stderr] Compiling ockam_key_exchange_xx v0.33.0 [INFO] [stderr] Compiling ockam_vault_sync_core v0.32.0 [INFO] [stderr] Compiling ockam_transport_tcp v0.35.0 [INFO] [stderr] Compiling ockam_channel v0.36.0 [INFO] [stderr] Compiling ockam_entity v0.30.0 [INFO] [stderr] Compiling ockam v0.40.0 (/opt/rustwide/workdir) [INFO] [stderr] Finished dev [unoptimized + debuginfo] target(s) in 35.41s [INFO] running `Command { std: "docker" "inspect" "fcd4ebdb087ec5067648a9a8148ded041bb26d20d910fc0419c2fed8308c10ab", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "fcd4ebdb087ec5067648a9a8148ded041bb26d20d910fc0419c2fed8308c10ab", kill_on_drop: false }` [INFO] [stdout] fcd4ebdb087ec5067648a9a8148ded041bb26d20d910fc0419c2fed8308c10ab [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:e8397a04204aaf8b9f284182a6f9e27a3c2e67044d0943b4415b160bfc957681" "/opt/rustwide/cargo-home/bin/cargo" "+62efba8a050c64249dab942951bb28f710208bc8" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 272e84fc2f8b95c514487515beb7e13f48c37f10d33f9b032b006726f0a0771a [INFO] running `Command { std: "docker" "start" "-a" "272e84fc2f8b95c514487515beb7e13f48c37f10d33f9b032b006726f0a0771a", kill_on_drop: false }` [INFO] [stderr] Compiling serde v1.0.130 [INFO] [stderr] Compiling trybuild v1.0.52 [INFO] [stderr] Compiling glob v0.3.0 [INFO] [stderr] Compiling termcolor v1.1.2 [INFO] [stderr] Compiling dissimilar v1.0.3 [INFO] [stderr] Compiling rand_xorshift v0.3.0 [INFO] [stderr] Compiling serde_bare v0.5.0 [INFO] [stderr] Compiling hashbrown v0.11.2 [INFO] [stderr] Compiling serde-big-array v0.3.2 [INFO] [stderr] Compiling serde_json v1.0.72 [INFO] [stderr] Compiling toml v0.5.8 [INFO] [stderr] Compiling ockam_core v0.40.0 [INFO] [stderr] Compiling ockam_vault_core v0.34.0 [INFO] [stderr] Compiling ockam_executor v0.9.0 [INFO] [stderr] Compiling ockam_transport_core v0.14.0 [INFO] [stderr] Compiling ockam_node v0.39.0 [INFO] [stderr] Compiling ockam_key_exchange_core v0.32.0 [INFO] [stderr] Compiling ockam_vault v0.34.0 [INFO] [stderr] Compiling ockam_key_exchange_xx v0.33.0 [INFO] [stderr] Compiling ockam_vault_sync_core v0.32.0 [INFO] [stderr] Compiling ockam_transport_tcp v0.35.0 [INFO] [stderr] Compiling ockam_channel v0.36.0 [INFO] [stderr] Compiling ockam_entity v0.30.0 [INFO] [stderr] Compiling ockam v0.40.0 (/opt/rustwide/workdir) [INFO] [stderr] Finished test [unoptimized + debuginfo] target(s) in 22.47s [INFO] running `Command { std: "docker" "inspect" "272e84fc2f8b95c514487515beb7e13f48c37f10d33f9b032b006726f0a0771a", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "272e84fc2f8b95c514487515beb7e13f48c37f10d33f9b032b006726f0a0771a", kill_on_drop: false }` [INFO] [stdout] 272e84fc2f8b95c514487515beb7e13f48c37f10d33f9b032b006726f0a0771a [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:e8397a04204aaf8b9f284182a6f9e27a3c2e67044d0943b4415b160bfc957681" "/opt/rustwide/cargo-home/bin/cargo" "+62efba8a050c64249dab942951bb28f710208bc8" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] 369216291a1ae29654ffd9ae52d968f4f2c9903123b3a68bd586864a8b3aed29 [INFO] running `Command { std: "docker" "start" "-a" "369216291a1ae29654ffd9ae52d968f4f2c9903123b3a68bd586864a8b3aed29", kill_on_drop: false }` [INFO] [stderr] Finished test [unoptimized + debuginfo] target(s) in 0.27s [INFO] [stderr] Running unittests (/opt/rustwide/target/debug/deps/ockam-c1ba7d9546d367a5) [INFO] [stdout] [INFO] [stdout] running 6 tests [INFO] [stdout] test lease::test_serialization ... ok [INFO] [stdout] 2021-12-02T16:31:23.820172Z  INFO ockam_node::node: Initializing ockam node [INFO] [stdout] 2021-12-02T16:31:23.820227Z  INFO ockam_node::node: Initializing ockam node [INFO] [stdout] 2021-12-02T16:31:23.820584Z  INFO ockam_node::node: Initializing ockam node [INFO] [stdout] 2021-12-02T16:31:23.820612Z  INFO ockam_node::node: Initializing ockam node [INFO] [stdout] 2021-12-02T16:31:23.820648Z  INFO ockam_node::node: Initializing ockam node [INFO] [stdout] 2021-12-02T16:31:23.993865Z  INFO ockam::pipe::tests: Sending message 'Hello Ockam!' through pipe sender 0#0da1e5e14e395c2d9862ece5f0e065a0 [INFO] [stdout] 2021-12-02T16:31:24.052485Z  INFO ockam::pipe::tests: Sending message 'Hello Ockam!' through pipe sender 0#d9f32d7f4c1210c53c375f5893318513 [INFO] [stdout] 2021-12-02T16:31:24.057320Z  INFO ockam::pipe::tests: Sending message 'Hello Ockam!' through pipe sender 0#9193b0659b30c4e5c372cb28a0cb254f [INFO] [stdout] 2021-12-02T16:31:24.057424Z  INFO ockam::pipe::tests: App reiceved msg: 'Hello Ockam!' [INFO] [stdout] 2021-12-02T16:31:24.057653Z  INFO ockam_node::router::shutdown: Initiate graceful node shutdown [INFO] [stdout] 2021-12-02T16:31:24.058257Z  INFO ockam::pipe::tests: Sending message 'Message for my best friend' through pipe sender 0#2c600601d2c15ad17ba84d79ba03ead7 [INFO] [stdout] 2021-12-02T16:31:24.058344Z  INFO ockam::pipe::tests: Sending message 'Message number one' through pipe sender 0#ffbec714f331e5632bce19299d83d349 [INFO] [stdout] 2021-12-02T16:31:24.058508Z  INFO ockam_node::router: No more workers left. Goodbye! [INFO] [stdout] 2021-12-02T16:31:24.058895Z  INFO ockam::pipe::tests: Sending message 'Message number two' through pipe sender 0#ffbec714f331e5632bce19299d83d349 [INFO] [stdout] 2021-12-02T16:31:24.059280Z  INFO ockam::pipe::listener: Creating new PipeReceiver for incoming handshake [INFO] [stdout] 2021-12-02T16:31:24.060711Z  INFO ockam::pipe::tests: App reiceved msg: 'Hello Ockam!' [INFO] [stdout] 2021-12-02T16:31:24.061001Z  INFO ockam::pipe::tests: App reiceved msg: 'Message number one' [INFO] [stdout] 2021-12-02T16:31:24.061154Z  INFO ockam_node::router::shutdown: Initiate graceful node shutdown [INFO] [stdout] 2021-12-02T16:31:24.061235Z  INFO ockam::pipe::tests: App reiceved msg: 'Message number two' [INFO] [stdout] 2021-12-02T16:31:24.061347Z  INFO ockam_node::router::shutdown: Initiate graceful node shutdown [INFO] [stdout] 2021-12-02T16:31:24.061904Z  INFO ockam_node::router: No more workers left. Goodbye! [INFO] [stdout] 2021-12-02T16:31:24.062230Z  INFO ockam_node::router: No more workers left. Goodbye! [INFO] [stdout] test pipe::tests::static_confirm_pipe ... ok [INFO] [stdout] 2021-12-02T16:31:24.062803Z  INFO ockam::pipe::tests: App received msg: 'Message for my best friend' [INFO] [stdout] 2021-12-02T16:31:24.064038Z  INFO ockam_node::router::shutdown: Initiate graceful node shutdown [INFO] [stdout] 2021-12-02T16:31:24.064830Z  INFO ockam_node::router: No more workers left. Goodbye! [INFO] [stdout] test pipe::tests::static_ordering_pipe ... ok [INFO] [stdout] test pipe::tests::simple_pipe_handshake ... ok [INFO] [stdout] test pipe::tests::static_simple_pipe ... ok [INFO] [stdout] 2021-12-02T16:31:28.998265Z  INFO ockam::pipe::tests: Sender received timeout for sent message! [INFO] [stdout] 2021-12-02T16:31:28.998611Z  WARN ockam::pipe::tests: App reiceved msg: 'Shut it down...' [INFO] [stdout] 2021-12-02T16:31:28.998655Z  INFO ockam_node::context: Immediately shutting down all workers [INFO] [stdout] 2021-12-02T16:31:28.998911Z  WARN ockam_node::router: One (or more) internal I/O failures caused by ungraceful router shutdown! [INFO] [stdout] test pipe::tests::fails_static_confirm_pipe ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 6 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 5.20s [INFO] [stdout] [INFO] [stderr] Doc-tests ockam [INFO] [stdout] [INFO] [stdout] running 0 tests [INFO] [stdout] [INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s [INFO] [stdout] [INFO] running `Command { std: "docker" "inspect" "369216291a1ae29654ffd9ae52d968f4f2c9903123b3a68bd586864a8b3aed29", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "369216291a1ae29654ffd9ae52d968f4f2c9903123b3a68bd586864a8b3aed29", kill_on_drop: false }` [INFO] [stdout] 369216291a1ae29654ffd9ae52d968f4f2c9903123b3a68bd586864a8b3aed29