[INFO] fetching crate ockam 0.52.0...
[INFO] testing ockam-0.52.0 against beta-2022-04-10 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 beta-2022-04-10
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+beta-2022-04-10" "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" "+beta-2022-04-10" "generate-lockfile" "--manifest-path" "Cargo.toml" "-Zno-index-update", kill_on_drop: false }`
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+beta-2022-04-10" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[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" "+beta-2022-04-10" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] a2deb85601a71cdf46d020b6246e442b44eb04441831c409f93ddfb935db6256
[INFO] running `Command { std: "docker" "start" "-a" "a2deb85601a71cdf46d020b6246e442b44eb04441831c409f93ddfb935db6256", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "a2deb85601a71cdf46d020b6246e442b44eb04441831c409f93ddfb935db6256", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "a2deb85601a71cdf46d020b6246e442b44eb04441831c409f93ddfb935db6256", kill_on_drop: false }`
[INFO] [stdout] a2deb85601a71cdf46d020b6246e442b44eb04441831c409f93ddfb935db6256
[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" "+beta-2022-04-10" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 9c00952b7758efb0221a5e77f424117b3d925b44f259e1ec6d68fdb4ea36241e
[INFO] running `Command { std: "docker" "start" "-a" "9c00952b7758efb0221a5e77f424117b3d925b44f259e1ec6d68fdb4ea36241e", 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 memchr v2.4.1
[INFO] [stderr]    Compiling serde v1.0.136
[INFO] [stderr]    Compiling futures-core v0.3.21
[INFO] [stderr]    Compiling autocfg v1.1.0
[INFO] [stderr]    Compiling pin-project-lite v0.2.8
[INFO] [stderr]    Compiling byteorder v1.4.3
[INFO] [stderr]    Compiling once_cell v1.10.0
[INFO] [stderr]    Compiling futures-task v0.3.21
[INFO] [stderr]    Compiling futures-channel v0.3.21
[INFO] [stderr]    Compiling ppv-lite86 v0.2.16
[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 heapless v0.7.10
[INFO] [stderr]    Compiling futures-io v0.3.21
[INFO] [stderr]    Compiling getrandom v0.1.16
[INFO] [stderr]    Compiling lazy_static v1.4.0
[INFO] [stderr]    Compiling pin-utils v0.1.0
[INFO] [stderr]    Compiling slab v0.4.6
[INFO] [stderr]    Compiling async-trait v0.1.53
[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 cpufeatures v0.2.2
[INFO] [stderr]    Compiling crossbeam-utils v0.8.8
[INFO] [stderr]    Compiling regex-syntax v0.6.25
[INFO] [stderr]    Compiling opaque-debug v0.3.0
[INFO] [stderr]    Compiling crossbeam-queue v0.3.5
[INFO] [stderr]    Compiling smallvec v1.8.0
[INFO] [stderr]    Compiling bytes v1.1.0
[INFO] [stderr]    Compiling ansi_term v0.12.1
[INFO] [stderr]    Compiling signature v1.5.0
[INFO] [stderr]    Compiling serde_json v1.0.79
[INFO] [stderr]    Compiling ryu v1.0.9
[INFO] [stderr]    Compiling itoa v1.0.1
[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 tracing-core v0.1.25
[INFO] [stderr]    Compiling sharded-slab v0.1.4
[INFO] [stderr]    Compiling thread_local v1.1.4
[INFO] [stderr]    Compiling ed25519 v1.4.1
[INFO] [stderr]    Compiling hash32 v0.2.1
[INFO] [stderr]    Compiling hashbrown v0.9.1
[INFO] [stderr]    Compiling generic-array v0.14.5
[INFO] [stderr]    Compiling ahash v0.7.6
[INFO] [stderr]    Compiling lock_api v0.4.7
[INFO] [stderr]    Compiling tracing-log v0.1.2
[INFO] [stderr]    Compiling core2 v0.3.3
[INFO] [stderr]    Compiling quote v1.0.18
[INFO] [stderr]    Compiling spin v0.9.2
[INFO] [stderr]    Compiling getrandom v0.2.6
[INFO] [stderr]    Compiling socket2 v0.4.4
[INFO] [stderr]    Compiling num_cpus v1.13.1
[INFO] [stderr]    Compiling mio v0.8.2
[INFO] [stderr]    Compiling rand_core v0.5.1
[INFO] [stderr]    Compiling rand_core v0.6.3
[INFO] [stderr]    Compiling rand_chacha v0.2.2
[INFO] [stderr]    Compiling rand_chacha v0.3.1
[INFO] [stderr]    Compiling ff v0.10.1
[INFO] [stderr]    Compiling rand v0.7.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 crypto-mac v0.11.1
[INFO] [stderr]    Compiling block-buffer v0.9.0
[INFO] [stderr]    Compiling aead v0.4.3
[INFO] [stderr]    Compiling group v0.10.0
[INFO] [stderr]    Compiling rand v0.8.5
[INFO] [stderr]    Compiling sha2 v0.9.9
[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 regex-automata v0.1.10
[INFO] [stderr]    Compiling regex v1.5.5
[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 tracing-subscriber v0.3.11
[INFO] [stderr]    Compiling x25519-dalek v1.1.1
[INFO] [stderr]    Compiling ed25519-dalek v1.0.1
[INFO] [stderr]    Compiling futures v0.3.21
[INFO] [stderr]    Compiling serde_bare v0.5.0
[INFO] [stderr]    Compiling hashbrown v0.11.2
[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 35.94s
[INFO] running `Command { std: "docker" "inspect" "9c00952b7758efb0221a5e77f424117b3d925b44f259e1ec6d68fdb4ea36241e", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "9c00952b7758efb0221a5e77f424117b3d925b44f259e1ec6d68fdb4ea36241e", kill_on_drop: false }`
[INFO] [stdout] 9c00952b7758efb0221a5e77f424117b3d925b44f259e1ec6d68fdb4ea36241e
[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" "+beta-2022-04-10" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 3212d1d1e91c087f234759faec85235e872a1791db7bf6e853e87bad96a9573f
[INFO] running `Command { std: "docker" "start" "-a" "3212d1d1e91c087f234759faec85235e872a1791db7bf6e853e87bad96a9573f", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]    Compiling trybuild v1.0.59
[INFO] [stderr]    Compiling glob v0.3.0
[INFO] [stderr]    Compiling dissimilar v1.0.3
[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 23.57s
[INFO] [stderr]   Executable unittests src/lib.rs (/opt/rustwide/target/debug/deps/ockam-8c536067999d51a6)
[INFO] running `Command { std: "docker" "inspect" "3212d1d1e91c087f234759faec85235e872a1791db7bf6e853e87bad96a9573f", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "3212d1d1e91c087f234759faec85235e872a1791db7bf6e853e87bad96a9573f", kill_on_drop: false }`
[INFO] [stdout] 3212d1d1e91c087f234759faec85235e872a1791db7bf6e853e87bad96a9573f
[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" "+beta-2022-04-10" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 6e31f4bebeabd74e78948c6d407423783c7051ac22a7425b6048259241c25986
[INFO] running `Command { std: "docker" "start" "-a" "6e31f4bebeabd74e78948c6d407423783c7051ac22a7425b6048259241c25986", 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]     Blocking waiting for file lock on package cache
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 0.53s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/ockam-8c536067999d51a6)
[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:20:49.715574Z  INFO ockam_node::node: Initializing ockam node
[INFO] [stdout] 2022-04-13T20:20:49.715582Z  INFO ockam_node::node: Initializing ockam node
[INFO] [stdout] 2022-04-13T20:20:49.715695Z  INFO ockam_node::node: Initializing ockam node
[INFO] [stdout] 2022-04-13T20:20:49.715665Z  INFO ockam_node::node: Initializing ockam node
[INFO] [stdout] 2022-04-13T20:20:49.715711Z  INFO ockam_node::node: Initializing ockam node
[INFO] [stdout] 2022-04-13T20:20:49.715649Z  INFO ockam_node::node: Initializing ockam node
[INFO] [stdout] 2022-04-13T20:20:49.715778Z  INFO ockam_node::node: Initializing ockam node
[INFO] [stdout] 2022-04-13T20:20:49.715665Z  INFO ockam_node::node: Initializing ockam node
[INFO] [stdout] 2022-04-13T20:20:49.715770Z  INFO ockam_node::node: Initializing ockam node
[INFO] [stdout] 2022-04-13T20:20:49.715781Z  INFO ockam_node::node: Initializing ockam node
[INFO] [stdout] 2022-04-13T20:20:49.716034Z  INFO ockam_node::node: Initializing ockam node
[INFO] [stdout] 2022-04-13T20:20:49.715634Z  INFO ockam_node::node: Initializing ockam node
[INFO] [stdout] 2022-04-13T20:20:49.716097Z  INFO ockam_node::node: Initializing ockam node
[INFO] [stdout] 2022-04-13T20:20:49.716166Z  INFO ockam_node::node: Initializing ockam node
[INFO] [stdout] 2022-04-13T20:20:49.716124Z  INFO ockam_node::node: Initializing ockam node
[INFO] [stdout] 2022-04-13T20:20:49.716124Z  INFO ockam_node::node: Initializing ockam node
[INFO] [stdout] 2022-04-13T20:20:49.716276Z  INFO ockam_node::node: Initializing ockam node
[INFO] [stdout] 2022-04-13T20:20:49.716363Z  INFO ockam_node::node: Initializing ockam node
[INFO] [stdout] 2022-04-13T20:20:49.716423Z  INFO ockam_node::node: Initializing ockam node
[INFO] [stdout] 2022-04-13T20:20:49.716624Z  INFO ockam_node::node: Initializing ockam node
[INFO] [stdout] 2022-04-13T20:20:49.717306Z  INFO ockam_node::node: Initializing ockam node
[INFO] [stdout] 2022-04-13T20:20:50.597338Z  WARN ockam::remote::test: No CLOUD_ADDRESS specified, skipping the test
[INFO] [stdout] 2022-04-13T20:20:50.606448Z  INFO ockam_node::router::shutdown: Initiate graceful node shutdown
[INFO] [stdout] 2022-04-13T20:20:50.606605Z  INFO ockam_node::router: No more workers left.  Goodbye!
[INFO] [stdout] 2022-04-13T20:20:50.627350Z  INFO ockam::pipe2::tests: Starting the test...
[INFO] [stdout] 2022-04-13T20:20:50.631253Z  WARN ockam::remote::test: No CLOUD_ADDRESS specified, skipping the test
[INFO] [stdout] 2022-04-13T20:20:50.632230Z  INFO ockam::system::tests: Handling message for AddMetadata
[INFO] [stdout] 2022-04-13T20:20:50.632451Z  INFO ockam_node::router::shutdown: Initiate graceful node shutdown
[INFO] [stdout] 2022-04-13T20:20:50.632591Z  INFO ockam_node::router: No more workers left.  Goodbye!
[INFO] [stdout] 2022-04-13T20:20:50.632731Z  INFO ockam::system::tests: Handling message for AddMetadata
[INFO] [stdout] 2022-04-13T20:20:50.632813Z  INFO ockam::pipe2::tests: Created receiver pipe: 0#51f1b7a8d91ed19273dd97681cb707d1
[INFO] [stdout] 2022-04-13T20:20:50.633185Z  INFO ockam::system::tests: Received message metadata: 'Some(Metadata({"bar": [7], "foo": [42]}))'
[INFO] [stdout] 2022-04-13T20:20:50.633275Z  INFO ockam::system::tests: Received message data: Hello Ockam!
[INFO] [stdout] 2022-04-13T20:20:50.633409Z  INFO ockam_node::router::shutdown: Initiate graceful node shutdown
[INFO] [stdout] 2022-04-13T20:20:50.633803Z  INFO ockam::pipe2::tests: Created sender pipe: 0#683a8abedb8585f7b378b84ae86cc757
[INFO] [stdout] 2022-04-13T20:20:50.633920Z  INFO ockam::pipe::tests: Sending message 'Message for my best friend' through pipe sender 0#0f444f487cabb1647852f33d8b835e63
[INFO] [stdout] 2022-04-13T20:20:50.635099Z  INFO ockam_node::router: No more workers left.  Goodbye!
[INFO] [stdout] 2022-04-13T20:20:50.635453Z  INFO ockam::pipe2::tests: Created receiver pipe: 0#4a1fd3fec6e23b15ddb94be754e66a1c
[INFO] [stdout] 2022-04-13T20:20:50.638270Z  INFO ockam::pipe::listener: Creating new PipeReceiver for incoming handshake
[INFO] [stdout] 2022-04-13T20:20:50.659832Z  INFO ockam::pipe::tests: Sending message 'Message number one' through pipe sender 0#16837100c14650b9c29cf4fdc39d64f9
[INFO] [stdout] 2022-04-13T20:20:50.659898Z  INFO ockam::channel::listener: Receiving new channel creation request from Route { inner: [0#486a8065e005b468fc0a43859e444ec5] }: ChannelCreationHandshake { channel_addr: 0#8d6e71646c9cbdd13f6963c40a80f496, tx_addr: 0#711df8e1baaf3ff62e2df1c5de2002a8, tx_int_addr: 0#bc963c72037a1ae3c635c0ad88580fb7, rx_addr: 0#7c683698e317eeb5a5ad0bcc8c97d96e, rx_int_addr: 0#8e815437a79fc31574d42319f0ea54f7 }
[INFO] [stdout] 2022-04-13T20:20:50.660488Z  INFO ockam_node::router::shutdown: Initiate graceful node shutdown
[INFO] [stdout] 2022-04-13T20:20:50.660709Z  INFO ockam::pipe2::tests: Created sender pipe: 0#34898991b491534b2bee47a6e893926f
[INFO] [stdout] 2022-04-13T20:20:50.660860Z  INFO ockam::pipe::tests: Sending message 'Message number two' through pipe sender 0#16837100c14650b9c29cf4fdc39d64f9
[INFO] [stdout] 2022-04-13T20:20:50.660917Z  INFO ockam_node::router: No more workers left.  Goodbye!
[INFO] [stdout] 2022-04-13T20:20:50.661187Z  INFO ockam::pipe::tests: Sending message 'Hello Ockam!' through pipe sender 0#3d9c001cf58f8ae16c1689acce3df81c
[INFO] [stdout] 2022-04-13T20:20:50.661222Z  INFO ockam::channel::listener: Receiving new channel creation request from Route { inner: [0#4e91b15603f6ff288b382da7c83f8e25] }: ChannelCreationHandshake { channel_addr: 0#87f2780683a2d837cbba9653eaeeede7, tx_addr: 0#1c189f1e95b5b17282374e9a99aea09c, tx_int_addr: 0#13127ac919d5480fdad641ff89ce0121, rx_addr: 0#563c1b83d67239977aaf6c7efdf3e39e, rx_int_addr: 0#bd602a5fcd6ec2ca1afc7c05ed991ae0 }
[INFO] [stdout] 2022-04-13T20:20:50.661544Z  INFO ockam_node::router::shutdown: Initiate graceful node shutdown
[INFO] [stdout] 2022-04-13T20:20:50.662484Z  INFO ockam_node::router: No more workers left.  Goodbye!
[INFO] [stdout] 2022-04-13T20:20:50.663462Z  INFO ockam::pipe::tests: App reiceved msg: 'Message number one'
[INFO] [stdout] 2022-04-13T20:20:50.663623Z  INFO ockam::channel::tests: Received message 'Hello through the channel!' through channel
[INFO] [stdout] 2022-04-13T20:20:50.663440Z  INFO ockam::pipe::tests: Sending message 'Message number one' through pipe sender 0#9173d77b7493776acd94fa66bd8fa3a4
[INFO] [stdout] 2022-04-13T20:20:50.663652Z  INFO ockam::pipe::tests: App received msg: 'Message for my best friend'
[INFO] [stdout] 2022-04-13T20:20:50.663848Z  INFO ockam_node::router::shutdown: Initiate graceful node shutdown
[INFO] [stdout] 2022-04-13T20:20:50.664311Z  INFO ockam_node::router::shutdown: Initiate graceful node shutdown
[INFO] [stdout] 2022-04-13T20:20:50.664421Z  INFO ockam::pipe::tests: Sending message 'Message number two' through pipe sender 0#9173d77b7493776acd94fa66bd8fa3a4
[INFO] [stdout] 2022-04-13T20:20:50.664825Z  INFO ockam_node::router: No more workers left.  Goodbye!
[INFO] [stdout] 2022-04-13T20:20:50.664953Z  INFO ockam::pipe::tests: App reiceved msg: 'Message number two'
[INFO] [stdout] 2022-04-13T20:20:50.668487Z  INFO ockam_node::router::shutdown: Initiate graceful node shutdown
[INFO] [stdout] 2022-04-13T20:20:50.670043Z  INFO ockam_node::router: No more workers left.  Goodbye!
[INFO] [stdout] 2022-04-13T20:20:50.670172Z  INFO ockam::pipe::tests: App reiceved msg: 'Hello Ockam!'
[INFO] [stdout] 2022-04-13T20:20:50.670403Z  INFO ockam_node::router::shutdown: Initiate graceful node shutdown
[INFO] [stdout] 2022-04-13T20:20:50.670806Z  INFO ockam_node::router: No more workers left.  Goodbye!
[INFO] [stdout] 2022-04-13T20:20:50.681239Z  INFO ockam::system::hooks::delivery: Received ACK for message: 0#f4400645235a130ed84bc847879bdd9d
[INFO] [stdout] 2022-04-13T20:20:50.683283Z  INFO ockam_node::router: No more workers left.  Goodbye!
[INFO] [stdout] 2022-04-13T20:20:50.684701Z  INFO ockam::pipe::tests: App reiceved msg: 'Message number one'
[INFO] [stdout] 2022-04-13T20:20:50.684718Z  INFO ockam_node::router::shutdown: Initiate graceful node shutdown
[INFO] [stdout] 2022-04-13T20:20:50.685333Z  INFO ockam::system::hooks::delivery: Received ACK for message: 0#1abb6c2040328988e7594664fd829fdc
[INFO] [stdout] 2022-04-13T20:20:50.685618Z  INFO ockam::pipe::tests: App reiceved msg: 'Message number two'
[INFO] [stdout] 2022-04-13T20:20:50.685737Z  INFO ockam_node::router: No more workers left.  Goodbye!
[INFO] [stdout] 2022-04-13T20:20:50.685767Z  INFO ockam::channel::tests: Received message 'Hello through the channel!' through channel
[INFO] [stdout] 2022-04-13T20:20:50.685812Z  INFO ockam_node::router::shutdown: Initiate graceful node shutdown
[INFO] [stdout] 2022-04-13T20:20:50.686003Z  INFO ockam_node::router::shutdown: Initiate graceful node shutdown
[INFO] [stdout] 2022-04-13T20:20:50.686592Z  INFO ockam_node::router: No more workers left.  Goodbye!
[INFO] [stdout] 2022-04-13T20:20:50.686869Z  INFO ockam_node::router::shutdown: Initiate graceful node shutdown
[INFO] [stdout] 2022-04-13T20:20:50.690723Z  INFO ockam_node::router: No more workers left.  Goodbye!
[INFO] [stdout] test pipe::tests::static_confirm_ordering_pipe_reversed ... ok
[INFO] [stdout] test pipe::tests::static_confirm_pipe ... ok
[INFO] [stdout] test pipe2::tests::fixed_delivery_and_ordering_pipe ... ok
[INFO] [stdout] test pipe::tests::static_confirm_ordering_pipe ... ok
[INFO] [stdout] 2022-04-13T20:20:50.755705Z  INFO ockam_node::router: No more workers left.  Goodbye!
[INFO] [stdout] 2022-04-13T20:20:50.759492Z  INFO ockam::pipe::tests: Sending message 'Hello Ockam!' through pipe sender 0#61b3200109851556abe01dbf02aab12f
[INFO] [stdout] 2022-04-13T20:20:50.759685Z  INFO ockam::pipe::tests: Sending message 'Message number one' through pipe sender 0#fc456dede8fa81933a203576323e37f9
[INFO] [stdout] 2022-04-13T20:20:50.760347Z  INFO ockam::pipe::tests: Sending message 'Message number two' through pipe sender 0#fc456dede8fa81933a203576323e37f9
[INFO] [stdout] 2022-04-13T20:20:50.762472Z  INFO ockam::pipe::tests: App reiceved msg: 'Message number one'
[INFO] [stdout] 2022-04-13T20:20:50.762563Z  INFO ockam::pipe::tests: App reiceved msg: 'Message number two'
[INFO] [stdout] 2022-04-13T20:20:50.762717Z  INFO ockam_node::router::shutdown: Initiate graceful node shutdown
[INFO] [stdout] 2022-04-13T20:20:50.763327Z  INFO ockam_node::router: No more workers left.  Goodbye!
[INFO] [stdout] 2022-04-13T20:20:50.802058Z  INFO ockam::pipe::tests: Sending message 'Hello Ockam!' through pipe sender 0#16365b8ae1da2afabd6d681a3aae9800
[INFO] [stdout] test pipe2::tests::dynamic_delivery_pipe ... ok
[INFO] [stdout] 2022-04-13T20:20:50.803120Z  INFO ockam::pipe::tests: App reiceved msg: 'Hello Ockam!'
[INFO] [stdout] 2022-04-13T20:20:50.803288Z  INFO ockam_node::router::shutdown: Initiate graceful node shutdown
[INFO] [stdout] 2022-04-13T20:20:50.803778Z  INFO ockam_node::router: No more workers left.  Goodbye!
[INFO] [stdout] test remote::test::forwarding__static_address__should_respond ... ok
[INFO] [stdout] test remote::test::forwarding__ephemeral_address__should_respond ... ok
[INFO] [stdout] 2022-04-13T20:20:50.888386Z  INFO ockam::system::hooks::delivery: Received ACK for message: 0#78ddd9f78c8c6af2e1f80207298a1cac
[INFO] [stdout] 2022-04-13T20:20:50.889489Z  INFO ockam_node::router::shutdown: Initiate graceful node shutdown
[INFO] [stdout] 2022-04-13T20:20:50.895492Z  INFO ockam_node::router: No more workers left.  Goodbye!
[INFO] [stdout] test pipe2::tests::handshake_pipe ... ok
[INFO] [stdout] test pipe2::tests::dynamic_delivery_and_ordering_pipe ... ok
[INFO] [stdout] test channel::tests::reliable_channel ... ok
[INFO] [stdout] 2022-04-13T20:20:50.909696Z  INFO ockam::pipe::tests: App received message: Hello through nested pipes!
[INFO] [stdout] 2022-04-13T20:20:50.910006Z  INFO ockam_node::router::shutdown: Initiate graceful node shutdown
[INFO] [stdout] 2022-04-13T20:20:50.911031Z  INFO ockam_node::router: No more workers left.  Goodbye!
[INFO] [stdout] 2022-04-13T20:20:50.940912Z  INFO ockam::pipe2::tests: Created receiver pipe: 0#6adee7f73d55dcdda886b693bdfca09e
[INFO] [stdout] 2022-04-13T20:20:50.942215Z  INFO ockam::pipe2::tests: Created sender pipe: 0#f9eab72bcd76896a61e07e5068d22541
[INFO] [stdout] 2022-04-13T20:20:50.944896Z  INFO ockam_node::router::shutdown: Initiate graceful node shutdown
[INFO] [stdout] 2022-04-13T20:20:50.945505Z  INFO ockam_node::router: No more workers left.  Goodbye!
[INFO] [stdout] test pipe2::tests::very_simple_pipe2 ... ok
[INFO] [stdout] test channel::tests::simple_channel ... ok
[INFO] [stdout] test system::tests::attach_metadata ... ok
[INFO] [stdout] test pipe::tests::simple_pipe_handshake ... ok
[INFO] [stdout] 2022-04-13T20:20:50.995036Z  INFO ockam::pipe2::tests: Created receiver pipe: 0#785a1749f946e71f76effba5cb671a44
[INFO] [stdout] 2022-04-13T20:20:50.995529Z  INFO ockam::pipe2::tests: Created sender pipe: 0#de2f4f04d1dded0842d2c4eb89178ef2
[INFO] [stdout] 2022-04-13T20:20:50.995895Z  INFO ockam::system::tests: Handling message via StepHandler
[INFO] [stdout] 2022-04-13T20:20:50.996816Z  INFO ockam::system::tests: Handling message via StepHandler
[INFO] [stdout] 2022-04-13T20:20:50.997117Z  INFO ockam::system::tests: Received message 'Hello Ockam!'
[INFO] [stdout] 2022-04-13T20:20:50.997266Z  INFO ockam_node::router::shutdown: Initiate graceful node shutdown
[INFO] [stdout] 2022-04-13T20:20:50.997638Z  INFO ockam_node::router: No more workers left.  Goodbye!
[INFO] [stdout] 2022-04-13T20:20:51.000374Z  INFO ockam::system::hooks::delivery: Received ACK for message: 0#287998a370ac61ca03fcc2c7d24d2d68
[INFO] [stdout] 2022-04-13T20:20:51.000929Z  INFO ockam_node::router::shutdown: Initiate graceful node shutdown
[INFO] [stdout] 2022-04-13T20:20:51.001539Z  INFO ockam_node::router: No more workers left.  Goodbye!
[INFO] [stdout] test pipe2::tests::fixed_delivery_pipe ... ok
[INFO] [stdout] test pipe2::tests::fixed_ordering_pipe ... ok
[INFO] [stdout] test pipe::tests::static_simple_pipe ... ok
[INFO] [stdout] test pipe::tests::layered_pipe ... ok
[INFO] [stdout] test system::tests::send_messages ... ok
[INFO] [stdout] test pipe::tests::static_ordering_pipe ... ok
[INFO] [stdout] 2022-04-13T20:20:51.756813Z  WARN ockam_node::router::shutdown: Shutdown timeout reached; aborting node!
[INFO] [stdout] 2022-04-13T20:20:51.756982Z ERROR ockam_node::router::shutdown: Failed to send node abort signal to router
[INFO] [stdout] 2022-04-13T20:20:51.899001Z  WARN ockam_node::router::shutdown: Shutdown timeout reached; aborting node!
[INFO] [stdout] 2022-04-13T20:20:51.899140Z ERROR ockam_node::router::shutdown: Failed to send node abort signal to router
[INFO] [stdout] 2022-04-13T20:20:55.664246Z ERROR ockam::delay: Failed to send delayed message: Error { code: 11010, domain: "ockam_node::error::InternalIOFailure" }
[INFO] [stdout] 2022-04-13T20:20:55.664625Z ERROR ockam::delay: Failed to send delayed message: Error { code: 11010, domain: "ockam_node::error::InternalIOFailure" }
[INFO] [stdout] 2022-04-13T20:20:55.673089Z ERROR ockam::delay: Failed to send delayed message: Error { code: 11010, domain: "ockam_node::error::InternalIOFailure" }
[INFO] [stdout] 2022-04-13T20:20:55.685174Z ERROR ockam::delay: Failed to send delayed message: Error { code: 11010, domain: "ockam_node::error::InternalIOFailure" }
[INFO] [stdout] 2022-04-13T20:20:55.738388Z ERROR ockam::delay: Failed to send delayed message: Error { code: 11010, domain: "ockam_node::error::InternalIOFailure" }
[INFO] [stdout] 2022-04-13T20:20:55.739157Z ERROR ockam::delay: Failed to send delayed message: Error { code: 11010, domain: "ockam_node::error::InternalIOFailure" }
[INFO] [stdout] 2022-04-13T20:20:55.739225Z ERROR ockam::delay: Failed to send delayed message: Error { code: 11010, domain: "ockam_node::error::InternalIOFailure" }
[INFO] [stdout] 2022-04-13T20:20:55.773121Z  INFO ockam::pipe::tests: Sender received timeout for sent message!
[INFO] [stdout] 2022-04-13T20:20:55.774269Z  WARN ockam::pipe::tests: App reiceved msg: 'Shut it down...'
[INFO] [stdout] 2022-04-13T20:20:55.774628Z  INFO ockam_node::router::shutdown: Initiate graceful node shutdown
[INFO] [stdout] 2022-04-13T20:20:55.775417Z  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.12s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests ockam
[INFO] [stdout] 
[INFO] [stdout] running 3 tests
[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] test src/pipe2/mod.rs - pipe2::PipeBuilder (line 58) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.59s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "6e31f4bebeabd74e78948c6d407423783c7051ac22a7425b6048259241c25986", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "6e31f4bebeabd74e78948c6d407423783c7051ac22a7425b6048259241c25986", kill_on_drop: false }`
[INFO] [stdout] 6e31f4bebeabd74e78948c6d407423783c7051ac22a7425b6048259241c25986