[INFO] fetching crate ockam_transport_tcp 0.47.0... [INFO] testing ockam_transport_tcp-0.47.0 against 1.60.0 for beta-1.61-1 [INFO] extracting crate ockam_transport_tcp 0.47.0 into /workspace/builds/worker-55/source [INFO] validating manifest of crates.io crate ockam_transport_tcp 0.47.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_transport_tcp 0.47.0 [INFO] finished tweaking crates.io crate ockam_transport_tcp 0.47.0 [INFO] tweaked toml for crates.io crate ockam_transport_tcp 0.47.0 written to /workspace/builds/worker-55/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] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-55/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-55/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] 10a736712735fa2d96a2681af504f0e22837c999fef3ac7e215c306c784402cc [INFO] running `Command { std: "docker" "start" "-a" "10a736712735fa2d96a2681af504f0e22837c999fef3ac7e215c306c784402cc", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "10a736712735fa2d96a2681af504f0e22837c999fef3ac7e215c306c784402cc", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "10a736712735fa2d96a2681af504f0e22837c999fef3ac7e215c306c784402cc", kill_on_drop: false }` [INFO] [stdout] 10a736712735fa2d96a2681af504f0e22837c999fef3ac7e215c306c784402cc [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-55/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-55/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] 7dc937ff14995a1e13fcdadcc3e1080839751bda3b8466ac39cc534f73eb2fc5 [INFO] running `Command { std: "docker" "start" "-a" "7dc937ff14995a1e13fcdadcc3e1080839751bda3b8466ac39cc534f73eb2fc5", kill_on_drop: false }` [INFO] [stderr] Blocking waiting for file lock on package cache [INFO] [stderr] Compiling syn v1.0.91 [INFO] [stderr] Compiling serde v1.0.136 [INFO] [stderr] Compiling futures-channel v0.3.21 [INFO] [stderr] Compiling log v0.4.16 [INFO] [stderr] Compiling futures-sink v0.3.21 [INFO] [stderr] Compiling heapless v0.7.10 [INFO] [stderr] Compiling crossbeam-utils v0.8.8 [INFO] [stderr] Compiling stable_deref_trait v1.2.0 [INFO] [stderr] Compiling crossbeam-queue v0.3.5 [INFO] [stderr] Compiling hex v0.4.3 [INFO] [stderr] Compiling ahash v0.4.7 [INFO] [stderr] Compiling lock_api v0.4.7 [INFO] [stderr] Compiling ahash v0.7.6 [INFO] [stderr] Compiling tracing-core v0.1.25 [INFO] [stderr] Compiling async-trait v0.1.53 [INFO] [stderr] Compiling hash32 v0.2.1 [INFO] [stderr] Compiling regex-automata v0.1.10 [INFO] [stderr] Compiling regex v1.5.5 [INFO] [stderr] Compiling thread_local v1.1.4 [INFO] [stderr] Compiling sharded-slab v0.1.4 [INFO] [stderr] Compiling futures-task v0.3.21 [INFO] [stderr] Compiling getrandom v0.2.6 [INFO] [stderr] Compiling core2 v0.3.3 [INFO] [stderr] Compiling getrandom v0.1.16 [INFO] [stderr] Compiling num_cpus v1.13.1 [INFO] [stderr] Compiling socket2 v0.4.4 [INFO] [stderr] Compiling rand_core v0.5.1 [INFO] [stderr] Compiling rand_core v0.6.3 [INFO] [stderr] Compiling hashbrown v0.9.1 [INFO] [stderr] Compiling tracing v0.1.33 [INFO] [stderr] Compiling rand_chacha v0.2.2 [INFO] [stderr] Compiling rand_chacha v0.3.1 [INFO] [stderr] Compiling rand v0.7.3 [INFO] [stderr] Compiling tracing-log v0.1.2 [INFO] [stderr] Compiling mio v0.8.2 [INFO] [stderr] Compiling spin v0.9.2 [INFO] [stderr] Compiling matchers v0.1.0 [INFO] [stderr] Compiling rand v0.8.5 [INFO] [stderr] Compiling tracing-subscriber v0.3.11 [INFO] [stderr] Compiling synstructure v0.12.6 [INFO] [stderr] Compiling serde_derive v1.0.136 [INFO] [stderr] Compiling futures-macro v0.3.21 [INFO] [stderr] Compiling zeroize_derive v1.3.2 [INFO] [stderr] Compiling ockam_macros v0.12.0 [INFO] [stderr] Compiling tokio-macros v1.7.0 [INFO] [stderr] Compiling zeroize v1.5.4 [INFO] [stderr] Compiling tokio v1.17.0 [INFO] [stderr] Compiling futures-util v0.3.21 [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 ockam_core v0.51.0 [INFO] [stderr] Compiling ockam_executor v0.19.0 [INFO] [stderr] Compiling ockam_transport_core v0.24.0 [INFO] [stderr] Compiling ockam_node v0.51.0 [INFO] [stderr] Compiling ockam_transport_tcp v0.47.0 (/opt/rustwide/workdir) [INFO] [stderr] Finished dev [unoptimized + debuginfo] target(s) in 44.70s [INFO] running `Command { std: "docker" "inspect" "7dc937ff14995a1e13fcdadcc3e1080839751bda3b8466ac39cc534f73eb2fc5", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "7dc937ff14995a1e13fcdadcc3e1080839751bda3b8466ac39cc534f73eb2fc5", kill_on_drop: false }` [INFO] [stdout] 7dc937ff14995a1e13fcdadcc3e1080839751bda3b8466ac39cc534f73eb2fc5 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-55/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-55/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] 3fb92c42b404afa2ee784824d9e1987fd7aa188aca5be33a6181a218b4686870 [INFO] running `Command { std: "docker" "start" "-a" "3fb92c42b404afa2ee784824d9e1987fd7aa188aca5be33a6181a218b4686870", kill_on_drop: false }` [INFO] [stderr] Compiling serde v1.0.136 [INFO] [stderr] Compiling trybuild v1.0.59 [INFO] [stderr] Compiling termcolor v1.1.3 [INFO] [stderr] Compiling dissimilar v1.0.3 [INFO] [stderr] Compiling serde_bare v0.5.0 [INFO] [stderr] Compiling hashbrown v0.11.2 [INFO] [stderr] Compiling toml v0.5.8 [INFO] [stderr] Compiling serde_json v1.0.79 [INFO] [stderr] Compiling ockam_core v0.51.0 [INFO] [stderr] Compiling ockam_executor v0.19.0 [INFO] [stderr] Compiling ockam_transport_core v0.24.0 [INFO] [stderr] Compiling ockam_node v0.51.0 [INFO] [stderr] Compiling ockam_transport_tcp v0.47.0 (/opt/rustwide/workdir) [INFO] [stderr] Finished test [unoptimized + debuginfo] target(s) in 15.46s [INFO] running `Command { std: "docker" "inspect" "3fb92c42b404afa2ee784824d9e1987fd7aa188aca5be33a6181a218b4686870", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "3fb92c42b404afa2ee784824d9e1987fd7aa188aca5be33a6181a218b4686870", kill_on_drop: false }` [INFO] [stdout] 3fb92c42b404afa2ee784824d9e1987fd7aa188aca5be33a6181a218b4686870 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-55/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-55/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] ea537476e1fb9aa773f41b5af443fa0bb4661e7e8c9b80b666143a034e80d9ed [INFO] running `Command { std: "docker" "start" "-a" "ea537476e1fb9aa773f41b5af443fa0bb4661e7e8c9b80b666143a034e80d9ed", kill_on_drop: false }` [INFO] [stderr] Finished test [unoptimized + debuginfo] target(s) in 0.11s [INFO] [stderr] Running unittests (/opt/rustwide/target/debug/deps/ockam_transport_tcp-4bab44789652bb24) [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] [stderr] Running tests/portal.rs (/opt/rustwide/target/debug/deps/portal-88886b30895235af) [INFO] [stdout] [INFO] [stdout] running 2 tests [INFO] [stdout] 2022-04-13T20:17:32.039147Z  INFO ockam_node::node: Initializing ockam node [INFO] [stdout] 2022-04-13T20:17:32.041752Z  INFO ockam_node::node: Initializing ockam node [INFO] [stdout] 2022-04-13T20:17:32.218891Z  INFO ockam_transport_tcp::portal::portal_worker: Creating new Inlet at internal: 0#d88deda365ccc4015b615d545e165910, remote: 0#b0c70b37dedc08b67210189bcde050e8 [INFO] [stdout] 2022-04-13T20:17:32.219851Z  INFO ockam_transport_tcp::portal::portal_worker: Creating new Outlet at internal: 0#399eed1e2884b7a73a1b674bd5a8cdf9, remote: 0#65db8249cd0d9fb0eeb36c33309d5d9f [INFO] [stdout] 2022-04-13T20:17:32.224221Z  INFO ockam_transport_tcp::portal::portal_worker: Creating new Inlet at internal: 0#21c3ff2409d2b53465520ead3aefcc1a, remote: 0#f87d9c988dcb4f9e81516b1e4decb80a [INFO] [stdout] 2022-04-13T20:17:32.227604Z  INFO ockam_transport_tcp::portal::portal_worker: Creating new Outlet at internal: 0#64adf6516f7612c0c6467c6af95df756, remote: 0#8baecdb49f61b1d1b237417224568408 [INFO] [stdout] 2022-04-13T20:17:32.230452Z  INFO ockam_transport_tcp::portal::portal_worker: Tcp stream was dropped for Outlet at: 0#399eed1e2884b7a73a1b674bd5a8cdf9 [INFO] [stdout] 2022-04-13T20:17:32.232557Z  INFO ockam_transport_tcp::portal::portal_worker: Tcp stream was dropped for Outlet at: 0#64adf6516f7612c0c6467c6af95df756 [INFO] [stdout] 2022-04-13T20:17:32.233016Z  INFO ockam_node::router::shutdown: Initiate graceful node shutdown [INFO] [stdout] 2022-04-13T20:17:32.233026Z  INFO ockam_node::router::shutdown: Initiate graceful node shutdown [INFO] [stdout] 2022-04-13T20:17:32.234507Z  WARN ockam_node::router: Router error: Error { code: 11010, domain: "ockam_node::error::InternalIOFailure" } while handling StopNode [INFO] [stdout] 2022-04-13T20:17:32.235257Z ERROR ockam_node::relay::worker_relay: Error encountered during '0#64adf6516f7612c0c6467c6af95df756' message handling: Error { code: 11016, domain: "ockam_node::error::CommandRejected" } [INFO] [stdout] 2022-04-13T20:17:32.235861Z  INFO ockam_node::router: No more workers left. Goodbye! [INFO] [stdout] test portal__reverse_flow__should_succeed ... ok [INFO] [stdout] 2022-04-13T20:17:33.233502Z  INFO ockam_transport_tcp::portal::portal_worker: Outlet at: 0#399eed1e2884b7a73a1b674bd5a8cdf9 stopped due to connection drop [INFO] [stdout] 2022-04-13T20:17:33.234748Z  WARN ockam_node::router::shutdown: Shutdown timeout reached; aborting node! [INFO] [stdout] test portal__standard_flow__should_succeed ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 1.23s [INFO] [stdout] [INFO] [stderr] Running tests/send_receive.rs (/opt/rustwide/target/debug/deps/send_receive-5f87c437658916ee) [INFO] [stdout] [INFO] [stdout] running 2 tests [INFO] [stdout] 2022-04-13T20:17:33.287407Z  INFO ockam_node::node: Initializing ockam node [INFO] [stdout] 2022-04-13T20:17:33.287524Z  INFO ockam_node::node: Initializing ockam node [INFO] [stdout] 2022-04-13T20:17:33.520629Z  INFO ockam_node::router::shutdown: Initiate graceful node shutdown [INFO] [stdout] 2022-04-13T20:17:33.527763Z  INFO ockam_node::router: No more workers left. Goodbye! [INFO] [stdout] test send_receive ... ok [INFO] [stdout] 2022-04-13T20:17:33.609984Z  INFO ockam_transport_tcp::workers::receiver: Connection to peer '1#127.0.0.1:36934' was closed; dropping stream [INFO] [stdout] 2022-04-13T20:17:33.610928Z  WARN ockam_transport_tcp::workers::sender: Stopping sender due to closed connection 127.0.0.1:36934 [INFO] [stdout] 2022-04-13T20:17:33.639956Z  INFO ockam_node::router::shutdown: Initiate graceful node shutdown [INFO] [stdout] 2022-04-13T20:17:33.641355Z  WARN ockam_node::router: Router error: Error { code: 11010, domain: "ockam_node::error::InternalIOFailure" } while handling StopProcessor [INFO] [stdout] 2022-04-13T20:17:33.641497Z  WARN ockam_node::router: Router error: Error { code: 11010, domain: "ockam_node::error::InternalIOFailure" } while handling StopProcessor [INFO] [stdout] 2022-04-13T20:17:33.642155Z  INFO ockam_node::router: No more workers left. Goodbye! [INFO] [stdout] test tcp_lifecycle__reconnect__should_not_error ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.43s [INFO] [stdout] [INFO] [stderr] Doc-tests ockam_transport_tcp [INFO] [stdout] [INFO] [stdout] running 9 tests [INFO] [stdout] test src/transport.rs - transport::TcpTransport::create (line 52) ... ok [INFO] [stdout] test src/transport.rs - transport::TcpTransport::stop_inlet (line 142) ... ok [INFO] [stdout] test src/transport.rs - transport::TcpTransport::listen (line 92) ... ok [INFO] [stdout] test src/transport.rs - transport::TcpTransport::stop_outlet (line 189) ... ok [INFO] [stdout] test src/transport.rs - transport::TcpTransport (line 21) ... ok [INFO] [stdout] test src/transport.rs - transport::TcpTransport::create_outlet (line 167) ... ok [INFO] [stdout] test src/transport.rs - transport::TcpTransport::create_inlet (line 113) ... ok [INFO] [stdout] test src/transport.rs - transport::TcpTransport::connect (line 72) ... ok [INFO] [stdout] test src/transport.rs - transport::TcpTransport (line 34) ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 9 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.93s [INFO] [stdout] [INFO] running `Command { std: "docker" "inspect" "ea537476e1fb9aa773f41b5af443fa0bb4661e7e8c9b80b666143a034e80d9ed", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "ea537476e1fb9aa773f41b5af443fa0bb4661e7e8c9b80b666143a034e80d9ed", kill_on_drop: false }` [INFO] [stdout] ea537476e1fb9aa773f41b5af443fa0bb4661e7e8c9b80b666143a034e80d9ed