[INFO] fetching crate fast-logger 0.6.0... [INFO] testing fast-logger-0.6.0 against 1.44.0 for beta-1.45-1 [INFO] extracting crate fast-logger 0.6.0 into /workspace/builds/worker-0/source [INFO] validating manifest of crates.io crate fast-logger 0.6.0 on toolchain 1.44.0 [INFO] running `"/workspace/cargo-home/bin/cargo" "+1.44.0" "read-manifest" "--manifest-path" "Cargo.toml"` [INFO] started tweaking crates.io crate fast-logger 0.6.0 [INFO] finished tweaking crates.io crate fast-logger 0.6.0 [INFO] tweaked toml for crates.io crate fast-logger 0.6.0 written to /workspace/builds/worker-0/source/Cargo.toml [INFO] running `"/workspace/cargo-home/bin/cargo" "+1.44.0" "generate-lockfile" "--manifest-path" "Cargo.toml" "-Zno-index-update"` [INFO] running `"/workspace/cargo-home/bin/cargo" "+1.44.0" "fetch" "--locked" "--manifest-path" "Cargo.toml"` [INFO] running `"docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0/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" "MAP_USER_ID=0" "-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" "--network" "none" "rustops/crates-build-env" "/opt/rustwide/cargo-home/bin/cargo" "+1.44.0" "build" "--frozen"` [INFO] [stderr] WARNING: Your kernel does not support swap limit capabilities or the cgroup is not mounted. Memory limited without swap. [INFO] [stdout] 7974e2f9c39cfa8782535e475eeecb1d02a114de385c322ee39596c9bee19d1b [INFO] running `"docker" "start" "-a" "7974e2f9c39cfa8782535e475eeecb1d02a114de385c322ee39596c9bee19d1b"` [INFO] [stderr] sudo: setrlimit(RLIMIT_CORE): Operation not permitted [INFO] [stderr] Compiling num-integer v0.1.42 [INFO] [stderr] Compiling crossbeam-utils v0.6.6 [INFO] [stderr] Compiling colored v1.9.3 [INFO] [stderr] Compiling crossbeam-channel v0.3.9 [INFO] [stderr] Compiling chrono v0.4.11 [INFO] [stderr] Compiling fast-logger v0.6.0 (/opt/rustwide/workdir) [INFO] [stderr] Finished dev [unoptimized + debuginfo] target(s) in 5.43s [INFO] running `"docker" "inspect" "7974e2f9c39cfa8782535e475eeecb1d02a114de385c322ee39596c9bee19d1b"` [INFO] running `"docker" "rm" "-f" "7974e2f9c39cfa8782535e475eeecb1d02a114de385c322ee39596c9bee19d1b"` [INFO] [stdout] 7974e2f9c39cfa8782535e475eeecb1d02a114de385c322ee39596c9bee19d1b [INFO] running `"docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0/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" "MAP_USER_ID=0" "-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" "--network" "none" "rustops/crates-build-env" "/opt/rustwide/cargo-home/bin/cargo" "+1.44.0" "test" "--frozen" "--no-run"` [INFO] [stdout] c374fcf17dc3f365b3a10ec80ecb1fe7a607758aadf67ef198c90688261a16ac [INFO] [stderr] WARNING: Your kernel does not support swap limit capabilities or the cgroup is not mounted. Memory limited without swap. [INFO] running `"docker" "start" "-a" "c374fcf17dc3f365b3a10ec80ecb1fe7a607758aadf67ef198c90688261a16ac"` [INFO] [stderr] sudo: setrlimit(RLIMIT_CORE): Operation not permitted [INFO] [stderr] Compiling serde v1.0.111 [INFO] [stderr] Compiling byteorder v1.3.4 [INFO] [stderr] Compiling slog v2.5.2 [INFO] [stderr] Compiling syn v1.0.30 [INFO] [stderr] Compiling same-file v1.0.6 [INFO] [stderr] Compiling oorandom v11.1.1 [INFO] [stderr] Compiling itertools v0.9.0 [INFO] [stderr] Compiling rustc_version v0.2.3 [INFO] [stderr] Compiling dirs-sys v0.3.5 [INFO] [stderr] Compiling clap v2.33.1 [INFO] [stderr] Compiling plotters v0.2.15 [INFO] [stderr] Compiling crossbeam-channel v0.4.2 [INFO] [stderr] Compiling walkdir v2.3.1 [INFO] [stderr] Compiling dirs v2.0.2 [INFO] [stderr] Compiling term v0.6.1 [INFO] [stderr] Compiling cast v0.2.3 [INFO] [stderr] Compiling slog-async v2.5.0 [INFO] [stderr] Compiling slog-term v2.6.0 [INFO] [stderr] Compiling criterion-plot v0.4.2 [INFO] [stderr] Compiling regex-automata v0.1.9 [INFO] [stderr] Compiling serde_derive v1.0.111 [INFO] [stderr] Compiling bstr v0.2.13 [INFO] [stderr] Compiling serde_json v1.0.53 [INFO] [stderr] Compiling csv v1.1.3 [INFO] [stderr] Compiling tinytemplate v1.1.0 [INFO] [stderr] Compiling criterion v0.3.2 [INFO] [stderr] Compiling fast-logger v0.6.0 (/opt/rustwide/workdir) [INFO] [stderr] Finished test [unoptimized + debuginfo] target(s) in 54.55s [INFO] running `"docker" "inspect" "c374fcf17dc3f365b3a10ec80ecb1fe7a607758aadf67ef198c90688261a16ac"` [INFO] running `"docker" "rm" "-f" "c374fcf17dc3f365b3a10ec80ecb1fe7a607758aadf67ef198c90688261a16ac"` [INFO] [stdout] c374fcf17dc3f365b3a10ec80ecb1fe7a607758aadf67ef198c90688261a16ac [INFO] running `"docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0/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" "MAP_USER_ID=0" "-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" "--network" "none" "rustops/crates-build-env" "/opt/rustwide/cargo-home/bin/cargo" "+1.44.0" "test" "--frozen"` [INFO] [stderr] WARNING: Your kernel does not support swap limit capabilities or the cgroup is not mounted. Memory limited without swap. [INFO] [stdout] 83a38add359f5380a8545c259b9681d5438677de0cae9ba3bc66b48b3c0823df [INFO] running `"docker" "start" "-a" "83a38add359f5380a8545c259b9681d5438677de0cae9ba3bc66b48b3c0823df"` [INFO] [stderr] sudo: setrlimit(RLIMIT_CORE): Operation not permitted [INFO] [stderr] Finished test [unoptimized + debuginfo] target(s) in 0.21s [INFO] [stderr] Running /opt/rustwide/target/debug/deps/fast_logger-784a2420675fb0d9 [INFO] [stdout] [INFO] [stdout] running 30 tests [INFO] [stdout] test tests::count_digits ... ok [INFO] [stdout] Jun 5 2020 22:20:24.891561224+0000: 123 tst: lorem ipsum, dolor=sit, amet=1234 [INFO] [stdout] Jun 5 2020 22:20:24.891732241+0000: 128 tst: lorem ipsum, a=b [INFO] [stdout] Jun 5 2020 22:20:24.891771664+0000: 064 tst: lorem ipsum, a=b [INFO] [stdout] Jun 5 2020 22:20:24.891803598+0000: 000 tst: lorem ipsum, a=b [INFO] [stdout] Jun 5 2020 22:20:24.891833111+0000: 128 tst: lorem 1, dolor=sit [INFO] [stdout] Jun 5 2020 22:20:24.892054643+0000: 128 tst: Compatibility layer [INFO] [stdout] test tests::indebug ... ok [INFO] [stdout] test tests::generic ... ok [INFO] [stdout] Jun 5 2020 22:20:24.917696517+0000: 000 tst: Message [INFO] [stdout] Jun 5 2020 22:20:24.917798060+0000: 000 tst: Message [INFO] [stdout] test tests::custom_writer_with_generic ... ok [INFO] [stdout] Jun 5 2020 22:20:24.942651253+0000: 128 tst: Message [INFO] [stdout] Jun 5 2020 22:20:24.942747806+0000: 128 tst: Message [INFO] [stdout] Jun 5 2020 22:20:24.942778466+0000: 128 tst: Message argument [INFO] [stdout] Jun 5 2020 22:20:24.942807180+0000: 128 tst: Message argument [INFO] [stdout] Jun 5 2020 22:20:24.942836081+0000: 128 tst: Message [INFO] [stdout] Jun 5 2020 22:20:24.942864991+0000: 128 tst: Message, a=b [INFO] [stdout] Jun 5 2020 22:20:24.942893209+0000: 128 tst: Message, a=b [INFO] [stdout] Jun 5 2020 22:20:24.942919895+0000: 128 tst: Message [INFO] [stdout] Jun 5 2020 22:20:24.942944101+0000: 128 tst: Message, a=b [INFO] [stdout] Jun 5 2020 22:20:24.942984395+0000: 128 tst: Message, a=b [INFO] [stdout] Jun 5 2020 22:20:24.943014222+0000: 128 tst: Message argument [INFO] [stdout] Jun 5 2020 22:20:24.943044372+0000: 128 tst: Message argument, a=b [INFO] [stdout] Jun 5 2020 22:20:24.943074732+0000: 128 tst: Message argument, a=b [INFO] [stdout] Jun 5 2020 22:20:24.943103355+0000: 128 tst: Message argument [INFO] [stdout] Jun 5 2020 22:20:24.943130354+0000: 128 tst: Message argument, a=b [INFO] [stdout] Jun 5 2020 22:20:24.943160137+0000: 128 tst: Message argument, a=b [INFO] [stdout] Jun 5 2020 22:20:24.943189782+0000: 064 tst: Message [INFO] [stdout] Jun 5 2020 22:20:24.943256246+0000: 064 tst: Message [INFO] [stdout] Jun 5 2020 22:20:24.943284847+0000: 064 tst: Message argument [INFO] [stdout] Jun 5 2020 22:20:24.943313549+0000: 064 tst: Message argument [INFO] [stdout] Jun 5 2020 22:20:24.943340484+0000: 064 tst: Message [INFO] [stdout] Jun 5 2020 22:20:24.943368041+0000: 064 tst: Message, a=b [INFO] [stdout] Jun 5 2020 22:20:24.943397821+0000: 064 tst: Message, a=b [INFO] [stdout] Jun 5 2020 22:20:24.943599453+0000: 064 tst: Message [INFO] [stdout] Jun 5 2020 22:20:24.943635709+0000: 064 tst: Message, a=b [INFO] [stdout] Jun 5 2020 22:20:24.943657109+0000: 064 tst: Message, a=b [INFO] [stdout] Jun 5 2020 22:20:24.943677923+0000: 064 tst: Message argument [INFO] [stdout] Jun 5 2020 22:20:24.943698918+0000: 064 tst: Message argument, a=b [INFO] [stdout] Jun 5 2020 22:20:24.943720052+0000: 064 tst: Message argument, a=b [INFO] [stdout] Jun 5 2020 22:20:24.943740986+0000: 064 tst: Message argument [INFO] [stdout] Jun 5 2020 22:20:24.943761292+0000: 064 tst: Message argument, a=b [INFO] [stdout] Jun 5 2020 22:20:24.943782229+0000: 064 tst: Message argument, a=b [INFO] [stdout] Jun 5 2020 22:20:24.943803132+0000: 000 tst: Message [INFO] [stdout] Jun 5 2020 22:20:24.943823231+0000: 000 tst: Message [INFO] [stdout] Jun 5 2020 22:20:24.943843527+0000: 000 tst: Message argument [INFO] [stdout] Jun 5 2020 22:20:24.943864094+0000: 000 tst: Message argument [INFO] [stdout] Jun 5 2020 22:20:24.943884656+0000: 000 tst: Message [INFO] [stdout] Jun 5 2020 22:20:24.943905146+0000: 000 tst: Message, a=b [INFO] [stdout] Jun 5 2020 22:20:24.943925703+0000: 000 tst: Message, a=b [INFO] [stdout] Jun 5 2020 22:20:24.943951567+0000: 000 tst: Message [INFO] [stdout] Jun 5 2020 22:20:24.943972636+0000: 000 tst: Message, a=b [INFO] [stdout] Jun 5 2020 22:20:24.943993091+0000: 000 tst: Message, a=b [INFO] [stdout] Jun 5 2020 22:20:24.944013489+0000: 000 tst: Message argument [INFO] [stdout] Jun 5 2020 22:20:24.944039678+0000: 000 tst: Message argument, a=b [INFO] [stdout] Jun 5 2020 22:20:24.944064656+0000: 000 tst: Message argument, a=b [INFO] [stdout] Jun 5 2020 22:20:24.944089217+0000: 000 tst: Message argument [INFO] [stdout] Jun 5 2020 22:20:24.944110505+0000: 000 tst: Message argument, a=b [INFO] [stdout] Jun 5 2020 22:20:24.944131919+0000: 000 tst: Message argument, a=b [INFO] [stdout] Jun 5 2020 22:20:24.944153242+0000: 128 tst: Message 123 [INFO] [stdout] Jun 5 2020 22:20:24.944173722+0000: 064 tst: Message 123 [INFO] [stdout] Jun 5 2020 22:20:24.944193856+0000: 000 tst: Message 123 [INFO] [stdout] Jun 5 2020 22:20:24.944214506+0000: 128 tst: Message 123 [INFO] [stdout] test tests::ensure_ending_message_when_exit_3 ... ok [INFO] [stdout] test tests::custom_writer ... ok [INFO] [stdout] test tests::compatibility_layer ... ok [INFO] [stdout] test tests::ensure_ending_message_when_exit_2 ... ok [INFO] [stdout] test tests::inhex ... ok [INFO] [stdout] Jun 5 2020 22:20:24.961541625+0000: 128 tst: An info message [INFO] [stdout] Jun 5 2020 22:20:24.961689717+0000: 064 tst: A warning message [INFO] [stdout] Jun 5 2020 22:20:24.961744919+0000: 000 tst: An error message [INFO] [stdout] Jun 5 2020 22:20:24.961789278+0000: 128 tst [1/4]: On [INFO] [stdout] Jun 5 2020 22:20:24.961789278+0000: 128 tst [2/4]: multiple [INFO] [stdout] Jun 5 2020 22:20:24.961789278+0000: 128 tst [3/4]: lines [INFO] [stdout] Jun 5 2020 22:20:24.961789278+0000: 128 tst [4/4]: [INFO] [stdout] Jun 5 2020 22:20:24.961917545+0000: 196 logger: Unable to receive message. Exiting logger, reason=receiving on an empty and disconnected channel [INFO] [stdout] Jun 5 2020 22:20:24.963550142+0000: 128 tst: Message [INFO] [stdout] Jun 5 2020 22:20:24.967556221+0000: 128 tst: Message [INFO] [stdout] Jun 5 2020 22:20:24.967651109+0000: 128 tst: Message 2 [INFO] [stdout] test tests::ensure_ending_message_when_exit_1 ... ok [INFO] [stdout] test tests::ensure_all_macro_variants_can_be_used ... ok [INFO] [stdout] test tests::ensure_proper_message_format ... ok [INFO] [stdout] test tests::colorize ... ok [INFO] [stdout] test tests::logpass ... ok [INFO] [stdout] test tests::send_simple_string ... ok [INFO] [stdout] Jun 5 2020 22:20:24.986918554+0000: 128 tst: Message [INFO] [stdout] test tests::send_successful_message ... ok [INFO] [stdout] Jun 5 2020 22:20:25.015611178+0000: 128 tst: Message [INFO] [stdout] test tests::info_is_enabled_by_default ... ok [INFO] [stdout] Jun 5 2020 22:20:25.027246732+0000: 128 tst: Message, value=Debug Value [INFO] [stderr] Jun 5 2020 22:20:25.023574060+0000: 128 test: An info message [INFO] [stdout] Jun 5 2020 22:20:25.027344323+0000: 128 tst: Message, value=Debug Value [INFO] [stderr] Jun 5 2020 22:20:25.023715153+0000: 064 test: A warning message [INFO] [stderr] Jun 5 2020 22:20:25.023770839+0000: 000 test: An error message [INFO] [stderr] Jun 5 2020 22:20:25.023825807+0000: 196 logger: Unable to receive message. Exiting logger, reason=receiving on an empty and disconnected channel [INFO] [stdout] test tests::test_spawn_test ... ok [INFO] [stdout] test tests::using_indebug ... ok [INFO] [stdout] test tests::spawn_void ... ok [INFO] [stdout] test tests::ensure_proper_message_format_line_ending_with_newline ... ok [INFO] [stdout] test tests::using_inhex ... ok [INFO] [stdout] Jun 5 2020 22:20:25.071603377+0000: 000 tst: Message [INFO] [stdout] test tests::error_is_enabled_by_default ... ok [INFO] [stdout] test tests::trace_is_disabled_by_default ... ok [INFO] [stdout] test tests::debug_is_disabled_by_default ... ok [INFO] [stdout] test tests::nested_contexts ... ok [INFO] [stdout] Jun 5 2020 22:20:25.155564283+0000: 064 tst: Message [INFO] [stdout] test tests::warn_is_enabled_by_default ... ok [INFO] [stdout] test tests::multistuff ... ok [INFO] [stdout] test tests::multiple_lines_count_correctly ... ok [INFO] [stdout] test tests::multiple_lines_count_correctly_trailing ... ok [INFO] [stderr] Doc-tests fast_logger [INFO] [stdout] [INFO] [stdout] test result: ok. 30 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] running 8 tests [INFO] [stdout] test lib.rs - (line 172) ... ok [INFO] [stdout] test lib.rs - (line 128) ... ok [INFO] [stdout] test lib.rs - (line 202) ... ok [INFO] [stdout] test lib.rs - (line 91) ... ok [INFO] [stdout] test lib.rs - (line 55) ... ok [INFO] [stdout] test lib.rs - (line 44) ... ok [INFO] [stdout] test lib.rs - Compatibility (line 267) ... ok [INFO] [stdout] test lib.rs - (line 221) ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 8 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out [INFO] [stdout] [INFO] running `"docker" "inspect" "83a38add359f5380a8545c259b9681d5438677de0cae9ba3bc66b48b3c0823df"` [INFO] running `"docker" "rm" "-f" "83a38add359f5380a8545c259b9681d5438677de0cae9ba3bc66b48b3c0823df"` [INFO] [stdout] 83a38add359f5380a8545c259b9681d5438677de0cae9ba3bc66b48b3c0823df