[INFO] fetching crate ockam 0.52.0...
[INFO] testing ockam-0.52.0 against 1.60.0 for beta-1.61-1
[INFO] extracting crate ockam 0.52.0 into /workspace/builds/worker-80/source
[INFO] validating manifest of crates.io crate ockam 0.52.0 on toolchain 1.60.0
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+1.60.0" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] started tweaking crates.io crate ockam 0.52.0
[INFO] removed 1 missing tests
[INFO] finished tweaking crates.io crate ockam 0.52.0
[INFO] tweaked toml for crates.io crate ockam 0.52.0 written to /workspace/builds/worker-80/source/Cargo.toml
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+1.60.0" "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" "+1.60.0" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded ockam_transport_tcp v0.47.0
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-80/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-80/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:b0c94ce3c1162fcb8e57cac5b65ec2f72eabb1eebea4fcc35e269e823f681646" "/opt/rustwide/cargo-home/bin/cargo" "+1.60.0" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] b3fdfa0508a4d84fb14a2855378a29c6dd7d810d81276dfe64d5253250c5b6dc
[INFO] running `Command { std: "docker" "start" "-a" "b3fdfa0508a4d84fb14a2855378a29c6dd7d810d81276dfe64d5253250c5b6dc", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "b3fdfa0508a4d84fb14a2855378a29c6dd7d810d81276dfe64d5253250c5b6dc", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "b3fdfa0508a4d84fb14a2855378a29c6dd7d810d81276dfe64d5253250c5b6dc", kill_on_drop: false }`
[INFO] [stdout] b3fdfa0508a4d84fb14a2855378a29c6dd7d810d81276dfe64d5253250c5b6dc
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-80/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-80/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=warn" "-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:b0c94ce3c1162fcb8e57cac5b65ec2f72eabb1eebea4fcc35e269e823f681646" "/opt/rustwide/cargo-home/bin/cargo" "+1.60.0" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] a75802758077b9f854e3c9fa4580966e1b9355a1928ff57eadf56aeb5dc7477e
[INFO] running `Command { std: "docker" "start" "-a" "a75802758077b9f854e3c9fa4580966e1b9355a1928ff57eadf56aeb5dc7477e", kill_on_drop: false }`
[INFO] [stderr]    Compiling cfg-if v1.0.0
[INFO] [stderr]    Compiling proc-macro2 v1.0.37
[INFO] [stderr]    Compiling unicode-xid v0.2.2
[INFO] [stderr]    Compiling version_check v0.9.4
[INFO] [stderr]    Compiling syn v1.0.91
[INFO] [stderr]    Compiling libc v0.2.123
[INFO] [stderr]    Compiling typenum v1.15.0
[INFO] [stderr]    Compiling subtle v2.4.1
[INFO] [stderr]    Compiling serde_derive v1.0.136
[INFO] [stderr]    Compiling serde v1.0.136
[INFO] [stderr]    Compiling memchr v2.4.1
[INFO] [stderr]    Compiling autocfg v1.1.0
[INFO] [stderr]    Compiling byteorder v1.4.3
[INFO] [stderr]    Compiling pin-project-lite v0.2.8
[INFO] [stderr]    Compiling futures-core v0.3.21
[INFO] [stderr]    Compiling futures-channel v0.3.21
[INFO] [stderr]    Compiling once_cell v1.10.0
[INFO] [stderr]    Compiling ppv-lite86 v0.2.16
[INFO] [stderr]    Compiling futures-task v0.3.21
[INFO] [stderr]    Compiling scopeguard v1.1.0
[INFO] [stderr]    Compiling futures-util v0.3.21
[INFO] [stderr]    Compiling futures-sink v0.3.21
[INFO] [stderr]    Compiling getrandom v0.1.16
[INFO] [stderr]    Compiling futures-io v0.3.21
[INFO] [stderr]    Compiling async-trait v0.1.53
[INFO] [stderr]    Compiling lazy_static v1.4.0
[INFO] [stderr]    Compiling slab v0.4.6
[INFO] [stderr]    Compiling pin-utils v0.1.0
[INFO] [stderr]    Compiling heapless v0.7.10
[INFO] [stderr]    Compiling stable_deref_trait v1.2.0
[INFO] [stderr]    Compiling log v0.4.16
[INFO] [stderr]    Compiling hex v0.4.3
[INFO] [stderr]    Compiling opaque-debug v0.3.0
[INFO] [stderr]    Compiling crossbeam-utils v0.8.8
[INFO] [stderr]    Compiling cpufeatures v0.2.2
[INFO] [stderr]    Compiling regex-syntax v0.6.25
[INFO] [stderr]    Compiling crossbeam-queue v0.3.5
[INFO] [stderr]    Compiling ansi_term v0.12.1
[INFO] [stderr]    Compiling smallvec v1.8.0
[INFO] [stderr]    Compiling bytes v1.1.0
[INFO] [stderr]    Compiling signature v1.5.0
[INFO] [stderr]    Compiling serde_json v1.0.79
[INFO] [stderr]    Compiling itoa v1.0.1
[INFO] [stderr]    Compiling ryu v1.0.9
[INFO] [stderr]    Compiling arrayref v0.3.6
[INFO] [stderr]    Compiling ahash v0.4.7
[INFO] [stderr]    Compiling dyn-clone v1.0.5
[INFO] [stderr]    Compiling thread_local v1.1.4
[INFO] [stderr]    Compiling ed25519 v1.4.1
[INFO] [stderr]    Compiling tracing-core v0.1.25
[INFO] [stderr]    Compiling sharded-slab v0.1.4
[INFO] [stderr]    Compiling hash32 v0.2.1
[INFO] [stderr]    Compiling hashbrown v0.9.1
[INFO] [stderr]    Compiling lock_api v0.4.7
[INFO] [stderr]    Compiling generic-array v0.14.5
[INFO] [stderr]    Compiling ahash v0.7.6
[INFO] [stderr]    Compiling core2 v0.3.3
[INFO] [stderr]    Compiling tracing-log v0.1.2
[INFO] [stderr]    Compiling getrandom v0.2.6
[INFO] [stderr]    Compiling mio v0.8.2
[INFO] [stderr]    Compiling socket2 v0.4.4
[INFO] [stderr]    Compiling num_cpus v1.13.1
[INFO] [stderr]    Compiling quote v1.0.18
[INFO] [stderr]    Compiling rand_core v0.5.1
[INFO] [stderr]    Compiling rand_core v0.6.3
[INFO] [stderr]    Compiling rand_chacha v0.3.1
[INFO] [stderr]    Compiling ff v0.10.1
[INFO] [stderr]    Compiling spin v0.9.2
[INFO] [stderr]    Compiling rand_chacha v0.2.2
[INFO] [stderr]    Compiling rand v0.7.3
[INFO] [stderr]    Compiling group v0.10.0
[INFO] [stderr]    Compiling rand v0.8.5
[INFO] [stderr]    Compiling regex-automata v0.1.10
[INFO] [stderr]    Compiling regex v1.5.5
[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.9
[INFO] [stderr]    Compiling hmac v0.11.0
[INFO] [stderr]    Compiling aes v0.7.5
[INFO] [stderr]    Compiling ctr v0.8.0
[INFO] [stderr]    Compiling polyval v0.5.3
[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 matchers v0.1.0
[INFO] [stderr]    Compiling synstructure v0.12.6
[INFO] [stderr]    Compiling zeroize_derive v1.3.2
[INFO] [stderr]    Compiling futures-macro v0.3.21
[INFO] [stderr]    Compiling ockam_macros v0.12.0
[INFO] [stderr]    Compiling tracing-attributes v0.1.20
[INFO] [stderr]    Compiling tokio-macros v1.7.0
[INFO] [stderr]    Compiling tokio v1.17.0
[INFO] [stderr]    Compiling zeroize v1.5.4
[INFO] [stderr]    Compiling curve25519-dalek v3.2.0
[INFO] [stderr]    Compiling tracing v0.1.33
[INFO] [stderr]    Compiling ed25519-dalek v1.0.1
[INFO] [stderr]    Compiling x25519-dalek v1.1.1
[INFO] [stderr]    Compiling tracing-subscriber v0.3.11
[INFO] [stderr]    Compiling futures v0.3.21
[INFO] [stderr]    Compiling hashbrown v0.11.2
[INFO] [stderr]    Compiling serde_bare v0.5.0
[INFO] [stderr]    Compiling serde-big-array v0.3.3
[INFO] [stderr]    Compiling ockam_core v0.51.0
[INFO] [stderr]    Compiling ockam_executor v0.19.0
[INFO] [stderr]    Compiling ockam_key_exchange_core v0.42.0
[INFO] [stderr]    Compiling ockam_transport_core v0.24.0
[INFO] [stderr]    Compiling ockam_key_exchange_xx v0.44.0
[INFO] [stderr]    Compiling ockam_node v0.51.0
[INFO] [stderr]    Compiling ockam_vault v0.44.0
[INFO] [stderr]    Compiling ockam_transport_tcp v0.47.0
[INFO] [stderr]    Compiling ockam_channel v0.47.0
[INFO] [stderr]    Compiling ockam_identity v0.41.0
[INFO] [stderr]    Compiling ockam v0.52.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 1m 01s
[INFO] running `Command { std: "docker" "inspect" "a75802758077b9f854e3c9fa4580966e1b9355a1928ff57eadf56aeb5dc7477e", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "a75802758077b9f854e3c9fa4580966e1b9355a1928ff57eadf56aeb5dc7477e", kill_on_drop: false }`
[INFO] [stdout] a75802758077b9f854e3c9fa4580966e1b9355a1928ff57eadf56aeb5dc7477e
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-80/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-80/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=warn" "-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:b0c94ce3c1162fcb8e57cac5b65ec2f72eabb1eebea4fcc35e269e823f681646" "/opt/rustwide/cargo-home/bin/cargo" "+1.60.0" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 488f39776a2dcb37cb2f29b135e92e3f45ec31ae7cafcc8da14794aea8ac9807
[INFO] running `Command { std: "docker" "start" "-a" "488f39776a2dcb37cb2f29b135e92e3f45ec31ae7cafcc8da14794aea8ac9807", kill_on_drop: false }`
[INFO] [stderr]    Compiling trybuild v1.0.59
[INFO] [stderr]    Compiling dissimilar v1.0.3
[INFO] [stderr]    Compiling glob v0.3.0
[INFO] [stderr]    Compiling termcolor v1.1.3
[INFO] [stderr]    Compiling rand_xorshift v0.3.0
[INFO] [stderr]    Compiling toml v0.5.8
[INFO] [stderr]    Compiling ockam_key_exchange_xx v0.44.0
[INFO] [stderr]    Compiling ockam_vault v0.44.0
[INFO] [stderr]    Compiling ockam_channel v0.47.0
[INFO] [stderr]    Compiling ockam_identity v0.41.0
[INFO] [stderr]    Compiling ockam v0.52.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 27.09s
[INFO] running `Command { std: "docker" "inspect" "488f39776a2dcb37cb2f29b135e92e3f45ec31ae7cafcc8da14794aea8ac9807", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "488f39776a2dcb37cb2f29b135e92e3f45ec31ae7cafcc8da14794aea8ac9807", kill_on_drop: false }`
[INFO] [stdout] 488f39776a2dcb37cb2f29b135e92e3f45ec31ae7cafcc8da14794aea8ac9807
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-80/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-80/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=warn" "-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:b0c94ce3c1162fcb8e57cac5b65ec2f72eabb1eebea4fcc35e269e823f681646" "/opt/rustwide/cargo-home/bin/cargo" "+1.60.0" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 83d2b9b1c3c7a64e8347a43fbfc759d3ea96139f0367e802c0d7a8b301111bf5
[INFO] running `Command { std: "docker" "start" "-a" "83d2b9b1c3c7a64e8347a43fbfc759d3ea96139f0367e802c0d7a8b301111bf5", kill_on_drop: false }`
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 0.09s
[INFO] [stderr]      Running unittests (/opt/rustwide/target/debug/deps/ockam-4c9557db1396c65c)
[INFO] [stdout] 
[INFO] [stdout] running 23 tests
[INFO] [stdout] test lease::test_serialization ... ok
[INFO] [stdout] test metadata::nest_metadata ... ok
[INFO] [stdout] 2022-04-13T20:17:57.247712Z  INFO ockam_node::node: Initializing ockam node
[INFO] [stdout] 2022-04-13T20:17:57.247754Z  INFO ockam_node::node: Initializing ockam node
[INFO] [stdout] 2022-04-13T20:17:57.247743Z  INFO ockam_node::node: Initializing ockam node
[INFO] [stdout] 2022-04-13T20:17:57.247726Z  INFO ockam_node::node: Initializing ockam node
[INFO] [stdout] 2022-04-13T20:17:57.247742Z  INFO ockam_node::node: Initializing ockam node
[INFO] [stdout] 2022-04-13T20:17:57.247743Z  INFO ockam_node::node: Initializing ockam node
[INFO] [stdout] 2022-04-13T20:17:57.247742Z  INFO ockam_node::node: Initializing ockam node
[INFO] [stdout] 2022-04-13T20:17:57.247733Z  INFO ockam_node::node: Initializing ockam node
[INFO] [stdout] 2022-04-13T20:17:57.247830Z  INFO ockam_node::node: Initializing ockam node
[INFO] [stdout] 2022-04-13T20:17:57.247806Z  INFO ockam_node::node: Initializing ockam node
[INFO] [stdout] 2022-04-13T20:17:57.247947Z  INFO ockam_node::node: Initializing ockam node
[INFO] [stdout] 2022-04-13T20:17:57.247806Z  INFO ockam_node::node: Initializing ockam node
[INFO] [stdout] 2022-04-13T20:17:57.247838Z  INFO ockam_node::node: Initializing ockam node
[INFO] [stdout] 2022-04-13T20:17:57.247786Z  INFO ockam_node::node: Initializing ockam node
[INFO] [stdout] 2022-04-13T20:17:57.247742Z  INFO ockam_node::node: Initializing ockam node
[INFO] [stdout] 2022-04-13T20:17:57.247838Z  INFO ockam_node::node: Initializing ockam node
[INFO] [stdout] 2022-04-13T20:17:57.247732Z  INFO ockam_node::node: Initializing ockam node
[INFO] [stdout] 2022-04-13T20:17:57.248062Z  INFO ockam_node::node: Initializing ockam node
[INFO] [stdout] 2022-04-13T20:17:57.247838Z  INFO ockam_node::node: Initializing ockam node
[INFO] [stdout] 2022-04-13T20:17:57.248119Z  INFO ockam_node::node: Initializing ockam node
[INFO] [stdout] 2022-04-13T20:17:57.248149Z  INFO ockam_node::node: Initializing ockam node
[INFO] [stdout] 2022-04-13T20:17:57.708430Z  INFO ockam::channel::listener: Receiving new channel creation request from Route { inner: [0#5752fab3a426a7cdd66ce2d20fa55d12] }: ChannelCreationHandshake { channel_addr: 0#11357ae8a6e8414ad77b43328ca52af6, tx_addr: 0#d46607514078922be952094e437dee5d, tx_int_addr: 0#6af77ea3c1ec264335a4b908794c20dc, rx_addr: 0#0b1e34a4de455716d274a37e682dbafb, rx_int_addr: 0#459173c6c9d35c0f8a119b623b913afa }
[INFO] [stdout] 2022-04-13T20:17:57.718175Z  INFO ockam::channel::tests: Received message 'Hello through the channel!' through channel
[INFO] [stdout] 2022-04-13T20:17:57.718462Z  INFO ockam_node::router::shutdown: Initiate graceful node shutdown
[INFO] [stdout] 2022-04-13T20:17:57.721447Z  INFO ockam_node::router: No more workers left.  Goodbye!
[INFO] [stdout] test channel::tests::reliable_channel ... ok
[INFO] [stdout] 2022-04-13T20:17:57.816685Z  INFO ockam::pipe::tests: Sending message 'Hello Ockam!' through pipe sender 0#fe4b34ec5953bcf9734a05e4dd0cc354
[INFO] [stdout] 2022-04-13T20:17:57.824581Z  INFO ockam::pipe::tests: App reiceved msg: 'Hello Ockam!'
[INFO] [stdout] 2022-04-13T20:17:57.824729Z  INFO ockam_node::router::shutdown: Initiate graceful node shutdown
[INFO] [stdout] 2022-04-13T20:17:57.836372Z  WARN ockam::remote::test: No CLOUD_ADDRESS specified, skipping the test
[INFO] [stdout] 2022-04-13T20:17:57.836536Z  WARN ockam::remote::test: No CLOUD_ADDRESS specified, skipping the test
[INFO] [stdout] 2022-04-13T20:17:57.837280Z  INFO ockam_node::router::shutdown: Initiate graceful node shutdown
[INFO] [stdout] 2022-04-13T20:17:57.840017Z  INFO ockam_node::router: No more workers left.  Goodbye!
[INFO] [stdout] 2022-04-13T20:17:57.842123Z  INFO ockam_node::router::shutdown: Initiate graceful node shutdown
[INFO] [stdout] 2022-04-13T20:17:57.842251Z  INFO ockam_node::router: No more workers left.  Goodbye!
[INFO] [stdout] 2022-04-13T20:17:57.858670Z  INFO ockam::pipe::tests: Sending message 'Hello Ockam!' through pipe sender 0#a9b5fcaea15f934d56a951b6cdd826b6
[INFO] [stdout] 2022-04-13T20:17:57.914747Z  INFO ockam_node::router: No more workers left.  Goodbye!
[INFO] [stdout] 2022-04-13T20:17:57.974912Z  INFO ockam::pipe::tests: Sending message 'Message for my best friend' through pipe sender 0#f4755727691e2c497c2aad8c0b0b9892
[INFO] [stdout] 2022-04-13T20:17:57.976285Z  INFO ockam::pipe::listener: Creating new PipeReceiver for incoming handshake
[INFO] [stdout] 2022-04-13T20:17:57.977896Z  INFO ockam::pipe::tests: App received msg: 'Message for my best friend'
[INFO] [stdout] 2022-04-13T20:17:57.977990Z  INFO ockam_node::router::shutdown: Initiate graceful node shutdown
[INFO] [stdout] 2022-04-13T20:17:57.978789Z  INFO ockam_node::router: No more workers left.  Goodbye!
[INFO] [stdout] test pipe::tests::static_simple_pipe ... ok
[INFO] [stdout] 2022-04-13T20:17:58.060732Z  INFO ockam::pipe::tests: App reiceved msg: 'Hello Ockam!'
[INFO] [stdout] 2022-04-13T20:17:58.061383Z  INFO ockam_node::router::shutdown: Initiate graceful node shutdown
[INFO] [stdout] 2022-04-13T20:17:58.062562Z  INFO ockam_node::router: No more workers left.  Goodbye!
[INFO] [stdout] 2022-04-13T20:17:58.080394Z  INFO ockam::system::tests: Handling message via StepHandler
[INFO] [stdout] 2022-04-13T20:17:58.082254Z  INFO ockam::pipe::tests: Sending message 'Hello Ockam!' through pipe sender 0#3031ee3a09f4908e3fe2cf664d2a04dc
[INFO] [stdout] 2022-04-13T20:17:58.088953Z  INFO ockam::system::hooks::delivery: Received ACK for message: 0#13d1da947c12d3d092008b5c78169ea5
[INFO] [stdout] 2022-04-13T20:17:58.090378Z  INFO ockam_node::router::shutdown: Initiate graceful node shutdown
[INFO] [stdout] 2022-04-13T20:17:58.093629Z  INFO ockam_node::router::shutdown: Initiate graceful node shutdown
[INFO] [stdout] 2022-04-13T20:17:58.094827Z  INFO ockam_node::router: No more workers left.  Goodbye!
[INFO] [stdout] 2022-04-13T20:17:58.095866Z  INFO ockam::system::tests: Handling message via StepHandler
[INFO] [stdout] 2022-04-13T20:17:58.099945Z  INFO ockam_node::router: No more workers left.  Goodbye!
[INFO] [stdout] test pipe::tests::static_confirm_pipe ... ok
[INFO] [stdout] test pipe2::tests::handshake_pipe ... ok
[INFO] [stdout] 2022-04-13T20:17:58.154478Z  INFO ockam::pipe2::tests: Created receiver pipe: 0#3676458baf0db851904bbf8f9de006d1
[INFO] [stdout] 2022-04-13T20:17:58.167505Z  INFO ockam::system::tests: Received message 'Hello Ockam!'
[INFO] [stdout] 2022-04-13T20:17:58.167867Z  INFO ockam_node::router::shutdown: Initiate graceful node shutdown
[INFO] [stdout] test pipe2::tests::dynamic_delivery_pipe ... ok
[INFO] [stdout] 2022-04-13T20:17:58.170905Z  INFO ockam::system::tests: Handling message for AddMetadata
[INFO] [stdout] 2022-04-13T20:17:58.171452Z  INFO ockam_node::router: No more workers left.  Goodbye!
[INFO] [stdout] test remote::test::forwarding__static_address__should_respond ... ok
[INFO] [stdout] 2022-04-13T20:17:58.175500Z  INFO ockam::system::tests: Handling message for AddMetadata
[INFO] [stdout] 2022-04-13T20:17:58.177054Z  INFO ockam::system::tests: Received message metadata: 'Some(Metadata({"bar": [7], "foo": [42]}))'
[INFO] [stdout] 2022-04-13T20:17:58.177149Z  INFO ockam::system::tests: Received message data: Hello Ockam!
[INFO] [stdout] 2022-04-13T20:17:58.179542Z  INFO ockam_node::router::shutdown: Initiate graceful node shutdown
[INFO] [stdout] 2022-04-13T20:17:58.181854Z  INFO ockam::pipe::tests: Sending message 'Message number one' through pipe sender 0#502a51b49b0e294c121139a07910b477
[INFO] [stdout] test pipe::tests::simple_pipe_handshake ... ok
[INFO] [stdout] 2022-04-13T20:17:58.183735Z  INFO ockam::pipe2::tests: Starting the test...
[INFO] [stdout] 2022-04-13T20:17:58.184146Z  INFO ockam::pipe::tests: Sending message 'Message number one' through pipe sender 0#ac5e58bd545323699b6c2edcd8a19bec
[INFO] [stdout] 2022-04-13T20:17:58.185107Z  INFO ockam::pipe::tests: Sending message 'Message number two' through pipe sender 0#ac5e58bd545323699b6c2edcd8a19bec
[INFO] [stdout] 2022-04-13T20:17:58.254033Z  INFO ockam::system::hooks::delivery: Received ACK for message: 0#e26391375344aa2a7e5d1e50f5175ffb
[INFO] [stdout] 2022-04-13T20:17:58.254203Z  INFO ockam::pipe2::tests: Created sender pipe: 0#cdebe2cfeea4107ff11205123f49bc04
[INFO] [stdout] 2022-04-13T20:17:58.261817Z  INFO ockam::pipe2::tests: Created receiver pipe: 0#b66d0a16c34611d6c939591881aec003
[INFO] [stdout] 2022-04-13T20:17:58.262070Z  INFO ockam_node::router::shutdown: Initiate graceful node shutdown
[INFO] [stdout] 2022-04-13T20:17:58.266350Z  INFO ockam_node::router: No more workers left.  Goodbye!
[INFO] [stdout] 2022-04-13T20:17:58.266550Z  INFO ockam::pipe::tests: Sending message 'Message number two' through pipe sender 0#502a51b49b0e294c121139a07910b477
[INFO] [stdout] 2022-04-13T20:17:58.270148Z  INFO ockam::pipe2::tests: Created sender pipe: 0#3df3882307927abed885c35ab991d11d
[INFO] [stdout] test remote::test::forwarding__ephemeral_address__should_respond ... ok
[INFO] [stdout] test system::tests::send_messages ... ok
[INFO] [stdout] 2022-04-13T20:17:58.293091Z  INFO ockam_node::router: No more workers left.  Goodbye!
[INFO] [stdout] 2022-04-13T20:17:58.311882Z  INFO ockam::pipe2::tests: Created receiver pipe: 0#4b4fc132b41deaa098a2d5cde1e10ebf
[INFO] [stdout] 2022-04-13T20:17:58.330443Z  INFO ockam::pipe2::tests: Created sender pipe: 0#fa6ac4f48ffd92a335349783d4efc3d9
[INFO] [stdout] 2022-04-13T20:17:58.332664Z  INFO ockam::pipe2::tests: Created receiver pipe: 0#eacff85c0252c8b22969c013453b6bd6
[INFO] [stdout] 2022-04-13T20:17:58.333214Z  INFO ockam::pipe2::tests: Created sender pipe: 0#80786e2f1ef7c20ec526ff2ef5d96294
[INFO] [stdout] test pipe2::tests::dynamic_delivery_and_ordering_pipe ... ok
[INFO] [stdout] 2022-04-13T20:17:58.350087Z  INFO ockam_node::router::shutdown: Initiate graceful node shutdown
[INFO] [stdout] 2022-04-13T20:17:58.350749Z  INFO ockam_node::router: No more workers left.  Goodbye!
[INFO] [stdout] 2022-04-13T20:17:58.352179Z  INFO ockam::channel::listener: Receiving new channel creation request from Route { inner: [0#1bbf2f72071744edf6d7d10023e58609] }: ChannelCreationHandshake { channel_addr: 0#4d9d9761221e2a8981ce231ad047697a, tx_addr: 0#b8e86d651f071afabfb5b327a1101fef, tx_int_addr: 0#ed54d1c4b0109c03854d2e60718dc312, rx_addr: 0#6aead3198dd9070eef34b266b205f16e, rx_int_addr: 0#3ffc1610a34bfe1f2f9d9ecad997ce7c }
[INFO] [stdout] 2022-04-13T20:17:58.353833Z  INFO ockam_node::router::shutdown: Initiate graceful node shutdown
[INFO] [stdout] 2022-04-13T20:17:58.354559Z  INFO ockam_node::router: No more workers left.  Goodbye!
[INFO] [stdout] 2022-04-13T20:17:58.360735Z  INFO ockam::pipe::tests: App received message: Hello through nested pipes!
[INFO] [stdout] 2022-04-13T20:17:58.360976Z  INFO ockam_node::router::shutdown: Initiate graceful node shutdown
[INFO] [stdout] 2022-04-13T20:17:58.362371Z  INFO ockam_node::router: No more workers left.  Goodbye!
[INFO] [stdout] 2022-04-13T20:17:58.363977Z  INFO ockam::system::hooks::delivery: Received ACK for message: 0#9bf3e143235e27b236ad758b588e03d2
[INFO] [stdout] 2022-04-13T20:17:58.382200Z  INFO ockam_node::router::shutdown: Initiate graceful node shutdown
[INFO] [stdout] 2022-04-13T20:17:58.384119Z  INFO ockam_node::router: No more workers left.  Goodbye!
[INFO] [stdout] 2022-04-13T20:17:58.397513Z  INFO ockam::pipe::tests: App reiceved msg: 'Message number one'
[INFO] [stdout] 2022-04-13T20:17:58.397917Z  INFO ockam::pipe::tests: App reiceved msg: 'Message number one'
[INFO] [stdout] 2022-04-13T20:17:58.399862Z  INFO ockam::pipe::tests: App reiceved msg: 'Message number two'
[INFO] [stdout] 2022-04-13T20:17:58.400050Z  INFO ockam_node::router::shutdown: Initiate graceful node shutdown
[INFO] [stdout] 2022-04-13T20:17:58.400162Z  INFO ockam::pipe::tests: App reiceved msg: 'Message number two'
[INFO] [stdout] 2022-04-13T20:17:58.400571Z  INFO ockam_node::router: No more workers left.  Goodbye!
[INFO] [stdout] 2022-04-13T20:17:58.400525Z  INFO ockam_node::router::shutdown: Initiate graceful node shutdown
[INFO] [stdout] 2022-04-13T20:17:58.403842Z  INFO ockam_node::router: No more workers left.  Goodbye!
[INFO] [stdout] test pipe2::tests::fixed_delivery_and_ordering_pipe ... ok
[INFO] [stdout] test pipe::tests::static_confirm_ordering_pipe ... ok
[INFO] [stdout] test pipe::tests::static_confirm_ordering_pipe_reversed ... ok
[INFO] [stdout] 2022-04-13T20:17:58.449383Z  INFO ockam::pipe::tests: Sending message 'Message number one' through pipe sender 0#c77d88cedb47708cbb4409e873d72ed4
[INFO] [stdout] 2022-04-13T20:17:58.449410Z  INFO ockam::system::hooks::delivery: Received ACK for message: 0#2f5f2cffec2e2c8746366aa15afdfadf
[INFO] [stdout] 2022-04-13T20:17:58.450548Z  INFO ockam_node::router::shutdown: Initiate graceful node shutdown
[INFO] [stdout] 2022-04-13T20:17:58.451132Z  INFO ockam_node::router: No more workers left.  Goodbye!
[INFO] [stdout] 2022-04-13T20:17:58.451355Z  INFO ockam::pipe::tests: Sending message 'Message number two' through pipe sender 0#c77d88cedb47708cbb4409e873d72ed4
[INFO] [stdout] 2022-04-13T20:17:58.453178Z  INFO ockam::pipe::tests: App reiceved msg: 'Message number one'
[INFO] [stdout] test pipe2::tests::fixed_delivery_pipe ... ok
[INFO] [stdout] 2022-04-13T20:17:58.453636Z  INFO ockam::pipe::tests: App reiceved msg: 'Message number two'
[INFO] [stdout] 2022-04-13T20:17:58.453859Z  INFO ockam_node::router::shutdown: Initiate graceful node shutdown
[INFO] [stdout] 2022-04-13T20:17:58.454466Z  INFO ockam_node::router: No more workers left.  Goodbye!
[INFO] [stdout] 2022-04-13T20:17:58.455611Z  INFO ockam::channel::tests: Received message 'Hello through the channel!' through channel
[INFO] [stdout] 2022-04-13T20:17:58.460030Z  INFO ockam_node::router::shutdown: Initiate graceful node shutdown
[INFO] [stdout] test system::tests::attach_metadata ... ok
[INFO] [stdout] 2022-04-13T20:17:58.537166Z  INFO ockam_node::router: No more workers left.  Goodbye!
[INFO] [stdout] test pipe::tests::layered_pipe ... ok
[INFO] [stdout] test pipe::tests::static_ordering_pipe ... ok
[INFO] [stdout] test pipe2::tests::fixed_ordering_pipe ... ok
[INFO] [stdout] test pipe2::tests::very_simple_pipe2 ... ok
[INFO] [stdout] test channel::tests::simple_channel ... ok
[INFO] [stdout] 2022-04-13T20:17:59.101618Z  WARN ockam_node::router::shutdown: Shutdown timeout reached; aborting node!
[INFO] [stdout] 2022-04-13T20:17:59.101798Z ERROR ockam_node::router::shutdown: Failed to send node abort signal to router
[INFO] [stdout] 2022-04-13T20:17:59.292191Z  WARN ockam_node::router::shutdown: Shutdown timeout reached; aborting node!
[INFO] [stdout] 2022-04-13T20:17:59.292366Z ERROR ockam_node::router::shutdown: Failed to send node abort signal to router
[INFO] [stdout] 2022-04-13T20:18:02.863082Z ERROR ockam::delay: Failed to send delayed message: Error { code: 11010, domain: "ockam_node::error::InternalIOFailure" }
[INFO] [stdout] 2022-04-13T20:18:02.918077Z ERROR ockam::delay: Failed to send delayed message: Error { code: 11010, domain: "ockam_node::error::InternalIOFailure" }
[INFO] [stdout] 2022-04-13T20:18:03.174089Z ERROR ockam::delay: Failed to send delayed message: Error { code: 11010, domain: "ockam_node::error::InternalIOFailure" }
[INFO] [stdout] 2022-04-13T20:18:03.267403Z  INFO ockam::pipe::tests: Sender received timeout for sent message!
[INFO] [stdout] 2022-04-13T20:18:03.267799Z  WARN ockam::pipe::tests: App reiceved msg: 'Shut it down...'
[INFO] [stdout] 2022-04-13T20:18:03.267955Z  INFO ockam_node::router::shutdown: Initiate graceful node shutdown
[INFO] [stdout] 2022-04-13T20:18:03.268462Z  INFO ockam_node::router: No more workers left.  Goodbye!
[INFO] [stdout] test pipe::tests::fails_static_confirm_pipe ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 23 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 6.04s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests ockam
[INFO] [stdout] 
[INFO] [stdout] running 3 tests
[INFO] [stdout] test src/pipe2/mod.rs - pipe2::PipeBuilder (line 58) ... ok
[INFO] [stdout] test src/pipe2/mod.rs - pipe2::PipeBuilder (line 78) ... ok
[INFO] [stdout] test src/channel/mod.rs - channel::ChannelBuilder::new (line 31) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.64s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "83d2b9b1c3c7a64e8347a43fbfc759d3ea96139f0367e802c0d7a8b301111bf5", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "83d2b9b1c3c7a64e8347a43fbfc759d3ea96139f0367e802c0d7a8b301111bf5", kill_on_drop: false }`
[INFO] [stdout] 83d2b9b1c3c7a64e8347a43fbfc759d3ea96139f0367e802c0d7a8b301111bf5