[INFO] updating cached repository https://github.com/chaintope/tapyrus-signer [INFO] running `"git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "-c" "remote.origin.fetch=refs/heads/*:refs/heads/*" "fetch" "origin" "--force" "--prune"` [INFO] [stderr] From https://github.com/chaintope/tapyrus-signer [INFO] [stderr] bb3ce97..5ebf8c3 master -> master [INFO] [stderr] * [new branch] release/v0.4.0 -> release/v0.4.0 [INFO] running `"git" "rev-parse" "HEAD"` [INFO] [stdout] 5ebf8c32fa7612eabeb10728cab4e2f821dff02e [INFO] testing chaintope/tapyrus-signer against master#28742a1146f10a4f09369baad027a464acb7a766 for pr-71274 [INFO] running `"git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fchaintope%2Ftapyrus-signer" "/workspace/builds/worker-4/source"` [INFO] [stderr] Cloning into '/workspace/builds/worker-4/source'... [INFO] [stderr] done. [INFO] validating manifest of git repo https://github.com/chaintope/tapyrus-signer on toolchain 28742a1146f10a4f09369baad027a464acb7a766 [INFO] running `"/workspace/cargo-home/bin/cargo" "+28742a1146f10a4f09369baad027a464acb7a766" "read-manifest" "--manifest-path" "Cargo.toml"` [INFO] started tweaking git repo https://github.com/chaintope/tapyrus-signer [INFO] finished tweaking git repo https://github.com/chaintope/tapyrus-signer [INFO] tweaked toml for git repo https://github.com/chaintope/tapyrus-signer written to /workspace/builds/worker-4/source/Cargo.toml [INFO] crate git repo https://github.com/chaintope/tapyrus-signer already has a lockfile, it will not be regenerated [INFO] running `"/workspace/cargo-home/bin/cargo" "+28742a1146f10a4f09369baad027a464acb7a766" "fetch" "--locked" "--manifest-path" "Cargo.toml"` [INFO] [stderr] Blocking waiting for file lock on package cache [INFO] [stderr] Blocking waiting for file lock on package cache [INFO] running `"docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4/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=forbid" "-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" "+28742a1146f10a4f09369baad027a464acb7a766" "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] 90586284de8957fe4f86c604215a6d07fc61468674d85c63b3e5d78ee3d4edef [INFO] running `"docker" "start" "-a" "90586284de8957fe4f86c604215a6d07fc61468674d85c63b3e5d78ee3d4edef"` [INFO] [stderr] Compiling syn v1.0.8 [INFO] [stderr] Compiling log v0.4.8 [INFO] [stderr] Compiling serde v1.0.102 [INFO] [stderr] Compiling byte-tools v0.3.1 [INFO] [stderr] Compiling cc v1.0.41 [INFO] [stderr] Compiling ident_case v1.0.1 [INFO] [stderr] Compiling derive_builder v0.9.0 [INFO] [stderr] Compiling redis v0.10.0 [INFO] [stderr] Compiling constant_time_eq v0.1.4 [INFO] [stderr] Compiling hex v0.3.2 [INFO] [stderr] Compiling keccak v0.1.0 [INFO] [stderr] Compiling bech32 v0.7.1 [INFO] [stderr] Compiling termcolor v1.0.5 [INFO] [stderr] Compiling zeroize v0.10.1 [INFO] [stderr] Compiling num-traits v0.2.9 [INFO] [stderr] Compiling rust-crypto v0.2.36 [INFO] [stderr] Compiling unicase v1.4.2 [INFO] [stderr] Compiling humantime v1.3.0 [INFO] [stderr] Compiling block-padding v0.1.5 [INFO] [stderr] Compiling tokio-executor v0.1.8 [INFO] [stderr] Compiling blake2b_simd v0.5.9 [INFO] [stderr] Compiling rand v0.4.6 [INFO] [stderr] Compiling rand_os v0.1.3 [INFO] [stderr] Compiling daemonize v0.4.1 [INFO] [stderr] Compiling base64 v0.9.3 [INFO] [stderr] Compiling bitcoin_hashes v0.3.2 [INFO] [stderr] Compiling combine v3.8.1 [INFO] [stderr] Compiling bytes v0.4.12 [INFO] [stderr] Compiling clap v2.33.0 [INFO] [stderr] Compiling signal-hook v0.1.12 [INFO] [stderr] Compiling unicode-normalization v0.1.9 [INFO] [stderr] Compiling block-buffer v0.7.3 [INFO] [stderr] Compiling digest v0.8.1 [INFO] [stderr] Compiling rand v0.6.5 [INFO] [stderr] Compiling http v0.1.19 [INFO] [stderr] Compiling sha3 v0.8.2 [INFO] [stderr] Compiling sha2 v0.8.1 [INFO] [stderr] Compiling parking_lot v0.9.0 [INFO] [stderr] Compiling tokio-io v0.1.12 [INFO] [stderr] Compiling log v0.3.9 [INFO] [stderr] Compiling mio v0.6.19 [INFO] [stderr] Compiling env_logger v0.6.2 [INFO] [stderr] Compiling rand v0.3.23 [INFO] [stderr] Compiling idna v0.1.5 [INFO] [stderr] Compiling mime v0.2.6 [INFO] [stderr] Compiling tokio-codec v0.1.1 [INFO] [stderr] Compiling url v1.7.2 [INFO] [stderr] Compiling secp256k1 v0.15.5 [INFO] [stderr] Compiling tokio-reactor v0.1.10 [INFO] [stderr] Compiling hyper v0.10.16 [INFO] [stderr] Compiling tokio-tcp v0.1.3 [INFO] [stderr] Compiling merkle-sha3 v0.1.0 [INFO] [stderr] Compiling darling_core v0.10.2 [INFO] [stderr] Compiling serde_derive v1.0.102 [INFO] [stderr] Compiling darling_macro v0.10.2 [INFO] [stderr] Compiling darling v0.10.2 [INFO] [stderr] Compiling derive_builder_core v0.9.0 [INFO] [stderr] Compiling serde_json v1.0.41 [INFO] [stderr] Compiling bitcoin_hashes v0.7.1 [INFO] [stderr] Compiling toml v0.5.5 [INFO] [stderr] Compiling rust-gmp v0.5.0 (https://github.com/KZen-networks/rust-gmp#a15e0ec2) [INFO] [stderr] Compiling jsonrpc v0.11.0 [INFO] [stderr] Compiling curv v0.2.0 (https://github.com/KZen-networks/curv?tag=v0.2.0#1d858a07) [INFO] [stderr] Compiling bitcoin v0.20.0 [INFO] [stderr] Compiling tapyrus_signer v0.3.1 (/opt/rustwide/workdir) [INFO] [stderr] Finished dev [unoptimized + debuginfo] target(s) in 3m 09s [INFO] running `"docker" "inspect" "90586284de8957fe4f86c604215a6d07fc61468674d85c63b3e5d78ee3d4edef"` [INFO] running `"docker" "rm" "-f" "90586284de8957fe4f86c604215a6d07fc61468674d85c63b3e5d78ee3d4edef"` [INFO] [stdout] 90586284de8957fe4f86c604215a6d07fc61468674d85c63b3e5d78ee3d4edef [INFO] running `"docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4/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=forbid" "-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" "+28742a1146f10a4f09369baad027a464acb7a766" "test" "--frozen" "--no-run"` [INFO] [stderr] WARNING: Your kernel does not support swap limit capabilities or the cgroup is not mounted. Memory limited without swap. [INFO] [stdout] 4bc94665258425ce2308cfc4e9be463c500ff3b653eb0d4dbb715d12af4f5bcd [INFO] running `"docker" "start" "-a" "4bc94665258425ce2308cfc4e9be463c500ff3b653eb0d4dbb715d12af4f5bcd"` [INFO] [stderr] Compiling tapyrus_signer v0.3.1 (/opt/rustwide/workdir) [INFO] [stderr] Finished test [unoptimized + debuginfo] target(s) in 33.40s [INFO] running `"docker" "inspect" "4bc94665258425ce2308cfc4e9be463c500ff3b653eb0d4dbb715d12af4f5bcd"` [INFO] running `"docker" "rm" "-f" "4bc94665258425ce2308cfc4e9be463c500ff3b653eb0d4dbb715d12af4f5bcd"` [INFO] [stdout] 4bc94665258425ce2308cfc4e9be463c500ff3b653eb0d4dbb715d12af4f5bcd [INFO] running `"docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4/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=forbid" "-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" "+28742a1146f10a4f09369baad027a464acb7a766" "test" "--frozen"` [INFO] [stdout] 798d514ad1dac6cab6dd87872873ccc5ed1fafadaf8f800d39aeddd13af2a48d [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" "798d514ad1dac6cab6dd87872873ccc5ed1fafadaf8f800d39aeddd13af2a48d"` [INFO] [stderr] Finished test [unoptimized + debuginfo] target(s) in 0.89s [INFO] [stderr] Running /opt/rustwide/target/debug/deps/tapyrus_signer-34039deeb70c325e [INFO] [stdout] [INFO] [stdout] running 123 tests [INFO] [stdout] test blockdata::tests::test_block_debug_fmt ... ok [INFO] [stdout] test blockdata::tests::test_add_aggregated_public_key ... ok [INFO] [stdout] test blockdata::tests::test_add_proof ... ok [INFO] [stdout] test blockdata::tests::test_block_hash_debug_fmt ... ok [INFO] [stdout] test blockdata::tests::test_get_header_without_proof ... ok [INFO] [stdout] test blockdata::tests::test_get_aggregated_public_key ... ok [INFO] [stdout] test blockdata::tests::test_block_serialize ... ok [INFO] [stdout] test cli::setup::aggregate::tests::test_aggregate_response ... ok [INFO] [stdout] test cli::setup::compute_sig::tests::test_execute_invalid_aggregated_public_key ... ok [INFO] [stdout] test cli::setup::aggregate::tests::test_execute_invalid_private_key ... ok [INFO] [stdout] test cli::setup::compute_sig::tests::test_execute_invalid_node_vss ... ok [INFO] [stdout] test cli::setup::compute_sig::tests::test_execute_invalid_block ... ok [INFO] [stdout] test cli::setup::aggregate::tests::test_execute_invalid_vss ... ok [INFO] [stdout] test cli::setup::compute_sig::tests::test_execute_invalid_threshold ... ok [INFO] [stdout] test cli::setup::compute_sig::tests::test_execute_invalid_block_vss ... ok [INFO] [stdout] test cli::setup::create_block_vss::tests::test_execute_invalid_private_key ... ok [INFO] [stdout] test cli::setup::compute_sig::tests::test_execute_invalid_node_secret_share ... ok [INFO] [stdout] test cli::setup::create_block_vss::tests::test_execute_invalid_public_key ... ok [INFO] [stdout] test cli::setup::compute_sig::tests::test_execute_invalid_private_key ... ok [INFO] [stdout] test cli::setup::create_key::test::test_format ... ok [INFO] [stdout] test cli::setup::create_block_vss::tests::test_execute_invalid_threshold ... ok [INFO] [stdout] test cli::setup::create_node_vss::tests::test_execute_invalid_public_key ... ok [INFO] [stdout] test cli::setup::compute_sig::tests::test_execute_invalid_sig ... ok [INFO] [stdout] test cli::setup::sign::tests::test_execute_invalid_block_vss ... ok [INFO] [stdout] test cli::setup::create_node_vss::tests::test_execute_invalid_private_key ... ok [INFO] [stdout] test cli::setup::sign::tests::test_execute_invalid_aggregated_public_key ... ok [INFO] [stdout] test command_args::test_allow_no_exists_config_file ... ok [INFO] [stdout] test cli::setup::sign::tests::test_execute_invalid_threshold ... ok [INFO] [stdout] test cli::setup::sign::tests::test_execute_invalid_private_key ... ok [INFO] [stdout] test cli::setup::sign::tests::test_execute_invalid_block ... ok [INFO] [stdout] test command_args::test_priority_commandline ... ok [INFO] [stdout] test command_args::test_load ... ok [INFO] [stdout] test command_args::test_load_from_file ... ok [INFO] [stdout] test cli::setup::sign::tests::test_execute_invalid_node_secret_share ... ok [INFO] [stdout] test crypto::multi_party_schnorr::tests::test_compute_e ... ok [INFO] [stdout] test crypto::vss::tests::test_create_block_shares ... ok [INFO] [stdout] test cli::setup::tests::test_index_of ... ok [INFO] [stdout] test cli::setup::aggregate::tests::test_execute ... ok [INFO] [stdout] test crypto::vss::tests::test_create_local_sig_from_shares ... ok [INFO] [stdout] test cli::setup::compute_sig::tests::test_execute ... ok [INFO] [stdout] test crypto::vss::tests::test_decode_commitment ... ok [INFO] [stdout] test crypto::vss::tests::test_create_node_shares ... ok [INFO] [stdout] test crypto::vss::tests::test_encode_commitment ... ok [INFO] [stdout] test crypto::vss::tests::test_encode_vss ... ok [INFO] [stdout] test crypto::vss::tests::test_decode_vss ... ok [INFO] [stdout] test crypto::test_multi_party_schnorr::test_t2_n4 ... ok [INFO] [stdout] test crypto::test_multi_party_schnorr::test_sign ... ok [INFO] [stdout] test crypto::vss::tests::test_serde_support ... ok [INFO] [stdout] test cli::setup::create_block_vss::tests::test_execute ... ok [INFO] [stdout] test cli::setup::create_node_vss::tests::test_execute ... ok [INFO] [stdout] test federation::tests::test_from_pubkey_and_toml ... ok [INFO] [stdout] test cli::setup::sign::tests::test_execute ... ok [INFO] [stdout] test net::test::redis_connection_test ... ignored [INFO] [stdout] test crypto::test_multi_party_schnorr::test_t2_n5_sign_with_4 ... ok [INFO] [stdout] test federation::tests::test_serialize_deserialize ... ok [INFO] [stdout] test net::test::signer_id_deserialize_test ... ok [INFO] [stdout] test net::test::test_sort_signer_id ... ok [INFO] [stdout] test rpc::tests::test_getnewblock ... ignored [INFO] [stdout] test rpc::tests::test_testproposedblock ... ignored [INFO] [stdout] test sign::test_create_key ... ok [INFO] [stdout] test sign::test_format_signature ... ok [INFO] [stdout] test sign::test_private_key_to_big_int ... ok [INFO] [stdout] test federation::tests::test_federations_validate ... ok [INFO] [stdout] test net::test::signer_id_serialize_test ... ok [INFO] [stdout] test federation::tests::test_signers ... ok [INFO] [stdout] test key::tests::test_generate_key_pair ... ok [INFO] [stdout] test federation::tests::test_get_by_block_height ... ok [INFO] [stdout] test cli::setup::compute_sig::tests::test_execute_wrong_number_of_sig ... ok [INFO] [stdout] test command_args::test_invalid_to_address ... ok [INFO] [stdout] test cli::setup::compute_sig::tests::test_execute_wrong_number_of_block_vss ... ok [INFO] [stdout] test command_args::test_invalid_public_key ... ok [INFO] [stdout] test command_args::test_no_public_key ... ok [INFO] [stdout] test crypto::vss::tests::test_create_node_shares_invalid_large_threshold ... ok [INFO] [stdout] test net::test::test_error_when_sending_message_without_redis_connection ... ok [INFO] [stdout] test command_args::test_invalid_format_config_file ... ok [INFO] [stdout] test crypto::vss::tests::test_create_block_shares_invalid_large_threshold ... ok [INFO] [stdout] test signer_node::message_processor::process_blockparticipants::tests::test_process_blockparticipants_master_from_fake_master ... ok [INFO] [stdout] test federation::tests::test_federation_validate ... ok [INFO] [stdout] test signer_node::message_processor::process_blockparticipants::tests::test_process_blockparticipants_master ... ok [INFO] [stdout] test signer_node::message_processor::process_blockparticipants::tests::test_process_blockparticipants_member ... ok [INFO] [stdout] test signer_node::message_processor::process_blockvss::tests::test_process_blockvss_master_with_1_shared_block_secrets ... ok [INFO] [stdout] test signer_node::message_processor::process_blockparticipants::tests::test_process_blockparticipants_not_include_the_node ... ok [INFO] [stdout] test signer_node::message_processor::process_blockparticipants::tests::test_process_blockparticipants_member_from_fake_master ... ok [INFO] [stdout] test signer_node::message_processor::process_blockparticipants::tests::test_process_blockparticipants_with_shortage_shared_block_secrets ... ok [INFO] [stdout] test signer_node::message_processor::process_blocksig::tests::test_process_blocksig_1_valid_block ... ok [INFO] [stdout] test signer_node::message_processor::process_blockvss::tests::test_process_blockvss_member_with_1_shared_block_secrets ... ok [INFO] [stdout] test signer_node::message_processor::process_blockvss::tests::test_process_blockvss_member_without_block ... ok [INFO] [stdout] test signer_node::message_processor::process_blocksig::tests::test_process_blocksig_invalid_block ... ok [INFO] [stdout] test signer_node::message_processor::process_blocksig::tests::test_process_blocksig_from_non_participants ... ok [INFO] [stdout] test signer_node::message_processor::process_blockvss::tests::test_process_blockvss_master_invalid_block ... ok [INFO] [stdout] test signer_node::message_processor::process_blocksig::tests::test_process_blocksig_successfully ... ok [INFO] [stdout] test signer_node::message_processor::tests::test_get_valid_block_invalid_blockhash_for_master ... ok [INFO] [stdout] test signer_node::message_processor::tests::test_get_valid_block_invalid_blockhash_for_member ... ok [INFO] [stdout] test signer_node::message_processor::tests::test_get_valid_block_invalid_node_state ... ok [INFO] [stdout] test signer_node::message_processor::tests::test_get_valid_block_valid_for_master ... ok [INFO] [stdout] test signer_node::message_processor::tests::test_get_valid_block_valid_for_member ... ok [INFO] [stdout] test signer_node::message_processor::process_blocksig::tests::test_process_blocksig_for_member ... ok [INFO] [stdout] test signer_node::message_processor::process_blocksig::tests::test_process_blocksig_with_invalid_signature ... ok [INFO] [stdout] test signer_node::message_processor::process_candidateblock::tests::test_as_master_with_valid_args ... ok [INFO] [stdout] test signer_node::message_processor::process_blockvss::tests::test_process_blockvss_member_invalid_block ... ok [INFO] [stdout] test signer_node::message_processor::process_blocksig::tests::test_process_blocksig_receiving_invalid_signature ... ok [INFO] [stderr] [2020-04-24T22:54:41Z TRACE tapyrus_signer::timer] Start Timer name=test timer timelimit=1s [INFO] [stdout] test signer_node::message_processor::process_blockvss::tests::test_process_blockvss_master_with_all_shared_block_secrets_has_participants ... ok [INFO] [stdout] test signer_node::message_processor::process_blockvss::tests::test_process_blockvss_member_had_got_participants_first ... ok [INFO] [stderr] [2020-04-24T22:54:41Z TRACE tapyrus_signer::timer] Start Timer name=test timer timelimit=0ns [INFO] [stderr] [2020-04-24T22:54:41Z TRACE tapyrus_signer::timer] Stop Timer by time out name=test timer [INFO] [stderr] [2020-04-24T22:54:41Z TRACE tapyrus_signer::timer] Start Timer name=test timer timelimit=1s [INFO] [stderr] [2020-04-24T22:54:41Z TRACE tapyrus_signer::timer] RoundTimeoutObserverThread finished. [INFO] [stdout] test timer::tests::test_timeout_signal ... ok [INFO] [stdout] test signer_node::tests::test_for_waiting_ibd_finish::test_wait_for_ibd_finish ... ok [INFO] [stdout] test util::tests::test_jacobi ... ok [INFO] [stderr] [2020-04-24T22:54:41Z TRACE tapyrus_signer::timer] Start Timer name=test timer timelimit=1s [INFO] [stderr] [2020-04-24T22:54:41Z TRACE tapyrus_signer::timer] Stop Timer by Stop command name=test timer [INFO] [stderr] [2020-04-24T22:54:41Z TRACE tapyrus_signer::timer] RoundTimeoutObserverThread finished. [INFO] [stdout] test signer_node::message_processor::process_candidateblock::tests::test_as_member_with_valid_args ... ok [INFO] [stdout] test util::tests::test_sum_point ... ok [INFO] [stdout] test util::tests::test_signals ... ok [INFO] [stdout] test signer_node::node_parameters::tests::test_sort_publickey ... ok [INFO] [stdout] test signer_node::message_processor::process_candidateblock::tests::test_as_member_with_invalid_block ... ok [INFO] [stderr] [2020-04-24T22:54:42Z TRACE tapyrus_signer::timer] Start Timer name=round_timer timelimit=10s [INFO] [stderr] [2020-04-24T22:54:42Z TRACE tapyrus_signer::timer] Stop Timer by Stop command name=round_timer [INFO] [stderr] [2020-04-24T22:54:42Z TRACE tapyrus_signer::timer] RoundTimeoutObserverThread finished. [INFO] [stderr] [2020-04-24T22:54:42Z TRACE tapyrus_signer::timer] Start Timer name=round_timer timelimit=10s [INFO] [stdout] test signer_node::tests::test_is_federation_member ... ok [INFO] [stdout] test signer_node::message_processor::process_candidateblock::tests::test_as_master_with_invalid_block ... ok [INFO] [stdout] test signer_node::message_processor::process_completedblock::tests::test_process_completedblock_with_submit_block_failure ... ok [INFO] [stdout] test signer_node::message_processor::process_completedblock::tests::test_process_completedblock_ignore_different_master ... ok [INFO] [stdout] test signer_node::message_processor::process_completedblock::tests::test_process_completedblock ... ok [INFO] [stdout] test signer_node::message_processor::process_candidateblock::tests::test_verify_aggregated_public_key ... ok [INFO] [stdout] test timer::tests::test_prevent_duplicate_start ... ok [INFO] [stderr] [2020-04-24T22:54:42Z TRACE tapyrus_signer::timer] Stop Timer by time out name=test timer [INFO] [stdout] test signer_node::message_processor::process_candidateblock::tests::test_modify_master_index ... ok [INFO] [stderr] [2020-04-24T22:54:42Z TRACE tapyrus_signer::timer] RoundTimeoutObserverThread finished. [INFO] [stderr] [2020-04-24T22:54:42Z TRACE tapyrus_signer::timer] Stop Timer by time out name=test timer [INFO] [stderr] [2020-04-24T22:54:42Z TRACE tapyrus_signer::timer] RoundTimeoutObserverThread finished. [INFO] [stdout] test timer::tests::test_timeout_and_restart ... FAILED [INFO] [stdout] test timer::tests::test_timer_stop ... ok [INFO] [stderr] [2020-04-24T22:54:47Z TRACE tapyrus_signer::timer] Start Timer name=round_timer timelimit=10s [INFO] [stderr] [2020-04-24T22:54:52Z TRACE tapyrus_signer::timer] Stop Timer by time out name=round_timer [INFO] [stdout] test signer_node::tests::test_start_next_round ... ok [INFO] [stderr] [2020-04-24T22:54:52Z TRACE tapyrus_signer::timer] RoundTimeoutObserverThread finished. [INFO] [stderr] [2020-04-24T22:54:57Z TRACE tapyrus_signer::timer] Stop Timer by time out name=round_timer [INFO] [stderr] [2020-04-24T22:54:57Z TRACE tapyrus_signer::timer] RoundTimeoutObserverThread finished. [INFO] [stderr] [2020-04-24T22:54:57Z TRACE tapyrus_signer::timer] Start Timer name=round_timer timelimit=10s [INFO] [stderr] [2020-04-24T22:54:58Z TRACE tapyrus_signer::timer] Stop Timer by Stop command name=round_timer [INFO] [stderr] [2020-04-24T22:54:58Z TRACE tapyrus_signer::timer] RoundTimeoutObserverThread finished. [INFO] [stdout] test signer_node::tests::test_timeout_roundrobin ... ok [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] [INFO] [stdout] ---- timer::tests::test_timeout_and_restart stdout ---- [INFO] [stdout] thread 'timer::tests::test_timeout_and_restart' panicked at 'Timeout signal not received. Timeout', src/timer.rs:205:23 [INFO] [stdout] stack backtrace: [INFO] [stdout] 0: 0x55895495c284 - backtrace::backtrace::libunwind::trace::hbb4ce8c04526962e [INFO] [stdout] at /cargo/registry/src/github.com-1ecc6299db9ec823/backtrace-0.3.46/src/backtrace/libunwind.rs:86 [INFO] [stdout] 1: 0x55895495c284 - backtrace::backtrace::trace_unsynchronized::h79251c088e7be038 [INFO] [stdout] at /cargo/registry/src/github.com-1ecc6299db9ec823/backtrace-0.3.46/src/backtrace/mod.rs:66 [INFO] [stdout] 2: 0x55895495c284 - std::sys_common::backtrace::_print_fmt::h6b0ea30a9223acae [INFO] [stdout] at src/libstd/sys_common/backtrace.rs:78 [INFO] [stdout] 3: 0x55895495c284 - ::fmt::h4d51ef07ce58e54b [INFO] [stdout] at src/libstd/sys_common/backtrace.rs:59 [INFO] [stdout] 4: 0x55895498865c - core::fmt::write::he16b9c1345d8565e [INFO] [stdout] at src/libcore/fmt/mod.rs:1069 [INFO] [stdout] 5: 0x558954598ef5 - std::io::Write::write_fmt::hc31efe493f037457 [INFO] [stdout] at /rustc/28742a1146f10a4f09369baad027a464acb7a766/src/libstd/io/mod.rs:1504 [INFO] [stdout] 6: 0x558954953fc1 - std::io::impls::>::write_fmt::hfcb1ff6c5b834381 [INFO] [stdout] at src/libstd/io/impls.rs:156 [INFO] [stdout] 7: 0x55895495eb75 - std::sys_common::backtrace::_print::h9288fa7d9f6629cb [INFO] [stdout] at src/libstd/sys_common/backtrace.rs:62 [INFO] [stdout] 8: 0x55895495eb75 - std::sys_common::backtrace::print::h82e606e62781d8af [INFO] [stdout] at src/libstd/sys_common/backtrace.rs:49 [INFO] [stdout] 9: 0x55895495eb75 - std::panicking::default_hook::{{closure}}::h1fe350ecbf7604e4 [INFO] [stdout] at src/libstd/panicking.rs:198 [INFO] [stdout] 10: 0x55895495e85b - std::panicking::default_hook::h1b8b42953fdc841d [INFO] [stdout] at src/libstd/panicking.rs:215 [INFO] [stdout] 11: 0x55895495f1d2 - std::panicking::rust_panic_with_hook::he73826f4bb75a21e [INFO] [stdout] at src/libstd/panicking.rs:511 [INFO] [stdout] 12: 0x55895495edbb - rust_begin_unwind [INFO] [stdout] at src/libstd/panicking.rs:419 [INFO] [stdout] 13: 0x55895495ed2b - std::panicking::begin_panic_fmt::hf14ab99546f1bbc1 [INFO] [stdout] at src/libstd/panicking.rs:373 [INFO] [stdout] 14: 0x558953ffce17 - tapyrus_signer::timer::tests::test_timeout_and_restart::h37d61e526dab6c8f [INFO] [stdout] at src/timer.rs:205 [INFO] [stdout] 15: 0x558953ffc82a - tapyrus_signer::timer::tests::test_timeout_and_restart::{{closure}}::hb42efbb8f3ddb240 [INFO] [stdout] at src/timer.rs:199 [INFO] [stdout] 16: 0x558954093cde - core::ops::function::FnOnce::call_once::h0ad0a1a44efcf83c [INFO] [stdout] at /rustc/28742a1146f10a4f09369baad027a464acb7a766/src/libcore/ops/function.rs:232 [INFO] [stdout] 17: 0x5589545a4def - as core::ops::function::FnOnce>::call_once::he1ba1da20629a2d8 [INFO] [stdout] at /rustc/28742a1146f10a4f09369baad027a464acb7a766/src/liballoc/boxed.rs:1008 [INFO] [stdout] 18: 0x5589545c03ee - as core::ops::function::FnOnce<()>>::call_once::hf94927858761de4a [INFO] [stdout] at /rustc/28742a1146f10a4f09369baad027a464acb7a766/src/libstd/panic.rs:318 [INFO] [stdout] 19: 0x5589545c03ee - std::panicking::try::do_call::h1585003392664d93 [INFO] [stdout] at /rustc/28742a1146f10a4f09369baad027a464acb7a766/src/libstd/panicking.rs:331 [INFO] [stdout] 20: 0x5589545c03ee - std::panicking::try::h43d950f958a74468 [INFO] [stdout] at /rustc/28742a1146f10a4f09369baad027a464acb7a766/src/libstd/panicking.rs:274 [INFO] [stdout] 21: 0x5589545c03ee - std::panic::catch_unwind::hb5284de2977ddeea [INFO] [stdout] at /rustc/28742a1146f10a4f09369baad027a464acb7a766/src/libstd/panic.rs:394 [INFO] [stdout] 22: 0x5589545c03ee - test::run_test_in_process::had39bb305b26a3ae [INFO] [stdout] at src/libtest/lib.rs:541 [INFO] [stdout] 23: 0x5589545c03ee - test::run_test::run_test_inner::{{closure}}::h12b1756b854ada1a [INFO] [stdout] at src/libtest/lib.rs:450 [INFO] [stdout] 24: 0x5589545983a6 - std::sys_common::backtrace::__rust_begin_short_backtrace::hec04b80bece92bf9 [INFO] [stdout] at /rustc/28742a1146f10a4f09369baad027a464acb7a766/src/libstd/sys_common/backtrace.rs:130 [INFO] [stdout] 25: 0x55895459d6c5 - std::thread::Builder::spawn_unchecked::{{closure}}::{{closure}}::heca431434c1ac418 [INFO] [stdout] at /rustc/28742a1146f10a4f09369baad027a464acb7a766/src/libstd/thread/mod.rs:475 [INFO] [stdout] 26: 0x55895459d6c5 - as core::ops::function::FnOnce<()>>::call_once::he2457d582c032747 [INFO] [stdout] at /rustc/28742a1146f10a4f09369baad027a464acb7a766/src/libstd/panic.rs:318 [INFO] [stdout] 27: 0x55895459d6c5 - std::panicking::try::do_call::hbcd49dc3823e8b28 [INFO] [stdout] at /rustc/28742a1146f10a4f09369baad027a464acb7a766/src/libstd/panicking.rs:331 [INFO] [stdout] 28: 0x55895459d6c5 - std::panicking::try::h1ba95c07dd8d422b [INFO] [stdout] at /rustc/28742a1146f10a4f09369baad027a464acb7a766/src/libstd/panicking.rs:274 [INFO] [stdout] 29: 0x55895459d6c5 - std::panic::catch_unwind::h95854794ba9eee4c [INFO] [stdout] at /rustc/28742a1146f10a4f09369baad027a464acb7a766/src/libstd/panic.rs:394 [INFO] [stdout] 30: 0x55895459d6c5 - std::thread::Builder::spawn_unchecked::{{closure}}::h4e297b8574092d6e [INFO] [stdout] at /rustc/28742a1146f10a4f09369baad027a464acb7a766/src/libstd/thread/mod.rs:474 [INFO] [stdout] 31: 0x55895459d6c5 - core::ops::function::FnOnce::call_once{{vtable.shim}}::h1b855aad7f5ac67e [INFO] [stdout] at /rustc/28742a1146f10a4f09369baad027a464acb7a766/src/libcore/ops/function.rs:232 [INFO] [stdout] 32: 0x55895494f10f - as core::ops::function::FnOnce>::call_once::h48069b269ca48108 [INFO] [stdout] at /rustc/28742a1146f10a4f09369baad027a464acb7a766/src/liballoc/boxed.rs:1008 [INFO] [stdout] 33: 0x558954966c13 - as core::ops::function::FnOnce>::call_once::heff660b32cd3afd8 [INFO] [stdout] at /rustc/28742a1146f10a4f09369baad027a464acb7a766/src/liballoc/boxed.rs:1008 [INFO] [stdout] 34: 0x558954966c13 - std::sys::unix::thread::Thread::new::thread_start::ha538939ed1e2e958 [INFO] [stdout] at src/libstd/sys/unix/thread.rs:87 [INFO] [stdout] 35: 0x7f5e1c9d56db - start_thread [INFO] [stdout] 36: 0x7f5e1c4e688f - __clone [INFO] [stdout] 37: 0x0 - [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] timer::tests::test_timeout_and_restart [INFO] [stdout] [INFO] [stdout] test result: FAILED. 119 passed; 1 failed; 3 ignored; 0 measured; 0 filtered out [INFO] [stdout] [INFO] [stderr] error: test failed, to rerun pass '--lib' [INFO] running `"docker" "inspect" "798d514ad1dac6cab6dd87872873ccc5ed1fafadaf8f800d39aeddd13af2a48d"` [INFO] running `"docker" "rm" "-f" "798d514ad1dac6cab6dd87872873ccc5ed1fafadaf8f800d39aeddd13af2a48d"` [INFO] [stdout] 798d514ad1dac6cab6dd87872873ccc5ed1fafadaf8f800d39aeddd13af2a48d