[INFO] fetching crate ntex-amqp 0.5.5... [INFO] testing ntex-amqp-0.5.5 against master#62efba8a050c64249dab942951bb28f710208bc8 for pr-90799 [INFO] extracting crate ntex-amqp 0.5.5 into /workspace/builds/worker-74/source [INFO] validating manifest of crates.io crate ntex-amqp 0.5.5 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 ntex-amqp 0.5.5 [INFO] finished tweaking crates.io crate ntex-amqp 0.5.5 [INFO] tweaked toml for crates.io crate ntex-amqp 0.5.5 written to /workspace/builds/worker-74/source/Cargo.toml [INFO] crate crates.io crate ntex-amqp 0.5.5 already has a lockfile, it will not be regenerated [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] [stderr] Downloading crates ... [INFO] [stderr] Downloaded ntex v0.4.8 [INFO] [stderr] Downloaded ntex-amqp-codec v0.7.3 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-74/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-74/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] ed962f781ad6541506da26094f5ef5eeff5de050c9e4cf8929ae2fd571e4caf6 [INFO] running `Command { std: "docker" "start" "-a" "ed962f781ad6541506da26094f5ef5eeff5de050c9e4cf8929ae2fd571e4caf6", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "ed962f781ad6541506da26094f5ef5eeff5de050c9e4cf8929ae2fd571e4caf6", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "ed962f781ad6541506da26094f5ef5eeff5de050c9e4cf8929ae2fd571e4caf6", kill_on_drop: false }` [INFO] [stdout] ed962f781ad6541506da26094f5ef5eeff5de050c9e4cf8929ae2fd571e4caf6 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-74/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-74/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] 95692eb140b9af8e43273d03d87ceee936a75c96e59cbf4527ace2493f80ac1f [INFO] running `Command { std: "docker" "start" "-a" "95692eb140b9af8e43273d03d87ceee936a75c96e59cbf4527ace2493f80ac1f", kill_on_drop: false }` [INFO] [stderr] Compiling autocfg v1.0.1 [INFO] [stderr] Compiling libc v0.2.106 [INFO] [stderr] Compiling proc-macro2 v1.0.32 [INFO] [stderr] Compiling cfg-if v1.0.0 [INFO] [stderr] Compiling unicode-xid v0.2.2 [INFO] [stderr] Compiling syn v1.0.81 [INFO] [stderr] Compiling pin-project-lite v0.2.7 [INFO] [stderr] Compiling memchr v2.4.1 [INFO] [stderr] Compiling futures-core v0.3.17 [INFO] [stderr] Compiling log v0.4.14 [INFO] [stderr] Compiling bytes v1.1.0 [INFO] [stderr] Compiling serde_derive v1.0.130 [INFO] [stderr] Compiling serde v1.0.130 [INFO] [stderr] Compiling futures-sink v0.3.17 [INFO] [stderr] Compiling version_check v0.9.3 [INFO] [stderr] Compiling typenum v1.14.0 [INFO] [stderr] Compiling once_cell v1.8.0 [INFO] [stderr] Compiling itoa v0.4.8 [INFO] [stderr] Compiling bitflags v1.3.2 [INFO] [stderr] Compiling futures-task v0.3.17 [INFO] [stderr] Compiling ryu v1.0.5 [INFO] [stderr] Compiling lazy_static v1.4.0 [INFO] [stderr] Compiling fnv v1.0.7 [INFO] [stderr] Compiling hashbrown v0.11.2 [INFO] [stderr] Compiling cache-padded v1.1.1 [INFO] [stderr] Compiling regex-syntax v0.6.25 [INFO] [stderr] Compiling percent-encoding v2.1.0 [INFO] [stderr] Compiling byteorder v1.4.3 [INFO] [stderr] Compiling pin-utils v0.1.0 [INFO] [stderr] Compiling matches v0.1.9 [INFO] [stderr] Compiling convert_case v0.4.0 [INFO] [stderr] Compiling encoding_rs v0.8.29 [INFO] [stderr] Compiling serde_json v1.0.68 [INFO] [stderr] Compiling ntex-amqp-codec v0.7.3 [INFO] [stderr] Compiling event-listener v2.5.1 [INFO] [stderr] Compiling cpufeatures v0.2.1 [INFO] [stderr] Compiling opaque-debug v0.3.0 [INFO] [stderr] Compiling base64 v0.13.0 [INFO] [stderr] Compiling nanorand v0.6.1 [INFO] [stderr] Compiling httpdate v1.0.1 [INFO] [stderr] Compiling futures-micro v0.5.0 [INFO] [stderr] Compiling concurrent-queue v1.2.2 [INFO] [stderr] Compiling tracing-core v0.1.21 [INFO] [stderr] Compiling fxhash v0.2.1 [INFO] [stderr] Compiling async-oneshot v0.5.0 [INFO] [stderr] Compiling form_urlencoded v1.0.1 [INFO] [stderr] Compiling http v0.2.5 [INFO] [stderr] Compiling tokio v1.13.0 [INFO] [stderr] Compiling num-traits v0.2.14 [INFO] [stderr] Compiling indexmap v1.7.0 [INFO] [stderr] Compiling futures-util v0.3.17 [INFO] [stderr] Compiling num-integer v0.1.44 [INFO] [stderr] Compiling generic-array v0.14.4 [INFO] [stderr] Compiling ntex-util v0.1.1 [INFO] [stderr] Compiling async-channel v1.6.1 [INFO] [stderr] Compiling mio v0.7.14 [INFO] [stderr] Compiling signal-hook-registry v1.4.0 [INFO] [stderr] Compiling getrandom v0.2.3 [INFO] [stderr] Compiling num_cpus v1.13.0 [INFO] [stderr] Compiling socket2 v0.4.2 [INFO] [stderr] Compiling uuid v0.8.2 [INFO] [stderr] Compiling tracing v0.1.29 [INFO] [stderr] Compiling quote v1.0.10 [INFO] [stderr] Compiling ntex-service v0.2.1 [INFO] [stderr] Compiling aho-corasick v0.7.18 [INFO] [stderr] Compiling ordered-float v2.8.0 [INFO] [stderr] Compiling digest v0.9.0 [INFO] [stderr] Compiling block-buffer v0.9.0 [INFO] [stderr] Compiling sha-1 v0.9.8 [INFO] [stderr] Compiling regex v1.5.4 [INFO] [stderr] Compiling chrono v0.4.19 [INFO] [stderr] Compiling tokio-util v0.6.9 [INFO] [stderr] Compiling ntex-rt v0.3.1 [INFO] [stderr] Compiling h2 v0.3.7 [INFO] [stderr] Compiling derive_more v0.99.16 [INFO] [stderr] Compiling ntex-macros v0.1.3 [INFO] [stderr] Compiling ntex-bytes v0.1.4 [INFO] [stderr] Compiling serde_urlencoded v0.7.0 [INFO] [stderr] Compiling ntex-codec v0.5.1 [INFO] [stderr] Compiling ntex-router v0.5.1 [INFO] [stderr] Compiling ntex v0.4.8 [INFO] [stderr] Compiling ntex-amqp v0.5.5 (/opt/rustwide/workdir) [INFO] [stderr] Finished dev [unoptimized + debuginfo] target(s) in 30.86s [INFO] running `Command { std: "docker" "inspect" "95692eb140b9af8e43273d03d87ceee936a75c96e59cbf4527ace2493f80ac1f", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "95692eb140b9af8e43273d03d87ceee936a75c96e59cbf4527ace2493f80ac1f", kill_on_drop: false }` [INFO] [stdout] 95692eb140b9af8e43273d03d87ceee936a75c96e59cbf4527ace2493f80ac1f [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-74/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-74/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] 44de9d52f447564317b214ac54e809621e2dcdcd7edf52c0716b874778709c67 [INFO] running `Command { std: "docker" "start" "-a" "44de9d52f447564317b214ac54e809621e2dcdcd7edf52c0716b874778709c67", kill_on_drop: false }` [INFO] [stderr] Compiling termcolor v1.1.2 [INFO] [stderr] Compiling atty v0.2.14 [INFO] [stderr] Compiling env_logger v0.8.4 [INFO] [stderr] Compiling ntex-amqp v0.5.5 (/opt/rustwide/workdir) [INFO] [stderr] Finished test [unoptimized + debuginfo] target(s) in 7.28s [INFO] running `Command { std: "docker" "inspect" "44de9d52f447564317b214ac54e809621e2dcdcd7edf52c0716b874778709c67", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "44de9d52f447564317b214ac54e809621e2dcdcd7edf52c0716b874778709c67", kill_on_drop: false }` [INFO] [stdout] 44de9d52f447564317b214ac54e809621e2dcdcd7edf52c0716b874778709c67 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-74/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-74/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] 52d2ae01bd4453f32493e47efbbdf649eb1aea41d6b80162ebfb262dc9dcba6d [INFO] running `Command { std: "docker" "start" "-a" "52d2ae01bd4453f32493e47efbbdf649eb1aea41d6b80162ebfb262dc9dcba6d", kill_on_drop: false }` [INFO] [stderr] Finished test [unoptimized + debuginfo] target(s) in 0.17s [INFO] [stderr] Running unittests (/opt/rustwide/target/debug/deps/ntex_amqp-1f9fe78023fa0cbe) [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/test_server.rs (/opt/rustwide/target/debug/deps/test_server-40a9d66a921c3876) [INFO] [stdout] [INFO] [stdout] running 3 tests [INFO] [stderr] [2021-12-02T15:24:58Z INFO ntex::server::builder] Starting 1 workers [INFO] [stderr] [2021-12-02T15:24:58Z INFO ntex::server::builder] Starting 1 workers [INFO] [stderr] [2021-12-02T15:24:58Z INFO ntex::server::builder] Starting 1 workers [INFO] [stderr] [2021-12-02T15:24:58Z INFO ntex::server::builder] Starting "test" service on 127.0.0.1:34307 [INFO] [stderr] [2021-12-02T15:24:58Z INFO ntex::server::builder] Starting "test" service on 127.0.0.1:40253 [INFO] [stderr] [2021-12-02T15:24:58Z INFO ntex::server::builder] Starting "test" service on 127.0.0.1:46675 [INFO] [stderr] [2021-12-02T15:24:58Z TRACE ntex_amqp::client::connector] Negotiation client protocol id: Amqp [INFO] [stderr] [2021-12-02T15:24:58Z TRACE ntex::server::accept] Starting server accept loop [INFO] [stderr] [2021-12-02T15:24:58Z TRACE ntex_amqp::client::connector] Negotiation client protocol id: Amqp [INFO] [stderr] [2021-12-02T15:24:58Z TRACE ntex::connect::service] TCP connector - connecting to "127.0.0.1" port:34307 [INFO] [stderr] [2021-12-02T15:24:58Z TRACE ntex::server::worker] Service "test" is available [INFO] [stderr] [2021-12-02T15:24:58Z TRACE ntex::connect::service] TCP connector - connecting to "127.0.0.1" port:46675 [INFO] [stderr] [2021-12-02T15:24:58Z TRACE ntex::connect::service] TCP connector - successfully connected to connecting to "127.0.0.1" - Ok(127.0.0.1:46675) [INFO] [stderr] [2021-12-02T15:24:58Z TRACE ntex_amqp::client::connector] Negotiation client protocol id: Amqp [INFO] [stderr] [2021-12-02T15:24:58Z TRACE ntex::connect::service] TCP connector - connecting to "127.0.0.1" port:40253 [INFO] [stderr] [2021-12-02T15:24:58Z TRACE ntex::connect::service] TCP connector - successfully connected to connecting to "127.0.0.1" - Ok(127.0.0.1:34307) [INFO] [stderr] [2021-12-02T15:24:58Z TRACE ntex_amqp::client::connector] Negotiation client protocol id: Amqp [INFO] [stderr] [2021-12-02T15:24:58Z TRACE ntex::server::accept] Starting server accept loop [INFO] [stderr] [2021-12-02T15:24:58Z TRACE ntex::server::accept] Accepting connection: Tcp(TcpStream { addr: 127.0.0.1:34307, peer: 127.0.0.1:40890, fd: 49 }) [INFO] [stderr] [2021-12-02T15:24:58Z TRACE ntex::server::accept] Accepting connection: Tcp(TcpStream { addr: 127.0.0.1:40253, peer: 127.0.0.1:57000, fd: 50 }) [INFO] [stderr] [2021-12-02T15:24:58Z TRACE ntex::server::accept] No available workers, enable back-pressure [INFO] [stderr] [2021-12-02T15:24:58Z TRACE ntex::server::accept] Enabling backpressure for 127.0.0.1:34307 [INFO] [stderr] [2021-12-02T15:24:58Z TRACE ntex::server::accept] Accepting connection: Tcp(TcpStream { addr: 127.0.0.1:34307, peer: 127.0.0.1:40890, fd: 49 }) [INFO] [stderr] [2021-12-02T15:24:58Z TRACE ntex::connect::service] TCP connector - successfully connected to connecting to "127.0.0.1" - Ok(127.0.0.1:40253) [INFO] [stderr] [2021-12-02T15:24:58Z TRACE ntex_amqp::client::connector] Negotiation client protocol id: AmqpSasl [INFO] [stderr] [2021-12-02T15:24:58Z TRACE ntex::server::worker] Got socket for service: "test" [INFO] [stderr] [2021-12-02T15:24:58Z TRACE ntex::server::accept] Starting server accept loop [INFO] [stderr] [2021-12-02T15:24:58Z TRACE ntex::server::accept] Accepting connection: Tcp(TcpStream { addr: 127.0.0.1:46675, peer: 127.0.0.1:57086, fd: 55 }) [INFO] [stderr] [2021-12-02T15:24:58Z TRACE ntex::server::accept] No available workers, enable back-pressure [INFO] [stderr] [2021-12-02T15:24:58Z TRACE ntex::server::accept] Enabling backpressure for 127.0.0.1:46675 [INFO] [stderr] [2021-12-02T15:24:58Z TRACE ntex::server::accept] Accepting connection: Tcp(TcpStream { addr: 127.0.0.1:46675, peer: 127.0.0.1:57086, fd: 55 }) [INFO] [stderr] [2021-12-02T15:24:58Z TRACE ntex::server::worker] Service "test" is available [INFO] [stderr] [2021-12-02T15:24:58Z TRACE ntex::server::worker] Got socket for service: "test" [INFO] [stderr] [2021-12-02T15:24:58Z INFO ntex::server::accept] Accepting connections on 127.0.0.1:34307 has been resumed [INFO] [stderr] [2021-12-02T15:24:58Z TRACE ntex_amqp::client::connector] Negotiation client protocol id: Amqp [INFO] [stderr] [2021-12-02T15:24:58Z TRACE ntex_amqp::client::connector] Open client amqp connection: Open(OpenInner { container_id: "4cf20eff74a44f4aabdf42bf452860ba", hostname: None, max_frame_size: 65535, channel_max: 1024, idle_time_out: Some(120000), outgoing_locales: None, incoming_locales: None, offered_capabilities: None, desired_capabilities: None, properties: None }) [INFO] [stderr] [2021-12-02T15:24:58Z TRACE ntex_amqp::server::handshake] Got open frame: Open(OpenInner { container_id: "4cf20eff74a44f4aabdf42bf452860ba", hostname: None, max_frame_size: 65535, channel_max: 1024, idle_time_out: Some(120000), outgoing_locales: None, incoming_locales: None, offered_capabilities: None, desired_capabilities: None, properties: None }) [INFO] [stderr] [2021-12-02T15:24:58Z TRACE ntex_amqp::client::connector] Open client amqp connection: Open(OpenInner { container_id: "cc77faa9d8c346ccbd23074cf8963164", hostname: None, max_frame_size: 65535, channel_max: 1024, idle_time_out: Some(120000), outgoing_locales: None, incoming_locales: None, offered_capabilities: None, desired_capabilities: None, properties: None }) [INFO] [stderr] [2021-12-02T15:24:58Z TRACE ntex_amqp::client::connector] Open confirmed: Open(OpenInner { container_id: "f933096869324473978f5e565fe6d4a9", hostname: None, max_frame_size: 65535, channel_max: 1024, idle_time_out: Some(120000), outgoing_locales: None, incoming_locales: None, offered_capabilities: None, desired_capabilities: None, properties: None }) [INFO] [stderr] [2021-12-02T15:24:58Z TRACE ntex::server::worker] Service "test" is available [INFO] [stderr] [2021-12-02T15:24:58Z TRACE ntex::server::worker] Got socket for service: "test" [INFO] [stderr] [2021-12-02T15:24:58Z TRACE ntex_amqp::server::sasl] Got open frame: Open(OpenInner { container_id: "cc77faa9d8c346ccbd23074cf8963164", hostname: None, max_frame_size: 65535, channel_max: 1024, idle_time_out: Some(120000), outgoing_locales: None, incoming_locales: None, offered_capabilities: None, desired_capabilities: None, properties: None }) [INFO] [stderr] [2021-12-02T15:24:58Z INFO ntex::server::accept] Accepting connections on 127.0.0.1:46675 has been resumed [INFO] [stdout] test test_sasl ... ok [INFO] [stderr] [2021-12-02T15:24:58Z TRACE ntex_amqp::client::connector] Open confirmed: Open(OpenInner { container_id: "c4f3704c57694585bf9fc03ad439cb5d", hostname: None, max_frame_size: 65535, channel_max: 1024, idle_time_out: Some(120000), outgoing_locales: None, incoming_locales: None, offered_capabilities: None, desired_capabilities: None, properties: None }) [INFO] [stderr] [2021-12-02T15:24:58Z TRACE ntex_amqp::connection] remote session opened: 0 [INFO] [stderr] [2021-12-02T15:24:58Z TRACE ntex::framed::dispatcher] not enough data to decode next frame, register dispatch task [INFO] [stderr] [2021-12-02T15:24:58Z TRACE ntex::framed::state] io stream is disconnected [INFO] [stderr] [2021-12-02T15:24:58Z TRACE ntex::framed::write] write io is closed [INFO] [stderr] [2021-12-02T15:24:58Z TRACE ntex::framed::dispatcher] dispatcher is instructed to stop [INFO] [stderr] [2021-12-02T15:24:58Z TRACE ntex_amqp::connection] Session opened: local 0 remote 0 [INFO] [stderr] [2021-12-02T15:24:58Z TRACE ntex_amqp::connection] Set connection error: Disconnected [INFO] [stderr] [2021-12-02T15:24:58Z TRACE ntex::framed::dispatcher] not enough data to decode next frame, register dispatch task [INFO] [stderr] [2021-12-02T15:24:58Z TRACE ntex::framed::dispatcher] service shutdown is completed, stop [INFO] [stderr] [2021-12-02T15:24:58Z TRACE ntex_amqp::session] Local sender link opening: "test" [INFO] [stderr] [2021-12-02T15:24:58Z TRACE ntex_amqp::router] Create handler service for test [INFO] [stderr] [2021-12-02T15:24:58Z TRACE ntex::framed::dispatcher] not enough data to decode next frame, register dispatch task [INFO] [stderr] [2021-12-02T15:24:58Z TRACE ntex_amqp::router] Handler service is created for test [INFO] [stderr] [2021-12-02T15:24:58Z TRACE ntex_amqp::session] Local sender link attached: "test" 0 -> 0 [INFO] [stderr] [2021-12-02T15:24:58Z TRACE ntex_amqp::session] Session received credit Some(50). window: 4294967295, pending: 0 [INFO] [stderr] [2021-12-02T15:24:58Z TRACE ntex_amqp::sndlink] Apply sender link "test" flow, credit: 50 flow count: 0, delivery count: 0 [INFO] [stderr] [2021-12-02T15:24:58Z TRACE ntex::framed::dispatcher] not enough data to decode next frame, register dispatch task [INFO] [stderr] [2021-12-02T15:24:58Z TRACE ntex_amqp::client::connector] Open client amqp connection: Open(OpenInner { container_id: "77fcf02b3380482783cfa9995f15a8a7", hostname: None, max_frame_size: 65535, channel_max: 1024, idle_time_out: Some(120000), outgoing_locales: None, incoming_locales: None, offered_capabilities: None, desired_capabilities: None, properties: None }) [INFO] [stderr] [2021-12-02T15:24:58Z TRACE ntex_amqp::session] Sending transfer over 0 window: 4294967294 [INFO] [stderr] [2021-12-02T15:24:58Z TRACE ntex_amqp::router] Outcome is ready Accept for test [INFO] [stderr] [2021-12-02T15:24:58Z TRACE ntex::framed::dispatcher] not enough data to decode next frame, register dispatch task [INFO] [stderr] [2021-12-02T15:24:58Z TRACE ntex_amqp::server::handshake] Got open frame: Open(OpenInner { container_id: "77fcf02b3380482783cfa9995f15a8a7", hostname: None, max_frame_size: 65535, channel_max: 1024, idle_time_out: Some(120000), outgoing_locales: None, incoming_locales: None, offered_capabilities: None, desired_capabilities: None, properties: None }) [INFO] [stderr] [2021-12-02T15:24:58Z TRACE ntex_amqp::session] Settle delivery from 0, state Some(Accepted(Accepted)) settled: true [INFO] [stderr] [2021-12-02T15:24:58Z TRACE ntex::framed::dispatcher] not enough data to decode next frame, register dispatch task [INFO] [stderr] [2021-12-02T15:24:58Z TRACE ntex_amqp::connection] Remote session end: 0 [INFO] [stderr] [2021-12-02T15:24:58Z TRACE ntex_amqp::session] Session is ended: SessionEnded(None) [INFO] [stderr] [2021-12-02T15:24:58Z TRACE ntex_amqp::session] Connection is failed, dropping state: SessionEnded(None) [INFO] [stderr] [2021-12-02T15:24:58Z TRACE ntex_amqp::rcvlink] Receiver link has been closed remotely [INFO] [stderr] [2021-12-02T15:24:58Z TRACE ntex::framed::dispatcher] not enough data to decode next frame, register dispatch task [INFO] [stderr] [2021-12-02T15:24:58Z TRACE ntex_amqp::connection] Remote session end: 0 [INFO] [stderr] [2021-12-02T15:24:58Z TRACE ntex_amqp::client::connector] Open confirmed: Open(OpenInner { container_id: "25a0a9aad8b14235ba150728d2f82468", hostname: None, max_frame_size: 65535, channel_max: 1024, idle_time_out: Some(120000), outgoing_locales: None, incoming_locales: None, offered_capabilities: None, desired_capabilities: None, properties: None }) [INFO] [stderr] [2021-12-02T15:24:58Z TRACE ntex_amqp::session] Session is ended: SessionEnded(None) [INFO] [stderr] [2021-12-02T15:24:58Z TRACE ntex_amqp::session] Connection is failed, dropping state: SessionEnded(None) [INFO] [stderr] [2021-12-02T15:24:58Z TRACE ntex_amqp::sndlink] Detaching sender link "test" with error SessionEnded(None) [INFO] [stderr] [2021-12-02T15:24:58Z TRACE ntex::framed::dispatcher] not enough data to decode next frame, register dispatch task [INFO] [stderr] [2021-12-02T15:24:58Z TRACE ntex::framed::dispatcher] dispatcher is instructed to stop [INFO] [stderr] [2021-12-02T15:24:58Z TRACE ntex::framed::state] initiate io shutdown RD_READY | DSP_STOP | DSP_ERR [INFO] [stderr] [2021-12-02T15:24:58Z TRACE ntex_amqp::connection] Set connection error: Disconnected [INFO] [stderr] [2021-12-02T15:24:58Z TRACE ntex::framed::dispatcher] service shutdown is completed, stop [INFO] [stderr] [2021-12-02T15:24:58Z TRACE ntex::framed::read] read task is instructed to shutdown [INFO] [stderr] [2021-12-02T15:24:58Z TRACE ntex::framed::write] write task is instructed to shutdown [INFO] [stderr] [2021-12-02T15:24:58Z TRACE ntex_amqp::connection] remote session opened: 0 [INFO] [stderr] [2021-12-02T15:24:58Z TRACE ntex::framed::dispatcher] not enough data to decode next frame, register dispatch task [INFO] [stdout] test test_session_end ... ok [INFO] [stderr] [2021-12-02T15:24:58Z TRACE ntex_amqp::connection] Session opened: local 0 remote 0 [INFO] [stderr] [2021-12-02T15:24:58Z TRACE ntex::framed::dispatcher] not enough data to decode next frame, register dispatch task [INFO] [stderr] [2021-12-02T15:24:58Z TRACE ntex_amqp::session] Local sender link opening: "test" [INFO] [stderr] [2021-12-02T15:24:58Z TRACE ntex_amqp::router] Create handler service for test [INFO] [stderr] [2021-12-02T15:24:58Z TRACE ntex::framed::dispatcher] not enough data to decode next frame, register dispatch task [INFO] [stderr] [2021-12-02T15:24:58Z TRACE ntex_amqp::router] Handler service is created for test [INFO] [stderr] [2021-12-02T15:24:58Z TRACE ntex_amqp::session] Local sender link attached: "test" 0 -> 0 [INFO] [stderr] [2021-12-02T15:24:58Z TRACE ntex_amqp::session] Session received credit Some(50). window: 4294967295, pending: 0 [INFO] [stderr] [2021-12-02T15:24:58Z TRACE ntex_amqp::sndlink] Apply sender link "test" flow, credit: 50 flow count: 0, delivery count: 0 [INFO] [stderr] [2021-12-02T15:24:58Z TRACE ntex::framed::dispatcher] not enough data to decode next frame, register dispatch task [INFO] [stderr] [2021-12-02T15:24:58Z TRACE ntex_amqp::session] Sending transfer over 0 window: 4294967294 [INFO] [stderr] [2021-12-02T15:24:58Z TRACE ntex_amqp::router] Outcome is ready Accept for test [INFO] [stderr] [2021-12-02T15:24:58Z TRACE ntex::framed::dispatcher] not enough data to decode next frame, register dispatch task [INFO] [stderr] [2021-12-02T15:24:58Z TRACE ntex_amqp::session] Settle delivery from 0, state Some(Accepted(Accepted)) settled: true [INFO] [stderr] [2021-12-02T15:24:58Z TRACE ntex::framed::dispatcher] not enough data to decode next frame, register dispatch task [INFO] [stderr] [2021-12-02T15:24:58Z TRACE ntex_amqp::session] Sending transfer over 0 window: 4294967293 [INFO] [stderr] [2021-12-02T15:24:58Z TRACE ntex_amqp::router] Outcome is ready Accept for test [INFO] [stderr] [2021-12-02T15:24:58Z TRACE ntex::framed::dispatcher] not enough data to decode next frame, register dispatch task [INFO] [stderr] [2021-12-02T15:24:58Z TRACE ntex_amqp::session] Settle delivery from 1, state Some(Accepted(Accepted)) settled: true [INFO] [stderr] [2021-12-02T15:24:58Z TRACE ntex::framed::dispatcher] not enough data to decode next frame, register dispatch task [INFO] [stdout] test test_simple ... ok [INFO] [stderr] [2021-12-02T15:24:59Z TRACE ntex::framed::state] io stream is disconnected [INFO] [stdout] [INFO] [stderr] [2021-12-02T15:24:59Z TRACE ntex::framed::write] write io is closed [INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.73s [INFO] [stderr] [2021-12-02T15:24:59Z TRACE ntex::framed::dispatcher] dispatcher is instructed to stop [INFO] [stdout] [INFO] [stderr] [2021-12-02T15:24:59Z TRACE ntex_amqp::connection] Set connection error: Disconnected [INFO] [stderr] [2021-12-02T15:24:59Z TRACE ntex_amqp::session] Connection is failed, dropping state: Disconnected [INFO] [stderr] [2021-12-02T15:24:59Z TRACE ntex_amqp::rcvlink] Receiver link has been closed remotely [INFO] [stderr] [2021-12-02T15:24:59Z TRACE ntex::framed::dispatcher] service shutdown is completed, stop [INFO] [stderr] Doc-tests ntex-amqp [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" "52d2ae01bd4453f32493e47efbbdf649eb1aea41d6b80162ebfb262dc9dcba6d", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "52d2ae01bd4453f32493e47efbbdf649eb1aea41d6b80162ebfb262dc9dcba6d", kill_on_drop: false }` [INFO] [stdout] 52d2ae01bd4453f32493e47efbbdf649eb1aea41d6b80162ebfb262dc9dcba6d