[INFO] cloning repository https://github.com/eHealthExperts/k2-creek
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/eHealthExperts/k2-creek" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2FeHealthExperts%2Fk2-creek", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2FeHealthExperts%2Fk2-creek'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] fd299cfe2dc1018618617c061df8a3825423c6c9
[INFO] testing eHealthExperts/k2-creek against beta-2020-10-09 for beta-1.48-1
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2FeHealthExperts%2Fk2-creek" "/workspace/builds/worker-9/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-9/source'...
[INFO] [stderr] done.
[INFO] validating manifest of git repo https://github.com/eHealthExperts/k2-creek on toolchain beta-2020-10-09
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+beta-2020-10-09" "read-manifest" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] removed /workspace/builds/worker-9/source/.cargo/config
[INFO] started tweaking git repo https://github.com/eHealthExperts/k2-creek
[INFO] finished tweaking git repo https://github.com/eHealthExperts/k2-creek
[INFO] tweaked toml for git repo https://github.com/eHealthExperts/k2-creek written to /workspace/builds/worker-9/source/Cargo.toml
[INFO] crate git repo https://github.com/eHealthExperts/k2-creek already has a lockfile, it will not be regenerated
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+beta-2020-10-09" "fetch" "--locked" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]     Updating git repository `https://github.com/ChriFo/test-server-rs`
[INFO] [stderr]     Updating git repository `https://github.com/budziq/rust-skeptic.git`
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded enum-iterator v0.6.0
[INFO] [stderr]   Downloaded enum-iterator-derive v0.6.0
[INFO] [stderr]   Downloaded der-parser v4.1.0
[INFO] [stderr]   Downloaded promptly v0.3.0
[INFO] [stderr]   Downloaded fragile v1.0.0
[INFO] [stderr]   Downloaded mockall_derive v0.8.1
[INFO] [stderr]   Downloaded mockall v0.8.1
[INFO] [stderr]   Downloaded serde_with v1.5.0
[INFO] [stderr]   Downloaded serial_test v0.5.0
[INFO] [stderr]   Downloaded serde_with_macros v1.2.0
[INFO] [stderr]   Downloaded cargo_metadata v0.7.4
[INFO] [stderr]   Downloaded serial_test_derive v0.5.0
[INFO] [stderr]   Downloaded downcast v0.10.0
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-9/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-9/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" "rustops/crates-build-env@sha256:d92eaeed33fa75130ca1ee6c8a01d1ffb62bac859dfdfb3450e3e5d1c0146529" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2020-10-09" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] c05db3021768ed5cf18911bd3d6f514ded8162d8dc357b40d2458ba7ffc01ff7
[INFO] [stderr] WARNING: Your kernel does not support swap limit capabilities or the cgroup is not mounted. Memory limited without swap.
[INFO] running `Command { std: "docker" "start" "-a" "c05db3021768ed5cf18911bd3d6f514ded8162d8dc357b40d2458ba7ffc01ff7", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "c05db3021768ed5cf18911bd3d6f514ded8162d8dc357b40d2458ba7ffc01ff7", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "c05db3021768ed5cf18911bd3d6f514ded8162d8dc357b40d2458ba7ffc01ff7", kill_on_drop: false }`
[INFO] [stdout] c05db3021768ed5cf18911bd3d6f514ded8162d8dc357b40d2458ba7ffc01ff7
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-9/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-9/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" "rustops/crates-build-env@sha256:d92eaeed33fa75130ca1ee6c8a01d1ffb62bac859dfdfb3450e3e5d1c0146529" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2020-10-09" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 5c4af06e368562138a7c103449152baeb6146f492f2d25c1e92cdebbe16650c7
[INFO] [stderr] WARNING: Your kernel does not support swap limit capabilities or the cgroup is not mounted. Memory limited without swap.
[INFO] running `Command { std: "docker" "start" "-a" "5c4af06e368562138a7c103449152baeb6146f492f2d25c1e92cdebbe16650c7", kill_on_drop: false }`
[INFO] [stderr]    Compiling libc v0.2.78
[INFO] [stderr]    Compiling syn v1.0.42
[INFO] [stderr]    Compiling cc v1.0.60
[INFO] [stderr]    Compiling fnv v1.0.7
[INFO] [stderr]    Compiling once_cell v1.4.1
[INFO] [stderr]    Compiling bytes v0.5.6
[INFO] [stderr]    Compiling pkg-config v0.3.18
[INFO] [stderr]    Compiling pin-project-lite v0.1.10
[INFO] [stderr]    Compiling arc-swap v0.4.7
[INFO] [stderr]    Compiling pin-project-internal v0.4.25
[INFO] [stderr]    Compiling itoa v0.4.6
[INFO] [stderr]    Compiling serde v1.0.116
[INFO] [stderr]    Compiling proc-macro-hack v0.5.18
[INFO] [stderr]    Compiling futures-sink v0.3.5
[INFO] [stderr]    Compiling untrusted v0.7.1
[INFO] [stderr]    Compiling lexical-core v0.7.4
[INFO] [stderr]    Compiling arrayvec v0.5.1
[INFO] [stderr]    Compiling futures-io v0.3.5
[INFO] [stderr]    Compiling openssl v0.10.30
[INFO] [stderr]    Compiling static_assertions v1.1.0
[INFO] [stderr]    Compiling hashbrown v0.9.1
[INFO] [stderr]    Compiling encoding_index_tests v0.1.4
[INFO] [stderr]    Compiling strsim v0.9.3
[INFO] [stderr]    Compiling try-lock v0.2.3
[INFO] [stderr]    Compiling native-tls v0.2.4
[INFO] [stderr]    Compiling ident_case v1.0.1
[INFO] [stderr]    Compiling httpdate v0.3.2
[INFO] [stderr]    Compiling nix v0.18.0
[INFO] [stderr]    Compiling encoding_rs v0.8.24
[INFO] [stderr]    Compiling serde_json v1.0.58
[INFO] [stderr]    Compiling utf8parse v0.2.0
[INFO] [stderr]    Compiling unicode-segmentation v1.6.0
[INFO] [stderr]    Compiling anyhow v1.0.32
[INFO] [stderr]    Compiling xml-rs v0.8.3
[INFO] [stderr]    Compiling ipnet v2.3.0
[INFO] [stderr]    Compiling k2-creek v1.0.0 (/opt/rustwide/workdir)
[INFO] [stderr]    Compiling termcolor v1.1.0
[INFO] [stderr]    Compiling base64 v0.13.0
[INFO] [stderr]    Compiling indexmap v1.6.0
[INFO] [stderr]    Compiling num-traits v0.2.12
[INFO] [stderr]    Compiling num-integer v0.1.43
[INFO] [stderr]    Compiling num-bigint v0.3.0
[INFO] [stderr]    Compiling nom v5.1.2
[INFO] [stderr]    Compiling tracing-core v0.1.17
[INFO] [stderr]    Compiling unicase v2.6.0
[INFO] [stderr]    Compiling futures-channel v0.3.5
[INFO] [stderr]    Compiling futures-task v0.3.5
[INFO] [stderr]    Compiling encoding-index-simpchinese v1.20141219.5
[INFO] [stderr]    Compiling encoding-index-korean v1.20141219.5
[INFO] [stderr]    Compiling encoding-index-singlebyte v1.20141219.5
[INFO] [stderr]    Compiling encoding-index-japanese v1.20141219.5
[INFO] [stderr]    Compiling encoding-index-tradchinese v1.20141219.5
[INFO] [stderr]    Compiling humantime v1.3.0
[INFO] [stderr]    Compiling http v0.2.1
[INFO] [stderr]    Compiling idna v0.2.0
[INFO] [stderr]    Compiling want v0.3.0
[INFO] [stderr]    Compiling tracing v0.1.21
[INFO] [stderr]    Compiling regex v1.3.9
[INFO] [stderr]    Compiling encoding v0.2.33
[INFO] [stderr]    Compiling mime_guess v2.0.3
[INFO] [stderr]    Compiling openssl-sys v0.9.58
[INFO] [stderr]    Compiling ring v0.16.15
[INFO] [stderr]    Compiling net2 v0.2.35
[INFO] [stderr]    Compiling iovec v0.1.4
[INFO] [stderr]    Compiling signal-hook-registry v1.2.1
[INFO] [stderr]    Compiling num_cpus v1.13.0
[INFO] [stderr]    Compiling socket2 v0.3.15
[INFO] [stderr]    Compiling dirs-sys-next v0.1.0
[INFO] [stderr]    Compiling atty v0.2.14
[INFO] [stderr]    Compiling mio v0.6.22
[INFO] [stderr]    Compiling dirs-next v1.0.1
[INFO] [stderr]    Compiling env_logger v0.7.1
[INFO] [stderr]    Compiling clap v2.33.3
[INFO] [stderr]    Compiling http-body v0.3.1
[INFO] [stderr]    Compiling mio-uds v0.6.8
[INFO] [stderr]    Compiling tokio v0.2.22
[INFO] [stderr]    Compiling rusticata-macros v2.1.0
[INFO] [stderr]    Compiling der-oid-macro v0.2.0
[INFO] [stderr]    Compiling webpki v0.21.3
[INFO] [stderr]    Compiling sct v0.6.0
[INFO] [stderr]    Compiling rustls v0.18.1
[INFO] [stderr]    Compiling webpki-roots v0.19.0
[INFO] [stderr]    Compiling der-parser v4.1.0
[INFO] [stderr]    Compiling darling_core v0.10.2
[INFO] [stderr]    Compiling rustyline v6.3.0
[INFO] [stderr]    Compiling tokio-util v0.3.1
[INFO] [stderr]    Compiling tokio-tls v0.3.1
[INFO] [stderr]    Compiling serde_derive v1.0.116
[INFO] [stderr]    Compiling futures-macro v0.3.5
[INFO] [stderr]    Compiling thiserror-impl v1.0.20
[INFO] [stderr]    Compiling enum-iterator-derive v0.6.0
[INFO] [stderr]    Compiling promptly v0.3.0
[INFO] [stderr]    Compiling tokio-rustls v0.14.1
[INFO] [stderr]    Compiling darling_macro v0.10.2
[INFO] [stderr]    Compiling enum-iterator v0.6.0
[INFO] [stderr]    Compiling thiserror v1.0.20
[INFO] [stderr]    Compiling pin-project v0.4.25
[INFO] [stderr]    Compiling darling v0.10.2
[INFO] [stderr]    Compiling serde_with_macros v1.2.0
[INFO] [stderr]    Compiling futures-util v0.3.5
[INFO] [stderr]    Compiling h2 v0.2.6
[INFO] [stderr]    Compiling url v2.1.1
[INFO] [stderr]    Compiling serde_with v1.5.0
[INFO] [stderr]    Compiling config v0.10.1
[INFO] [stderr]    Compiling serde-xml-rs v0.4.0
[INFO] [stderr]    Compiling serde_urlencoded v0.6.1
[INFO] [stderr]    Compiling hyper v0.13.8
[INFO] [stderr]    Compiling hyper-rustls v0.21.0
[INFO] [stderr]    Compiling hyper-tls v0.4.3
[INFO] [stderr]    Compiling reqwest v0.10.8
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 2m 22s
[INFO] running `Command { std: "docker" "inspect" "5c4af06e368562138a7c103449152baeb6146f492f2d25c1e92cdebbe16650c7", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "5c4af06e368562138a7c103449152baeb6146f492f2d25c1e92cdebbe16650c7", kill_on_drop: false }`
[INFO] [stdout] 5c4af06e368562138a7c103449152baeb6146f492f2d25c1e92cdebbe16650c7
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-9/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-9/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" "rustops/crates-build-env@sha256:d92eaeed33fa75130ca1ee6c8a01d1ffb62bac859dfdfb3450e3e5d1c0146529" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2020-10-09" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 2c781c1a882ca9da06530a5de59d9d7daa697497a85bf75cdcf63edc882fdc63
[INFO] [stderr] WARNING: Your kernel does not support swap limit capabilities or the cgroup is not mounted. Memory limited without swap.
[INFO] running `Command { std: "docker" "start" "-a" "2c781c1a882ca9da06530a5de59d9d7daa697497a85bf75cdcf63edc882fdc63", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]    Compiling scopeguard v1.1.0
[INFO] [stderr]    Compiling adler v0.2.3
[INFO] [stderr]    Compiling gimli v0.22.0
[INFO] [stderr]    Compiling object v0.20.0
[INFO] [stderr]    Compiling copyless v0.1.5
[INFO] [stderr]    Compiling either v1.6.1
[INFO] [stderr]    Compiling const_fn v0.4.2
[INFO] [stderr]    Compiling match_cfg v0.1.0
[INFO] [stderr]    Compiling linked-hash-map v0.5.3
[INFO] [stderr]    Compiling pulldown-cmark v0.2.0
[INFO] [stderr]    Compiling opaque-debug v0.3.0
[INFO] [stderr]    Compiling remove_dir_all v0.5.3
[INFO] [stderr]    Compiling cpuid-bool v0.1.2
[INFO] [stderr]    Compiling bytecount v0.6.0
[INFO] [stderr]    Compiling predicates-core v1.0.0
[INFO] [stderr]    Compiling difference v2.0.0
[INFO] [stderr]    Compiling tinyvec_macros v0.1.0
[INFO] [stderr]    Compiling treeline v0.1.0
[INFO] [stderr]    Compiling doc-comment v0.3.3
[INFO] [stderr]    Compiling normalize-line-endings v0.3.0
[INFO] [stderr]    Compiling fragile v1.0.0
[INFO] [stderr]    Compiling downcast v0.10.0
[INFO] [stderr]    Compiling futures-timer v3.0.2
[INFO] [stderr]    Compiling miniz_oxide v0.4.2
[INFO] [stderr]    Compiling instant v0.1.7
[INFO] [stderr]    Compiling standback v0.2.10
[INFO] [stderr]    Compiling generic-array v0.14.4
[INFO] [stderr]    Compiling time v0.2.22
[INFO] [stderr]    Compiling error-chain v0.12.4
[INFO] [stderr]    Compiling cookie v0.14.2
[INFO] [stderr]    Compiling bytestring v0.1.5
[INFO] [stderr]    Compiling heck v0.3.1
[INFO] [stderr]    Compiling lock_api v0.4.1
[INFO] [stderr]    Compiling lock_api v0.3.4
[INFO] [stderr]    Compiling tinyvec v1.0.1
[INFO] [stderr]    Compiling predicates-tree v1.0.0
[INFO] [stderr]    Compiling lru-cache v0.1.2
[INFO] [stderr]    Compiling getrandom v0.1.15
[INFO] [stderr]    Compiling parking_lot_core v0.8.0
[INFO] [stderr]    Compiling hostname v0.3.1
[INFO] [stderr]    Compiling parking_lot_core v0.7.2
[INFO] [stderr]    Compiling wait-timeout v0.2.0
[INFO] [stderr]    Compiling fxhash v0.2.1
[INFO] [stderr]    Compiling threadpool v1.8.1
[INFO] [stderr]    Compiling float-cmp v0.8.0
[INFO] [stderr]    Compiling resolv-conf v0.6.3
[INFO] [stderr]    Compiling derive_more v0.99.11
[INFO] [stderr]    Compiling actix-macros v0.1.2
[INFO] [stderr]    Compiling enum-as-inner v0.3.3
[INFO] [stderr]    Compiling async-trait v0.1.41
[INFO] [stderr]    Compiling actix-web-codegen v0.3.0
[INFO] [stderr]    Compiling serial_test_derive v0.5.0
[INFO] [stderr]    Compiling mockall_derive v0.8.1
[INFO] [stderr]    Compiling rand_core v0.5.1
[INFO] [stderr]    Compiling time-macros-impl v0.1.1
[INFO] [stderr]    Compiling parking_lot v0.11.0
[INFO] [stderr]    Compiling parking_lot v0.10.2
[INFO] [stderr]    Compiling predicates v1.0.5
[INFO] [stderr]    Compiling rand_chacha v0.2.2
[INFO] [stderr]    Compiling block-buffer v0.9.0
[INFO] [stderr]    Compiling digest v0.9.0
[INFO] [stderr]    Compiling semver v0.9.0
[INFO] [stderr]    Compiling actix-router v0.2.5
[INFO] [stderr]    Compiling assert_cmd v1.0.1
[INFO] [stderr]    Compiling actix-codec v0.3.0
[INFO] [stderr]    Compiling sha-1 v0.9.1
[INFO] [stderr]    Compiling actix-service v1.0.6
[INFO] [stderr]    Compiling futures-executor v0.3.5
[INFO] [stderr]    Compiling serial_test v0.5.0
[INFO] [stderr]    Compiling rand v0.7.3
[INFO] [stderr]    Compiling futures v0.3.5
[INFO] [stderr]    Compiling time-macros v0.1.1
[INFO] [stderr]    Compiling addr2line v0.13.0
[INFO] [stderr]    Compiling tempfile v3.1.0
[INFO] [stderr]    Compiling backtrace v0.3.51
[INFO] [stderr]    Compiling trust-dns-proto v0.19.5
[INFO] [stderr]    Compiling cargo_metadata v0.7.4
[INFO] [stderr]    Compiling actix-threadpool v0.3.3
[INFO] [stderr]    Compiling actix-rt v1.1.1
[INFO] [stderr]    Compiling mockall v0.8.1
[INFO] [stderr]    Compiling actix-utils v2.0.0
[INFO] [stderr]    Compiling skeptic v0.13.4 (https://github.com/budziq/rust-skeptic.git?branch=tempfile#97ec040b)
[INFO] [stderr]    Compiling actix-server v1.0.4
[INFO] [stderr]    Compiling actix-tls v2.0.0
[INFO] [stderr]    Compiling trust-dns-resolver v0.19.5
[INFO] [stderr]    Compiling test-server v0.9.1 (https://github.com/ChriFo/test-server-rs?tag=0.9.1#b64d1dbd)
[INFO] [stderr]    Compiling actix-testing v1.0.1
[INFO] [stderr]    Compiling actix-connect v2.0.0
[INFO] [stderr]    Compiling actix-http v2.0.0
[INFO] [stderr]    Compiling awc v2.0.0
[INFO] [stderr]    Compiling actix-web v3.1.0
[INFO] [stderr]    Compiling k2-creek v1.0.0 (/opt/rustwide/workdir)
[INFO] [stdout] warning: unused import: `std::io::prelude::*`
[INFO] [stdout]    --> src/tests.rs:122:21
[INFO] [stdout]     |
[INFO] [stdout] 122 |                   use std::io::prelude::*;
[INFO] [stdout]     |                       ^^^^^^^^^^^^^^^^^^^
[INFO] [stdout]     | 
[INFO] [stdout]    ::: src/egk/mod.rs:207:5
[INFO] [stdout]     |
[INFO] [stdout] 207 | /     test! {
[INFO] [stdout] 208 | |         name: all_files_written,
[INFO] [stdout] 209 | |         temp_dir: true,
[INFO] [stdout] 210 | |         vars: [
[INFO] [stdout] ...   |
[INFO] [stdout] 241 | |         ]
[INFO] [stdout] 242 | |     }
[INFO] [stdout]     | |_____- in this macro invocation
[INFO] [stdout]     |
[INFO] [stdout] note: the lint level is defined here
[INFO] [stdout]    --> src/lib.rs:6:9
[INFO] [stdout]     |
[INFO] [stdout] 6   | #![warn(unused_imports)]
[INFO] [stdout]     |         ^^^^^^^^^^^^^^
[INFO] [stdout]     = note: this warning originates in a macro (in Nightly builds, run with -Z macro-backtrace for more info)
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: unused import: `std::io::prelude::*`
[INFO] [stdout]    --> src/tests.rs:122:21
[INFO] [stdout]     |
[INFO] [stdout] 122 |                   use std::io::prelude::*;
[INFO] [stdout]     |                       ^^^^^^^^^^^^^^^^^^^
[INFO] [stdout]     | 
[INFO] [stdout]    ::: src/egk/mod.rs:207:5
[INFO] [stdout]     |
[INFO] [stdout] 207 | /     test! {
[INFO] [stdout] 208 | |         name: all_files_written,
[INFO] [stdout] 209 | |         temp_dir: true,
[INFO] [stdout] 210 | |         vars: [
[INFO] [stdout] ...   |
[INFO] [stdout] 241 | |         ]
[INFO] [stdout] 242 | |     }
[INFO] [stdout]     | |_____- in this macro invocation
[INFO] [stdout]     |
[INFO] [stdout]     = note: this warning originates in a macro (in Nightly builds, run with -Z macro-backtrace for more info)
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: 2 warnings emitted
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 2m 59s
[INFO] running `Command { std: "docker" "inspect" "2c781c1a882ca9da06530a5de59d9d7daa697497a85bf75cdcf63edc882fdc63", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "2c781c1a882ca9da06530a5de59d9d7daa697497a85bf75cdcf63edc882fdc63", kill_on_drop: false }`
[INFO] [stdout] 2c781c1a882ca9da06530a5de59d9d7daa697497a85bf75cdcf63edc882fdc63
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-9/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-9/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" "rustops/crates-build-env@sha256:d92eaeed33fa75130ca1ee6c8a01d1ffb62bac859dfdfb3450e3e5d1c0146529" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2020-10-09" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 063e93772f15388aa967654100e5da93178869001751b6c02ea8c73661a50276
[INFO] [stderr] WARNING: Your kernel does not support swap limit capabilities or the cgroup is not mounted. Memory limited without swap.
[INFO] running `Command { std: "docker" "start" "-a" "063e93772f15388aa967654100e5da93178869001751b6c02ea8c73661a50276", kill_on_drop: false }`
[INFO] [stderr] warning: unused import: `std::io::prelude::*`
[INFO] [stderr]    --> src/tests.rs:122:21
[INFO] [stderr]     |
[INFO] [stderr] 122 |                   use std::io::prelude::*;
[INFO] [stderr]     |                       ^^^^^^^^^^^^^^^^^^^
[INFO] [stderr]     | 
[INFO] [stderr]    ::: src/egk/mod.rs:207:5
[INFO] [stderr]     |
[INFO] [stderr] 207 | /     test! {
[INFO] [stderr] 208 | |         name: all_files_written,
[INFO] [stderr] 209 | |         temp_dir: true,
[INFO] [stderr] 210 | |         vars: [
[INFO] [stderr] ...   |
[INFO] [stderr] 241 | |         ]
[INFO] [stderr] 242 | |     }
[INFO] [stderr]     | |_____- in this macro invocation
[INFO] [stderr]     |
[INFO] [stderr] note: the lint level is defined here
[INFO] [stderr]    --> src/lib.rs:6:9
[INFO] [stderr]     |
[INFO] [stderr] 6   | #![warn(unused_imports)]
[INFO] [stderr]     |         ^^^^^^^^^^^^^^
[INFO] [stderr]     = note: this warning originates in a macro (in Nightly builds, run with -Z macro-backtrace for more info)
[INFO] [stderr] 
[INFO] [stderr] warning: unused import: `std::io::prelude::*`
[INFO] [stderr]    --> src/tests.rs:122:21
[INFO] [stderr]     |
[INFO] [stderr] 122 |                   use std::io::prelude::*;
[INFO] [stderr]     |                       ^^^^^^^^^^^^^^^^^^^
[INFO] [stderr]     | 
[INFO] [stderr]    ::: src/egk/mod.rs:207:5
[INFO] [stderr]     |
[INFO] [stderr] 207 | /     test! {
[INFO] [stderr] 208 | |         name: all_files_written,
[INFO] [stderr] 209 | |         temp_dir: true,
[INFO] [stderr] 210 | |         vars: [
[INFO] [stderr] ...   |
[INFO] [stderr] 241 | |         ]
[INFO] [stderr] 242 | |     }
[INFO] [stderr]     | |_____- in this macro invocation
[INFO] [stderr]     |
[INFO] [stderr]     = note: this warning originates in a macro (in Nightly builds, run with -Z macro-backtrace for more info)
[INFO] [stderr] 
[INFO] [stderr] warning: 2 warnings emitted
[INFO] [stderr] 
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 1.32s
[INFO] [stderr]      Running /opt/rustwide/target/debug/deps/k2_creek-402e830b88cd6124
[INFO] [stdout] 
[INFO] [stdout] running 46 tests
[INFO] [stderr] [2020-10-17T17:25:30Z DEBUG k2_creek::api::v1::tests] 
[INFO] [stderr]     
[INFO] [stderr]     DIR
[INFO] [stderr]      Ok("/opt/rustwide/workdir") 
[INFO] [stderr]     
[INFO] [stderr]     
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE k2_creek::kvk] Decoded: YIGLgBZUZWNobmlrZXIgS3Jhbmtlbmthc3NlgQcxMzc3NTA4jwUwMDAyMIIMMTIzNDU2Nzg5MDEygwQ1MDAwkAExhQtBbWVsaWUgU2luYYcFQXBmZWyICDExMDkxOTk1iRJDYXJsLVdvbGZmLVN0ci4gMTKKAUSLBTQ1Mjc5jAVFc3Nlbo0EMTAyMY4B/A==
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE k2_creek::kvk] Encoded: [96, 129, 139, 128, 22, 84, 101, 99, 104, 110, 105, 107, 101, 114, 32, 75, 114, 97, 110, 107, 101, 110, 107, 97, 115, 115, 101, 129, 7, 49, 51, 55, 55, 53, 48, 56, 143, 5, 48, 48, 48, 50, 48, 130, 12, 49, 50, 51, 52, 53, 54, 55, 56, 57, 48, 49, 50, 131, 4, 53, 48, 48, 48, 144, 1, 49, 133, 11, 65, 109, 101, 108, 105, 101, 32, 83, 105, 110, 97, 135, 5, 65, 112, 102, 101, 108, 136, 8, 49, 49, 48, 57, 49, 57, 57, 53, 137, 18, 67, 97, 114, 108, 45, 87, 111, 108, 102, 102, 45, 83, 116, 114, 46, 32, 49, 50, 138, 1, 68, 139, 5, 52, 53, 50, 55, 57, 140, 5, 69, 115, 115, 101, 110, 141, 4, 49, 48, 50, 49, 142, 1, 252]
[INFO] [stderr] [2020-10-17T17:25:30Z DEBUG k2_creek::cli] Msg error: The following required arguments were not provided:
[INFO] [stderr]         --url <URL>
[INFO] [stderr]     
[INFO] [stderr]     USAGE:
[INFO] [stderr]         test --output <DIRECTORY> --url <URL>
[INFO] [stderr]     
[INFO] [stderr]     For more information try --help
[INFO] [stderr] [2020-10-17T17:25:30Z DEBUG k2_creek::cli] Msg error: The following required arguments were not provided:
[INFO] [stderr]         --url <URL>
[INFO] [stderr]     
[INFO] [stderr]     USAGE:
[INFO] [stderr]         test --output <DIRECTORY> --url <URL>
[INFO] [stderr]     
[INFO] [stderr]     For more information try --help
[INFO] [stderr] [2020-10-17T17:25:30Z DEBUG k2_creek::cli] Msg error: The following required arguments were not provided:
[INFO] [stderr]         --url <URL>
[INFO] [stderr]     
[INFO] [stderr]     USAGE:
[INFO] [stderr]         test --timeout <SECONDS> --url <URL>
[INFO] [stderr]     
[INFO] [stderr]     For more information try --help
[INFO] [stderr] [2020-10-17T17:25:30Z DEBUG k2_creek::cli] Msg error: The following required arguments were not provided:
[INFO] [stderr]         --url <URL>
[INFO] [stderr]     
[INFO] [stderr]     USAGE:
[INFO] [stderr]         test --timeout <SECONDS> --url <URL>
[INFO] [stderr]     
[INFO] [stderr]     For more information try --help
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE k2_creek::kvk] Decoded: YIGLgBZUZWNobmlrZXIgS3Jhbmtlbmthc3NlgQcxMzc3NTA4jwUwMDAyMIIMMTIzNDU2Nzg5MDEygwQ1MDAwkAExhQtBbWVsaWUgU2luYYcFQXBmZWyICDExMDkxOTk1iRJDYXJsLVdvbGZmLVN0ci4gMTKKAUSLBTQ1Mjc5jAVFc3Nlbo0EMTAyMY4B/A==
[INFO] [stderr] [2020-10-17T17:25:30Z DEBUG k2_creek::cli] Msg error: The following required arguments were not provided:
[INFO] [stderr]         --url <URL>
[INFO] [stderr]     
[INFO] [stderr]     USAGE:
[INFO] [stderr]         test --api <VERSION> --url <URL>
[INFO] [stderr]     
[INFO] [stderr]     For more information try --help
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE k2_creek::kvk] Encoded: [96, 129, 139, 128, 22, 84, 101, 99, 104, 110, 105, 107, 101, 114, 32, 75, 114, 97, 110, 107, 101, 110, 107, 97, 115, 115, 101, 129, 7, 49, 51, 55, 55, 53, 48, 56, 143, 5, 48, 48, 48, 50, 48, 130, 12, 49, 50, 51, 52, 53, 54, 55, 56, 57, 48, 49, 50, 131, 4, 53, 48, 48, 48, 144, 1, 49, 133, 11, 65, 109, 101, 108, 105, 101, 32, 83, 105, 110, 97, 135, 5, 65, 112, 102, 101, 108, 136, 8, 49, 49, 48, 57, 49, 57, 57, 53, 137, 18, 67, 97, 114, 108, 45, 87, 111, 108, 102, 102, 45, 83, 116, 114, 46, 32, 49, 50, 138, 1, 68, 139, 5, 52, 53, 50, 55, 57, 140, 5, 69, 115, 115, 101, 110, 141, 4, 49, 48, 50, 49, 142, 1, 252]
[INFO] [stderr] [2020-10-17T17:25:30Z DEBUG k2_creek::cli] Msg error: The following required arguments were not provided:
[INFO] [stderr]         --url <URL>
[INFO] [stderr]     
[INFO] [stderr]     USAGE:
[INFO] [stderr]         test -f --url <URL>
[INFO] [stderr]     
[INFO] [stderr]     For more information try --help
[INFO] [stdout] test cli::required_argument_url ... ok
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE k2_creek::kvk] Decoded: YIGLgBZUZWNobmlrZXIgS3Jhbmtlbmthc3NlgQcxMzc3NTA4jwUwMDAyMIIMMTIzNDU2Nzg5MDEygwQ1MDAwkAExhQtBbWVsaWUgU2luYYcFQXBmZWyICDExMDkxOTk1iRJDYXJsLVdvbGZmLVN0ci4gMTKKAUSLBTQ1Mjc5jAVFc3Nlbo0EMTAyMY4B/A==
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE k2_creek::kvk] Encoded: [96, 129, 139, 128, 22, 84, 101, 99, 104, 110, 105, 107, 101, 114, 32, 75, 114, 97, 110, 107, 101, 110, 107, 97, 115, 115, 101, 129, 7, 49, 51, 55, 55, 53, 48, 56, 143, 5, 48, 48, 48, 50, 48, 130, 12, 49, 50, 51, 52, 53, 54, 55, 56, 57, 48, 49, 50, 131, 4, 53, 48, 48, 48, 144, 1, 49, 133, 11, 65, 109, 101, 108, 105, 101, 32, 83, 105, 110, 97, 135, 5, 65, 112, 102, 101, 108, 136, 8, 49, 49, 48, 57, 49, 57, 57, 53, 137, 18, 67, 97, 114, 108, 45, 87, 111, 108, 102, 102, 45, 83, 116, 114, 46, 32, 49, 50, 138, 1, 68, 139, 5, 52, 53, 50, 55, 57, 140, 5, 69, 115, 115, 101, 110, 141, 4, 49, 48, 50, 49, 142, 1, 252]
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE k2_creek::kvk] ASN.1 decoded:
[INFO] [stderr]     KrankenKassenName:    Techniker Krankenkasse
[INFO] [stderr]     KrankenKassenNummer:  1377508
[INFO] [stderr]     VKNR:                 00020
[INFO] [stderr]     VersichertenNummer:   123456789012
[INFO] [stderr]     VersichertenStatus:   5000
[INFO] [stderr]     StatusErgänzung:      1
[INFO] [stderr]     VorName:              Amelie Sina
[INFO] [stderr]     FamilienName:         Apfel
[INFO] [stderr]     GeburtsDatum:         11091995
[INFO] [stderr]     Straßenname:          Carl-Wolff-Str. 12
[INFO] [stderr]     WohnsitzLänderCode:   D
[INFO] [stderr]     Postleitzahl:         45279
[INFO] [stderr]     Orstname:             Essen
[INFO] [stderr]     GültigkeitsDatum:     1021
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE k2_creek::kvk] ASN.1 decoded:
[INFO] [stderr]     KrankenKassenName:    Techniker Krankenkasse
[INFO] [stderr]     KrankenKassenNummer:  1377508
[INFO] [stderr]     VKNR:                 00020
[INFO] [stderr]     VersichertenNummer:   123456789012
[INFO] [stderr]     VersichertenStatus:   5000
[INFO] [stderr]     StatusErgänzung:      1
[INFO] [stderr]     VorName:              Amelie Sina
[INFO] [stderr]     FamilienName:         Apfel
[INFO] [stderr]     GeburtsDatum:         11091995
[INFO] [stderr]     Straßenname:          Carl-Wolff-Str. 12
[INFO] [stderr]     WohnsitzLänderCode:   D
[INFO] [stderr]     Postleitzahl:         45279
[INFO] [stderr]     Orstname:             Essen
[INFO] [stderr]     GültigkeitsDatum:     1021
[INFO] [stdout] test api::tests::deserialize_kvk_carddata_v2 ... ok
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE k2_creek::kvk] ASN.1 decoded:
[INFO] [stdout] test api::tests::deserialize_egk_carddata_v1 ... ok
[INFO] [stderr]     KrankenKassenName:    Techniker Krankenkasse
[INFO] [stdout] test api::v1::tests::deserialize_kvk_carddata ... ok
[INFO] [stderr]     KrankenKassenNummer:  1377508
[INFO] [stdout] test api::v1::tests::deserialize_egk_carddata ... ok
[INFO] [stderr]     VKNR:                 00020
[INFO] [stdout] test api::tests::deserialize_kvk_carddata_v1 ... ok
[INFO] [stderr]     VersichertenNummer:   123456789012
[INFO] [stdout] test api::tests::deserialize_egk_carddata_v3 ... ok
[INFO] [stderr]     VersichertenStatus:   5000
[INFO] [stdout] test api::tests::deserialize_egk_carddata_v2 ... ok
[INFO] [stderr]     StatusErgänzung:      1
[INFO] [stderr]     VorName:              Amelie Sina
[INFO] [stderr]     FamilienName:         Apfel
[INFO] [stderr]     GeburtsDatum:         11091995
[INFO] [stderr]     Straßenname:          Carl-Wolff-Str. 12
[INFO] [stderr]     WohnsitzLänderCode:   D
[INFO] [stderr]     Postleitzahl:         45279
[INFO] [stderr]     Orstname:             Essen
[INFO] [stderr]     GültigkeitsDatum:     1021
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE k2_creek::kvk] Decoded: YIGLgBZUZWNobmlrZXIgS3Jhbmtlbmthc3NlgQcxMzc3NTA4jwUwMDAyMIIMMTIzNDU2Nzg5MDEygwQ1MDAwkAExhQtBbWVsaWUgU2luYYcFQXBmZWyICDExMDkxOTk1iRJDYXJsLVdvbGZmLVN0ci4gMTKKAUSLBTQ1Mjc5jAVFc3Nlbo0EMTAyMY4B/A==
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE k2_creek::kvk] Encoded: [96, 129, 139, 128, 22, 84, 101, 99, 104, 110, 105, 107, 101, 114, 32, 75, 114, 97, 110, 107, 101, 110, 107, 97, 115, 115, 101, 129, 7, 49, 51, 55, 55, 53, 48, 56, 143, 5, 48, 48, 48, 50, 48, 130, 12, 49, 50, 51, 52, 53, 54, 55, 56, 57, 48, 49, 50, 131, 4, 53, 48, 48, 48, 144, 1, 49, 133, 11, 65, 109, 101, 108, 105, 101, 32, 83, 105, 110, 97, 135, 5, 65, 112, 102, 101, 108, 136, 8, 49, 49, 48, 57, 49, 57, 57, 53, 137, 18, 67, 97, 114, 108, 45, 87, 111, 108, 102, 102, 45, 83, 116, 114, 46, 32, 49, 50, 138, 1, 68, 139, 5, 52, 53, 50, 55, 57, 140, 5, 69, 115, 115, 101, 110, 141, 4, 49, 48, 50, 49, 142, 1, 252]
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE k2_creek::kvk] ASN.1 decoded:
[INFO] [stderr]     KrankenKassenName:    Techniker Krankenkasse
[INFO] [stderr]     KrankenKassenNummer:  1377508
[INFO] [stderr]     VKNR:                 00020
[INFO] [stderr]     VersichertenNummer:   123456789012
[INFO] [stderr]     VersichertenStatus:   5000
[INFO] [stderr]     StatusErgänzung:      1
[INFO] [stderr]     VorName:              Amelie Sina
[INFO] [stderr]     FamilienName:         Apfel
[INFO] [stderr]     GeburtsDatum:         11091995
[INFO] [stderr]     Straßenname:          Carl-Wolff-Str. 12
[INFO] [stderr]     WohnsitzLänderCode:   D
[INFO] [stderr]     Postleitzahl:         45279
[INFO] [stderr]     Orstname:             Essen
[INFO] [stderr]     GültigkeitsDatum:     1021
[INFO] [stdout] test config::tests::defaults ... ok
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE k2_creek::kvk] Decoded: YIGLgBZUZWNobmlrZXIgS3Jhbmtlbmthc3NlgQcxMzc3NTA4jwUwMDAyMIIMMTIzNDU2Nzg5MDEygwQ1MDAwkAExhQtBbWVsaWUgU2luYYcFQXBmZWyICDExMDkxOTk1iRJDYXJsLVdvbGZmLVN0ci4gMTKKAUSLBTQ1Mjc5jAVFc3Nlbo0EMTAyMY4B/A==
[INFO] [stdout] test api::v2::tests::deserialize_kvk_carddata ... ok
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE k2_creek::kvk] Encoded: [96, 129, 139, 128, 22, 84, 101, 99, 104, 110, 105, 107, 101, 114, 32, 75, 114, 97, 110, 107, 101, 110, 107, 97, 115, 115, 101, 129, 7, 49, 51, 55, 55, 53, 48, 56, 143, 5, 48, 48, 48, 50, 48, 130, 12, 49, 50, 51, 52, 53, 54, 55, 56, 57, 48, 49, 50, 131, 4, 53, 48, 48, 48, 144, 1, 49, 133, 11, 65, 109, 101, 108, 105, 101, 32, 83, 105, 110, 97, 135, 5, 65, 112, 102, 101, 108, 136, 8, 49, 49, 48, 57, 49, 57, 57, 53, 137, 18, 67, 97, 114, 108, 45, 87, 111, 108, 102, 102, 45, 83, 116, 114, 46, 32, 49, 50, 138, 1, 68, 139, 5, 52, 53, 50, 55, 57, 140, 5, 69, 115, 115, 101, 110, 141, 4, 49, 48, 50, 49, 142, 1, 252]
[INFO] [stdout] test cli::tests::allowed_values_for_api ... ok
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE k2_creek::kvk] ASN.1 decoded:
[INFO] [stdout] test egk::tests::write_mfefgdo_failed ... ok
[INFO] [stderr]     KrankenKassenName:    Techniker Krankenkasse
[INFO] [stdout] test api::tests::deserialize_kvk_carddata_v3 ... ok
[INFO] [stderr]     KrankenKassenNummer:  1377508
[INFO] [stdout] test config::tests::no_valid_url_parameter ... ok
[INFO] [stderr]     VKNR:                 00020
[INFO] [stdout] test api::v2::tests::deserialize_egk_carddata ... ok
[INFO] [stderr]     VersichertenNummer:   123456789012
[INFO] [stdout] test api::v3::tests::deserialize_kvk_carddata ... ok
[INFO] [stderr]     VersichertenStatus:   5000
[INFO] [stdout] test config::tests::config_file_missing ... ok
[INFO] [stderr]     StatusErgänzung:      1
[INFO] [stdout] test config::tests::default_configuration ... ok
[INFO] [stderr]     VorName:              Amelie Sina
[INFO] [stdout] test http::tests::supported_api_version ... ok
[INFO] [stderr]     FamilienName:         Apfel
[INFO] [stderr]     GeburtsDatum:         11091995
[INFO] [stderr]     Straßenname:          Carl-Wolff-Str. 12
[INFO] [stderr]     WohnsitzLänderCode:   D
[INFO] [stderr]     Postleitzahl:         45279
[INFO] [stderr]     Orstname:             Essen
[INFO] [stderr]     GültigkeitsDatum:     1021
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE k2_creek::kvk] Decoded: YIGLgBZUZWNobmlrZXIgS3Jhbmtlbmthc3NlgQcxMzc3NTA4jwUwMDAyMIIMMTIzNDU2Nzg5MDEygwQ1MDAwkAExhQtBbWVsaWUgU2luYYcFQXBmZWyICDExMDkxOTk1iRJDYXJsLVdvbGZmLVN0ci4gMTKKAUSLBTQ1Mjc5jAVFc3Nlbo0EMTAyMY4B/A==
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE k2_creek::kvk] Encoded: [96, 129, 139, 128, 22, 84, 101, 99, 104, 110, 105, 107, 101, 114, 32, 75, 114, 97, 110, 107, 101, 110, 107, 97, 115, 115, 101, 129, 7, 49, 51, 55, 55, 53, 48, 56, 143, 5, 48, 48, 48, 50, 48, 130, 12, 49, 50, 51, 52, 53, 54, 55, 56, 57, 48, 49, 50, 131, 4, 53, 48, 48, 48, 144, 1, 49, 133, 11, 65, 109, 101, 108, 105, 101, 32, 83, 105, 110, 97, 135, 5, 65, 112, 102, 101, 108, 136, 8, 49, 49, 48, 57, 49, 57, 57, 53, 137, 18, 67, 97, 114, 108, 45, 87, 111, 108, 102, 102, 45, 83, 116, 114, 46, 32, 49, 50, 138, 1, 68, 139, 5, 52, 53, 50, 55, 57, 140, 5, 69, 115, 115, 101, 110, 141, 4, 49, 48, 50, 49, 142, 1, 252]
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE k2_creek::kvk] ASN.1 decoded:
[INFO] [stderr]     KrankenKassenName:    Techniker Krankenkasse
[INFO] [stderr]     KrankenKassenNummer:  1377508
[INFO] [stderr]     VKNR:                 00020
[INFO] [stderr]     VersichertenNummer:   123456789012
[INFO] [stderr]     VersichertenStatus:   5000
[INFO] [stderr]     StatusErgänzung:      1
[INFO] [stderr]     VorName:              Amelie Sina
[INFO] [stderr]     FamilienName:         Apfel
[INFO] [stderr]     GeburtsDatum:         11091995
[INFO] [stderr]     Straßenname:          Carl-Wolff-Str. 12
[INFO] [stderr]     WohnsitzLänderCode:   D
[INFO] [stderr]     Postleitzahl:         45279
[INFO] [stderr]     Orstname:             Essen
[INFO] [stderr]     GültigkeitsDatum:     1021
[INFO] [stderr] [2020-10-17T17:25:30Z DEBUG k2_creek::tests] Current dir: /tmp/.tmprnuKWV
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE k2_creek::tests] Files: []
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE k2_creek::tests] Leaving /tmp/.tmprnuKWV
[INFO] [stderr] [2020-10-17T17:25:30Z DEBUG k2_creek::tests] Current dir: /opt/rustwide/workdir
[INFO] [stdout] test egk::tests::write_mfefgdo_ok ... ok
[INFO] [stdout] test api::v3::tests::deserialize_egk_carddata ... ok
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE mio::poll] registering with poller
[INFO] [stdout] test config::tests::config_file_overrides ... ok
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:30Z DEBUG k2_creek::tests] Current dir: /tmp/.tmpWbcq0Q
[INFO] [stderr] [2020-10-17T17:25:30Z INFO  actix_server::builder] Starting 16 workers
[INFO] [stderr] [2020-10-17T17:25:30Z INFO  actix_server::builder] Starting 16 workers
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE k2_creek::tests] Files: [
[INFO] [stderr]         "/tmp/.tmpWbcq0Q/config.ini",
[INFO] [stderr]     ]
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE k2_creek::tests] Leaving /tmp/.tmpWbcq0Q
[INFO] [stderr] [2020-10-17T17:25:30Z DEBUG k2_creek::tests] Current dir: /opt/rustwide/workdir
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:30Z INFO  actix_server::builder] Starting 16 workers
[INFO] [stdout] test kvk::fmt::tests::fmt_replaces_chars_according_to_din_66003 ... ok
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE reqwest::blocking::wait] (ThreadId(31)) park without timeout
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE mio::poll] registering with poller
[INFO] [stdout] test kvk::fmt::tests::fmt_not_replaces_latin_alphabet_and_arabic_numbers ... ok
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:30Z DEBUG k2_creek::tests] Current dir: /tmp/.tmpX3tWi9
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE mio::poll] registering with poller
[INFO] [stdout] test kvk::fmt::tests::fmt_returns_error_string_if_invalid_bytes_given ... ok
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:30Z DEBUG k2_creek::writer::encode] No encoding found. Defaulting to iso-8859-15
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE mio::poll] registering with poller
[INFO] [stdout] test kvk::test::decode_base64_encoded_asn1_data ... ok
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE actix_server::worker] Service "actix-web-service-127.0.0.1:42183" is available
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE actix_server::worker] Service "actix-web-service-127.0.0.1:42183" is available
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE actix_server::worker] Service "actix-web-service-127.0.0.1:42183" is available
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE actix_server::worker] Service "actix-web-service-127.0.0.1:42183" is available
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE actix_server::worker] Service "actix-web-service-127.0.0.1:40169" is available
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE actix_server::worker] Service "actix-web-service-127.0.0.1:42183" is available
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE actix_server::worker] Service "actix-web-service-127.0.0.1:32905" is available
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE k2_creek::kvk] Decoded: YIGXgBpCdW5kZXNwb2xpemVpLUtyYW5rZW5rYXNzZYEHMzYwMDM0Mo8FMDAwMjCCDDEyMzQ1Njc4OTAxM4MEMTAwMJABMYUSRGFuaWVsIEd1c3RhdiBMdXR6hwZIfG5zY2iICDE3MDUxOTYxiRJDYXJsLVdvbGZmLVN0ci4gMTKKAUSLBTQ1Mjc5jAVFc3Nlbo0EMTAyMY4BiA==
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE actix_server::worker] Service "actix-web-service-127.0.0.1:40169" is available
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE k2_creek::kvk] Encoded: [96, 129, 151, 128, 26, 66, 117, 110, 100, 101, 115, 112, 111, 108, 105, 122, 101, 105, 45, 75, 114, 97, 110, 107, 101, 110, 107, 97, 115, 115, 101, 129, 7, 51, 54, 48, 48, 51, 52, 50, 143, 5, 48, 48, 48, 50, 48, 130, 12, 49, 50, 51, 52, 53, 54, 55, 56, 57, 48, 49, 51, 131, 4, 49, 48, 48, 48, 144, 1, 49, 133, 18, 68, 97, 110, 105, 101, 108, 32, 71, 117, 115, 116, 97, 118, 32, 76, 117, 116, 122, 135, 6, 72, 124, 110, 115, 99, 104, 136, 8, 49, 55, 48, 53, 49, 57, 54, 49, 137, 18, 67, 97, 114, 108, 45, 87, 111, 108, 102, 102, 45, 83, 116, 114, 46, 32, 49, 50, 138, 1, 68, 139, 5, 52, 53, 50, 55, 57, 140, 5, 69, 115, 115, 101, 110, 141, 4, 49, 48, 50, 49, 142, 1, 136]
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE actix_server::worker] Service "actix-web-service-127.0.0.1:42183" is available
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE k2_creek::kvk] ASN.1 decoded:
[INFO] [stderr]     KrankenKassenName:    Bundespolizei-Krankenkasse
[INFO] [stderr]     KrankenKassenNummer:  3600342
[INFO] [stderr]     VKNR:                 00020
[INFO] [stderr]     VersichertenNummer:   123456789013
[INFO] [stderr]     VersichertenStatus:   1000
[INFO] [stderr]     StatusErgänzung:      1
[INFO] [stderr]     VorName:              Daniel Gustav Lutz
[INFO] [stderr]     FamilienName:         Hönsch
[INFO] [stderr]     GeburtsDatum:         17051961
[INFO] [stderr]     Straßenname:          Carl-Wolff-Str. 12
[INFO] [stderr]     WohnsitzLänderCode:   D
[INFO] [stderr]     Postleitzahl:         45279
[INFO] [stderr]     Orstname:             Essen
[INFO] [stderr]     GültigkeitsDatum:     1021
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE actix_server::worker] Service "actix-web-service-127.0.0.1:42183" is available
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE actix_server::worker] Service "actix-web-service-127.0.0.1:42183" is available
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE actix_server::worker] Service "actix-web-service-127.0.0.1:32905" is available
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE actix_server::worker] Service "actix-web-service-127.0.0.1:42183" is available
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE actix_server::worker] Service "actix-web-service-127.0.0.1:42183" is available
[INFO] [stderr] [2020-10-17T17:25:30Z INFO  actix_server::builder] Starting "actix-web-service-127.0.0.1:42183" service on 127.0.0.1:42183
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE reqwest::blocking::client] (ThreadId(40)) start runtime::block_on
[INFO] [stdout] test writer::encode::tests::xml_declaration_is_added ... ok
[INFO] [stderr] [2020-10-17T17:25:30Z DEBUG k2_creek::writer::encode] No encoding found. Defaulting to iso-8859-15
[INFO] [stderr] [2020-10-17T17:25:30Z DEBUG k2_creek::writer::files] Wrote file "eGK_MFEFGDO.xml"
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE k2_creek::tests] Files: [
[INFO] [stderr]         "/tmp/.tmpX3tWi9/eGK_MFEFGDO.xml",
[INFO] [stderr]     ]
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE k2_creek::tests] Leaving /tmp/.tmpX3tWi9
[INFO] [stderr] [2020-10-17T17:25:30Z DEBUG k2_creek::tests] Current dir: /opt/rustwide/workdir
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE reqwest::blocking::wait] (ThreadId(33)) park without timeout
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE mio::poll] registering with poller
[INFO] [stdout] test writer::encode::tests::encoding_from_xml_declaration_or_default ... ok
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE mio::poll] registering with poller
[INFO] [stdout] test egk::mfefgdo::tests::valid_xml_layout ... ok
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE mio::poll] registering with poller
[INFO] [stdout] test writer::encode::tests::xml_declaration_is_not_added ... ok
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE actix_server::worker] Service "actix-web-service-127.0.0.1:32905" is available
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE actix_server::worker] Service "actix-web-service-127.0.0.1:42183" is available
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE actix_server::worker] Service "actix-web-service-127.0.0.1:42183" is available
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE actix_server::worker] Service "actix-web-service-127.0.0.1:42183" is available
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE actix_server::worker] Service "actix-web-service-127.0.0.1:42183" is available
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE actix_server::worker] Service "actix-web-service-127.0.0.1:32905" is available
[INFO] [stdout] test writer::files::tests::filenames ... ok
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE actix_server::worker] Service "actix-web-service-127.0.0.1:42183" is available
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE actix_server::worker] Service "actix-web-service-127.0.0.1:40169" is available
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE actix_server::worker] Service "actix-web-service-127.0.0.1:32905" is available
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE actix_server::worker] Service "actix-web-service-127.0.0.1:32905" is available
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE actix_server::worker] Service "actix-web-service-127.0.0.1:40169" is available
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE actix_server::worker] Service "actix-web-service-127.0.0.1:40169" is available
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE actix_server::worker] Service "actix-web-service-127.0.0.1:32905" is available
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE actix_server::worker] Service "actix-web-service-127.0.0.1:42183" is available
[INFO] [stderr] [2020-10-17T17:25:30Z DEBUG k2_creek::tests] Current dir: /tmp/.tmpwjQPu9
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE actix_server::worker] Service "actix-web-service-127.0.0.1:40169" is available
[INFO] [stderr] [2020-10-17T17:25:30Z DEBUG k2_creek::writer::encode] No encoding found. Defaulting to iso-8859-15
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE actix_server::worker] Service "actix-web-service-127.0.0.1:32905" is available
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE actix_server::worker] Service "actix-web-service-127.0.0.1:32905" is available
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE actix_server::worker] Service "actix-web-service-127.0.0.1:32905" is available
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE actix_server::worker] Service "actix-web-service-127.0.0.1:32905" is available
[INFO] [stderr] [2020-10-17T17:25:30Z INFO  actix_server::builder] Starting "actix-web-service-127.0.0.1:32905" service on 127.0.0.1:32905
[INFO] [stderr] [2020-10-17T17:25:30Z INFO  actix_server::builder] Starting "actix-web-service-127.0.0.1:40169" service on 127.0.0.1:40169
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE reqwest::blocking::client] closing runtime thread (ThreadId(40))
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE reqwest::blocking::client] (ThreadId(40)) Receiver is shutdown
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE reqwest::blocking::client] (ThreadId(40)) end runtime::block_on
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE reqwest::blocking::client] (ThreadId(40)) finished
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE reqwest::blocking::client] signaled close for runtime thread (ThreadId(40))
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE reqwest::blocking::client] closed runtime thread (ThreadId(40))
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE reqwest::blocking::wait] (ThreadId(31)) park without timeout
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE reqwest::blocking::wait] (ThreadId(30)) park without timeout
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE reqwest::blocking::wait] (ThreadId(32)) park without timeout
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE actix_server::worker] Service "actix-web-service-127.0.0.1:32905" is available
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE actix_server::worker] Service "actix-web-service-127.0.0.1:32905" is available
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE reqwest::blocking::client] (ThreadId(81)) start runtime::block_on
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE actix_server::worker] Service "actix-web-service-127.0.0.1:40169" is available
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE actix_server::worker] Service "actix-web-service-127.0.0.1:40169" is available
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE actix_server::worker] Service "actix-web-service-127.0.0.1:32905" is available
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:30Z DEBUG k2_creek::writer::files] Wrote file "eGK_PersoenlicheVersichertendaten.xml"
[INFO] [stderr] [2020-10-17T17:25:30Z DEBUG k2_creek::writer::encode] No encoding found. Defaulting to iso-8859-15
[INFO] [stderr] [2020-10-17T17:25:30Z DEBUG k2_creek::writer::files] Wrote file "eGK_allgemeineVersicherungsdaten.xml"
[INFO] [stderr] [2020-10-17T17:25:30Z DEBUG k2_creek::writer::encode] No encoding found. Defaulting to iso-8859-15
[INFO] [stderr] [2020-10-17T17:25:30Z DEBUG k2_creek::writer::files] Wrote file "eGK_MFDF_HCA_EF_StatusVD.xml"
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE actix_server::worker] Service "actix-web-service-127.0.0.1:40169" is available
[INFO] [stderr] [2020-10-17T17:25:30Z DEBUG k2_creek::writer::encode] No encoding found. Defaulting to iso-8859-15
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE actix_server::worker] Service "actix-web-service-127.0.0.1:32905" is available
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE reqwest::blocking::client] (ThreadId(104)) start runtime::block_on
[INFO] [stderr] [2020-10-17T17:25:30Z DEBUG k2_creek::writer::files] Wrote file "eGK_geschuetzteVersichertendaten.xml"
[INFO] [stderr] [2020-10-17T17:25:30Z DEBUG k2_creek::writer::encode] No encoding found. Defaulting to iso-8859-15
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE actix_server::worker] Service "actix-web-service-127.0.0.1:32905" is available
[INFO] [stderr] [2020-10-17T17:25:30Z DEBUG k2_creek::writer::files] Wrote file "eGK_Pruefungsnachweis.xml"
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE reqwest::blocking::client] (ThreadId(106)) start runtime::block_on
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE actix_server::worker] Service "actix-web-service-127.0.0.1:40169" is available
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE k2_creek::tests] Files: [
[INFO] [stderr]         "/tmp/.tmpwjQPu9/eGK_Pruefungsnachweis.xml",
[INFO] [stderr]         "/tmp/.tmpwjQPu9/eGK_MFDF_HCA_EF_StatusVD.xml",
[INFO] [stderr]         "/tmp/.tmpwjQPu9/eGK_PersoenlicheVersichertendaten.xml",
[INFO] [stderr]         "/tmp/.tmpwjQPu9/eGK_geschuetzteVersichertendaten.xml",
[INFO] [stderr]         "/tmp/.tmpwjQPu9/eGK_allgemeineVersicherungsdaten.xml",
[INFO] [stderr]     ]
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE actix_server::worker] Service "actix-web-service-127.0.0.1:40169" is available
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE k2_creek::tests] Leaving /tmp/.tmpwjQPu9
[INFO] [stderr] [2020-10-17T17:25:30Z DEBUG k2_creek::tests] Current dir: /opt/rustwide/workdir
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE reqwest::blocking::client] (ThreadId(105)) start runtime::block_on
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE actix_server::worker] Service "actix-web-service-127.0.0.1:40169" is available
[INFO] [stdout] test egk::tests::all_files_written ... ok
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE actix_server::worker] Service "actix-web-service-127.0.0.1:40169" is available
[INFO] [stdout] test egk::tests::pd_is_written ... ok
[INFO] [stderr] [2020-10-17T17:25:30Z DEBUG k2_creek::tests] Current dir: /tmp/.tmp3qisD8
[INFO] [stderr] [2020-10-17T17:25:30Z DEBUG k2_creek::writer::encode] No encoding found. Defaulting to iso-8859-15
[INFO] [stderr] [2020-10-17T17:25:30Z DEBUG k2_creek::writer::files] Wrote file "eGK_PersoenlicheVersichertendaten.xml"
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE k2_creek::tests] Files: [
[INFO] [stderr]         "/tmp/.tmp3qisD8/eGK_PersoenlicheVersichertendaten.xml",
[INFO] [stderr]     ]
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE k2_creek::tests] Leaving /tmp/.tmp3qisD8
[INFO] [stderr] [2020-10-17T17:25:30Z DEBUG k2_creek::tests] Current dir: /opt/rustwide/workdir
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE actix_server::worker] Service "actix-web-service-127.0.0.1:40169" is available
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE actix_server::worker] Service "actix-web-service-127.0.0.1:40169" is available
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE actix_server::worker] Service "actix-web-service-127.0.0.1:40169" is available
[INFO] [stderr] [2020-10-17T17:25:30Z DEBUG k2_creek::tests] Current dir: /tmp/.tmpsZjvyp
[INFO] [stderr] [2020-10-17T17:25:30Z DEBUG k2_creek::writer::encode] No encoding found. Defaulting to iso-8859-15
[INFO] [stderr] [2020-10-17T17:25:30Z DEBUG k2_creek::writer::files] Wrote file "eGK_geschuetzteVersichertendaten.xml"
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE k2_creek::tests] Files: [
[INFO] [stderr]         "/tmp/.tmpsZjvyp/eGK_geschuetzteVersichertendaten.xml",
[INFO] [stderr]     ]
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE k2_creek::tests] Leaving /tmp/.tmpsZjvyp
[INFO] [stderr] [2020-10-17T17:25:30Z DEBUG k2_creek::tests] Current dir: /opt/rustwide/workdir
[INFO] [stdout] test egk::tests::gvd_is_written ... ok
[INFO] [stderr] [2020-10-17T17:25:30Z DEBUG k2_creek::http] Requesting http://127.0.0.1:42183/k2/public/api/1/carddata ...
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE reqwest::blocking::client] closing runtime thread (ThreadId(104))
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE reqwest::blocking::client] signaled close for runtime thread (ThreadId(104))
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE reqwest::blocking::client] (ThreadId(104)) Receiver is shutdown
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE reqwest::blocking::client] (ThreadId(104)) end runtime::block_on
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE reqwest::blocking::wait] (ThreadId(33)) park without timeout
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE reqwest::blocking::client] (ThreadId(104)) finished
[INFO] [stdout] test http::tests::build_client_timeout ... ok
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE reqwest::blocking::client] closed runtime thread (ThreadId(104))
[INFO] [stderr] [2020-10-17T17:25:30Z DEBUG k2_creek::http] Requesting http://127.0.0.1:40169/k2/public/api/2/carddata ...
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE reqwest::blocking::wait] (ThreadId(30)) park without timeout
[INFO] [stderr] [2020-10-17T17:25:30Z DEBUG k2_creek::tests] Current dir: /tmp/.tmpwJ0Qw2
[INFO] [stderr] [2020-10-17T17:25:30Z DEBUG k2_creek::writer::encode] No encoding found. Defaulting to iso-8859-15
[INFO] [stderr] [2020-10-17T17:25:30Z DEBUG k2_creek::writer::files] Wrote file "eGK_allgemeineVersicherungsdaten.xml"
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE k2_creek::tests] Files: [
[INFO] [stderr]         "/tmp/.tmpwJ0Qw2/eGK_allgemeineVersicherungsdaten.xml",
[INFO] [stderr]     ]
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE k2_creek::tests] Leaving /tmp/.tmpwJ0Qw2
[INFO] [stderr] [2020-10-17T17:25:30Z DEBUG k2_creek::tests] Current dir: /opt/rustwide/workdir
[INFO] [stdout] test egk::tests::vd_is_written ... ok
[INFO] [stderr] [2020-10-17T17:25:30Z DEBUG k2_creek::tests] Current dir: /tmp/.tmpotVfbC
[INFO] [stderr] [2020-10-17T17:25:30Z DEBUG k2_creek::writer::encode] No encoding found. Defaulting to iso-8859-15
[INFO] [stderr] [2020-10-17T17:25:30Z DEBUG k2_creek::writer::files] Wrote file "eGK_MFDF_HCA_EF_StatusVD.xml"
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE k2_creek::tests] Files: [
[INFO] [stderr]         "/tmp/.tmpotVfbC/eGK_MFDF_HCA_EF_StatusVD.xml",
[INFO] [stderr]     ]
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE k2_creek::tests] Leaving /tmp/.tmpotVfbC
[INFO] [stderr] [2020-10-17T17:25:30Z DEBUG k2_creek::tests] Current dir: /opt/rustwide/workdir
[INFO] [stdout] test egk::tests::status_vd_is_written ... ok
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE hyper::client::pool] checkout waiting for idle connection: ("http", 127.0.0.1:40169) 
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE hyper::client::pool] checkout waiting for idle connection: ("http", 127.0.0.1:42183) 
[INFO] [stderr] [2020-10-17T17:25:30Z DEBUG reqwest::connect] starting new connection: http://127.0.0.1:40169/
[INFO] [stderr] [2020-10-17T17:25:30Z DEBUG reqwest::connect] starting new connection: http://127.0.0.1:42183/
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE hyper::client::connect::http] Http::connect; scheme=Some("http"), host=Some("127.0.0.1"), port=Some(Port(40169)) 
[INFO] [stderr] [2020-10-17T17:25:30Z DEBUG k2_creek::tests] Current dir: /tmp/.tmpcu40wT
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE hyper::client::connect::http] Http::connect; scheme=Some("http"), host=Some("127.0.0.1"), port=Some(Port(42183)) 
[INFO] [stderr] [2020-10-17T17:25:30Z DEBUG hyper::client::connect::http] connecting to 127.0.0.1:42183 
[INFO] [stdout] test egk::tests::pn_is_written ... ok
[INFO] [stderr] [2020-10-17T17:25:30Z DEBUG hyper::client::connect::http] connecting to 127.0.0.1:40169 
[INFO] [stdout] test writer::results::tests::optional_values_are_null ... ok
[INFO] [stderr] [2020-10-17T17:25:30Z DEBUG k2_creek::writer::encode] No encoding found. Defaulting to iso-8859-15
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:30Z DEBUG k2_creek::writer::files] Wrote file "eGK_Pruefungsnachweis.xml"
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE k2_creek::tests] Files: [
[INFO] [stderr]         "/tmp/.tmpcu40wT/eGK_Pruefungsnachweis.xml",
[INFO] [stderr]     ]
[INFO] [stderr] [2020-10-17T17:25:30Z DEBUG hyper::client::connect::http] connected to 127.0.0.1:40169 
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE k2_creek::tests] Leaving /tmp/.tmpcu40wT
[INFO] [stderr] [2020-10-17T17:25:30Z DEBUG k2_creek::tests] Current dir: /opt/rustwide/workdir
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE hyper::client::conn] client handshake HTTP/1 
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE hyper::client] handshake complete, spawning background dispatcher task 
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:30Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE want] signal: Want
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE want] signal found waiting giver, notifying
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE hyper::proto::h1::conn] flushed({role=client}): State { reading: Init, writing: Init, keep_alive: Busy } 
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE want] poll_want: taker wants!
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE hyper::client::pool] checkout dropped for ("http", 127.0.0.1:40169) 
[INFO] [stderr] [2020-10-17T17:25:31Z DEBUG hyper::client::connect::http] connected to 127.0.0.1:42183 
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE hyper::client::conn] client handshake HTTP/1 
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE hyper::proto::h1::role] encode_headers
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE hyper::proto::h1::role] -> encode_headers
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE hyper::proto::h1::role] Client::encode method=GET, body=None 
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE hyper::proto::h1::role] <- encode_headers
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE hyper::proto::h1::role] -- encode_headers
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE hyper::proto::h1::io] detected no usage of vectored write, flattening 
[INFO] [stderr] [2020-10-17T17:25:31Z DEBUG hyper::proto::h1::io] flushed 78 bytes 
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE hyper::proto::h1::conn] flushed({role=client}): State { reading: Init, writing: KeepAlive, keep_alive: Busy } 
[INFO] [stderr] [2020-10-17T17:25:31Z DEBUG k2_creek::tests] Current dir: /tmp/.tmpbmqQsb
[INFO] [stderr] [2020-10-17T17:25:31Z DEBUG k2_creek::writer::encode] No encoding found. Defaulting to iso-8859-15
[INFO] [stderr] [2020-10-17T17:25:31Z DEBUG k2_creek::writer::files] Wrote file "Result.xml"
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE k2_creek::tests] Files: [
[INFO] [stderr]         "/tmp/.tmpbmqQsb/Result.xml",
[INFO] [stderr]     ]
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE k2_creek::tests] Leaving /tmp/.tmpbmqQsb
[INFO] [stderr] [2020-10-17T17:25:31Z DEBUG k2_creek::tests] Current dir: /opt/rustwide/workdir
[INFO] [stderr] [2020-10-17T17:25:31Z DEBUG k2_creek::tests] Current dir: /tmp/.tmptFX9cr
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE hyper::proto::h1::conn] Conn::read_head 
[INFO] [stderr] [2020-10-17T17:25:31Z DEBUG hyper::proto::h1::io] read 77 bytes 
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE hyper::proto::h1::role] parse_headers
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE hyper::proto::h1::role] -> parse_headers
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE hyper::proto::h1::role] Response.parse([Header; 100], [u8; 77]) 
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE k2_creek::kvk] Decoded: YIGLgBZUZWNobmlrZXIgS3Jhbmtlbmthc3NlgQcxMzc3NTA4jwUwMDAyMIIMMTIzNDU2Nzg5MDEygwQ1MDAwkAExhQtBbWVsaWUgU2luYYcFQXBmZWyICDExMDkxOTk1iRJDYXJsLVdvbGZmLVN0ci4gMTKKAUSLBTQ1Mjc5jAVFc3Nlbo0EMTAyMY4B/A==
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE k2_creek::kvk] Encoded: [96, 129, 139, 128, 22, 84, 101, 99, 104, 110, 105, 107, 101, 114, 32, 75, 114, 97, 110, 107, 101, 110, 107, 97, 115, 115, 101, 129, 7, 49, 51, 55, 55, 53, 48, 56, 143, 5, 48, 48, 48, 50, 48, 130, 12, 49, 50, 51, 52, 53, 54, 55, 56, 57, 48, 49, 50, 131, 4, 53, 48, 48, 48, 144, 1, 49, 133, 11, 65, 109, 101, 108, 105, 101, 32, 83, 105, 110, 97, 135, 5, 65, 112, 102, 101, 108, 136, 8, 49, 49, 48, 57, 49, 57, 57, 53, 137, 18, 67, 97, 114, 108, 45, 87, 111, 108, 102, 102, 45, 83, 116, 114, 46, 32, 49, 50, 138, 1, 68, 139, 5, 52, 53, 50, 55, 57, 140, 5, 69, 115, 115, 101, 110, 141, 4, 49, 48, 50, 49, 142, 1, 252]
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE k2_creek::kvk] ASN.1 decoded:
[INFO] [stderr]     KrankenKassenName:    Techniker Krankenkasse
[INFO] [stderr]     KrankenKassenNummer:  1377508
[INFO] [stderr]     VKNR:                 00020
[INFO] [stderr]     VersichertenNummer:   123456789012
[INFO] [stderr]     VersichertenStatus:   5000
[INFO] [stderr]     StatusErgänzung:      1
[INFO] [stderr]     VorName:              Amelie Sina
[INFO] [stderr]     FamilienName:         Apfel
[INFO] [stderr]     GeburtsDatum:         11091995
[INFO] [stderr]     Straßenname:          Carl-Wolff-Str. 12
[INFO] [stderr]     WohnsitzLänderCode:   D
[INFO] [stderr]     Postleitzahl:         45279
[INFO] [stderr]     Orstname:             Essen
[INFO] [stderr]     GültigkeitsDatum:     1021
[INFO] [stderr] [2020-10-17T17:25:31Z DEBUG k2_creek::writer::files] Deleted "eGK_allgemeineVersicherungsdaten.xml"
[INFO] [stderr] [2020-10-17T17:25:31Z DEBUG k2_creek::writer::files] Deleted "eGK_geschuetzteVersichertendaten.xml"
[INFO] [stderr] [2020-10-17T17:25:31Z DEBUG k2_creek::writer::files] Deleted "eGK_PersoenlicheVersichertendaten.xml"
[INFO] [stderr] [2020-10-17T17:25:31Z DEBUG k2_creek::writer::files] Deleted "eGK_MFDF_HCA_EF_StatusVD.xml"
[INFO] [stderr] [2020-10-17T17:25:31Z DEBUG k2_creek::writer::files] Deleted "eGK_Pruefungsnachweis.xml"
[INFO] [stderr] [2020-10-17T17:25:31Z DEBUG k2_creek::writer::files] Deleted "eGK_MFEFGDO.xml"
[INFO] [stderr] [2020-10-17T17:25:31Z DEBUG k2_creek::writer::files] Deleted "Result.xml"
[INFO] [stderr] [2020-10-17T17:25:31Z DEBUG k2_creek::writer::encode] No encoding found. Defaulting to iso-8859-15
[INFO] [stderr] [2020-10-17T17:25:31Z DEBUG k2_creek::writer::files] Wrote file "Result.xml"
[INFO] [stderr] [2020-10-17T17:25:31Z DEBUG k2_creek::writer::files] Wrote file "KVK.dat"
[INFO] [stderr] [2020-10-17T17:25:31Z DEBUG k2_creek::writer::files] Wrote file "KVK_Daten.bin"
[INFO] [stdout] test writer::tests::not_prompting_before_cleanup ... ok
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE k2_creek::tests] Files: [
[INFO] [stderr]         "/tmp/.tmptFX9cr/Result.xml",
[INFO] [stderr]         "/tmp/.tmptFX9cr/KVK_Daten.bin",
[INFO] [stderr]         "/tmp/.tmptFX9cr/KVK.dat",
[INFO] [stderr]     ]
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE k2_creek::tests] Files: [
[INFO] [stderr]         "/tmp/.tmptFX9cr/Result.xml",
[INFO] [stderr]         "/tmp/.tmptFX9cr/KVK_Daten.bin",
[INFO] [stderr]         "/tmp/.tmptFX9cr/KVK.dat",
[INFO] [stderr]     ]
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE k2_creek::tests] Leaving /tmp/.tmptFX9cr
[INFO] [stderr] [2020-10-17T17:25:31Z DEBUG k2_creek::tests] Current dir: /opt/rustwide/workdir
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE hyper::proto::h1::role] Response.parse Complete(75) 
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE hyper::proto::h1::role] <- parse_headers
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE hyper::proto::h1::role] -- parse_headers
[INFO] [stderr] [2020-10-17T17:25:31Z DEBUG hyper::proto::h1::io] parsed 2 headers 
[INFO] [stderr] [2020-10-17T17:25:31Z DEBUG k2_creek::http] Requesting http://127.0.0.1:32905/k2/public/api/1/carddata ...
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE reqwest::blocking::wait] wait at most 2s
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE reqwest::blocking::wait] (ThreadId(32)) park timeout 1.9999922s
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE hyper::client] handshake complete, spawning background dispatcher task 
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE want] signal: Want
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE want] signal found waiting giver, notifying
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE hyper::proto::h1::conn] flushed({role=client}): State { reading: Init, writing: Init, keep_alive: Busy } 
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE want] poll_want: taker wants!
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE hyper::client::pool] checkout dropped for ("http", 127.0.0.1:42183) 
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE hyper::proto::h1::role] encode_headers
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE hyper::proto::h1::role] -> encode_headers
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE hyper::proto::h1::role] Client::encode method=GET, body=None 
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE hyper::proto::h1::role] <- encode_headers
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE hyper::proto::h1::role] -- encode_headers
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE hyper::proto::h1::io] detected no usage of vectored write, flattening 
[INFO] [stderr] [2020-10-17T17:25:31Z DEBUG hyper::proto::h1::io] flushed 78 bytes 
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE hyper::proto::h1::conn] flushed({role=client}): State { reading: Init, writing: KeepAlive, keep_alive: Busy } 
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE hyper::client::pool] checkout waiting for idle connection: ("http", 127.0.0.1:32905) 
[INFO] [stderr] [2020-10-17T17:25:31Z DEBUG reqwest::connect] starting new connection: http://127.0.0.1:32905/
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE hyper::client::connect::http] Http::connect; scheme=Some("http"), host=Some("127.0.0.1"), port=Some(Port(32905)) 
[INFO] [stderr] [2020-10-17T17:25:31Z DEBUG hyper::client::connect::http] connecting to 127.0.0.1:32905 
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:31Z DEBUG hyper::client::connect::http] connected to 127.0.0.1:32905 
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE hyper::client::conn] client handshake HTTP/1 
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE hyper::client] handshake complete, spawning background dispatcher task 
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE want] signal: Want
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE want] signal found waiting giver, notifying
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE hyper::proto::h1::conn] flushed({role=client}): State { reading: Init, writing: Init, keep_alive: Busy } 
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE want] poll_want: taker wants!
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE hyper::client::pool] checkout dropped for ("http", 127.0.0.1:32905) 
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE hyper::proto::h1::role] encode_headers
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE hyper::proto::h1::role] -> encode_headers
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE hyper::proto::h1::role] Client::encode method=GET, body=None 
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE hyper::proto::h1::role] <- encode_headers
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE hyper::proto::h1::role] -- encode_headers
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE hyper::proto::h1::io] detected no usage of vectored write, flattening 
[INFO] [stderr] [2020-10-17T17:25:31Z DEBUG hyper::proto::h1::io] flushed 78 bytes 
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE hyper::proto::h1::conn] flushed({role=client}): State { reading: Init, writing: KeepAlive, keep_alive: Busy } 
[INFO] [stderr] [2020-10-17T17:25:31Z DEBUG hyper::proto::h1::conn] incoming body is content-length (2 bytes) 
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE hyper::proto::h1::decode] decode; state=Length(2) 
[INFO] [stderr] [2020-10-17T17:25:31Z DEBUG hyper::proto::h1::conn] incoming body completed 
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE hyper::proto::h1::conn] maybe_notify; read_from_io blocked 
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE want] signal: Want
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE hyper::proto::h1::conn] flushed({role=client}): State { reading: Init, writing: Init, keep_alive: Idle } 
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE want] signal: Want
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE hyper::proto::h1::conn] flushed({role=client}): State { reading: Init, writing: Init, keep_alive: Idle } 
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE hyper::client::pool] put; add idle connection for ("http", 127.0.0.1:40169) 
[INFO] [stderr] [2020-10-17T17:25:31Z DEBUG hyper::client::pool] pooling idle connection for ("http", 127.0.0.1:40169) 
[INFO] [stderr] [2020-10-17T17:25:31Z DEBUG reqwest::async_impl::client] response '200 OK' for http://127.0.0.1:40169/k2/public/api/2/carddata
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE k2_creek::http] Response { url: "http://127.0.0.1:40169/k2/public/api/2/carddata", status: 200, headers: {"content-length": "2", "date": "Sat, 17 Oct 2020 17:25:30 GMT"} }
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE reqwest::blocking::client] closing runtime thread (ThreadId(106))
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE reqwest::blocking::client] signaled close for runtime thread (ThreadId(106))
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE reqwest::blocking::client] (ThreadId(106)) Receiver is shutdown
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE reqwest::blocking::client] (ThreadId(106)) end runtime::block_on
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE mio::poll] deregistering handle with poller
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE want] signal: Closed
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE mio::poll] deregistering handle with poller
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE reqwest::blocking::client] (ThreadId(106)) finished
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE reqwest::blocking::client] closed runtime thread (ThreadId(106))
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE reqwest::blocking::wait] (ThreadId(30)) park without timeout
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE reqwest::blocking::client] (ThreadId(108)) start runtime::block_on
[INFO] [stderr] [2020-10-17T17:25:31Z DEBUG k2_creek::http] Requesting http://127.0.0.1:40169/k2/public/api/1/carddata ...
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE reqwest::blocking::wait] (ThreadId(30)) park without timeout
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE hyper::client::pool] checkout waiting for idle connection: ("http", 127.0.0.1:40169) 
[INFO] [stderr] [2020-10-17T17:25:31Z DEBUG reqwest::connect] starting new connection: http://127.0.0.1:40169/
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE hyper::client::connect::http] Http::connect; scheme=Some("http"), host=Some("127.0.0.1"), port=Some(Port(40169)) 
[INFO] [stderr] [2020-10-17T17:25:31Z DEBUG hyper::client::connect::http] connecting to 127.0.0.1:40169 
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:31Z DEBUG hyper::client::connect::http] connected to 127.0.0.1:40169 
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE hyper::client::conn] client handshake HTTP/1 
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE hyper::client] handshake complete, spawning background dispatcher task 
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE want] signal: Want
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE want] signal found waiting giver, notifying
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE hyper::proto::h1::conn] flushed({role=client}): State { reading: Init, writing: Init, keep_alive: Busy } 
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE want] poll_want: taker wants!
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE hyper::client::pool] checkout dropped for ("http", 127.0.0.1:40169) 
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE hyper::proto::h1::role] encode_headers
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE hyper::proto::h1::role] -> encode_headers
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE hyper::proto::h1::role] Client::encode method=GET, body=None 
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE hyper::proto::h1::role] <- encode_headers
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE hyper::proto::h1::role] -- encode_headers
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE hyper::proto::h1::io] detected no usage of vectored write, flattening 
[INFO] [stderr] [2020-10-17T17:25:31Z DEBUG hyper::proto::h1::io] flushed 78 bytes 
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE hyper::proto::h1::conn] flushed({role=client}): State { reading: Init, writing: KeepAlive, keep_alive: Busy } 
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE hyper::proto::h1::conn] Conn::read_head 
[INFO] [stderr] [2020-10-17T17:25:31Z DEBUG hyper::proto::h1::io] read 77 bytes 
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE hyper::proto::h1::role] parse_headers
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE hyper::proto::h1::role] -> parse_headers
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE hyper::proto::h1::role] Response.parse([Header; 100], [u8; 77]) 
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE hyper::proto::h1::role] Response.parse Complete(75) 
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE hyper::proto::h1::role] <- parse_headers
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE hyper::proto::h1::role] -- parse_headers
[INFO] [stderr] [2020-10-17T17:25:31Z DEBUG hyper::proto::h1::io] parsed 2 headers 
[INFO] [stderr] [2020-10-17T17:25:31Z DEBUG hyper::proto::h1::conn] incoming body is content-length (2 bytes) 
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE hyper::proto::h1::decode] decode; state=Length(2) 
[INFO] [stderr] [2020-10-17T17:25:31Z DEBUG hyper::proto::h1::conn] incoming body completed 
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE hyper::proto::h1::conn] maybe_notify; read_from_io blocked 
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE want] signal: Want
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE hyper::proto::h1::conn] flushed({role=client}): State { reading: Init, writing: Init, keep_alive: Idle } 
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE want] signal: Want
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE hyper::proto::h1::conn] flushed({role=client}): State { reading: Init, writing: Init, keep_alive: Idle } 
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE hyper::client::pool] put; add idle connection for ("http", 127.0.0.1:40169) 
[INFO] [stderr] [2020-10-17T17:25:31Z DEBUG hyper::client::pool] pooling idle connection for ("http", 127.0.0.1:40169) 
[INFO] [stderr] [2020-10-17T17:25:31Z DEBUG reqwest::async_impl::client] response '200 OK' for http://127.0.0.1:40169/k2/public/api/1/carddata
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE k2_creek::http] Response { url: "http://127.0.0.1:40169/k2/public/api/1/carddata", status: 200, headers: {"content-length": "2", "date": "Sat, 17 Oct 2020 17:25:31 GMT"} }
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE reqwest::blocking::client] closing runtime thread (ThreadId(108))
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE reqwest::blocking::client] signaled close for runtime thread (ThreadId(108))
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE reqwest::blocking::client] (ThreadId(108)) Receiver is shutdown
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE reqwest::blocking::client] (ThreadId(108)) end runtime::block_on
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE mio::poll] deregistering handle with poller
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE mio::poll] deregistering handle with poller
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE want] signal: Closed
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE reqwest::blocking::client] (ThreadId(108)) finished
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE reqwest::blocking::client] closed runtime thread (ThreadId(108))
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE reqwest::blocking::wait] (ThreadId(30)) park without timeout
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE reqwest::blocking::client] (ThreadId(109)) start runtime::block_on
[INFO] [stderr] [2020-10-17T17:25:31Z DEBUG k2_creek::http] Requesting http://127.0.0.1:40169/k2/public/api/3/cards ...
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE reqwest::blocking::wait] (ThreadId(30)) park without timeout
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE hyper::client::pool] checkout waiting for idle connection: ("http", 127.0.0.1:40169) 
[INFO] [stderr] [2020-10-17T17:25:31Z DEBUG reqwest::connect] starting new connection: http://127.0.0.1:40169/
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE hyper::client::connect::http] Http::connect; scheme=Some("http"), host=Some("127.0.0.1"), port=Some(Port(40169)) 
[INFO] [stderr] [2020-10-17T17:25:31Z DEBUG hyper::client::connect::http] connecting to 127.0.0.1:40169 
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:31Z DEBUG hyper::client::connect::http] connected to 127.0.0.1:40169 
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE hyper::client::conn] client handshake HTTP/1 
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE hyper::client] handshake complete, spawning background dispatcher task 
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE want] signal: Want
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE want] signal found waiting giver, notifying
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE hyper::proto::h1::conn] flushed({role=client}): State { reading: Init, writing: Init, keep_alive: Busy } 
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE want] poll_want: taker wants!
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE hyper::client::pool] checkout dropped for ("http", 127.0.0.1:40169) 
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE hyper::proto::h1::role] encode_headers
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE hyper::proto::h1::role] -> encode_headers
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE hyper::proto::h1::role] Client::encode method=GET, body=None 
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE hyper::proto::h1::role] <- encode_headers
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE hyper::proto::h1::role] -- encode_headers
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE hyper::proto::h1::io] detected no usage of vectored write, flattening 
[INFO] [stderr] [2020-10-17T17:25:31Z DEBUG hyper::proto::h1::io] flushed 75 bytes 
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE hyper::proto::h1::conn] flushed({role=client}): State { reading: Init, writing: KeepAlive, keep_alive: Busy } 
[INFO] [stdout] test http::tests::build_client_api_endpoint ... ok
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE hyper::proto::h1::conn] Conn::read_head 
[INFO] [stderr] [2020-10-17T17:25:31Z DEBUG hyper::proto::h1::io] read 77 bytes 
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE hyper::proto::h1::role] parse_headers
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE hyper::proto::h1::role] -> parse_headers
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE hyper::proto::h1::role] Response.parse([Header; 100], [u8; 77]) 
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE hyper::proto::h1::role] Response.parse Complete(75) 
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE hyper::proto::h1::role] <- parse_headers
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE hyper::proto::h1::role] -- parse_headers
[INFO] [stderr] [2020-10-17T17:25:31Z DEBUG hyper::proto::h1::io] parsed 2 headers 
[INFO] [stderr] [2020-10-17T17:25:31Z DEBUG hyper::proto::h1::conn] incoming body is content-length (2 bytes) 
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE hyper::proto::h1::decode] decode; state=Length(2) 
[INFO] [stderr] [2020-10-17T17:25:31Z DEBUG hyper::proto::h1::conn] incoming body completed 
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE hyper::proto::h1::conn] maybe_notify; read_from_io blocked 
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE want] signal: Want
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE hyper::proto::h1::conn] flushed({role=client}): State { reading: Init, writing: Init, keep_alive: Idle } 
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE want] signal: Want
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE hyper::proto::h1::conn] flushed({role=client}): State { reading: Init, writing: Init, keep_alive: Idle } 
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE hyper::client::pool] put; add idle connection for ("http", 127.0.0.1:40169) 
[INFO] [stderr] [2020-10-17T17:25:31Z DEBUG hyper::client::pool] pooling idle connection for ("http", 127.0.0.1:40169) 
[INFO] [stderr] [2020-10-17T17:25:31Z DEBUG reqwest::async_impl::client] response '200 OK' for http://127.0.0.1:40169/k2/public/api/3/cards
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE k2_creek::http] Response { url: "http://127.0.0.1:40169/k2/public/api/3/cards", status: 200, headers: {"content-length": "2", "date": "Sat, 17 Oct 2020 17:25:31 GMT"} }
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE reqwest::blocking::client] closing runtime thread (ThreadId(109))
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE reqwest::blocking::client] signaled close for runtime thread (ThreadId(109))
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE want] signal: Want
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE hyper::proto::h1::conn] flushed({role=client}): State { reading: Init, writing: Init, keep_alive: Idle } 
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE reqwest::blocking::client] (ThreadId(109)) Receiver is shutdown
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE reqwest::blocking::client] (ThreadId(109)) end runtime::block_on
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE mio::poll] deregistering handle with poller
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE want] signal: Closed
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE reqwest::blocking::client] (ThreadId(109)) finished
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE reqwest::blocking::client] closed runtime thread (ThreadId(109))
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE mio::poll] deregistering handle with poller
[INFO] [stderr] [2020-10-17T17:25:31Z TRACE mio::poll] deregistering handle with poller
[INFO] [stdout] test http::tests::build_client_wrong_api ... ok
[INFO] [stdout] test http::tests::unsupported_api_version_four ... ok
[INFO] [stdout] test http::tests::unsupported_api_version_zero ... ok
[INFO] [stderr] [2020-10-17T17:25:33Z TRACE reqwest::blocking::wait] wait timeout exceeded
[INFO] [stderr] [2020-10-17T17:25:33Z TRACE reqwest::blocking::client] closing runtime thread (ThreadId(105))
[INFO] [stderr] [2020-10-17T17:25:33Z TRACE reqwest::blocking::client] signaled close for runtime thread (ThreadId(105))
[INFO] [stderr] [2020-10-17T17:25:33Z TRACE reqwest::blocking::client] (ThreadId(105)) Receiver is shutdown
[INFO] [stderr] [2020-10-17T17:25:33Z TRACE reqwest::blocking::client] (ThreadId(105)) end runtime::block_on
[INFO] [stderr] [2020-10-17T17:25:33Z TRACE mio::poll] deregistering handle with poller
[INFO] [stderr] [2020-10-17T17:25:33Z TRACE want] signal: Closed
[INFO] [stderr] [2020-10-17T17:25:33Z TRACE reqwest::blocking::client] (ThreadId(105)) finished
[INFO] [stderr] [2020-10-17T17:25:33Z TRACE reqwest::blocking::client] closed runtime thread (ThreadId(105))
[INFO] [stdout] test http::tests::build_client_with_timeout ... ok
[INFO] [stderr] [2020-10-17T17:25:33Z TRACE mio::poll] deregistering handle with poller
[INFO] [stderr] [2020-10-17T17:25:33Z TRACE hyper::proto::h1::conn] Conn::read_head 
[INFO] [stderr] [2020-10-17T17:25:33Z DEBUG hyper::proto::h1::io] read 92 bytes 
[INFO] [stderr] [2020-10-17T17:25:33Z TRACE hyper::proto::h1::role] parse_headers
[INFO] [stderr] [2020-10-17T17:25:33Z TRACE hyper::proto::h1::role] -> parse_headers
[INFO] [stderr] [2020-10-17T17:25:33Z TRACE hyper::proto::h1::role] Response.parse([Header; 100], [u8; 92]) 
[INFO] [stderr] [2020-10-17T17:25:33Z TRACE hyper::proto::h1::role] Response.parse Complete(76) 
[INFO] [stderr] [2020-10-17T17:25:33Z TRACE hyper::proto::h1::role] <- parse_headers
[INFO] [stderr] [2020-10-17T17:25:33Z TRACE hyper::proto::h1::role] -- parse_headers
[INFO] [stderr] [2020-10-17T17:25:33Z DEBUG hyper::proto::h1::io] parsed 2 headers 
[INFO] [stderr] [2020-10-17T17:25:33Z DEBUG hyper::proto::h1::conn] incoming body is content-length (16 bytes) 
[INFO] [stderr] [2020-10-17T17:25:33Z TRACE hyper::proto::h1::decode] decode; state=Length(16) 
[INFO] [stderr] [2020-10-17T17:25:33Z DEBUG hyper::proto::h1::conn] incoming body completed 
[INFO] [stderr] [2020-10-17T17:25:33Z TRACE hyper::proto::h1::conn] maybe_notify; read_from_io blocked 
[INFO] [stderr] [2020-10-17T17:25:33Z TRACE want] signal: Want
[INFO] [stderr] [2020-10-17T17:25:33Z TRACE hyper::proto::h1::conn] flushed({role=client}): State { reading: Init, writing: Init, keep_alive: Idle } 
[INFO] [stderr] [2020-10-17T17:25:33Z TRACE want] signal: Want
[INFO] [stderr] [2020-10-17T17:25:33Z TRACE hyper::proto::h1::conn] flushed({role=client}): State { reading: Init, writing: Init, keep_alive: Idle } 
[INFO] [stderr] [2020-10-17T17:25:33Z TRACE hyper::client::pool] put; add idle connection for ("http", 127.0.0.1:42183) 
[INFO] [stderr] [2020-10-17T17:25:33Z DEBUG hyper::client::pool] pooling idle connection for ("http", 127.0.0.1:42183) 
[INFO] [stderr] [2020-10-17T17:25:33Z DEBUG reqwest::async_impl::client] response '200 OK' for http://127.0.0.1:42183/k2/public/api/1/carddata
[INFO] [stderr] [2020-10-17T17:25:33Z TRACE k2_creek::http] Response { url: "http://127.0.0.1:42183/k2/public/api/1/carddata", status: 200, headers: {"content-length": "16", "date": "Sat, 17 Oct 2020 17:25:33 GMT"} }
[INFO] [stderr] [2020-10-17T17:25:33Z TRACE reqwest::blocking::client] closing runtime thread (ThreadId(81))
[INFO] [stderr] [2020-10-17T17:25:33Z TRACE reqwest::blocking::client] signaled close for runtime thread (ThreadId(81))
[INFO] [stderr] [2020-10-17T17:25:33Z TRACE want] signal: Want
[INFO] [stderr] [2020-10-17T17:25:33Z TRACE hyper::proto::h1::conn] flushed({role=client}): State { reading: Init, writing: Init, keep_alive: Idle } 
[INFO] [stderr] [2020-10-17T17:25:33Z TRACE reqwest::blocking::client] (ThreadId(81)) Receiver is shutdown
[INFO] [stderr] [2020-10-17T17:25:33Z TRACE reqwest::blocking::client] (ThreadId(81)) end runtime::block_on
[INFO] [stderr] [2020-10-17T17:25:33Z TRACE mio::poll] deregistering handle with poller
[INFO] [stderr] [2020-10-17T17:25:33Z TRACE mio::poll] deregistering handle with poller
[INFO] [stderr] [2020-10-17T17:25:33Z TRACE want] signal: Closed
[INFO] [stderr] [2020-10-17T17:25:33Z TRACE reqwest::blocking::client] (ThreadId(81)) finished
[INFO] [stderr] [2020-10-17T17:25:33Z TRACE reqwest::blocking::client] closed runtime thread (ThreadId(81))
[INFO] [stdout] test http::tests::build_client_without_timeout ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 46 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
[INFO] [stdout] 
[INFO] [stderr]      Running /opt/rustwide/target/debug/deps/k2_creek-acc2682a0aef0d34
[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
[INFO] [stdout] 
[INFO] [stderr]      Running /opt/rustwide/target/debug/deps/cli-bd04ca74d7ff1cfd
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test help_is_displayed ... ok
[INFO] [stderr]    Doc-tests k2-creek
[INFO] [stdout] test usage_is_displayed ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
[INFO] [stdout] 
[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
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "063e93772f15388aa967654100e5da93178869001751b6c02ea8c73661a50276", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "063e93772f15388aa967654100e5da93178869001751b6c02ea8c73661a50276", kill_on_drop: false }`
[INFO] [stdout] 063e93772f15388aa967654100e5da93178869001751b6c02ea8c73661a50276
