[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 1.47.0 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-8/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-8/source'...
[INFO] [stderr] done.
[INFO] validating manifest of git repo https://github.com/eHealthExperts/k2-creek on toolchain 1.47.0
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+1.47.0" "read-manifest" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] removed /workspace/builds/worker-8/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-8/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" "+1.47.0" "fetch" "--locked" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-8/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-8/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" "+1.47.0" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 3bfc9e40e4d9a989ed587b6e9efcdce87da50d9c4be7b9a8f7e26cd0de434c69
[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" "3bfc9e40e4d9a989ed587b6e9efcdce87da50d9c4be7b9a8f7e26cd0de434c69", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "3bfc9e40e4d9a989ed587b6e9efcdce87da50d9c4be7b9a8f7e26cd0de434c69", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "3bfc9e40e4d9a989ed587b6e9efcdce87da50d9c4be7b9a8f7e26cd0de434c69", kill_on_drop: false }`
[INFO] [stdout] 3bfc9e40e4d9a989ed587b6e9efcdce87da50d9c4be7b9a8f7e26cd0de434c69
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-8/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-8/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" "+1.47.0" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr] WARNING: Your kernel does not support swap limit capabilities or the cgroup is not mounted. Memory limited without swap.
[INFO] [stdout] 1cf524798844527b68ca5480f4d3517b69e03bcb23d58cdfe3ec5b1572be73b5
[INFO] running `Command { std: "docker" "start" "-a" "1cf524798844527b68ca5480f4d3517b69e03bcb23d58cdfe3ec5b1572be73b5", kill_on_drop: false }`
[INFO] [stderr]    Compiling libc v0.2.78
[INFO] [stderr]    Compiling syn v1.0.42
[INFO] [stderr]    Compiling futures-core v0.3.5
[INFO] [stderr]    Compiling pkg-config v0.3.18
[INFO] [stderr]    Compiling pin-project-internal v0.4.25
[INFO] [stderr]    Compiling futures-sink v0.3.5
[INFO] [stderr]    Compiling untrusted v0.7.1
[INFO] [stderr]    Compiling spin v0.5.2
[INFO] [stderr]    Compiling lexical-core v0.7.4
[INFO] [stderr]    Compiling openssl v0.10.30
[INFO] [stderr]    Compiling arrayvec v0.5.1
[INFO] [stderr]    Compiling futures-io v0.3.5
[INFO] [stderr]    Compiling native-tls v0.2.4
[INFO] [stderr]    Compiling try-lock v0.2.3
[INFO] [stderr]    Compiling strsim v0.9.3
[INFO] [stderr]    Compiling ident_case v1.0.1
[INFO] [stderr]    Compiling nix v0.18.0
[INFO] [stderr]    Compiling httpdate v0.3.2
[INFO] [stderr]    Compiling tower-service v0.3.0
[INFO] [stderr]    Compiling serde_json v1.0.58
[INFO] [stderr]    Compiling utf8parse v0.2.0
[INFO] [stderr]    Compiling rust-ini v0.13.0
[INFO] [stderr]    Compiling xml-rs v0.8.3
[INFO] [stderr]    Compiling k2-creek v1.0.0 (/opt/rustwide/workdir)
[INFO] [stderr]    Compiling ipnet v2.3.0
[INFO] [stderr]    Compiling base64 v0.13.0
[INFO] [stderr]    Compiling num-traits v0.2.12
[INFO] [stderr]    Compiling nom v5.1.2
[INFO] [stderr]    Compiling unicase v2.6.0
[INFO] [stderr]    Compiling num-integer v0.1.43
[INFO] [stderr]    Compiling num-bigint v0.3.0
[INFO] [stderr]    Compiling ring v0.16.15
[INFO] [stderr]    Compiling futures-task v0.3.5
[INFO] [stderr]    Compiling futures-channel v0.3.5
[INFO] [stderr]    Compiling http-body v0.3.1
[INFO] [stderr]    Compiling indexmap v1.6.0
[INFO] [stderr]    Compiling want v0.3.0
[INFO] [stderr]    Compiling mime_guess v2.0.3
[INFO] [stderr]    Compiling openssl-sys v0.9.58
[INFO] [stderr]    Compiling net2 v0.2.35
[INFO] [stderr]    Compiling iovec v0.1.4
[INFO] [stderr]    Compiling num_cpus v1.13.0
[INFO] [stderr]    Compiling signal-hook-registry v1.2.1
[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 clap v2.33.3
[INFO] [stderr]    Compiling env_logger v0.7.1
[INFO] [stderr]    Compiling dirs-next v1.0.1
[INFO] [stderr]    Compiling mio v0.6.22
[INFO] [stderr]    Compiling webpki v0.21.3
[INFO] [stderr]    Compiling sct v0.6.0
[INFO] [stderr]    Compiling mio-uds v0.6.8
[INFO] [stderr]    Compiling tokio v0.2.22
[INFO] [stderr]    Compiling rustls v0.18.1
[INFO] [stderr]    Compiling webpki-roots v0.19.0
[INFO] [stderr]    Compiling darling_core v0.10.2
[INFO] [stderr]    Compiling rusticata-macros v2.1.0
[INFO] [stderr]    Compiling der-oid-macro v0.2.0
[INFO] [stderr]    Compiling rustyline v6.3.0
[INFO] [stderr]    Compiling der-parser v4.1.0
[INFO] [stderr]    Compiling promptly v0.3.0
[INFO] [stderr]    Compiling tokio-util v0.3.1
[INFO] [stderr]    Compiling tokio-tls v0.3.1
[INFO] [stderr]    Compiling tokio-rustls v0.14.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 darling_macro v0.10.2
[INFO] [stderr]    Compiling enum-iterator v0.6.0
[INFO] [stderr]    Compiling darling v0.10.2
[INFO] [stderr]    Compiling serde_with_macros v1.2.0
[INFO] [stderr]    Compiling thiserror v1.0.20
[INFO] [stderr]    Compiling pin-project v0.4.25
[INFO] [stderr]    Compiling futures-util v0.3.5
[INFO] [stderr]    Compiling serde v1.0.116
[INFO] [stderr]    Compiling h2 v0.2.6
[INFO] [stderr]    Compiling url v2.1.1
[INFO] [stderr]    Compiling serde-xml-rs v0.4.0
[INFO] [stderr]    Compiling serde_with v1.5.0
[INFO] [stderr]    Compiling config v0.10.1
[INFO] [stderr]    Compiling serde_urlencoded v0.6.1
[INFO] [stderr]    Compiling hyper v0.13.8
[INFO] [stderr]    Compiling hyper-tls v0.4.3
[INFO] [stderr]    Compiling hyper-rustls v0.21.0
[INFO] [stderr]    Compiling reqwest v0.10.8
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 2m 09s
[INFO] running `Command { std: "docker" "inspect" "1cf524798844527b68ca5480f4d3517b69e03bcb23d58cdfe3ec5b1572be73b5", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "1cf524798844527b68ca5480f4d3517b69e03bcb23d58cdfe3ec5b1572be73b5", kill_on_drop: false }`
[INFO] [stdout] 1cf524798844527b68ca5480f4d3517b69e03bcb23d58cdfe3ec5b1572be73b5
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-8/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-8/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" "+1.47.0" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 143bc8c004d1a63d7ca9f1d619ef4581926fa21c7a06c656d5135bdfc57331de
[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" "143bc8c004d1a63d7ca9f1d619ef4581926fa21c7a06c656d5135bdfc57331de", kill_on_drop: false }`
[INFO] [stderr]    Compiling scopeguard v1.1.0
[INFO] [stderr]    Compiling pulldown-cmark v0.2.0
[INFO] [stderr]    Compiling remove_dir_all v0.5.3
[INFO] [stderr]    Compiling same-file v1.0.6
[INFO] [stderr]    Compiling bytecount v0.6.0
[INFO] [stderr]    Compiling glob v0.3.0
[INFO] [stderr]    Compiling normalize-line-endings v0.3.0
[INFO] [stderr]    Compiling downcast v0.10.0
[INFO] [stderr]    Compiling fragile v1.0.0
[INFO] [stderr]    Compiling futures-timer v3.0.2
[INFO] [stderr]    Compiling error-chain v0.12.4
[INFO] [stderr]    Compiling lock_api v0.4.1
[INFO] [stderr]    Compiling lock_api v0.3.4
[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 threadpool v1.8.1
[INFO] [stderr]    Compiling walkdir v2.3.1
[INFO] [stderr]    Compiling backtrace v0.3.51
[INFO] [stderr]    Compiling float-cmp v0.8.0
[INFO] [stderr]    Compiling derive_more v0.99.11
[INFO] [stderr]    Compiling actix-macros v0.1.2
[INFO] [stderr]    Compiling time-macros-impl v0.1.1
[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 resolv-conf v0.6.3
[INFO] [stderr]    Compiling rand_core v0.5.1
[INFO] [stderr]    Compiling predicates v1.0.5
[INFO] [stderr]    Compiling parking_lot v0.11.0
[INFO] [stderr]    Compiling parking_lot v0.10.2
[INFO] [stderr]    Compiling semver v0.9.0
[INFO] [stderr]    Compiling actix-router v0.2.5
[INFO] [stderr]    Compiling rand_chacha v0.2.2
[INFO] [stderr]    Compiling actix-service v1.0.6
[INFO] [stderr]    Compiling futures-executor v0.3.5
[INFO] [stderr]    Compiling assert_cmd v1.0.1
[INFO] [stderr]    Compiling cargo_metadata v0.7.4
[INFO] [stderr]    Compiling actix-codec v0.3.0
[INFO] [stderr]    Compiling serial_test v0.5.0
[INFO] [stderr]    Compiling futures v0.3.5
[INFO] [stderr]    Compiling rand v0.7.3
[INFO] [stderr]    Compiling time-macros v0.1.1
[INFO] [stderr]    Compiling time v0.2.22
[INFO] [stderr]    Compiling tempfile v3.1.0
[INFO] [stderr]    Compiling cookie v0.14.2
[INFO] [stderr]    Compiling trust-dns-proto v0.19.5
[INFO] [stderr]    Compiling skeptic v0.13.4 (https://github.com/budziq/rust-skeptic.git?branch=tempfile#97ec040b)
[INFO] [stderr]    Compiling test-server v0.9.1 (https://github.com/ChriFo/test-server-rs?tag=0.9.1#b64d1dbd)
[INFO] [stderr]    Compiling actix-threadpool v0.3.3
[INFO] [stderr]    Compiling actix-rt v1.1.1
[INFO] [stderr]    Compiling actix-utils v2.0.0
[INFO] [stderr]    Compiling mockall v0.8.1
[INFO] [stderr]    Compiling trust-dns-resolver v0.19.5
[INFO] [stderr]    Compiling actix-server v1.0.4
[INFO] [stderr]    Compiling actix-tls v2.0.0
[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] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 2m 41s
[INFO] running `Command { std: "docker" "inspect" "143bc8c004d1a63d7ca9f1d619ef4581926fa21c7a06c656d5135bdfc57331de", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "143bc8c004d1a63d7ca9f1d619ef4581926fa21c7a06c656d5135bdfc57331de", kill_on_drop: false }`
[INFO] [stdout] 143bc8c004d1a63d7ca9f1d619ef4581926fa21c7a06c656d5135bdfc57331de
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-8/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-8/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" "+1.47.0" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 9df3c8106d5f046a0eca68f29abe21ef1a9fe5271219586094813fe36825a8c7
[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" "9df3c8106d5f046a0eca68f29abe21ef1a9fe5271219586094813fe36825a8c7", kill_on_drop: false }`
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 0.93s
[INFO] [stdout] 
[INFO] [stderr]      Running /opt/rustwide/target/debug/deps/k2_creek-4f58fdc52cace8b5
[INFO] [stdout] running 46 tests
[INFO] [stderr] [2020-10-17T17:25:15Z 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:15Z TRACE k2_creek::kvk] Decoded: YIGLgBZUZWNobmlrZXIgS3Jhbmtlbmthc3NlgQcxMzc3NTA4jwUwMDAyMIIMMTIzNDU2Nzg5MDEygwQ1MDAwkAExhQtBbWVsaWUgU2luYYcFQXBmZWyICDExMDkxOTk1iRJDYXJsLVdvbGZmLVN0ci4gMTKKAUSLBTQ1Mjc5jAVFc3Nlbo0EMTAyMY4B/A==
[INFO] [stderr] [2020-10-17T17:25:15Z 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:15Z 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:15Z TRACE k2_creek::kvk] Decoded: YIGLgBZUZWNobmlrZXIgS3Jhbmtlbmthc3NlgQcxMzc3NTA4jwUwMDAyMIIMMTIzNDU2Nzg5MDEygwQ1MDAwkAExhQtBbWVsaWUgU2luYYcFQXBmZWyICDExMDkxOTk1iRJDYXJsLVdvbGZmLVN0ci4gMTKKAUSLBTQ1Mjc5jAVFc3Nlbo0EMTAyMY4B/A==
[INFO] [stderr] [2020-10-17T17:25:15Z 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:15Z 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:15Z TRACE k2_creek::kvk] Decoded: YIGLgBZUZWNobmlrZXIgS3Jhbmtlbmthc3NlgQcxMzc3NTA4jwUwMDAyMIIMMTIzNDU2Nzg5MDEygwQ1MDAwkAExhQtBbWVsaWUgU2luYYcFQXBmZWyICDExMDkxOTk1iRJDYXJsLVdvbGZmLVN0ci4gMTKKAUSLBTQ1Mjc5jAVFc3Nlbo0EMTAyMY4B/A==
[INFO] [stderr] [2020-10-17T17:25:15Z 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:15Z 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:15Z 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:15Z 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:15Z 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:15Z 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] [stderr] [2020-10-17T17:25:15Z TRACE k2_creek::kvk] Decoded: YIGLgBZUZWNobmlrZXIgS3Jhbmtlbmthc3NlgQcxMzc3NTA4jwUwMDAyMIIMMTIzNDU2Nzg5MDEygwQ1MDAwkAExhQtBbWVsaWUgU2luYYcFQXBmZWyICDExMDkxOTk1iRJDYXJsLVdvbGZmLVN0ci4gMTKKAUSLBTQ1Mjc5jAVFc3Nlbo0EMTAyMY4B/A==
[INFO] [stderr] [2020-10-17T17:25:15Z 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:15Z 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:15Z 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] [stdout] test api::tests::deserialize_egk_carddata_v2 ... ok
[INFO] [stdout] test api::tests::deserialize_egk_carddata_v1 ... ok
[INFO] [stdout] test api::tests::deserialize_kvk_carddata_v2 ... ok
[INFO] [stdout] test api::v1::tests::deserialize_egk_carddata ... ok
[INFO] [stdout] test api::tests::deserialize_kvk_carddata_v1 ... ok
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE k2_creek::kvk] ASN.1 decoded:
[INFO] [stdout] test api::v1::tests::deserialize_kvk_carddata ... ok
[INFO] [stderr]     KrankenKassenName:    Techniker Krankenkasse
[INFO] [stdout] test cli::required_argument_url ... ok
[INFO] [stderr]     KrankenKassenNummer:  1377508
[INFO] [stdout] test api::tests::deserialize_egk_carddata_v3 ... ok
[INFO] [stderr]     VKNR:                 00020
[INFO] [stdout] test config::tests::default_configuration ... ok
[INFO] [stderr]     VersichertenNummer:   123456789012
[INFO] [stdout] test config::tests::defaults ... ok
[INFO] [stderr]     VersichertenStatus:   5000
[INFO] [stdout] test api::v2::tests::deserialize_egk_carddata ... ok
[INFO] [stderr]     StatusErgänzung:      1
[INFO] [stdout] test api::tests::deserialize_kvk_carddata_v3 ... ok
[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 cli::tests::allowed_values_for_api ... ok
[INFO] [stdout] test config::tests::no_valid_url_parameter ... ok
[INFO] [stdout] test egk::tests::write_mfefgdo_failed ... ok
[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:15Z TRACE k2_creek::kvk] Decoded: YIGLgBZUZWNobmlrZXIgS3Jhbmtlbmthc3NlgQcxMzc3NTA4jwUwMDAyMIIMMTIzNDU2Nzg5MDEygwQ1MDAwkAExhQtBbWVsaWUgU2luYYcFQXBmZWyICDExMDkxOTk1iRJDYXJsLVdvbGZmLVN0ci4gMTKKAUSLBTQ1Mjc5jAVFc3Nlbo0EMTAyMY4B/A==
[INFO] [stdout] test api::v2::tests::deserialize_kvk_carddata ... ok
[INFO] [stderr] [2020-10-17T17:25:15Z 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:15Z 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:15Z TRACE k2_creek::kvk] Decoded: YIGLgBZUZWNobmlrZXIgS3Jhbmtlbmthc3NlgQcxMzc3NTA4jwUwMDAyMIIMMTIzNDU2Nzg5MDEygwQ1MDAwkAExhQtBbWVsaWUgU2luYYcFQXBmZWyICDExMDkxOTk1iRJDYXJsLVdvbGZmLVN0ci4gMTKKAUSLBTQ1Mjc5jAVFc3Nlbo0EMTAyMY4B/A==
[INFO] [stderr] [2020-10-17T17:25:15Z 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 http::tests::supported_api_version ... ok
[INFO] [stderr] [2020-10-17T17:25:15Z 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:15Z TRACE reqwest::blocking::wait] (ThreadId(31)) park without timeout
[INFO] [stdout] test api::v3::tests::deserialize_kvk_carddata ... ok
[INFO] [stderr] [2020-10-17T17:25:15Z DEBUG k2_creek::tests] Current dir: /tmp/.tmpylYyrq
[INFO] [stderr] [2020-10-17T17:25:15Z DEBUG k2_creek::writer::encode] No encoding found. Defaulting to iso-8859-15
[INFO] [stderr] [2020-10-17T17:25:15Z DEBUG k2_creek::writer::files] Wrote file "eGK_MFEFGDO.xml"
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE k2_creek::tests] Files: [
[INFO] [stderr]         "/tmp/.tmpylYyrq/eGK_MFEFGDO.xml",
[INFO] [stderr]     ]
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE k2_creek::tests] Leaving /tmp/.tmpylYyrq
[INFO] [stderr] [2020-10-17T17:25:15Z DEBUG k2_creek::tests] Current dir: /opt/rustwide/workdir
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE mio::poll] registering with poller
[INFO] [stdout] test egk::mfefgdo::tests::valid_xml_layout ... ok
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE reqwest::blocking::client] (ThreadId(33)) start runtime::block_on
[INFO] [stderr] [2020-10-17T17:25:15Z DEBUG k2_creek::tests] Current dir: /tmp/.tmp9dYJKq
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE k2_creek::tests] Files: []
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE k2_creek::tests] Leaving /tmp/.tmp9dYJKq
[INFO] [stderr] [2020-10-17T17:25:15Z DEBUG k2_creek::tests] Current dir: /opt/rustwide/workdir
[INFO] [stdout] test config::tests::config_file_missing ... ok
[INFO] [stdout] test kvk::fmt::tests::fmt_not_replaces_latin_alphabet_and_arabic_numbers ... ok
[INFO] [stdout] test kvk::fmt::tests::fmt_replaces_chars_according_to_din_66003 ... ok
[INFO] [stdout] test kvk::fmt::tests::fmt_returns_error_string_if_invalid_bytes_given ... ok
[INFO] [stderr] [2020-10-17T17:25:15Z DEBUG k2_creek::tests] Current dir: /tmp/.tmp20zxP7
[INFO] [stderr] [2020-10-17T17:25:15Z INFO  actix_server::builder] Starting 16 workers
[INFO] [stderr] [2020-10-17T17:25:15Z INFO  actix_server::builder] Starting 16 workers
[INFO] [stderr] [2020-10-17T17:25:15Z INFO  actix_server::builder] Starting 16 workers
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE reqwest::blocking::client] closing runtime thread (ThreadId(33))
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE reqwest::blocking::client] signaled close for runtime thread (ThreadId(33))
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE reqwest::blocking::client] (ThreadId(33)) Receiver is shutdown
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE reqwest::blocking::client] (ThreadId(33)) end runtime::block_on
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE reqwest::blocking::client] (ThreadId(33)) finished
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE reqwest::blocking::client] closed runtime thread (ThreadId(33))
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE reqwest::blocking::wait] (ThreadId(31)) park without timeout
[INFO] [stdout] test writer::encode::tests::encoding_from_xml_declaration_or_default ... ok
[INFO] [stderr] [2020-10-17T17:25:15Z DEBUG k2_creek::writer::encode] No encoding found. Defaulting to iso-8859-15
[INFO] [stdout] test config::tests::config_file_overrides ... ok
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE k2_creek::tests] Files: [
[INFO] [stderr]         "/tmp/.tmp20zxP7/config.ini",
[INFO] [stderr]     ]
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE k2_creek::tests] Leaving /tmp/.tmp20zxP7
[INFO] [stderr] [2020-10-17T17:25:15Z DEBUG k2_creek::tests] Current dir: /opt/rustwide/workdir
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE k2_creek::kvk] Decoded: YIGXgBpCdW5kZXNwb2xpemVpLUtyYW5rZW5rYXNzZYEHMzYwMDM0Mo8FMDAwMjCCDDEyMzQ1Njc4OTAxM4MEMTAwMJABMYUSRGFuaWVsIEd1c3RhdiBMdXR6hwZIfG5zY2iICDE3MDUxOTYxiRJDYXJsLVdvbGZmLVN0ci4gMTKKAUSLBTQ1Mjc5jAVFc3Nlbo0EMTAyMY4BiA==
[INFO] [stderr] [2020-10-17T17:25:15Z 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:15Z 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:15Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE mio::poll] registering with poller
[INFO] [stdout] test kvk::test::decode_base64_encoded_asn1_data ... ok
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE mio::poll] registering with poller
[INFO] [stdout] test writer::encode::tests::xml_declaration_is_not_added ... ok
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE mio::poll] registering with poller
[INFO] [stdout] test writer::encode::tests::xml_declaration_is_added ... ok
[INFO] [stderr] [2020-10-17T17:25:15Z INFO  actix_server::builder] Starting "actix-web-service-127.0.0.1:36301" service on 127.0.0.1:36301
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE reqwest::blocking::client] (ThreadId(69)) start runtime::block_on
[INFO] [stdout] test writer::files::tests::filenames ... ok
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE actix_server::worker] Service "actix-web-service-127.0.0.1:39505" is available
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE actix_server::worker] Service "actix-web-service-127.0.0.1:41659" is available
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE actix_server::worker] Service "actix-web-service-127.0.0.1:39505" is available
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE actix_server::worker] Service "actix-web-service-127.0.0.1:36301" is available
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE actix_server::worker] Service "actix-web-service-127.0.0.1:36301" is available
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE actix_server::worker] Service "actix-web-service-127.0.0.1:41659" is available
[INFO] [stdout] test egk::tests::all_files_written ... ok
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE actix_server::worker] Service "actix-web-service-127.0.0.1:39505" is available
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE actix_server::worker] Service "actix-web-service-127.0.0.1:36301" is available
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE actix_server::worker] Service "actix-web-service-127.0.0.1:36301" is available
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:15Z DEBUG k2_creek::tests] Current dir: /tmp/.tmpGQJa1t
[INFO] [stderr] [2020-10-17T17:25:15Z DEBUG k2_creek::writer::encode] No encoding found. Defaulting to iso-8859-15
[INFO] [stderr] [2020-10-17T17:25:15Z DEBUG k2_creek::writer::files] Wrote file "eGK_PersoenlicheVersichertendaten.xml"
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE actix_server::worker] Service "actix-web-service-127.0.0.1:36301" is available
[INFO] [stderr] [2020-10-17T17:25:15Z DEBUG k2_creek::writer::encode] No encoding found. Defaulting to iso-8859-15
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:15Z DEBUG k2_creek::writer::files] Wrote file "eGK_allgemeineVersicherungsdaten.xml"
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:15Z DEBUG k2_creek::writer::encode] No encoding found. Defaulting to iso-8859-15
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:15Z DEBUG k2_creek::writer::files] Wrote file "eGK_MFDF_HCA_EF_StatusVD.xml"
[INFO] [stderr] [2020-10-17T17:25:15Z DEBUG k2_creek::writer::encode] No encoding found. Defaulting to iso-8859-15
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:15Z DEBUG k2_creek::writer::files] Wrote file "eGK_geschuetzteVersichertendaten.xml"
[INFO] [stderr] [2020-10-17T17:25:15Z DEBUG k2_creek::writer::encode] No encoding found. Defaulting to iso-8859-15
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE actix_server::worker] Service "actix-web-service-127.0.0.1:39505" is available
[INFO] [stderr] [2020-10-17T17:25:15Z DEBUG k2_creek::writer::files] Wrote file "eGK_Pruefungsnachweis.xml"
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE actix_server::worker] Service "actix-web-service-127.0.0.1:39505" is available
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE k2_creek::tests] Files: [
[INFO] [stderr]         "/tmp/.tmpGQJa1t/eGK_Pruefungsnachweis.xml",
[INFO] [stderr]         "/tmp/.tmpGQJa1t/eGK_MFDF_HCA_EF_StatusVD.xml",
[INFO] [stderr]         "/tmp/.tmpGQJa1t/eGK_PersoenlicheVersichertendaten.xml",
[INFO] [stderr]         "/tmp/.tmpGQJa1t/eGK_geschuetzteVersichertendaten.xml",
[INFO] [stderr]         "/tmp/.tmpGQJa1t/eGK_allgemeineVersicherungsdaten.xml",
[INFO] [stderr]     ]
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE actix_server::worker] Service "actix-web-service-127.0.0.1:41659" is available
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE k2_creek::tests] Leaving /tmp/.tmpGQJa1t
[INFO] [stderr] [2020-10-17T17:25:15Z DEBUG k2_creek::tests] Current dir: /opt/rustwide/workdir
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE actix_server::worker] Service "actix-web-service-127.0.0.1:41659" is available
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:15Z INFO  actix_server::builder] Starting "actix-web-service-127.0.0.1:39505" service on 127.0.0.1:39505
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE actix_server::worker] Service "actix-web-service-127.0.0.1:36301" is available
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE actix_server::worker] Service "actix-web-service-127.0.0.1:39505" is available
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE actix_server::worker] Service "actix-web-service-127.0.0.1:41659" is available
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE actix_server::worker] Service "actix-web-service-127.0.0.1:36301" is available
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE actix_server::worker] Service "actix-web-service-127.0.0.1:36301" is available
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE actix_server::worker] Service "actix-web-service-127.0.0.1:36301" is available
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE actix_server::worker] Service "actix-web-service-127.0.0.1:39505" is available
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE actix_server::worker] Service "actix-web-service-127.0.0.1:41659" is available
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE actix_server::worker] Service "actix-web-service-127.0.0.1:36301" is available
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE actix_server::worker] Service "actix-web-service-127.0.0.1:36301" is available
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE actix_server::worker] Service "actix-web-service-127.0.0.1:36301" is available
[INFO] [stderr] [2020-10-17T17:25:15Z INFO  actix_server::builder] Starting "actix-web-service-127.0.0.1:41659" service on 127.0.0.1:41659
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE actix_server::worker] Service "actix-web-service-127.0.0.1:39505" is available
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE actix_server::worker] Service "actix-web-service-127.0.0.1:41659" is available
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE actix_server::worker] Service "actix-web-service-127.0.0.1:39505" is available
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE actix_server::worker] Service "actix-web-service-127.0.0.1:39505" is available
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE actix_server::worker] Service "actix-web-service-127.0.0.1:36301" is available
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE reqwest::blocking::wait] (ThreadId(35)) park without timeout
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE actix_server::worker] Service "actix-web-service-127.0.0.1:39505" is available
[INFO] [stderr] [2020-10-17T17:25:15Z DEBUG k2_creek::tests] Current dir: /tmp/.tmpkrgbQg
[INFO] [stderr] [2020-10-17T17:25:15Z DEBUG k2_creek::writer::encode] No encoding found. Defaulting to iso-8859-15
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE reqwest::blocking::wait] (ThreadId(30)) park without timeout
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE reqwest::blocking::wait] (ThreadId(32)) park without timeout
[INFO] [stdout] test http::tests::build_client_timeout ... ok
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE reqwest::blocking::client] closing runtime thread (ThreadId(69))
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE reqwest::blocking::client] signaled close for runtime thread (ThreadId(69))
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE reqwest::blocking::client] (ThreadId(69)) Receiver is shutdown
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE reqwest::blocking::client] (ThreadId(69)) end runtime::block_on
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE reqwest::blocking::client] (ThreadId(69)) finished
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE reqwest::blocking::client] closed runtime thread (ThreadId(69))
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE reqwest::blocking::client] (ThreadId(106)) start runtime::block_on
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE reqwest::blocking::client] (ThreadId(103)) start runtime::block_on
[INFO] [stderr] [2020-10-17T17:25:15Z DEBUG k2_creek::http] Requesting http://127.0.0.1:39505/k2/public/api/1/carddata ...
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE reqwest::blocking::wait] (ThreadId(35)) park without timeout
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE actix_server::worker] Service "actix-web-service-127.0.0.1:41659" is available
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::client::pool] checkout waiting for idle connection: ("http", 127.0.0.1:39505) 
[INFO] [stderr] [2020-10-17T17:25:15Z DEBUG reqwest::connect] starting new connection: http://127.0.0.1:39505/
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::client::connect::http] Http::connect; scheme=Some("http"), host=Some("127.0.0.1"), port=Some(Port(39505)) 
[INFO] [stderr] [2020-10-17T17:25:15Z DEBUG hyper::client::connect::http] connecting to 127.0.0.1:39505 
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE mio::poll] registering with poller
[INFO] [stdout] test egk::tests::status_vd_is_written ... ok
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE mio::poll] registering with poller
[INFO] [stdout] test egk::tests::gvd_is_written ... ok
[INFO] [stderr] [2020-10-17T17:25:15Z DEBUG hyper::client::connect::http] connected to 127.0.0.1:39505 
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::client::conn] client handshake HTTP/1 
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::client] handshake complete, spawning background dispatcher task 
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE actix_server::worker] Service "actix-web-service-127.0.0.1:41659" is available
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE want] signal: Want
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE want] signal found waiting giver, notifying
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::proto::h1::conn] flushed({role=client}): State { reading: Init, writing: Init, keep_alive: Busy } 
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE want] poll_want: taker wants!
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::client::pool] checkout dropped for ("http", 127.0.0.1:39505) 
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE actix_server::worker] Service "actix-web-service-127.0.0.1:41659" is available
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::proto::h1::role] encode_headers
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::proto::h1::role] -> encode_headers
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::proto::h1::role] Client::encode method=GET, body=None 
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::proto::h1::role] <- encode_headers
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::proto::h1::role] -- encode_headers
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::proto::h1::io] detected no usage of vectored write, flattening 
[INFO] [stderr] [2020-10-17T17:25:15Z DEBUG hyper::proto::h1::io] flushed 78 bytes 
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::proto::h1::conn] flushed({role=client}): State { reading: Init, writing: KeepAlive, keep_alive: Busy } 
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE actix_server::worker] Service "actix-web-service-127.0.0.1:41659" is available
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE actix_server::worker] Service "actix-web-service-127.0.0.1:41659" is available
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE actix_server::worker] Service "actix-web-service-127.0.0.1:39505" is available
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE actix_server::worker] Service "actix-web-service-127.0.0.1:41659" is available
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE actix_server::worker] Service "actix-web-service-127.0.0.1:39505" is available
[INFO] [stderr] [2020-10-17T17:25:15Z DEBUG k2_creek::writer::files] Wrote file "eGK_geschuetzteVersichertendaten.xml"
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE actix_server::worker] Service "actix-web-service-127.0.0.1:36301" is available
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE actix_server::worker] Service "actix-web-service-127.0.0.1:39505" is available
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE k2_creek::tests] Files: [
[INFO] [stderr]         "/tmp/.tmpkrgbQg/eGK_geschuetzteVersichertendaten.xml",
[INFO] [stderr]     ]
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE k2_creek::tests] Leaving /tmp/.tmpkrgbQg
[INFO] [stderr] [2020-10-17T17:25:15Z DEBUG k2_creek::tests] Current dir: /opt/rustwide/workdir
[INFO] [stderr] [2020-10-17T17:25:15Z DEBUG k2_creek::tests] Current dir: /tmp/.tmp1JzVpN
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:15Z DEBUG k2_creek::http] Requesting http://127.0.0.1:36301/k2/public/api/1/carddata ...
[INFO] [stderr] [2020-10-17T17:25:15Z DEBUG k2_creek::writer::encode] No encoding found. Defaulting to iso-8859-15
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE reqwest::blocking::wait] wait at most 2s
[INFO] [stderr] [2020-10-17T17:25:15Z DEBUG k2_creek::writer::files] Wrote file "eGK_MFDF_HCA_EF_StatusVD.xml"
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE reqwest::blocking::wait] (ThreadId(32)) park timeout 1.9999908s
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE actix_server::worker] Service "actix-web-service-127.0.0.1:36301" is available
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE k2_creek::tests] Files: [
[INFO] [stderr]         "/tmp/.tmp1JzVpN/eGK_MFDF_HCA_EF_StatusVD.xml",
[INFO] [stderr]     ]
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE k2_creek::tests] Leaving /tmp/.tmp1JzVpN
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:15Z DEBUG k2_creek::tests] Current dir: /opt/rustwide/workdir
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE reqwest::blocking::client] (ThreadId(105)) start runtime::block_on
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::client::pool] checkout waiting for idle connection: ("http", 127.0.0.1:36301) 
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE actix_server::worker] Service "actix-web-service-127.0.0.1:39505" is available
[INFO] [stderr] [2020-10-17T17:25:15Z DEBUG reqwest::connect] starting new connection: http://127.0.0.1:36301/
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::client::connect::http] Http::connect; scheme=Some("http"), host=Some("127.0.0.1"), port=Some(Port(36301)) 
[INFO] [stderr] [2020-10-17T17:25:15Z DEBUG hyper::client::connect::http] connecting to 127.0.0.1:36301 
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE actix_server::worker] Service "actix-web-service-127.0.0.1:41659" is available
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:15Z DEBUG hyper::client::connect::http] connected to 127.0.0.1:36301 
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::client::conn] client handshake HTTP/1 
[INFO] [stderr] [2020-10-17T17:25:15Z DEBUG k2_creek::http] Requesting http://127.0.0.1:41659/k2/public/api/2/carddata ...
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE actix_server::worker] Service "actix-web-service-127.0.0.1:39505" is available
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::client] handshake complete, spawning background dispatcher task 
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE want] signal: Want
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE reqwest::blocking::wait] (ThreadId(30)) park without timeout
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE want] signal found waiting giver, notifying
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::proto::h1::conn] flushed({role=client}): State { reading: Init, writing: Init, keep_alive: Busy } 
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE want] poll_want: taker wants!
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::client::pool] checkout dropped for ("http", 127.0.0.1:36301) 
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::client::pool] checkout waiting for idle connection: ("http", 127.0.0.1:41659) 
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::proto::h1::role] encode_headers
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::proto::h1::role] -> encode_headers
[INFO] [stderr] [2020-10-17T17:25:15Z DEBUG reqwest::connect] starting new connection: http://127.0.0.1:41659/
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::proto::h1::role] Client::encode method=GET, body=None 
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::proto::h1::role] <- encode_headers
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::proto::h1::role] -- encode_headers
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::client::connect::http] Http::connect; scheme=Some("http"), host=Some("127.0.0.1"), port=Some(Port(41659)) 
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::proto::h1::io] detected no usage of vectored write, flattening 
[INFO] [stderr] [2020-10-17T17:25:15Z DEBUG hyper::proto::h1::io] flushed 78 bytes 
[INFO] [stderr] [2020-10-17T17:25:15Z DEBUG hyper::client::connect::http] connecting to 127.0.0.1:41659 
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::proto::h1::conn] flushed({role=client}): State { reading: Init, writing: KeepAlive, keep_alive: Busy } 
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:15Z DEBUG hyper::client::connect::http] connected to 127.0.0.1:41659 
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::client::conn] client handshake HTTP/1 
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::client] handshake complete, spawning background dispatcher task 
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE want] signal: Want
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE want] signal found waiting giver, notifying
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::proto::h1::conn] flushed({role=client}): State { reading: Init, writing: Init, keep_alive: Busy } 
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE want] poll_want: taker wants!
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::client::pool] checkout dropped for ("http", 127.0.0.1:41659) 
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::proto::h1::role] encode_headers
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::proto::h1::role] -> encode_headers
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::proto::h1::role] Client::encode method=GET, body=None 
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::proto::h1::role] <- encode_headers
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::proto::h1::role] -- encode_headers
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::proto::h1::io] detected no usage of vectored write, flattening 
[INFO] [stderr] [2020-10-17T17:25:15Z DEBUG hyper::proto::h1::io] flushed 78 bytes 
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::proto::h1::conn] flushed({role=client}): State { reading: Init, writing: KeepAlive, keep_alive: Busy } 
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE actix_server::worker] Service "actix-web-service-127.0.0.1:41659" is available
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::proto::h1::conn] Conn::read_head 
[INFO] [stderr] [2020-10-17T17:25:15Z DEBUG hyper::proto::h1::io] read 77 bytes 
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::proto::h1::role] parse_headers
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::proto::h1::role] -> parse_headers
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::proto::h1::role] Response.parse([Header; 100], [u8; 77]) 
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::proto::h1::role] Response.parse Complete(75) 
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::proto::h1::role] <- parse_headers
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::proto::h1::role] -- parse_headers
[INFO] [stderr] [2020-10-17T17:25:15Z DEBUG hyper::proto::h1::io] parsed 2 headers 
[INFO] [stderr] [2020-10-17T17:25:15Z DEBUG hyper::proto::h1::conn] incoming body is content-length (2 bytes) 
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::proto::h1::decode] decode; state=Length(2) 
[INFO] [stderr] [2020-10-17T17:25:15Z DEBUG hyper::proto::h1::conn] incoming body completed 
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE actix_server::worker] Service "actix-web-service-127.0.0.1:36301" is available
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::proto::h1::conn] maybe_notify; read_from_io blocked 
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE want] signal: Want
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::proto::h1::conn] flushed({role=client}): State { reading: Init, writing: Init, keep_alive: Idle } 
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE want] signal: Want
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::proto::h1::conn] flushed({role=client}): State { reading: Init, writing: Init, keep_alive: Idle } 
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::client::pool] put; add idle connection for ("http", 127.0.0.1:41659) 
[INFO] [stderr] [2020-10-17T17:25:15Z DEBUG hyper::client::pool] pooling idle connection for ("http", 127.0.0.1:41659) 
[INFO] [stderr] [2020-10-17T17:25:15Z DEBUG reqwest::async_impl::client] response '200 OK' for http://127.0.0.1:41659/k2/public/api/2/carddata
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE k2_creek::http] Response { url: "http://127.0.0.1:41659/k2/public/api/2/carddata", status: 200, headers: {"content-length": "2", "date": "Sat, 17 Oct 2020 17:25:15 GMT"} }
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE reqwest::blocking::client] closing runtime thread (ThreadId(105))
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE reqwest::blocking::client] signaled close for runtime thread (ThreadId(105))
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE want] signal: Want
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::proto::h1::conn] flushed({role=client}): State { reading: Init, writing: Init, keep_alive: Idle } 
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE reqwest::blocking::client] (ThreadId(105)) Receiver is shutdown
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE reqwest::blocking::client] (ThreadId(105)) end runtime::block_on
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE mio::poll] deregistering handle with poller
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE want] signal: Closed
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE reqwest::blocking::client] (ThreadId(105)) finished
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE reqwest::blocking::client] closed runtime thread (ThreadId(105))
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE reqwest::blocking::wait] (ThreadId(30)) park without timeout
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE mio::poll] deregistering handle with poller
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE actix_server::worker] Service "actix-web-service-127.0.0.1:41659" is available
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE reqwest::blocking::client] (ThreadId(107)) start runtime::block_on
[INFO] [stderr] [2020-10-17T17:25:15Z DEBUG k2_creek::tests] Current dir: /tmp/.tmpinvOhy
[INFO] [stderr] [2020-10-17T17:25:15Z DEBUG k2_creek::writer::encode] No encoding found. Defaulting to iso-8859-15
[INFO] [stderr] [2020-10-17T17:25:15Z DEBUG k2_creek::writer::files] Wrote file "eGK_allgemeineVersicherungsdaten.xml"
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE k2_creek::tests] Files: [
[INFO] [stderr]         "/tmp/.tmpinvOhy/eGK_allgemeineVersicherungsdaten.xml",
[INFO] [stderr]     ]
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE k2_creek::tests] Leaving /tmp/.tmpinvOhy
[INFO] [stderr] [2020-10-17T17:25:15Z DEBUG k2_creek::tests] Current dir: /opt/rustwide/workdir
[INFO] [stderr] [2020-10-17T17:25:15Z DEBUG k2_creek::tests] Current dir: /tmp/.tmpUsQKVl
[INFO] [stderr] [2020-10-17T17:25:15Z DEBUG k2_creek::writer::encode] No encoding found. Defaulting to iso-8859-15
[INFO] [stderr] [2020-10-17T17:25:15Z DEBUG k2_creek::writer::files] Wrote file "eGK_Pruefungsnachweis.xml"
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE k2_creek::tests] Files: [
[INFO] [stderr]         "/tmp/.tmpUsQKVl/eGK_Pruefungsnachweis.xml",
[INFO] [stderr]     ]
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE k2_creek::tests] Leaving /tmp/.tmpUsQKVl
[INFO] [stderr] [2020-10-17T17:25:15Z DEBUG k2_creek::tests] Current dir: /opt/rustwide/workdir
[INFO] [stderr] [2020-10-17T17:25:15Z DEBUG k2_creek::tests] Current dir: /tmp/.tmp2ginqG
[INFO] [stderr] [2020-10-17T17:25:15Z DEBUG k2_creek::writer::encode] No encoding found. Defaulting to iso-8859-15
[INFO] [stderr] [2020-10-17T17:25:15Z DEBUG k2_creek::writer::files] Wrote file "eGK_PersoenlicheVersichertendaten.xml"
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE k2_creek::tests] Files: [
[INFO] [stderr]         "/tmp/.tmp2ginqG/eGK_PersoenlicheVersichertendaten.xml",
[INFO] [stderr]     ]
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE k2_creek::tests] Leaving /tmp/.tmp2ginqG
[INFO] [stderr] [2020-10-17T17:25:15Z DEBUG k2_creek::tests] Current dir: /opt/rustwide/workdir
[INFO] [stderr] [2020-10-17T17:25:15Z DEBUG k2_creek::tests] Current dir: /tmp/.tmpyCBrVE
[INFO] [stderr] [2020-10-17T17:25:15Z DEBUG k2_creek::writer::encode] No encoding found. Defaulting to iso-8859-15
[INFO] [stderr] [2020-10-17T17:25:15Z DEBUG k2_creek::writer::files] Wrote file "Result.xml"
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE k2_creek::tests] Files: [
[INFO] [stderr]         "/tmp/.tmpyCBrVE/Result.xml",
[INFO] [stderr]     ]
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE k2_creek::tests] Leaving /tmp/.tmpyCBrVE
[INFO] [stderr] [2020-10-17T17:25:15Z DEBUG k2_creek::tests] Current dir: /opt/rustwide/workdir
[INFO] [stderr] [2020-10-17T17:25:15Z DEBUG k2_creek::tests] Current dir: /tmp/.tmpEQGwa1
[INFO] [stdout] test egk::tests::pd_is_written ... ok
[INFO] [stderr] [2020-10-17T17:25:15Z DEBUG k2_creek::http] Requesting http://127.0.0.1:41659/k2/public/api/1/carddata ...
[INFO] [stdout] test egk::tests::pn_is_written ... ok
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE reqwest::blocking::wait] (ThreadId(30)) park without timeout
[INFO] [stdout] test writer::results::tests::optional_values_are_null ... ok
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::client::pool] checkout waiting for idle connection: ("http", 127.0.0.1:41659) 
[INFO] [stdout] test egk::tests::vd_is_written ... ok
[INFO] [stderr] [2020-10-17T17:25:15Z DEBUG reqwest::connect] starting new connection: http://127.0.0.1:41659/
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::client::connect::http] Http::connect; scheme=Some("http"), host=Some("127.0.0.1"), port=Some(Port(41659)) 
[INFO] [stderr] [2020-10-17T17:25:15Z DEBUG hyper::client::connect::http] connecting to 127.0.0.1:41659 
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:15Z DEBUG hyper::client::connect::http] connected to 127.0.0.1:41659 
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::client::conn] client handshake HTTP/1 
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::client] handshake complete, spawning background dispatcher task 
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE want] signal: Want
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE want] signal found waiting giver, notifying
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::proto::h1::conn] flushed({role=client}): State { reading: Init, writing: Init, keep_alive: Busy } 
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE want] poll_want: taker wants!
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::client::pool] checkout dropped for ("http", 127.0.0.1:41659) 
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::proto::h1::role] encode_headers
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::proto::h1::role] -> encode_headers
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::proto::h1::role] Client::encode method=GET, body=None 
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::proto::h1::role] <- encode_headers
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::proto::h1::role] -- encode_headers
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::proto::h1::io] detected no usage of vectored write, flattening 
[INFO] [stderr] [2020-10-17T17:25:15Z DEBUG hyper::proto::h1::io] flushed 78 bytes 
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::proto::h1::conn] flushed({role=client}): State { reading: Init, writing: KeepAlive, keep_alive: Busy } 
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::proto::h1::conn] Conn::read_head 
[INFO] [stderr] [2020-10-17T17:25:15Z DEBUG hyper::proto::h1::io] read 77 bytes 
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::proto::h1::role] parse_headers
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::proto::h1::role] -> parse_headers
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::proto::h1::role] Response.parse([Header; 100], [u8; 77]) 
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::proto::h1::role] Response.parse Complete(75) 
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::proto::h1::role] <- parse_headers
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::proto::h1::role] -- parse_headers
[INFO] [stderr] [2020-10-17T17:25:15Z DEBUG hyper::proto::h1::io] parsed 2 headers 
[INFO] [stderr] [2020-10-17T17:25:15Z DEBUG hyper::proto::h1::conn] incoming body is content-length (2 bytes) 
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::proto::h1::decode] decode; state=Length(2) 
[INFO] [stderr] [2020-10-17T17:25:15Z DEBUG hyper::proto::h1::conn] incoming body completed 
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::proto::h1::conn] maybe_notify; read_from_io blocked 
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE want] signal: Want
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::proto::h1::conn] flushed({role=client}): State { reading: Init, writing: Init, keep_alive: Idle } 
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE want] signal: Want
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::proto::h1::conn] flushed({role=client}): State { reading: Init, writing: Init, keep_alive: Idle } 
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::client::pool] put; add idle connection for ("http", 127.0.0.1:41659) 
[INFO] [stderr] [2020-10-17T17:25:15Z DEBUG hyper::client::pool] pooling idle connection for ("http", 127.0.0.1:41659) 
[INFO] [stderr] [2020-10-17T17:25:15Z DEBUG reqwest::async_impl::client] response '200 OK' for http://127.0.0.1:41659/k2/public/api/1/carddata
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE k2_creek::http] Response { url: "http://127.0.0.1:41659/k2/public/api/1/carddata", status: 200, headers: {"content-length": "2", "date": "Sat, 17 Oct 2020 17:25:15 GMT"} }
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE reqwest::blocking::client] closing runtime thread (ThreadId(107))
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE reqwest::blocking::client] signaled close for runtime thread (ThreadId(107))
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE want] signal: Want
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::proto::h1::conn] flushed({role=client}): State { reading: Init, writing: Init, keep_alive: Idle } 
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE reqwest::blocking::client] (ThreadId(107)) Receiver is shutdown
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE reqwest::blocking::client] (ThreadId(107)) end runtime::block_on
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE mio::poll] deregistering handle with poller
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE mio::poll] deregistering handle with poller
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE want] signal: Closed
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE reqwest::blocking::client] (ThreadId(107)) finished
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE reqwest::blocking::client] closed runtime thread (ThreadId(107))
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE reqwest::blocking::wait] (ThreadId(30)) park without timeout
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE k2_creek::kvk] Decoded: YIGLgBZUZWNobmlrZXIgS3Jhbmtlbmthc3NlgQcxMzc3NTA4jwUwMDAyMIIMMTIzNDU2Nzg5MDEygwQ1MDAwkAExhQtBbWVsaWUgU2luYYcFQXBmZWyICDExMDkxOTk1iRJDYXJsLVdvbGZmLVN0ci4gMTKKAUSLBTQ1Mjc5jAVFc3Nlbo0EMTAyMY4B/A==
[INFO] [stderr] [2020-10-17T17:25:15Z 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:15Z 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:15Z DEBUG k2_creek::writer::files] Deleted "eGK_allgemeineVersicherungsdaten.xml"
[INFO] [stderr] [2020-10-17T17:25:15Z DEBUG k2_creek::writer::files] Deleted "eGK_geschuetzteVersichertendaten.xml"
[INFO] [stderr] [2020-10-17T17:25:15Z DEBUG k2_creek::writer::files] Deleted "eGK_PersoenlicheVersichertendaten.xml"
[INFO] [stderr] [2020-10-17T17:25:15Z DEBUG k2_creek::writer::files] Deleted "eGK_MFDF_HCA_EF_StatusVD.xml"
[INFO] [stderr] [2020-10-17T17:25:15Z DEBUG k2_creek::writer::files] Deleted "eGK_Pruefungsnachweis.xml"
[INFO] [stderr] [2020-10-17T17:25:15Z DEBUG k2_creek::writer::files] Deleted "eGK_MFEFGDO.xml"
[INFO] [stderr] [2020-10-17T17:25:15Z DEBUG k2_creek::writer::files] Deleted "Result.xml"
[INFO] [stderr] [2020-10-17T17:25:15Z DEBUG k2_creek::writer::encode] No encoding found. Defaulting to iso-8859-15
[INFO] [stderr] [2020-10-17T17:25:15Z DEBUG k2_creek::writer::files] Wrote file "Result.xml"
[INFO] [stderr] [2020-10-17T17:25:15Z DEBUG k2_creek::writer::files] Wrote file "KVK.dat"
[INFO] [stderr] [2020-10-17T17:25:15Z DEBUG k2_creek::writer::files] Wrote file "KVK_Daten.bin"
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE k2_creek::tests] Files: [
[INFO] [stderr]         "/tmp/.tmpEQGwa1/Result.xml",
[INFO] [stderr]         "/tmp/.tmpEQGwa1/KVK_Daten.bin",
[INFO] [stderr]         "/tmp/.tmpEQGwa1/KVK.dat",
[INFO] [stderr]     ]
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE k2_creek::tests] Files: [
[INFO] [stderr]         "/tmp/.tmpEQGwa1/Result.xml",
[INFO] [stderr]         "/tmp/.tmpEQGwa1/KVK_Daten.bin",
[INFO] [stderr]         "/tmp/.tmpEQGwa1/KVK.dat",
[INFO] [stderr]     ]
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE k2_creek::tests] Leaving /tmp/.tmpEQGwa1
[INFO] [stderr] [2020-10-17T17:25:15Z DEBUG k2_creek::tests] Current dir: /opt/rustwide/workdir
[INFO] [stdout] test writer::tests::not_prompting_before_cleanup ... ok
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE reqwest::blocking::client] (ThreadId(109)) start runtime::block_on
[INFO] [stderr] [2020-10-17T17:25:15Z DEBUG k2_creek::http] Requesting http://127.0.0.1:41659/k2/public/api/3/cards ...
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE reqwest::blocking::wait] (ThreadId(30)) park without timeout
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::client::pool] checkout waiting for idle connection: ("http", 127.0.0.1:41659) 
[INFO] [stderr] [2020-10-17T17:25:15Z DEBUG reqwest::connect] starting new connection: http://127.0.0.1:41659/
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::client::connect::http] Http::connect; scheme=Some("http"), host=Some("127.0.0.1"), port=Some(Port(41659)) 
[INFO] [stderr] [2020-10-17T17:25:15Z DEBUG hyper::client::connect::http] connecting to 127.0.0.1:41659 
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE mio::poll] registering with poller
[INFO] [stderr] [2020-10-17T17:25:15Z DEBUG hyper::client::connect::http] connected to 127.0.0.1:41659 
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::client::conn] client handshake HTTP/1 
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::client] handshake complete, spawning background dispatcher task 
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE want] signal: Want
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE want] signal found waiting giver, notifying
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::proto::h1::conn] flushed({role=client}): State { reading: Init, writing: Init, keep_alive: Busy } 
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE want] poll_want: taker wants!
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::client::pool] checkout dropped for ("http", 127.0.0.1:41659) 
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::proto::h1::role] encode_headers
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::proto::h1::role] -> encode_headers
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::proto::h1::role] Client::encode method=GET, body=None 
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::proto::h1::role] <- encode_headers
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::proto::h1::role] -- encode_headers
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::proto::h1::io] detected no usage of vectored write, flattening 
[INFO] [stderr] [2020-10-17T17:25:15Z DEBUG hyper::proto::h1::io] flushed 75 bytes 
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::proto::h1::conn] flushed({role=client}): State { reading: Init, writing: KeepAlive, keep_alive: Busy } 
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE mio::poll] registering with poller
[INFO] [stdout] test http::tests::build_client_api_endpoint ... ok
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::proto::h1::conn] Conn::read_head 
[INFO] [stderr] [2020-10-17T17:25:15Z DEBUG hyper::proto::h1::io] read 77 bytes 
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::proto::h1::role] parse_headers
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::proto::h1::role] -> parse_headers
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::proto::h1::role] Response.parse([Header; 100], [u8; 77]) 
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::proto::h1::role] Response.parse Complete(75) 
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::proto::h1::role] <- parse_headers
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::proto::h1::role] -- parse_headers
[INFO] [stderr] [2020-10-17T17:25:15Z DEBUG hyper::proto::h1::io] parsed 2 headers 
[INFO] [stderr] [2020-10-17T17:25:15Z DEBUG hyper::proto::h1::conn] incoming body is content-length (2 bytes) 
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::proto::h1::decode] decode; state=Length(2) 
[INFO] [stderr] [2020-10-17T17:25:15Z DEBUG hyper::proto::h1::conn] incoming body completed 
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::proto::h1::conn] maybe_notify; read_from_io blocked 
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE want] signal: Want
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::proto::h1::conn] flushed({role=client}): State { reading: Init, writing: Init, keep_alive: Idle } 
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE want] signal: Want
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::proto::h1::conn] flushed({role=client}): State { reading: Init, writing: Init, keep_alive: Idle } 
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::client::pool] put; add idle connection for ("http", 127.0.0.1:41659) 
[INFO] [stderr] [2020-10-17T17:25:15Z DEBUG hyper::client::pool] pooling idle connection for ("http", 127.0.0.1:41659) 
[INFO] [stderr] [2020-10-17T17:25:15Z DEBUG reqwest::async_impl::client] response '200 OK' for http://127.0.0.1:41659/k2/public/api/3/cards
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE k2_creek::http] Response { url: "http://127.0.0.1:41659/k2/public/api/3/cards", status: 200, headers: {"content-length": "2", "date": "Sat, 17 Oct 2020 17:25:15 GMT"} }
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE reqwest::blocking::client] closing runtime thread (ThreadId(109))
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE reqwest::blocking::client] signaled close for runtime thread (ThreadId(109))
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE want] signal: Want
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE hyper::proto::h1::conn] flushed({role=client}): State { reading: Init, writing: Init, keep_alive: Idle } 
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE reqwest::blocking::client] (ThreadId(109)) Receiver is shutdown
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE reqwest::blocking::client] (ThreadId(109)) end runtime::block_on
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE mio::poll] deregistering handle with poller
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE want] signal: Closed
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE reqwest::blocking::client] (ThreadId(109)) finished
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE reqwest::blocking::client] closed runtime thread (ThreadId(109))
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE mio::poll] deregistering handle with poller
[INFO] [stderr] [2020-10-17T17:25:15Z TRACE mio::poll] deregistering handle with poller
[INFO] [stdout] test http::tests::unsupported_api_version_four ... ok
[INFO] [stdout] test http::tests::unsupported_api_version_zero ... ok
[INFO] [stdout] test http::tests::build_client_wrong_api ... ok
[INFO] [stderr] [2020-10-17T17:25:17Z TRACE reqwest::blocking::wait] wait timeout exceeded
[INFO] [stdout] test http::tests::build_client_with_timeout ... ok
[INFO] [stderr] [2020-10-17T17:25:17Z TRACE reqwest::blocking::client] closing runtime thread (ThreadId(106))
[INFO] [stderr] [2020-10-17T17:25:17Z TRACE reqwest::blocking::client] signaled close for runtime thread (ThreadId(106))
[INFO] [stderr] [2020-10-17T17:25:17Z TRACE reqwest::blocking::client] (ThreadId(106)) Receiver is shutdown
[INFO] [stderr] [2020-10-17T17:25:17Z TRACE reqwest::blocking::client] (ThreadId(106)) end runtime::block_on
[INFO] [stderr] [2020-10-17T17:25:17Z TRACE mio::poll] deregistering handle with poller
[INFO] [stderr] [2020-10-17T17:25:17Z TRACE want] signal: Closed
[INFO] [stderr] [2020-10-17T17:25:17Z TRACE reqwest::blocking::client] (ThreadId(106)) finished
[INFO] [stderr] [2020-10-17T17:25:17Z TRACE reqwest::blocking::client] closed runtime thread (ThreadId(106))
[INFO] [stderr] [2020-10-17T17:25:17Z TRACE mio::poll] deregistering handle with poller
[INFO] [stderr] [2020-10-17T17:25:17Z TRACE hyper::proto::h1::conn] Conn::read_head 
[INFO] [stderr] [2020-10-17T17:25:17Z DEBUG hyper::proto::h1::io] read 92 bytes 
[INFO] [stderr] [2020-10-17T17:25:17Z TRACE hyper::proto::h1::role] parse_headers
[INFO] [stderr] [2020-10-17T17:25:17Z TRACE hyper::proto::h1::role] -> parse_headers
[INFO] [stderr] [2020-10-17T17:25:17Z TRACE hyper::proto::h1::role] Response.parse([Header; 100], [u8; 92]) 
[INFO] [stderr] [2020-10-17T17:25:17Z TRACE hyper::proto::h1::role] Response.parse Complete(76) 
[INFO] [stderr] [2020-10-17T17:25:17Z TRACE hyper::proto::h1::role] <- parse_headers
[INFO] [stderr] [2020-10-17T17:25:17Z TRACE hyper::proto::h1::role] -- parse_headers
[INFO] [stderr] [2020-10-17T17:25:17Z DEBUG hyper::proto::h1::io] parsed 2 headers 
[INFO] [stderr] [2020-10-17T17:25:17Z DEBUG hyper::proto::h1::conn] incoming body is content-length (16 bytes) 
[INFO] [stderr] [2020-10-17T17:25:17Z TRACE hyper::proto::h1::decode] decode; state=Length(16) 
[INFO] [stderr] [2020-10-17T17:25:17Z DEBUG hyper::proto::h1::conn] incoming body completed 
[INFO] [stderr] [2020-10-17T17:25:17Z TRACE hyper::proto::h1::conn] maybe_notify; read_from_io blocked 
[INFO] [stderr] [2020-10-17T17:25:17Z TRACE want] signal: Want
[INFO] [stderr] [2020-10-17T17:25:17Z TRACE hyper::proto::h1::conn] flushed({role=client}): State { reading: Init, writing: Init, keep_alive: Idle } 
[INFO] [stderr] [2020-10-17T17:25:17Z TRACE want] signal: Want
[INFO] [stderr] [2020-10-17T17:25:17Z TRACE hyper::proto::h1::conn] flushed({role=client}): State { reading: Init, writing: Init, keep_alive: Idle } 
[INFO] [stderr] [2020-10-17T17:25:17Z TRACE hyper::client::pool] put; add idle connection for ("http", 127.0.0.1:39505) 
[INFO] [stderr] [2020-10-17T17:25:17Z DEBUG hyper::client::pool] pooling idle connection for ("http", 127.0.0.1:39505) 
[INFO] [stderr] [2020-10-17T17:25:17Z DEBUG reqwest::async_impl::client] response '200 OK' for http://127.0.0.1:39505/k2/public/api/1/carddata
[INFO] [stderr] [2020-10-17T17:25:17Z TRACE k2_creek::http] Response { url: "http://127.0.0.1:39505/k2/public/api/1/carddata", status: 200, headers: {"content-length": "16", "date": "Sat, 17 Oct 2020 17:25:17 GMT"} }
[INFO] [stderr] [2020-10-17T17:25:17Z TRACE want] signal: Want
[INFO] [stderr] [2020-10-17T17:25:17Z TRACE reqwest::blocking::client] closing runtime thread (ThreadId(103))
[INFO] [stderr] [2020-10-17T17:25:17Z TRACE hyper::proto::h1::conn] flushed({role=client}): State { reading: Init, writing: Init, keep_alive: Idle } 
[INFO] [stderr] [2020-10-17T17:25:17Z TRACE reqwest::blocking::client] signaled close for runtime thread (ThreadId(103))
[INFO] [stderr] [2020-10-17T17:25:17Z TRACE reqwest::blocking::client] (ThreadId(103)) Receiver is shutdown
[INFO] [stderr] [2020-10-17T17:25:17Z TRACE reqwest::blocking::client] (ThreadId(103)) end runtime::block_on
[INFO] [stderr] [2020-10-17T17:25:17Z TRACE mio::poll] deregistering handle with poller
[INFO] [stderr] [2020-10-17T17:25:17Z TRACE want] signal: Closed
[INFO] [stderr] [2020-10-17T17:25:17Z TRACE mio::poll] deregistering handle with poller
[INFO] [stderr] [2020-10-17T17:25:17Z TRACE reqwest::blocking::client] (ThreadId(103)) finished
[INFO] [stderr] [2020-10-17T17:25:17Z TRACE reqwest::blocking::client] closed runtime thread (ThreadId(103))
[INFO] [stderr] [2020-10-17T17:25:17Z TRACE mio::poll] deregistering handle with poller
[INFO] [stdout] test http::tests::build_client_without_timeout ... ok
[INFO] [stderr]      Running /opt/rustwide/target/debug/deps/k2_creek-7d3825ef1bdc640f
[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/cli-5895ee034368de1a
[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] [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" "9df3c8106d5f046a0eca68f29abe21ef1a9fe5271219586094813fe36825a8c7", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "9df3c8106d5f046a0eca68f29abe21ef1a9fe5271219586094813fe36825a8c7", kill_on_drop: false }`
[INFO] [stdout] 9df3c8106d5f046a0eca68f29abe21ef1a9fe5271219586094813fe36825a8c7
