[INFO] cloning repository https://github.com/Taganyer/tlog-rust
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/Taganyer/tlog-rust" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2FTaganyer%2Ftlog-rust", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2FTaganyer%2Ftlog-rust'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] 41a9dda755874b52987d468437e2713564b893fa
[INFO] testing Taganyer/tlog-rust against master#ec6f9a5b4413f74386267ef8efc93712c2ce6db6 for pr-155739
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2FTaganyer%2Ftlog-rust" "/workspace/builds/worker-5-tc1/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-5-tc1/source'...
[INFO] [stderr] done.
[INFO] started tweaking git repo https://github.com/Taganyer/tlog-rust
[INFO] finished tweaking git repo https://github.com/Taganyer/tlog-rust
[INFO] tweaked toml for git repo https://github.com/Taganyer/tlog-rust written to /workspace/builds/worker-5-tc1/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/Taganyer/tlog-rust on toolchain ec6f9a5b4413f74386267ef8efc93712c2ce6db6
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+ec6f9a5b4413f74386267ef8efc93712c2ce6db6" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate git repo https://github.com/Taganyer/tlog-rust already has a lockfile, it will not be regenerated
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+ec6f9a5b4413f74386267ef8efc93712c2ce6db6" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Updating crates.io index
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded ringbuf v0.3.3
[INFO] [stderr]   Downloaded properties v0.1.1
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+ec6f9a5b4413f74386267ef8efc93712c2ce6db6" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] cdf3bc615676a798db415b91beaf9059b938ba2db11e66d3fb8503cc292108e3
[INFO] running `Command { std: "docker" "start" "-a" "cdf3bc615676a798db415b91beaf9059b938ba2db11e66d3fb8503cc292108e3", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "cdf3bc615676a798db415b91beaf9059b938ba2db11e66d3fb8503cc292108e3", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "cdf3bc615676a798db415b91beaf9059b938ba2db11e66d3fb8503cc292108e3", kill_on_drop: false }`
[INFO] [stdout] cdf3bc615676a798db415b91beaf9059b938ba2db11e66d3fb8503cc292108e3
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+ec6f9a5b4413f74386267ef8efc93712c2ce6db6" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 1158af6b4e2970d89ab563f04fa592b9786f9e20f813c672e80ffaee44f02e30
[INFO] running `Command { std: "docker" "start" "-a" "1158af6b4e2970d89ab563f04fa592b9786f9e20f813c672e80ffaee44f02e30", kill_on_drop: false }`
[INFO] [stderr]    Compiling parking_lot_core v0.9.12
[INFO] [stderr]    Compiling tokio-macros v2.6.1
[INFO] [stderr]    Compiling chrono v0.4.44
[INFO] [stderr]    Compiling serde_yaml v0.9.34+deprecated
[INFO] [stderr]    Compiling ringbuf v0.3.3
[INFO] [stderr]    Compiling bincode v1.3.3
[INFO] [stderr]    Compiling properties v0.1.1
[INFO] [stderr]    Compiling atomic v0.5.3
[INFO] [stderr]    Compiling parking_lot v0.12.5
[INFO] [stderr]    Compiling tokio v1.50.0
[INFO] [stderr]    Compiling tlog-rust v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 17.75s
[INFO] running `Command { std: "docker" "inspect" "1158af6b4e2970d89ab563f04fa592b9786f9e20f813c672e80ffaee44f02e30", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "1158af6b4e2970d89ab563f04fa592b9786f9e20f813c672e80ffaee44f02e30", kill_on_drop: false }`
[INFO] [stdout] 1158af6b4e2970d89ab563f04fa592b9786f9e20f813c672e80ffaee44f02e30
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+ec6f9a5b4413f74386267ef8efc93712c2ce6db6" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 856b0879127981d8b7fce4f8aa1897294d8f533cb619c13efa391163b607dffa
[INFO] running `Command { std: "docker" "start" "-a" "856b0879127981d8b7fce4f8aa1897294d8f533cb619c13efa391163b607dffa", kill_on_drop: false }`
[INFO] [stderr]    Compiling rustix v1.1.4
[INFO] [stderr]    Compiling getrandom v0.4.2
[INFO] [stderr]    Compiling tempfile v3.27.0
[INFO] [stderr]    Compiling tlog-rust v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 10.79s
[INFO] running `Command { std: "docker" "inspect" "856b0879127981d8b7fce4f8aa1897294d8f533cb619c13efa391163b607dffa", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "856b0879127981d8b7fce4f8aa1897294d8f533cb619c13efa391163b607dffa", kill_on_drop: false }`
[INFO] [stdout] 856b0879127981d8b7fce4f8aa1897294d8f533cb619c13efa391163b607dffa
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+ec6f9a5b4413f74386267ef8efc93712c2ce6db6" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 9de226d5aefbb5d71fd11d5b406c369b8eb115dc6d79566821765c3ff62bb40b
[INFO] running `Command { std: "docker" "start" "-a" "9de226d5aefbb5d71fd11d5b406c369b8eb115dc6d79566821765c3ff62bb40b", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.17s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/tlog_rust-a60275aeb30cf402)
[INFO] [stdout] 
[INFO] [stdout] running 103 tests
[INFO] [stderr] large message number 1 that will fill buffer
[INFO] [stderr] 
[INFO] [stdout] test appender::callback::tests::test_flush_does_nothing ... ok
[INFO] [stdout] test appender::callback::tests::test_callback_receives_correct_record ... ok
[INFO] [stdout] test appender::callback::tests::test_level_filtering ... ok
[INFO] [stderr] large message number 2 that will fill buffer
[INFO] [stderr] 
[INFO] [stdout] test appender::console::tests::test_all_types_send_sync ... ok
[INFO] [stderr] large message number 3 that will fill buffer
[INFO] [stderr] 
[INFO] [stderr] large message number 4 that will fill buffer
[INFO] [stderr] 
[INFO] [stderr] large message number 5 that will fill buffer
[INFO] [stderr] 
[INFO] [stdout] test appender::callback::tests::test_change_threshold ... ok
[INFO] [stderr] async 2
[INFO] [stdout] sync test message 0
[INFO] [stderr] 
[INFO] [stderr] async 3
[INFO] [stderr] 
[INFO] [stderr] large message number 6 that will fill buffer
[INFO] [stderr] 
[INFO] [stderr] large message number 7 that will fill buffer
[INFO] [stderr] 
[INFO] [stderr] large message number 8 that will fill buffer
[INFO] [stderr] 
[INFO] [stderr] large message number 9 that will fill buffer
[INFO] [stderr] 
[INFO] [stdout] test appender::console::tests::test_singleton ... ok
[INFO] [stderr] large message number 10 that will fill buffer
[INFO] [stderr] 
[INFO] [stderr] large message number 11 that will fill buffer
[INFO] [stderr] 
[INFO] [stderr] async 4
[INFO] [stderr] 
[INFO] [stderr] large message number 12 that will fill buffer
[INFO] [stderr] 
[INFO] [stdout] test appender::callback::tests::test_callback_called_for_each_log ... ok
[INFO] [stdout] test appender::console::tests::test_level_filtering ... ok
[INFO] [stderr] large message number 13 that will fill buffer
[INFO] [stderr] 
[INFO] [stderr] large message number 14 that will fill buffer
[INFO] [stderr] 
[INFO] [stderr] large message number 15 that will fill buffer
[INFO] [stderr] 
[INFO] [stderr] large message number 16 that will fill buffer
[INFO] [stderr] 
[INFO] [stderr] large message number 17 that will fill buffer
[INFO] [stderr] 
[INFO] [stdout] test appender::console::tests::test_sync_mode_append ... ok
[INFO] [stderr] large message number 18 that will fill buffer
[INFO] [stderr] 
[INFO] [stderr] large message number 19 that will fill buffer
[INFO] [stderr] 
[INFO] [stdout] test appender::console::tests::test_async_mode_buffer_flush ... ok
[INFO] [stdout] test appender::console::tests::test_async_buffer_full ... ok
[INFO] [stdout] test appender::console::tests::test_thread_safe_threshold_modify ... ok
[INFO] [stdout] test appender::rolling_file::tests::test_multiple_appenders_share_file ... ok
[INFO] [stdout] test appender::rolling_file::tests::test_max_backup_cleanup ... ok
[INFO] [stdout] test appender::rolling_file::tests::test_level_filtering ... ok
[INFO] [stdout] test appender::rolling_file::tests::test_single_file_write ... ok
[INFO] [stdout] test appender::rolling_file::tests::test_trigger_rotation ... ok
[INFO] [stdout] test appender::rolling_file::tests::test_pool_recovers_after_drop ... ok
[INFO] [stdout] test appender::rolling_file::tests::test_append_on_restart ... ok
[INFO] [stdout] test appender::tcp::tests::test_basic_send ... ok
[INFO] [stdout] test appender::tcp::tests::test_tcp_appender_is_send_and_sync ... ok
[INFO] [stdout] test config::tests::test_appender_threshold_filtering ... ok
[INFO] [stdout] test appender::tcp::tests::test_larger_than_buffer_direct_send ... ok
[INFO] [stdout] test appender::tcp::tests::test_buffer_full_trigger_flush ... ok
[INFO] [stdout] test appender::tcp::tests::test_large_message_above_free_space ... ok
[INFO] [stdout] test appender::tcp::tests::test_flush_residual_data ... ok
[INFO] [stdout] test appender::tcp::tests::test_level_filtering ... ok
[INFO] [stdout] test config::tests::test_default_config ... ok
[INFO] [stdout] test config::tests::test_combined_filtering ... ok
[INFO] [stdout] test config::tests::test_default_values ... ok
[INFO] [stdout] test config::tests::test_logger_level_filtering ... ok
[INFO] [stdout] test config::tests::test_logger_inheritance ... ok
[INFO] [stdout] test config::tests::test_validate_appender ... ok
[INFO] [stdout] test config::tests::test_send_sync ... ok
[INFO] [stdout] test config_parser::tests::test_invalid_level_gives_warning ... ok
[INFO] [stdout] test config_parser::tests::test_normalize_type ... ok
[INFO] [stdout] test config_parser::tests::test_parse_bool ... ok
[INFO] [stdout] test config_parser::tests::test_parse_appender ... ok
[INFO] [stdout] test config_parser::tests::test_parse_global_config ... ok
[INFO] [stdout] test config_parser::tests::test_parse_comments_and_empty_lines ... ok
[INFO] [stdout] test config_parser::tests::test_parse_named_loggers ... ok
[INFO] [stdout] test config_parser::tests::test_parse_size ... ok
[INFO] [stdout] test config_parser::tests::test_parse_layout ... ok
[INFO] [stdout] test config_parser::tests::test_split_list ... ok
[INFO] [stdout] test config_parser::tests::test_unknown_keys_are_ignored ... ok
[INFO] [stdout] test config_parser::tests::test_parse_root_logger ... ok
[INFO] [stdout] test layout::pattern::tests::test_combined_modifier ... ok
[INFO] [stdout] test config_parser::tests::test_parse_time ... ok
[INFO] [stdout] test config_parser::tests::test_split_key_value ... ok
[INFO] [stdout] test layout::iso8601::tests::test_send_sync ... ok
[INFO] [stdout] test layout::iso8601::tests::test_iso8601_format ... ok
[INFO] [stdout] test layout::pattern::tests::test_message ... ok
[INFO] [stdout] test layout::pattern::tests::test_left_pad ... ok
[INFO] [stdout] test layout::pattern::tests::test_newline ... ok
[INFO] [stdout] test layout::pattern::tests::test_percent ... ok
[INFO] [stdout] test layout::pattern::tests::test_level ... ok
[INFO] [stdout] test layout::pattern::tests::test_logger_name ... ok
[INFO] [stdout] test layout::pattern::tests::test_process_id ... ok
[INFO] [stdout] test layout::pattern::tests::test_right_pad ... ok
[INFO] [stdout] test layout::pattern::tests::test_thread_id ... ok
[INFO] [stdout] test layout::pattern::tests::test_send_sync ... ok
[INFO] [stdout] test layout::pattern::tests::test_truncate ... ok
[INFO] [stdout] test layout::ttcc::tests::test_send_sync ... ok
[INFO] [stdout] test layout::simple::tests::test_simple_layout ... ok
[INFO] [stdout] test layout::simple::tests::test_send_sync ... ok
[INFO] [stdout] test level::tests::test_display ... ok
[INFO] [stdout] test level::tests::test_from_str ... ok
[INFO] [stdout] test level::tests::test_level_ordering ... ok
[INFO] [stdout] test logger::tests::test_additivity_false ... ok
[INFO] [stdout] test layout::pattern::tests::test_date_format ... ok
[INFO] [stdout] test logger::tests::test_empty_appender_list ... ok
[INFO] [stdout] test logger::tests::test_convenience_methods ... ok
[INFO] [stdout] test logger::tests::test_find_parent_name ... ok
[INFO] [stdout] test logger::tests::test_multiple_appenders ... ok
[INFO] [stdout] test logger::tests::test_level_filtering ... ok
[INFO] [stdout] test logger::tests::test_parent_inheritance ... ok
[INFO] [stdout] test logger::tests::test_set_level_runtime ... ok
[INFO] [stdout] test manager::tests::test_double_init_fails ... ok
[INFO] [stdout] test manager::tests::test_flush_all ... ok
[INFO] [stdout] test manager::tests::test_lazy_creation ... ok
[INFO] [stdout] test manager::tests::test_init_default ... ok
[INFO] [stdout] test manager::tests::test_register_callback_not_found ... ok
[INFO] [stdout] test message
[INFO] [stdout] test manager::tests::test_register_callback_success ... ok
[INFO] [stdout] test manager::tests::test_send_sync ... ok
[INFO] [stdout] test manager::tests::test_register_callback_not_callback_appender ... ok
[INFO] [stdout] test layout::pattern::tests::test_millisecond_microsecond ... ok
[INFO] [stdout] test record::tests::test_create_record ... ok
[INFO] [stdout] info
[INFO] [stdout] warn
[INFO] [stdout] error
[INFO] [stdout] fatal
[INFO] [stdout] test manager::tests::test_static_logging_methods ... ok
[INFO] [stdout] msg1
[INFO] [stdout] msg2
[INFO] [stdout] test manager::tests::test_set_callback_multiple_times ... ok
[INFO] [stdout] test scheduler::tests::test_concurrent_register ... ok
[INFO] [stdout] test scheduler::tests::test_manual_wake ... ok
[INFO] [stdout] test layout::ttcc::tests::test_ttcc_format ... ok
[INFO] [stdout] test scheduler::tests::test_register_unregister ... ok
[INFO] [stdout] test layout::pattern::tests::test_full_pattern ... ok
[INFO] [stdout] test logger::tests::test_concurrent_logging ... ok
[INFO] [stdout] test scheduler::tests::test_auto_cleanup ... ok
[INFO] [stdout] test scheduler::tests::test_shutdown_flushes_all ... ok
[INFO] [stdout] test server::tests::test_assert_send_sync ... ok
[INFO] [stdout] test server::tests::test_basic_server_start ... ok
[INFO] [stdout] test scheduler::tests::test_periodic_flush ... ok
[INFO] [stdout] test appender::tcp::tests::test_min_reconnect_interval ... ok
[INFO] [stdout] test server::tests::test_e2e_client_server ... ok
[INFO] [stdout] test appender::tcp::tests::test_disconnect_drops_logs ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 103 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 2.04s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests tlog_rust
[INFO] [stdout] 
[INFO] [stdout] running 3 tests
[INFO] [stdout] test src/layout/pattern.rs - layout::pattern::PatternLayout (line 87) ... ok
[INFO] [stdout] test src/appender/callback.rs - appender::callback::CallbackAppender (line 38) ... ok
[INFO] [stdout] test src/manager.rs - manager::LogManager (line 45) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stdout] all doctests ran in 0.71s; merged doctests compilation took 0.68s
[INFO] running `Command { std: "docker" "inspect" "9de226d5aefbb5d71fd11d5b406c369b8eb115dc6d79566821765c3ff62bb40b", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "9de226d5aefbb5d71fd11d5b406c369b8eb115dc6d79566821765c3ff62bb40b", kill_on_drop: false }`
[INFO] [stdout] 9de226d5aefbb5d71fd11d5b406c369b8eb115dc6d79566821765c3ff62bb40b
