[INFO] fetching crate smpp-pdu 0.1.4...
[INFO] testing smpp-pdu-0.1.4 against try#722e1797249a965b6335aebd65d777f917e498f1 for pr-91031
[INFO] extracting crate smpp-pdu 0.1.4 into /workspace/builds/worker-18/source
[INFO] validating manifest of crates.io crate smpp-pdu 0.1.4 on toolchain 722e1797249a965b6335aebd65d777f917e498f1
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+722e1797249a965b6335aebd65d777f917e498f1" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] started tweaking crates.io crate smpp-pdu 0.1.4
[INFO] finished tweaking crates.io crate smpp-pdu 0.1.4
[INFO] tweaked toml for crates.io crate smpp-pdu 0.1.4 written to /workspace/builds/worker-18/source/Cargo.toml
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+722e1797249a965b6335aebd65d777f917e498f1" "generate-lockfile" "--manifest-path" "Cargo.toml" "-Zno-index-update", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+722e1797249a965b6335aebd65d777f917e498f1" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-18/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-18/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:5736fa189c1c60b01babf4b8b698fe57b6ecc41933a7ff2e0b8d7a221459412b" "/opt/rustwide/cargo-home/bin/cargo" "+722e1797249a965b6335aebd65d777f917e498f1" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] e20e22ad81a60d87986b59ec081e4451daaa803026f9b91b6110849861af443c
[INFO] running `Command { std: "docker" "start" "-a" "e20e22ad81a60d87986b59ec081e4451daaa803026f9b91b6110849861af443c", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "e20e22ad81a60d87986b59ec081e4451daaa803026f9b91b6110849861af443c", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "e20e22ad81a60d87986b59ec081e4451daaa803026f9b91b6110849861af443c", kill_on_drop: false }`
[INFO] [stdout] e20e22ad81a60d87986b59ec081e4451daaa803026f9b91b6110849861af443c
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-18/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-18/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" "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:5736fa189c1c60b01babf4b8b698fe57b6ecc41933a7ff2e0b8d7a221459412b" "/opt/rustwide/cargo-home/bin/cargo" "+722e1797249a965b6335aebd65d777f917e498f1" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 6f41ddb940e96e97b01190f72844ab2ec196445184ed5f8dc2b59db47f800ee7
[INFO] running `Command { std: "docker" "start" "-a" "6f41ddb940e96e97b01190f72844ab2ec196445184ed5f8dc2b59db47f800ee7", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]    Compiling memchr v2.4.1
[INFO] [stderr]    Compiling syn v1.0.81
[INFO] [stderr]    Compiling ascii v1.0.0
[INFO] [stderr]    Compiling tokio v1.14.0
[INFO] [stderr]    Compiling num-traits v0.2.14
[INFO] [stderr]    Compiling aho-corasick v0.7.18
[INFO] [stderr]    Compiling regex v1.5.4
[INFO] [stderr]    Compiling tokio-macros v1.6.0
[INFO] [stderr]    Compiling num-derive v0.3.3
[INFO] [stderr]    Compiling smpp-pdu v0.1.4 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 25.02s
[INFO] running `Command { std: "docker" "inspect" "6f41ddb940e96e97b01190f72844ab2ec196445184ed5f8dc2b59db47f800ee7", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "6f41ddb940e96e97b01190f72844ab2ec196445184ed5f8dc2b59db47f800ee7", kill_on_drop: false }`
[INFO] [stdout] 6f41ddb940e96e97b01190f72844ab2ec196445184ed5f8dc2b59db47f800ee7
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-18/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-18/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" "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:5736fa189c1c60b01babf4b8b698fe57b6ecc41933a7ff2e0b8d7a221459412b" "/opt/rustwide/cargo-home/bin/cargo" "+722e1797249a965b6335aebd65d777f917e498f1" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 41a9c6d1a4c3f4e87252e29202048a3d3b8e24c1ddcbaaa98e100d543ead111a
[INFO] running `Command { std: "docker" "start" "-a" "41a9c6d1a4c3f4e87252e29202048a3d3b8e24c1ddcbaaa98e100d543ead111a", kill_on_drop: false }`
[INFO] [stderr]    Compiling smpp-pdu v0.1.4 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 4.42s
[INFO] running `Command { std: "docker" "inspect" "41a9c6d1a4c3f4e87252e29202048a3d3b8e24c1ddcbaaa98e100d543ead111a", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "41a9c6d1a4c3f4e87252e29202048a3d3b8e24c1ddcbaaa98e100d543ead111a", kill_on_drop: false }`
[INFO] [stdout] 41a9c6d1a4c3f4e87252e29202048a3d3b8e24c1ddcbaaa98e100d543ead111a
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-18/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-18/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" "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:5736fa189c1c60b01babf4b8b698fe57b6ecc41933a7ff2e0b8d7a221459412b" "/opt/rustwide/cargo-home/bin/cargo" "+722e1797249a965b6335aebd65d777f917e498f1" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 473bfc8621e06d272a0c0577a7377ee252462945510e3bf9754bed0f6f9067fb
[INFO] running `Command { std: "docker" "start" "-a" "473bfc8621e06d272a0c0577a7377ee252462945510e3bf9754bed0f6f9067fb", kill_on_drop: false }`
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 0.03s
[INFO] [stderr]      Running unittests (/opt/rustwide/target/debug/deps/smpp_pdu-445f75f7d16862e4)
[INFO] [stdout] 
[INFO] [stdout] running 75 tests
[INFO] [stderr]    Doc-tests smpp-pdu
[INFO] [stdout] test pdu::check::tests::check_errors_if_read_error ... ok
[INFO] [stdout] test pdu::check::tests::check_errors_without_reading_all_if_long_length ... ok
[INFO] [stdout] test pdu::formats::tests::can_read_octetstring_without_trailing_zero_and_extra_bytes_after ... ok
[INFO] [stdout] test pdu::formats::tests::read_coctetstring_max_length ... ok
[INFO] [stdout] test pdu::formats::tests::read_coctetstring_missing_zero_byte ... ok
[INFO] [stdout] test pdu::check::tests::check_is_incomplete_if_fewer_bytes ... ok
[INFO] [stdout] test pdu::formats::tests::read_integer1 ... ok
[INFO] [stdout] test pdu::formats::tests::read_coctetstring ... ok
[INFO] [stdout] test pdu::check::tests::check_is_ok_if_more_bytes ... ok
[INFO] [stdout] test pdu::formats::tests::write_coctetstring ... ok
[INFO] [stdout] test pdu::formats::tests::read_error_integer1 ... ok
[INFO] [stdout] test pdu::formats::tests::when_finding_eof_within_octetstring_fail ... ok
[INFO] [stdout] test pdu::formats::tests::read_error_coctetstring ... ok
[INFO] [stdout] test pdu::check::tests::check_errors_if_short_length ... ok
[INFO] [stdout] test pdu::check::tests::check_is_ok_if_exact_bytes ... ok
[INFO] [stdout] test pdu::formats::tests::write_integer1 ... ok
[INFO] [stdout] test pdu::formats::tests::read_coctetstring_too_long ... ok
[INFO] [stdout] test pdu::formats::tests::when_reading_octetstring_ending_zero_the_zero_is_included_in_output ... ok
[INFO] [stdout] test pdu::formats::tests::read_coctetstring_zero_not_included_in_length ... ok
[INFO] [stdout] test pdu::formats::tests::read_integer4 ... ok
[INFO] [stdout] test pdu::formats::tests::read_error_integer4 ... ok
[INFO] [stdout] test pdu::formats::tests::write_integer4 ... ok
[INFO] [stdout] test pdu::formats::tests::write_octetstring_containing_zeroes ... ok
[INFO] [stdout] test pdu::pdu::tests::check_is_incomplete_if_fewer_bytes ... ok
[INFO] [stdout] test pdu::operations::deliver_sm::tests::when_id_is_in_tlv_and_short_message_tlv_wins ... ok
[INFO] [stdout] test pdu::pdu::tests::check_is_ok_if_more_bytes ... ok
[INFO] [stdout] test pdu::pdu::tests::parse_enquire_link ... ok
[INFO] [stdout] test pdu::pdu::tests::parse_bind_transmitter_with_too_long_system_id ... ok
[INFO] [stdout] test pdu::operations::deliver_sm::tests::when_null_terminated_id_is_in_tlv_we_can_extract_it ... ok
[INFO] [stdout] test pdu::operations::deliver_sm::tests::when_id_is_in_tlv_we_can_extract_it ... ok
[INFO] [stdout] test pdu::pdu::tests::parse_submit_sm_with_too_long_message_length ... ok
[INFO] [stdout] test pdu::pdu::tests::parse_bind_transmitter_with_nonzero_status ... ok
[INFO] [stdout] test pdu::pdu::tests::parse_enquire_link_resp ... ok
[INFO] [stdout] test pdu::pdu::tests::parse_bind_transmitter_with_massive_length ... ok
[INFO] [stdout] test pdu::pdu::tests::parse_bind_transmitter_with_short_length ... ok
[INFO] [stdout] test pdu::pdu::tests::parse_bind_transmitter_ending_before_all_fields ... ok
[INFO] [stdout] test pdu::pdu::tests::parse_bind_transmitter_with_length_ending_within_string ... ok
[INFO] [stdout] test pdu::pdu::tests::parse_submit_sm_resp_error_with_message_id_is_an_error ... ok
[INFO] [stdout] test pdu::pdu::tests::parse_submit_sm_resp_error_without_message_id ... ok
[INFO] [stdout] test pdu::pdu::tests::parse_bind_transmitter_hitting_eof_before_end_of_length ... ok
[INFO] [stdout] test pdu::pdu::tests::parse_submit_sm_resp_ok_without_message_id_is_an_error ... ok
[INFO] [stdout] test pdu::pdu::tests::parse_valid_bind_receiver ... ok
[INFO] [stdout] test pdu::pdu::tests::parse_valid_bind_receiver_resp ... ok
[INFO] [stdout] test pdu::pdu::tests::parse_valid_submit_sm_with_short_message_and_no_tlvs ... ok
[INFO] [stdout] test pdu::pdu::tests::parse_valid_bind_transceiver ... ok
[INFO] [stdout] test pdu::pdu::tests::parse_valid_bind_transmitter ... ok
[INFO] [stdout] test pdu::pdu::tests::parse_valid_deliver_sm_with_empty_short_message_and_no_tlvs ... ok
[INFO] [stdout] test pdu::pdu::tests::parse_bind_transmitter_containing_nonascii_characters ... ok
[INFO] [stdout] test pdu::pdu::tests::parse_submit_sm_resp_ok_with_message_id ... ok
[INFO] [stdout] test pdu::pdu::tests::parse_valid_submit_sm_with_empty_short_message_and_no_tlvs ... ok
[INFO] [stdout] test pdu::formats::tests::write_octetstring ... ok
[INFO] [stdout] test pdu::pdu::tests::parse_valid_bind_transmitter_resp ... ok
[INFO] [stdout] test pdu::pdu::tests::parse_valid_submit_sm_with_tlvs ... ok
[INFO] [stdout] test pdu::pdu::tests::parse_valid_bind_transceiver_resp ... ok
[INFO] [stdout] test pdu::operations::deliver_sm::tests::can_parse_more_realistic_dr_short_messages ... ok
[INFO] [stdout] test pdu::pdu::tests::parsepdu_resp_error_with_too_large_sequence_number_is_an_error ... ok
[INFO] [stdout] test pdu::pdu::tests::parsepdu_resp_error_with_zero_sequence_number_is_an_error ... ok
[INFO] [stdout] test pdu::pdu::tests::write_deliver_sm_with_short_message_and_no_tlvs ... ok
[INFO] [stdout] test pdu::pduparseerror::tests::formatting_unknown_command_id ... ok
[INFO] [stdout] test pdu::pdu::tests::write_submit_sm_with_message_in_tlvs ... ok
[INFO] [stdout] test pdu::operations::deliver_sm::tests::when_id_is_at_start_of_short_message_and_no_tlv_we_can_extract_id ... ok
[INFO] [stdout] test pdu::operations::deliver_sm::tests::can_parse_short_message_from_appendix_b ... ok
[INFO] [stdout] test pdu::tlvs::tests::can_get_existing_tlv_from_list ... ok
[INFO] [stdout] test pdu::tlvs::tests::reading_malformed_tlvs_is_an_error ... ok
[INFO] [stdout] test pdu::tlvs::tests::getting_nonexistent_tlv_returns_none ... ok
[INFO] [stdout] test pdu::tlvs::tests::read_tlv_with_length_longer_than_input_fails ... ok
[INFO] [stdout] test pdu::tlvs::tests::error_reading_tlv_is_surfaced ... ok
[INFO] [stdout] test pdu::tlvs::tests::read_tlv_with_unknown_tag_works ... ok
[INFO] [stdout] test pdu::tlvs::tests::io_error_reading_tlvs_is_surfaced ... ok
[INFO] [stdout] test pdu::tlvs::tests::read_multiple_tlvs ... ok
[INFO] [stdout] test pdu::tlvs::tests::write_tlv_with_known_tag ... ok
[INFO] [stdout] test pdu::tlvs::tests::read_message_payload_tlv ... ok
[INFO] [stdout] test pdu::tlvs::tests::write_multiple_tlvs ... ok
[INFO] [stdout] test pdu::tlvs::tests::write_tlv_with_unknown_tag ... ok
[INFO] [stdout] test pdu::pdu::tests::write_submit_sm_with_short_message_and_no_tlvs ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 75 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.10s
[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; finished in 0.00s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "473bfc8621e06d272a0c0577a7377ee252462945510e3bf9754bed0f6f9067fb", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "473bfc8621e06d272a0c0577a7377ee252462945510e3bf9754bed0f6f9067fb", kill_on_drop: false }`
[INFO] [stdout] 473bfc8621e06d272a0c0577a7377ee252462945510e3bf9754bed0f6f9067fb