[INFO] fetching crate webrtc-ice 0.3.2...
[INFO] testing webrtc-ice-0.3.2 against 1.54.0 for beta-1.55-1
[INFO] extracting crate webrtc-ice 0.3.2 into /workspace/builds/worker-13/source
[INFO] validating manifest of crates.io crate webrtc-ice 0.3.2 on toolchain 1.54.0
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+1.54.0" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] started tweaking crates.io crate webrtc-ice 0.3.2
[INFO] removed 0 missing examples
[INFO] finished tweaking crates.io crate webrtc-ice 0.3.2
[INFO] tweaked toml for crates.io crate webrtc-ice 0.3.2 written to /workspace/builds/worker-13/source/Cargo.toml
[INFO] crate crates.io crate webrtc-ice 0.3.2 already has a lockfile, it will not be regenerated
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+1.54.0" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-13/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-13/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:34b6a614d2c27851fe6cbf88fbd1137609cefab8b10d0615aaeb6fd47975d74e" "/opt/rustwide/cargo-home/bin/cargo" "+1.54.0" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 73b03d169567da8224cacd4e440c5fb290b8eb18e0786f629b71bc1fc53503a9
[INFO] running `Command { std: "docker" "start" "-a" "73b03d169567da8224cacd4e440c5fb290b8eb18e0786f629b71bc1fc53503a9", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "73b03d169567da8224cacd4e440c5fb290b8eb18e0786f629b71bc1fc53503a9", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "73b03d169567da8224cacd4e440c5fb290b8eb18e0786f629b71bc1fc53503a9", kill_on_drop: false }`
[INFO] [stdout] 73b03d169567da8224cacd4e440c5fb290b8eb18e0786f629b71bc1fc53503a9
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-13/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-13/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" "ghcr.io/rust-lang/crates-build-env/linux@sha256:34b6a614d2c27851fe6cbf88fbd1137609cefab8b10d0615aaeb6fd47975d74e" "/opt/rustwide/cargo-home/bin/cargo" "+1.54.0" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] f2f5a3e5060deb0d5bf72b12b68c30ceb21596fc2e244ad9a78fa84da785c347
[INFO] running `Command { std: "docker" "start" "-a" "f2f5a3e5060deb0d5bf72b12b68c30ceb21596fc2e244ad9a78fa84da785c347", kill_on_drop: false }`
[INFO] [stderr]    Compiling libc v0.2.95
[INFO] [stderr]    Compiling async-trait v0.1.50
[INFO] [stderr]    Compiling anyhow v1.0.41
[INFO] [stderr]    Compiling cc v1.0.68
[INFO] [stderr]    Compiling crc-catalog v1.1.1
[INFO] [stderr]    Compiling subtle v2.4.0
[INFO] [stderr]    Compiling tokio v1.6.0
[INFO] [stderr]    Compiling waitgroup v0.1.2
[INFO] [stderr]    Compiling thiserror-impl v1.0.25
[INFO] [stderr]    Compiling md-5 v0.9.1
[INFO] [stderr]    Compiling crc v2.0.0
[INFO] [stderr]    Compiling getrandom v0.2.3
[INFO] [stderr]    Compiling parking_lot_core v0.8.3
[INFO] [stderr]    Compiling mio v0.7.11
[INFO] [stderr]    Compiling num_cpus v1.13.0
[INFO] [stderr]    Compiling signal-hook-registry v1.3.0
[INFO] [stderr]    Compiling nix v0.8.1
[INFO] [stderr]    Compiling socket2 v0.4.0
[INFO] [stderr]    Compiling ring v0.16.20
[INFO] [stderr]    Compiling parking_lot v0.11.1
[INFO] [stderr]    Compiling rand_core v0.6.2
[INFO] [stderr]    Compiling uuid v0.8.2
[INFO] [stderr]    Compiling rand_chacha v0.3.0
[INFO] [stderr]    Compiling rand v0.8.4
[INFO] [stderr]    Compiling thiserror v1.0.25
[INFO] [stderr]    Compiling webrtc-util v0.4.1
[INFO] [stderr]    Compiling stun v0.3.1
[INFO] [stderr]    Compiling webrtc-mdns v0.3.1
[INFO] [stderr]    Compiling turn v0.3.1
[INFO] [stderr]    Compiling webrtc-ice v0.3.2 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 34.01s
[INFO] running `Command { std: "docker" "inspect" "f2f5a3e5060deb0d5bf72b12b68c30ceb21596fc2e244ad9a78fa84da785c347", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "f2f5a3e5060deb0d5bf72b12b68c30ceb21596fc2e244ad9a78fa84da785c347", kill_on_drop: false }`
[INFO] [stdout] f2f5a3e5060deb0d5bf72b12b68c30ceb21596fc2e244ad9a78fa84da785c347
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-13/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-13/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" "ghcr.io/rust-lang/crates-build-env/linux@sha256:34b6a614d2c27851fe6cbf88fbd1137609cefab8b10d0615aaeb6fd47975d74e" "/opt/rustwide/cargo-home/bin/cargo" "+1.54.0" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 747e8598c83700707e022148695fa5c04702bf8bdf53984ce745273bb51184e9
[INFO] running `Command { std: "docker" "start" "-a" "747e8598c83700707e022148695fa5c04702bf8bdf53984ce745273bb51184e9", kill_on_drop: false }`
[INFO] [stderr]    Compiling futures-task v0.3.15
[INFO] [stderr]    Compiling futures-channel v0.3.15
[INFO] [stderr]    Compiling futures-util v0.3.15
[INFO] [stderr]    Compiling atty v0.2.14
[INFO] [stderr]    Compiling tracing v0.1.26
[INFO] [stderr]    Compiling time v0.1.44
[INFO] [stderr]    Compiling want v0.3.0
[INFO] [stderr]    Compiling async-stream-impl v0.3.2
[INFO] [stderr]    Compiling tokio-util v0.6.7
[INFO] [stderr]    Compiling tokio-stream v0.1.6
[INFO] [stderr]    Compiling clap v2.33.3
[INFO] [stderr]    Compiling env_logger v0.8.3
[INFO] [stderr]    Compiling chrono v0.4.19
[INFO] [stderr]    Compiling async-stream v0.3.2
[INFO] [stderr]    Compiling tokio-test v0.4.2
[INFO] [stderr]    Compiling h2 v0.3.3
[INFO] [stderr]    Compiling hyper v0.14.8
[INFO] [stderr]    Compiling webrtc-ice v0.3.2 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 46.10s
[INFO] running `Command { std: "docker" "inspect" "747e8598c83700707e022148695fa5c04702bf8bdf53984ce745273bb51184e9", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "747e8598c83700707e022148695fa5c04702bf8bdf53984ce745273bb51184e9", kill_on_drop: false }`
[INFO] [stdout] 747e8598c83700707e022148695fa5c04702bf8bdf53984ce745273bb51184e9
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-13/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-13/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" "ghcr.io/rust-lang/crates-build-env/linux@sha256:34b6a614d2c27851fe6cbf88fbd1137609cefab8b10d0615aaeb6fd47975d74e" "/opt/rustwide/cargo-home/bin/cargo" "+1.54.0" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] ff897facd77e4b7c75d4572c67d4af7f2d0cbc238e52c430650c0995a8a3ead0
[INFO] running `Command { std: "docker" "start" "-a" "ff897facd77e4b7c75d4572c67d4af7f2d0cbc238e52c430650c0995a8a3ead0", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 0.14s
[INFO] [stderr]      Running unittests (/opt/rustwide/target/debug/deps/webrtc_ice-fb26b0891b135340)
[INFO] [stdout] 
[INFO] [stdout] running 80 tests
[INFO] [stdout] test agent::agent_gather_test::test_vnet_gather_listen_udp ... ok
[INFO] [stdout] test agent::agent_gather_test::test_vnet_gather_turn_connection_leak ... ok
[INFO] [stdout] test agent::agent_gather_test::test_vnet_gather_with_interface_filter ... ok
[INFO] [stdout] test agent::agent_test::test_get_remote_credentials ... ok
[INFO] [stdout] test agent::agent_test::test_candidate_pair_stats ... ok
[INFO] [stdout] test agent::agent_test::test_connectivity_on_startup ... ok
[INFO] [stdout] test agent::agent_test::test_init_ext_ip_mapping ... ok
[INFO] [stdout] test agent::agent_test::test_handle_peer_reflexive_udp_pflx_candidate ... ok
[INFO] [stdout] test agent::agent_test::test_remote_candidate_stats ... ok
[INFO] [stdout] test agent::agent_test::test_pair_search ... ok
[INFO] [stdout] test agent::agent_test::test_invalid_gather ... ok
[INFO] [stdout] test agent::agent_test::test_on_selected_candidate_pair_change ... ok
[INFO] [stdout] test agent::agent_test::test_handle_peer_reflexive_unknown_remote ... ok
[INFO] [stdout] test agent::agent_gather_test::test_vnet_gather_no_local_ip_address ... ok
[INFO] [stdout] test candidate::candidate_pair_test::test_candidate_pair_equality ... ok
[INFO] [stdout] test agent::agent_test::test_binding_request_timeout ... ok
[INFO] [stdout] test candidate::candidate_pair_test::test_candidate_pair_priority ... ok
[INFO] [stdout] test agent::agent_test::test_agent_restart_during_gather ... ok
[INFO] [stdout] test agent::agent_gather_test::test_vnet_gather_with_nat_1to1_as_srflx_candidates ... ok
[INFO] [stdout] test agent::agent_test::test_invalid_agent_starts ... ok
[INFO] [stdout] test candidate::candidate_test::test_candidate_last_sent ... ok
[INFO] [stdout] test candidate::candidate_test::test_candidate_foundation ... ok
[INFO] [stdout] test candidate::candidate_test::test_candidate_priority ... ok
[INFO] [stdout] test agent::agent_vnet_test::test_write_use_valid_pair ... ok
[INFO] [stdout] test candidate::candidate_test::test_candidate_last_received ... ok
[INFO] [stdout] test control::control_test::test_control_get_from ... ok
[INFO] [stdout] test control::control_test::test_controlling_get_from ... ok
[INFO] [stdout] test control::control_test::test_controlled_get_from ... ok
[INFO] [stdout] test external_ip_mapper::external_ip_mapper_test::test_external_ip_mapper_find_external_ip_with_empty_map ... ok
[INFO] [stdout] test external_ip_mapper::external_ip_mapper_test::test_external_ip_mapper_find_external_ip_without_explicit_local_ip ... ok
[INFO] [stdout] test agent::agent_test::test_agent_restart_when_closed ... ok
[INFO] [stdout] test external_ip_mapper::external_ip_mapper_test::test_external_ip_mapper_find_external_ip_with_explicit_local_ip ... ok
[INFO] [stdout] test agent::agent_test::test_local_candidate_stats ... ok
[INFO] [stdout] test external_ip_mapper::external_ip_mapper_test::test_external_ip_mapper_new_external_ip_mapper ... ok
[INFO] [stdout] test external_ip_mapper::external_ip_mapper_test::test_external_ip_mapper_new_external_ip_mapper_with_explicit_local_ip ... ok
[INFO] [stdout] test external_ip_mapper::external_ip_mapper_test::test_external_ip_mapper_validate_ip_string ... ok
[INFO] [stdout] test agent::agent_test::test_pair_priority ... ok
[INFO] [stdout] test agent::agent_test::test_agent_credentials ... ok
[INFO] [stdout] test external_ip_mapper::external_ip_mapper_test::test_external_ip_mapper_new_external_ip_mapper_with_implicit_local_ip ... ok
[INFO] [stdout] test network_type::network_type_test::test_network_type_is_tcp ... ok
[INFO] [stdout] test network_type::network_type_test::test_network_type_is_udp ... ok
[INFO] [stdout] test network_type::network_type_test::test_network_type_parsing_success ... ok
[INFO] [stdout] test agent::agent_gather_test::test_vnet_gather_dynamic_ip_address ... ok
[INFO] [stdout] test state::state_test::test_connected_state_string ... ok
[INFO] [stdout] test network_type::network_type_test::test_network_type_parsing_failure ... ok
[INFO] [stdout] test agent::agent_test::test_inbound_validity ... ok
[INFO] [stdout] test agent::agent_gather_test::test_vnet_gather_with_nat_1to1_as_host_candidates ... ok
[INFO] [stdout] test priority::priority_test::test_priority_get_from ... ok
[INFO] [stdout] test tcp_type::tcp_type_test::test_tcp_type ... ok
[INFO] [stdout] test url::url_test::test_parse_url_failure ... ok
[INFO] [stdout] test url::url_test::test_parse_url_success ... ok
[INFO] [stdout] test use_candidate::use_candidate_test::test_use_candidate_attr_add_to ... ok
[INFO] [stdout] test state::state_test::test_gathering_state_string ... ok
[INFO] [stdout] test util::util_test::test_local_interfaces ... ok
[INFO] [stdout] test mdns::mdns_test::test_generate_multicast_dnsname ... ok
[INFO] [stdout] test candidate::candidate_test::test_candidate_marshal ... ok
[INFO] [stdout] test mdns::mdns_test::test_multicast_dns_static_host_name ... ok
[INFO] [stdout] test rand::rand_test::test_random_generator_collision ... ok
[INFO] [stdout] test candidate::candidate_server_reflexive_test::test_server_reflexive_only_connection ... ok
[INFO] [stdout] test agent::agent_vnet_test::test_connectivity_simple_vnet_full_cone_nats_on_both_ends ... ok
[INFO] [stdout] test agent::agent_test::test_connectivity_lite ... ok
[INFO] [stdout] test agent::agent_vnet_test::test_disconnected_to_connected ... ok
[INFO] [stdout] test agent::agent_transport_test::test_remote_local_addr ... ok
[INFO] [stdout] test agent::agent_vnet_test::test_connectivity_vnet_1to1_nat_with_host_candidate_vs_symmetric_nats ... ok
[INFO] [stdout] test agent::agent_vnet_test::test_connectivity_vnet_1to1_nat_with_srflx_candidate_vs_symmetric_nats ... ok
[INFO] [stdout] test agent::agent_test::test_connection_state_connecting_to_failed ... ok
[INFO] [stdout] test agent::agent_vnet_test::test_connectivity_vnet_full_cone_nats_on_both_ends ... ok
[INFO] [stdout] test candidate::candidate_relay_test::test_relay_only_connection ... ok
[INFO] [stdout] test agent::agent_vnet_test::test_connectivity_vnet_symmetric_nats_on_both_ends ... ok
[INFO] [stdout] test agent::agent_test::test_agent_restart_both_side has been running for over 60 seconds
[INFO] [stdout] test agent::agent_test::test_agent_restart_one_side has been running for over 60 seconds
[INFO] [stdout] test agent::agent_test::test_close_in_connection_state_callback has been running for over 60 seconds
[INFO] [stdout] test agent::agent_test::test_connection_state_callback has been running for over 60 seconds
[INFO] [stdout] test agent::agent_test::test_connection_state_failed_delete_all_candidates has been running for over 60 seconds
[INFO] [stdout] test agent::agent_test::test_lite_lifecycle has been running for over 60 seconds
[INFO] [stdout] test agent::agent_test::test_run_task_in_connection_state_callback has been running for over 60 seconds
[INFO] [stdout] test agent::agent_test::test_run_task_in_selected_candidate_pair_change_callback has been running for over 60 seconds
[INFO] [stdout] test agent::agent_transport_test::test_conn_stats has been running for over 60 seconds
[INFO] [stdout] test mdns::mdns_test::test_multicast_dns_mixed_connection has been running for over 60 seconds
[INFO] [stdout] test mdns::mdns_test::test_multicast_dns_only_connection has been running for over 60 seconds
[ERROR] error running command: no output for 300 seconds
[INFO] running `Command { std: "docker" "inspect" "ff897facd77e4b7c75d4572c67d4af7f2d0cbc238e52c430650c0995a8a3ead0", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "ff897facd77e4b7c75d4572c67d4af7f2d0cbc238e52c430650c0995a8a3ead0", kill_on_drop: false }`
[INFO] [stdout] ff897facd77e4b7c75d4572c67d4af7f2d0cbc238e52c430650c0995a8a3ead0
