Nov 18 19:24:10.282 INFO testing bluenrg-0.0.3 against try#2e026d6e5d7621634147ad4c8074f1d964dbb1be for pr-55982
Nov 18 19:24:10.282 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-4/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/pr-55982/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +2e026d6e5d7621634147ad4c8074f1d964dbb1be-alt build --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Nov 18 19:24:10.435 INFO [stdout] 953469a1f2e5e73b1a3bd11d45700e47a1c7cdaf132cfb8170dcddf79b72aa74
Nov 18 19:24:10.435 INFO running `"docker" "start" "-a" "953469a1f2e5e73b1a3bd11d45700e47a1c7cdaf132cfb8170dcddf79b72aa74"`
Nov 18 19:24:10.957 INFO [stderr] usermod: no changes
Nov 18 19:24:10.992 INFO [stderr]    Compiling bluetooth-hci v0.0.3
Nov 18 19:24:15.351 INFO [stderr]    Compiling bluenrg v0.0.3 (/source)
Nov 18 19:24:18.294 INFO [stderr] warning: the feature `extern_prelude` has been stable since 1.30.0 and no longer requires an attribute to enable
Nov 18 19:24:18.294 INFO [stderr]   --> src/lib.rs:42:12
Nov 18 19:24:18.294 INFO [stderr]    |
Nov 18 19:24:18.294 INFO [stderr] 42 | #![feature(extern_prelude)]
Nov 18 19:24:18.294 INFO [stderr]    |            ^^^^^^^^^^^^^^
Nov 18 19:24:18.294 INFO [stderr]    |
Nov 18 19:24:18.294 INFO [stderr]    = note: #[warn(stable_features)] on by default
Nov 18 19:24:18.294 INFO [stderr] 
Nov 18 19:24:18.323 INFO [stderr] warning: unused `core::result::Result` that must be used
Nov 18 19:24:18.324 INFO [stderr]     --> src/event/command.rs:1006:9
Nov 18 19:24:18.324 INFO [stderr]      |
Nov 18 19:24:18.324 INFO [stderr] 1006 |         write!(f, "status: {:?}; value: {{", self.status);
Nov 18 19:24:18.324 INFO [stderr]      |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Nov 18 19:24:18.325 INFO [stderr]      |
Nov 18 19:24:18.325 INFO [stderr]      = note: #[warn(unused_must_use)] on by default
Nov 18 19:24:18.325 INFO [stderr]      = note: this `Result` may be an `Err` variant, which should be handled
Nov 18 19:24:18.325 INFO [stderr]      = note: this error originates in a macro outside of the current crate (in Nightly builds, run with -Z external-macro-backtrace for more info)
Nov 18 19:24:18.326 INFO [stderr] 
Nov 18 19:24:19.994 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 9.02s
Nov 18 19:24:19.997 INFO [stderr] su: No module specific data is present
Nov 18 19:24:20.948 INFO running `"docker" "inspect" "953469a1f2e5e73b1a3bd11d45700e47a1c7cdaf132cfb8170dcddf79b72aa74"`
Nov 18 19:24:21.249 INFO running `"docker" "rm" "-f" "953469a1f2e5e73b1a3bd11d45700e47a1c7cdaf132cfb8170dcddf79b72aa74"`
Nov 18 19:24:21.532 INFO [stdout] 953469a1f2e5e73b1a3bd11d45700e47a1c7cdaf132cfb8170dcddf79b72aa74
Nov 18 19:24:21.536 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-4/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/pr-55982/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +2e026d6e5d7621634147ad4c8074f1d964dbb1be-alt test --frozen --no-run" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Nov 18 19:24:21.944 INFO [stdout] fed69882bc400b6f7b9a13602ad3218eca4bb3c0743bee23922cbe5f509da5c3
Nov 18 19:24:21.945 INFO running `"docker" "start" "-a" "fed69882bc400b6f7b9a13602ad3218eca4bb3c0743bee23922cbe5f509da5c3"`
Nov 18 19:24:22.320 INFO [stderr] usermod: no changes
Nov 18 19:24:22.357 INFO [stderr]    Compiling bluenrg v0.0.3 (/source)
Nov 18 19:24:31.331 INFO [stderr] warning: the feature `extern_prelude` has been stable since 1.30.0 and no longer requires an attribute to enable
Nov 18 19:24:31.331 INFO [stderr]   --> src/lib.rs:42:12
Nov 18 19:24:31.331 INFO [stderr]    |
Nov 18 19:24:31.331 INFO [stderr] 42 | #![feature(extern_prelude)]
Nov 18 19:24:31.331 INFO [stderr]    |            ^^^^^^^^^^^^^^
Nov 18 19:24:31.331 INFO [stderr]    |
Nov 18 19:24:31.331 INFO [stderr]    = note: #[warn(stable_features)] on by default
Nov 18 19:24:31.331 INFO [stderr] 
Nov 18 19:24:31.367 INFO [stderr] warning: unused `core::result::Result` that must be used
Nov 18 19:24:31.367 INFO [stderr]     --> src/event/command.rs:1006:9
Nov 18 19:24:31.367 INFO [stderr]      |
Nov 18 19:24:31.367 INFO [stderr] 1006 |         write!(f, "status: {:?}; value: {{", self.status);
Nov 18 19:24:31.367 INFO [stderr]      |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Nov 18 19:24:31.367 INFO [stderr]      |
Nov 18 19:24:31.367 INFO [stderr]      = note: #[warn(unused_must_use)] on by default
Nov 18 19:24:31.367 INFO [stderr]      = note: this `Result` may be an `Err` variant, which should be handled
Nov 18 19:24:31.367 INFO [stderr]      = note: this error originates in a macro outside of the current crate (in Nightly builds, run with -Z external-macro-backtrace for more info)
Nov 18 19:24:31.367 INFO [stderr] 
Nov 18 19:24:31.871 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 9.53s
Nov 18 19:24:31.875 INFO [stderr] su: No module specific data is present
Nov 18 19:24:32.404 INFO running `"docker" "inspect" "fed69882bc400b6f7b9a13602ad3218eca4bb3c0743bee23922cbe5f509da5c3"`
Nov 18 19:24:32.533 INFO running `"docker" "rm" "-f" "fed69882bc400b6f7b9a13602ad3218eca4bb3c0743bee23922cbe5f509da5c3"`
Nov 18 19:24:32.803 INFO [stdout] fed69882bc400b6f7b9a13602ad3218eca4bb3c0743bee23922cbe5f509da5c3
Nov 18 19:24:32.806 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-4/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/pr-55982/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +2e026d6e5d7621634147ad4c8074f1d964dbb1be-alt test --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Nov 18 19:24:33.291 INFO [stdout] 5b64c9cdee24e0dbf3ce6d7e5a2baf103cab06c5ee7d2f65e762f6ad94127fab
Nov 18 19:24:33.293 INFO running `"docker" "start" "-a" "5b64c9cdee24e0dbf3ce6d7e5a2baf103cab06c5ee7d2f65e762f6ad94127fab"`
Nov 18 19:24:34.255 INFO [stderr] usermod: no changes
Nov 18 19:24:34.304 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.02s
Nov 18 19:24:34.326 INFO [stderr]      Running /target/debug/deps/bluenrg-1c7c3523025a1986
Nov 18 19:24:34.332 INFO [stdout] 
Nov 18 19:24:34.332 INFO [stdout] running 6 tests
Nov 18 19:24:34.332 INFO [stdout] test cb::tests::empty_capacity ... ok
Nov 18 19:24:34.332 INFO [stdout] test cb::tests::peek ... ok
Nov 18 19:24:34.352 INFO [stdout] test cb::tests::empty_capacity_after_use ... ok
Nov 18 19:24:34.391 INFO [stdout] test cb::tests::request_too_many_bytes_read ... ok
Nov 18 19:24:34.391 INFO [stdout] test cb::tests::request_peek_too_far ... ok
Nov 18 19:24:34.391 INFO [stdout] test cb::tests::request_too_many_bytes_write ... ok
Nov 18 19:24:34.391 INFO [stdout] 
Nov 18 19:24:34.391 INFO [stdout] test result: ok. 6 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 19:24:34.391 INFO [stdout] 
Nov 18 19:24:34.393 INFO [stderr]      Running /target/debug/deps/cb-2aab7302880017dc
Nov 18 19:24:34.394 INFO [stdout] 
Nov 18 19:24:34.395 INFO [stdout] running 0 tests
Nov 18 19:24:34.395 INFO [stdout] 
Nov 18 19:24:34.395 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 19:24:34.395 INFO [stdout] 
Nov 18 19:24:34.395 INFO [stderr]      Running /target/debug/deps/command_complete-e8c4978d1510b7b5
Nov 18 19:24:34.400 INFO [stdout] 
Nov 18 19:24:34.400 INFO [stdout] running 69 tests
Nov 18 19:24:34.401 INFO [stdout] test gap_authorization_response ... ok
Nov 18 19:24:34.401 INFO [stdout] test gap_get_bonded_addresses_bad_addr_type ... ok
Nov 18 19:24:34.401 INFO [stdout] test gap_get_bonded_addresses ... ok
Nov 18 19:24:34.401 INFO [stdout] test gap_allow_rebond ... ok
Nov 18 19:24:34.405 INFO [stdout] test gap_clear_security_database ... ok
Nov 18 19:24:34.405 INFO [stdout] test gap_get_bonded_addresses_failed_mixed_signals ... ok
Nov 18 19:24:34.405 INFO [stdout] test gap_get_bonded_addresses_failed ... ok
Nov 18 19:24:34.405 INFO [stdout] test gap_get_bonded_addresses_partial ... ok
Nov 18 19:24:34.405 INFO [stdout] test gap_get_security_level_bad_bool ... ok
Nov 18 19:24:34.405 INFO [stdout] test gap_get_security_level ... ok
Nov 18 19:24:34.405 INFO [stdout] test gap_resolve_private_address ... ok
Nov 18 19:24:34.405 INFO [stdout] test gap_resolve_private_address_failed ... ok
Nov 18 19:24:34.405 INFO [stdout] test gap_resolve_private_address_failed_mixed_signals ... ok
Nov 18 19:24:34.405 INFO [stdout] test gap_set_authorization_requirement ... ok
Nov 18 19:24:34.411 INFO [stdout] test gap_is_device_bonded ... ok
Nov 18 19:24:34.411 INFO [stdout] test gap_delete_ad_type ... ok
Nov 18 19:24:34.411 INFO [stdout] test gap_pass_key_response ... ok
Nov 18 19:24:34.411 INFO [stdout] test gap_init ... ok
Nov 18 19:24:34.411 INFO [stdout] test gap_set_broadcast_mode ... ok
Nov 18 19:24:34.412 INFO [stdout] test gap_set_authentication_requirement ... ok
Nov 18 19:24:34.412 INFO [stdout] test gap_configure_white_list ... ok
Nov 18 19:24:34.412 INFO [stdout] test gap_get_security_level_bad_pass_key_requirement ... ok
Nov 18 19:24:34.412 INFO [stdout] test gap_start_observation_procedure ... ok
Nov 18 19:24:34.415 INFO [stdout] test gap_set_direct_connectable ... ok
Nov 18 19:24:34.419 INFO [stdout] test gap_set_io_capability ... ok
Nov 18 19:24:34.419 INFO [stdout] test gap_set_undirected_connectable ... ok
Nov 18 19:24:34.419 INFO [stdout] test gap_set_discoverable ... ok
Nov 18 19:24:34.419 INFO [stdout] test gap_terminate_procedure ... ok
Nov 18 19:24:34.419 INFO [stdout] test gap_set_nondiscoverable ... ok
Nov 18 19:24:34.419 INFO [stdout] test gap_set_event_mask ... ok
Nov 18 19:24:34.419 INFO [stdout] test gap_set_nonconnectable ... ok
Nov 18 19:24:34.419 INFO [stdout] test gatt_add_service ... ok
Nov 18 19:24:34.419 INFO [stdout] test gatt_allow_read ... ok
Nov 18 19:24:34.419 INFO [stdout] test gatt_delete_included_service ... ok
Nov 18 19:24:34.419 INFO [stdout] test gatt_delete_characteristic ... ok
Nov 18 19:24:34.419 INFO [stdout] test gatt_init ... ok
Nov 18 19:24:34.419 INFO [stdout] test gatt_include_service ... ok
Nov 18 19:24:34.419 INFO [stdout] test gatt_delete_service ... ok
Nov 18 19:24:34.419 INFO [stdout] test gatt_read_handle_value_offset ... ok
Nov 18 19:24:34.419 INFO [stdout] test gatt_set_descriptor_value ... ok
Nov 18 19:24:34.420 INFO [stdout] test gatt_read_handle_value ... ok
Nov 18 19:24:34.420 INFO [stdout] test gatt_set_event_mask ... ok
Nov 18 19:24:34.420 INFO [stdout] test gatt_set_security_permission ... ok
Nov 18 19:24:34.420 INFO [stdout] test gatt_signed_write_without_response ... ok
Nov 18 19:24:34.420 INFO [stdout] test gatt_update_characteristic_value ... ok
Nov 18 19:24:34.420 INFO [stdout] test gatt_update_long_characteristic_value ... ok
Nov 18 19:24:34.420 INFO [stdout] test gatt_write_without_response ... ok
Nov 18 19:24:34.420 INFO [stdout] test gatt_write_response ... ok
Nov 18 19:24:34.420 INFO [stdout] test hal_device_standby ... ok
Nov 18 19:24:34.420 INFO [stdout] test hal_get_anchor_period ... ok
Nov 18 19:24:34.420 INFO [stdout] test gatt_add_characteristic ... ok
Nov 18 19:24:34.421 INFO [stdout] test hal_get_tx_test_packet_count ... ok
Nov 18 19:24:34.430 INFO [stdout] test hal_read_config_byte ... ok
Nov 18 19:24:34.432 INFO [stdout] test hal_read_config_data_diversifier ... ok
Nov 18 19:24:34.432 INFO [stdout] test hal_read_config_data_key ... ok
Nov 18 19:24:34.432 INFO [stdout] test hal_get_firmware_revision ... ok
Nov 18 19:24:34.432 INFO [stdout] test gap_update_advertising_data ... ok
Nov 18 19:24:34.432 INFO [stdout] test gatt_confirm_indication ... ok
Nov 18 19:24:34.432 INFO [stdout] test gatt_add_characteristic_descriptor ... ok
Nov 18 19:24:34.432 INFO [stdout] test hal_get_link_status_invalid_status ... ok
Nov 18 19:24:34.432 INFO [stdout] test hal_get_link_status ... ok
Nov 18 19:24:34.432 INFO [stdout] test hal_write_config_data_nonstandard_status ... ok
Nov 18 19:24:34.432 INFO [stdout] test hal_write_config_data ... ok
Nov 18 19:24:34.432 INFO [stdout] test l2cap_connection_parameter_update_response ... ok
Nov 18 19:24:34.443 INFO [stdout] test hal_read_config_data_public_addr ... ok
Nov 18 19:24:34.453 INFO [stdout] test hal_start_tone ... ok
Nov 18 19:24:34.453 INFO [stdout] test hal_set_tx_power_level ... ok
Nov 18 19:24:34.455 INFO [stdout] test hal_stop_tone ... ok
Nov 18 19:24:34.459 INFO [stdout] test hal_read_config_invalid ... ok
Nov 18 19:24:34.459 INFO [stderr]      Running /target/debug/deps/event-405684668b80c425
Nov 18 19:24:34.459 INFO [stdout] 
Nov 18 19:24:34.459 INFO [stdout] test result: ok. 69 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 19:24:34.459 INFO [stdout] 
Nov 18 19:24:34.459 INFO [stdout] 
Nov 18 19:24:34.459 INFO [stdout] running 86 tests
Nov 18 19:24:34.476 INFO [stdout] test att_execute_write_response ... ok
Nov 18 19:24:34.483 INFO [stdout] test att_exchange_mtu_response ... ok
Nov 18 19:24:34.483 INFO [stdout] test att_error_response ... ok
Nov 18 19:24:34.483 INFO [stdout] test att_error_response_failed_bad_request_opcode ... ok
Nov 18 19:24:34.483 INFO [stdout] test att_find_by_type_value_response ... ok
Nov 18 19:24:34.483 INFO [stdout] test att_find_by_type_value_response_failed_partial_pair ... ok
Nov 18 19:24:34.483 INFO [stdout] test att_find_information_response_128bit_uuids ... ok
Nov 18 19:24:34.483 INFO [stdout] test att_error_response_failed_bad_error_code ... ok
Nov 18 19:24:34.483 INFO [stdout] test att_prepare_write_response ... ok
Nov 18 19:24:34.483 INFO [stdout] test att_find_information_response_failed_format ... ok
Nov 18 19:24:34.483 INFO [stdout] test att_prepare_write_permit_request_empty ... ok
Nov 18 19:24:34.483 INFO [stdout] test att_prepare_write_permit_request ... ok
Nov 18 19:24:34.483 INFO [stdout] test att_find_information_response_16bit_uuids ... ok
Nov 18 19:24:34.483 INFO [stdout] test att_find_information_response_failed_partial_uuid ... ok
Nov 18 19:24:34.483 INFO [stdout] test att_prepare_write_response_empty ... ok
Nov 18 19:24:34.483 INFO [stdout] test att_read_blob_response ... ok
Nov 18 19:24:34.483 INFO [stdout] test att_read_blob_response_empty ... ok
Nov 18 19:24:34.483 INFO [stdout] test att_read_by_group_type_response_failed ... ok
Nov 18 19:24:34.483 INFO [stdout] test att_read_by_group_type_response ... ok
Nov 18 19:24:34.484 INFO [stdout] test att_read_blob_response_failed ... ok
Nov 18 19:24:34.484 INFO [stdout] test att_read_multiple_permit_request_failed ... ok
Nov 18 19:24:34.484 INFO [stdout] test att_read_by_type_response_failed_partial_pair ... ok
Nov 18 19:24:34.484 INFO [stdout] test att_read_multiple_permit_request ... ok
Nov 18 19:24:34.484 INFO [stdout] test att_read_by_type_response ... ok
Nov 18 19:24:34.484 INFO [stdout] test att_write_permit_request ... ok
Nov 18 19:24:34.484 INFO [stdout] test att_read_response_empty ... ok
Nov 18 19:24:34.484 INFO [stdout] test att_read_response_failed ... ok
Nov 18 19:24:34.487 INFO [stdout] test att_read_response ... ok
Nov 18 19:24:34.487 INFO [stdout] test gap_authorization_request ... ok
Nov 18 19:24:34.487 INFO [stdout] test att_write_permit_request_empty ... ok
Nov 18 19:24:34.487 INFO [stdout] test gap_bond_lost ... ok
Nov 18 19:24:34.487 INFO [stdout] test gap_device_found ... ok
Nov 18 19:24:34.487 INFO [stdout] test gap_device_found_failure_bad_bdaddr_type ... ok
Nov 18 19:24:34.487 INFO [stdout] test gap_device_found_failure_bad_data_length ... ok
Nov 18 19:24:34.487 INFO [stdout] test gap_device_found_failure_bad_event ... ok
Nov 18 19:24:34.487 INFO [stdout] test gap_device_found_failure_bad_rssi ... ok
Nov 18 19:24:34.487 INFO [stdout] test gap_limited_discoverable ... ok
Nov 18 19:24:34.487 INFO [stdout] test gap_pairing_complete ... ok
Nov 18 19:24:34.487 INFO [stdout] test gap_pairing_complete_failed ... ok
Nov 18 19:24:34.487 INFO [stdout] test gap_pass_key_request ... ok
Nov 18 19:24:34.487 INFO [stdout] test gap_peripheral_security_initiated ... ok
Nov 18 19:24:34.487 INFO [stdout] test gap_procedure_complete_failed_bad_procedure ... ok
Nov 18 19:24:34.487 INFO [stdout] test gap_procedure_complete ... ok
Nov 18 19:24:34.487 INFO [stdout] test gap_procedure_complete_failed_bad_status ... ok
Nov 18 19:24:34.487 INFO [stdout] test gap_procedure_complete_general_connection_establishment ... ok
Nov 18 19:24:34.487 INFO [stdout] test gap_procedure_complete_name_discovery ... ok
Nov 18 19:24:34.487 INFO [stdout] test gap_procedure_complete_failed_general_connection_establishment_length ... ok
Nov 18 19:24:34.487 INFO [stdout] test gatt_attribute_modified ... ok
Nov 18 19:24:34.487 INFO [stdout] test gatt_attribute_modified_failed_bad_data_len ... ok
Nov 18 19:24:34.487 INFO [stdout] test gatt_discover_or_read_characteristic_by_uuid_response ... ok
Nov 18 19:24:34.487 INFO [stdout] test gatt_discover_or_read_characteristic_by_uuid_response_empty ... ok
Nov 18 19:24:34.487 INFO [stdout] test gatt_indication ... ok
Nov 18 19:24:34.487 INFO [stdout] test gatt_indication_empty ... ok
Nov 18 19:24:34.487 INFO [stdout] test gatt_notification_empty ... ok
Nov 18 19:24:34.487 INFO [stdout] test gatt_procedure_complete_error_unknown_code ... ok
Nov 18 19:24:34.487 INFO [stdout] test gatt_procedure_complete_failed ... ok
Nov 18 19:24:34.487 INFO [stdout] test gatt_procedure_complete_success ... ok
Nov 18 19:24:34.487 INFO [stdout] test gatt_procedure_timeout ... ok
Nov 18 19:24:34.487 INFO [stdout] test gatt_server_confirmation ... ok
Nov 18 19:24:34.487 INFO [stdout] test gatt_tx_pool_available ... ok
Nov 18 19:24:34.487 INFO [stdout] test hal_crash_info ... ok
Nov 18 19:24:34.487 INFO [stdout] test hal_crash_info_failed_bad_crash_reason ... ok
Nov 18 19:24:34.487 INFO [stdout] test hal_crash_info_failed_bad_debug_data_len ... ok
Nov 18 19:24:34.487 INFO [stdout] test hal_events_lost ... ok
Nov 18 19:24:34.487 INFO [stdout] test hal_events_lost_failure ... ok
Nov 18 19:24:34.487 INFO [stdout] test hal_initialized ... ok
Nov 18 19:24:34.487 INFO [stdout] test hal_initialized_failure ... ok
Nov 18 19:24:34.487 INFO [stdout] test l2cap_connection_update_request ... ok
Nov 18 19:24:34.487 INFO [stdout] test l2cap_connection_update_request_failed_event_data_len ... ok
Nov 18 19:24:34.487 INFO [stdout] test l2cap_connection_update_request_failed_l2cap_len ... ok
Nov 18 19:24:34.487 INFO [stdout] test l2cap_connection_update_response_cmd_rejected ... ok
Nov 18 19:24:34.495 INFO [stdout] test l2cap_connection_update_response_failed_data_length ... ok
Nov 18 19:24:34.511 INFO [stdout] test l2cap_connection_update_response_failed_code ... ok
Nov 18 19:24:34.511 INFO [stdout] test att_read_multiple_response_empty ... ok
Nov 18 19:24:34.511 INFO [stdout] test l2cap_connection_update_response_failed_unknown_result ... ok
Nov 18 19:24:34.511 INFO [stdout] test gatt_notification ... ok
Nov 18 19:24:34.511 INFO [stdout] test att_read_multiple_response ... ok
Nov 18 19:24:34.511 INFO [stdout] test l2cap_connection_update_response_failed_unknown_rejection_reason ... ok
Nov 18 19:24:34.511 INFO [stdout] test att_read_permit_request ... ok
Nov 18 19:24:34.511 INFO [stdout] test gap_addr_not_resolved ... ok
Nov 18 19:24:34.511 INFO [stdout] test l2cap_procedure_timeout ... ok
Nov 18 19:24:34.511 INFO [stdout] test l2cap_connection_update_response_failed_l2cap_length ... ok
Nov 18 19:24:34.511 INFO [stdout] test att_read_multiple_response_failed ... ok
Nov 18 19:24:34.511 INFO [stdout] test l2cap_connection_update_response_updated_accepted ... ok
Nov 18 19:24:34.511 INFO [stdout] test l2cap_connection_update_response_updated_param_rejected ... ok
Nov 18 19:24:34.519 INFO [stdout] test l2cap_procedure_timeout_failed ... ok
Nov 18 19:24:34.519 INFO [stdout] 
Nov 18 19:24:34.519 INFO [stdout] test result: ok. 86 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 19:24:34.519 INFO [stdout] 
Nov 18 19:24:34.519 INFO [stderr]      Running /target/debug/deps/extensions-a513cfec78915d93
Nov 18 19:24:34.520 INFO [stdout] 
Nov 18 19:24:34.520 INFO [stdout] running 2 tests
Nov 18 19:24:34.520 INFO [stdout] test hardware_error ... ok
Nov 18 19:24:34.523 INFO [stdout] test hardware_error_failed ... ok
Nov 18 19:24:34.523 INFO [stderr]      Running /target/debug/deps/gap-ac723325cc51c556
Nov 18 19:24:34.523 INFO [stdout] 
Nov 18 19:24:34.523 INFO [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 19:24:34.523 INFO [stdout] 
Nov 18 19:24:34.524 INFO [stdout] 
Nov 18 19:24:34.524 INFO [stdout] running 60 tests
Nov 18 19:24:34.524 INFO [stdout] test authorization_response ... ok
Nov 18 19:24:34.524 INFO [stdout] test clear_security_database ... ok
Nov 18 19:24:34.524 INFO [stdout] test configure_white_list ... ok
Nov 18 19:24:34.524 INFO [stdout] test delete_ad_type ... ok
Nov 18 19:24:34.524 INFO [stdout] test allow_rebond ... ok
Nov 18 19:24:34.524 INFO [stdout] test is_device_bonded ... ok
Nov 18 19:24:34.524 INFO [stdout] test get_bonded_devices ... ok
Nov 18 19:24:34.525 INFO [stdout] test pass_key_response_bad_pin ... ok
Nov 18 19:24:34.525 INFO [stdout] test pass_key_response ... ok
Nov 18 19:24:34.525 INFO [stdout] test set_authentication_requirement ... ok
Nov 18 19:24:34.525 INFO [stdout] test set_authentication_requirement_2 ... ok
Nov 18 19:24:34.525 INFO [stdout] test set_authentication_requirement_bad_key_size_range ... ok
Nov 18 19:24:34.525 INFO [stdout] test peripheral_security_request ... ok
Nov 18 19:24:34.525 INFO [stdout] test set_authentication_requirement_bad_pin ... ok
Nov 18 19:24:34.525 INFO [stdout] test set_broadcast_mode ... ok
Nov 18 19:24:34.525 INFO [stdout] test send_pairing_request ... ok
Nov 18 19:24:34.526 INFO [stdout] test set_authorization_requirement ... ok
Nov 18 19:24:34.526 INFO [stdout] test get_security_level ... ok
Nov 18 19:24:34.526 INFO [stdout] test set_broadcast_mode_advertising_data_too_long ... ok
Nov 18 19:24:34.526 INFO [stdout] test init ... ok
Nov 18 19:24:34.526 INFO [stdout] test set_broadcast_mode_white_list_too_long_no_adv_data ... ok
Nov 18 19:24:34.526 INFO [stdout] test set_direct_connectable ... ok
Nov 18 19:24:34.526 INFO [stdout] test resolve_private_address ... ok
Nov 18 19:24:34.526 INFO [stdout] test set_broadcast_mode_white_list_too_long ... ok
Nov 18 19:24:34.527 INFO [stdout] test set_broadcast_mode_bad_advertising_type ... ok
Nov 18 19:24:34.527 INFO [stdout] test create_connection ... ok
Nov 18 19:24:34.527 INFO [stdout] test set_discoverable_bad_adv_type ... ok
Nov 18 19:24:34.527 INFO [stdout] test set_event_mask ... ok
Nov 18 19:24:34.527 INFO [stdout] test set_io_capability ... ok
Nov 18 19:24:34.527 INFO [stdout] test set_discoverable_bad_conn_interval ... ok
Nov 18 19:24:34.527 INFO [stdout] test set_direct_connectable_bad_adv_type ... ok
Nov 18 19:24:34.527 INFO [stdout] test set_discoverable ... ok
Nov 18 19:24:34.527 INFO [stdout] test set_direct_connectable_bad_adv_interval ... ok
Nov 18 19:24:34.527 INFO [stdout] test set_discoverable_bad_adv_interval ... ok
Nov 18 19:24:34.528 INFO [stdout] test set_nondiscoverable ... ok
Nov 18 19:24:34.528 INFO [stdout] test set_limited_discoverable_bad_adv_type ... ok
Nov 18 19:24:34.528 INFO [stdout] test set_limited_discoverable_bad_adv_interval ... ok
Nov 18 19:24:34.528 INFO [stdout] test set_limited_discoverable ... ok
Nov 18 19:24:34.528 INFO [stdout] test set_limited_discoverable_bad_conn_interval ... ok
Nov 18 19:24:34.528 INFO [stdout] test set_nonconnectable ... ok
Nov 18 19:24:34.528 INFO [stdout] test set_nonconnectable_bad_type ... ok
Nov 18 19:24:34.528 INFO [stdout] test start_auto_connection_establishment_white_list_too_long ... ok
Nov 18 19:24:34.528 INFO [stdout] test set_undirected_connectable ... ok
Nov 18 19:24:34.528 INFO [stdout] test start_connection_update ... ok
Nov 18 19:24:34.528 INFO [stdout] test start_general_connection_establishment ... ok
Nov 18 19:24:34.528 INFO [stdout] test start_general_discovery_procedure ... ok
Nov 18 19:24:34.531 INFO [stdout] test start_observation_procedure ... ok
Nov 18 19:24:34.531 INFO [stdout] test set_undirected_connectable_bad_advertising_filter_policy ... ok
Nov 18 19:24:34.531 INFO [stdout] test start_name_discovery_procedure ... ok
Nov 18 19:24:34.531 INFO [stdout] test start_auto_connection_establishment ... ok
Nov 18 19:24:34.531 INFO [stdout] test start_limited_discovery_procedure ... ok
Nov 18 19:24:34.531 INFO [stdout] test start_selective_connection_establishment ... ok
Nov 18 19:24:34.531 INFO [stdout] test start_selective_connection_establishment_white_list_too_long ... ok
Nov 18 19:24:34.531 INFO [stdout] test terminate_bad_disconnection_reason ... ok
Nov 18 19:24:34.531 INFO [stdout] test terminate_multiple_procedure ... ok
Nov 18 19:24:34.531 INFO [stdout] test terminate_no_procedure ... ok
Nov 18 19:24:34.531 INFO [stdout] test update_advertising_data ... ok
Nov 18 19:24:34.531 INFO [stdout] test update_advertising_data_too_long ... ok
Nov 18 19:24:34.539 INFO [stdout] test terminate_procedure ... ok
Nov 18 19:24:34.539 INFO [stderr]      Running /target/debug/deps/gatt-b69cb03bb48aaad5
Nov 18 19:24:34.539 INFO [stdout] test terminate ... ok
Nov 18 19:24:34.539 INFO [stdout] 
Nov 18 19:24:34.539 INFO [stdout] test result: ok. 60 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 19:24:34.539 INFO [stdout] 
Nov 18 19:24:34.562 INFO [stdout] 
Nov 18 19:24:34.563 INFO [stderr]      Running /target/debug/deps/hal-76f4085ea7e3256d
Nov 18 19:24:34.563 INFO [stdout] running 73 tests
Nov 18 19:24:34.563 INFO [stdout] test add_characteristic_16 ... ok
Nov 18 19:24:34.563 INFO [stdout] test add_characteristic_128 ... ok
Nov 18 19:24:34.563 INFO [stdout] test add_characteristic_descriptor_buffer_too_long ... ok
Nov 18 19:24:34.563 INFO [stdout] test add_service_128 ... ok
Nov 18 19:24:34.563 INFO [stdout] test add_characteristic_descriptor_128 ... ok
Nov 18 19:24:34.563 INFO [stdout] test add_characteristic_pre_v72_128 ... ok
Nov 18 19:24:34.563 INFO [stdout] test add_characteristic_pre_v72_16 ... ok
Nov 18 19:24:34.563 INFO [stdout] test delete_characteristic ... ok
Nov 18 19:24:34.563 INFO [stdout] test bad_range ... ok
Nov 18 19:24:34.563 INFO [stdout] test delete_included_service ... ok
Nov 18 19:24:34.563 INFO [stdout] test delete_service ... ok
Nov 18 19:24:34.563 INFO [stdout] test discover_all_characteristic_descriptors ... ok
Nov 18 19:24:34.563 INFO [stdout] test discover_all_characteristics_of_service ... ok
Nov 18 19:24:34.563 INFO [stdout] test add_service_16 ... ok
Nov 18 19:24:34.563 INFO [stdout] test discover_all_primary_services ... ok
Nov 18 19:24:34.563 INFO [stdout] test discover_characteristics_by_uuid_128 ... ok
Nov 18 19:24:34.563 INFO [stdout] test discover_characteristics_by_uuid_16 ... ok
Nov 18 19:24:34.563 INFO [stdout] test discovery_primary_services_by_uuid_128 ... ok
Nov 18 19:24:34.563 INFO [stdout] test discovery_primary_services_by_uuid_16 ... ok
Nov 18 19:24:34.563 INFO [stdout] test encryption_key_size_range ... ok
Nov 18 19:24:34.563 INFO [stdout] test exchange_configuration ... ok
Nov 18 19:24:34.563 INFO [stdout] test execute_write_request ... ok
Nov 18 19:24:34.563 INFO [stdout] test find_by_type_value_request_value_too_long ... ok
Nov 18 19:24:34.563 INFO [stdout] test find_included_services ... ok
Nov 18 19:24:34.563 INFO [stdout] test find_information_request ... ok
Nov 18 19:24:34.563 INFO [stdout] test include_service_128 ... ok
Nov 18 19:24:34.563 INFO [stdout] test include_service_16 ... ok
Nov 18 19:24:34.563 INFO [stdout] test init ... ok
Nov 18 19:24:34.563 INFO [stdout] test prepare_write_request ... ok
Nov 18 19:24:34.563 INFO [stdout] test prepare_write_request_too_long ... ok
Nov 18 19:24:34.563 INFO [stdout] test read_by_group_type_request_128 ... ok
Nov 18 19:24:34.563 INFO [stdout] test read_by_group_type_request_16 ... ok
Nov 18 19:24:34.563 INFO [stdout] test read_by_type_request_128 ... ok
Nov 18 19:24:34.563 INFO [stdout] test read_by_type_request_16 ... ok
Nov 18 19:24:34.563 INFO [stdout] test read_characteristic_descriptor ... ok
Nov 18 19:24:34.563 INFO [stdout] test read_characteristic_using_uuid_128 ... ok
Nov 18 19:24:34.563 INFO [stdout] test read_characteristic_using_uuid_16 ... ok
Nov 18 19:24:34.563 INFO [stdout] test read_characteristic_value ... ok
Nov 18 19:24:34.563 INFO [stdout] test read_handle_value ... ok
Nov 18 19:24:34.563 INFO [stdout] test read_handle_value_offset ... ok
Nov 18 19:24:34.563 INFO [stdout] test read_long_characteristic_descriptor ... ok
Nov 18 19:24:34.563 INFO [stdout] test read_long_characteristic_value ... ok
Nov 18 19:24:34.563 INFO [stdout] test read_multiple_characteristic_values ... ok
Nov 18 19:24:34.563 INFO [stdout] test cancel_write_request ... ok
Nov 18 19:24:34.563 INFO [stdout] test confirm_indication ... ok
Nov 18 19:24:34.563 INFO [stdout] test allow_read ... ok
Nov 18 19:24:34.563 INFO [stdout] test find_by_type_value_request ... ok
Nov 18 19:24:34.563 INFO [stdout] test add_characteristic_descriptor_16 ... ok
Nov 18 19:24:34.563 INFO [stdout] test add_characteristic_descriptor_too_long ... ok
Nov 18 19:24:34.563 INFO [stdout] test set_descriptor_value ... ok
Nov 18 19:24:34.563 INFO [stdout] test read_multiple_characteristic_values_too_many_handles ... ok
Nov 18 19:24:34.563 INFO [stdout] test set_event_mask ... ok
Nov 18 19:24:34.563 INFO [stdout] test signed_write_without_response ... ok
Nov 18 19:24:34.563 INFO [stdout] test set_descriptor_value_too_long ... ok
Nov 18 19:24:34.563 INFO [stdout] test signed_write_without_response_too_long ... ok
Nov 18 19:24:34.563 INFO [stdout] test write_characteristic_value_reliably ... ok
Nov 18 19:24:34.563 INFO [stdout] test write_characteristic_value_reliably_too_long ... ok
Nov 18 19:24:34.563 INFO [stdout] test write_characteristic_value_too_long ... ok
Nov 18 19:24:34.563 INFO [stdout] test write_long_characteristic_descriptor ... ok
Nov 18 19:24:34.563 INFO [stdout] test write_long_characteristic_descriptor_too_long ... ok
Nov 18 19:24:34.563 INFO [stdout] test write_long_characteristic_value ... ok
Nov 18 19:24:34.563 INFO [stdout] test write_long_characteristic_value_too_long ... ok
Nov 18 19:24:34.563 INFO [stdout] test write_response ... ok
Nov 18 19:24:34.563 INFO [stdout] test write_response_too_long ... ok
Nov 18 19:24:34.563 INFO [stdout] test write_without_response ... ok
Nov 18 19:24:34.563 INFO [stdout] test write_without_response_too_long ... ok
Nov 18 19:24:34.563 INFO [stdout] test write_characteristic_descriptor_too_long ... ok
Nov 18 19:24:34.563 INFO [stdout] test write_characteristic_value ... ok
Nov 18 19:24:34.563 INFO [stdout] test update_long_characteristic_value ... ok
Nov 18 19:24:34.563 INFO [stdout] test update_long_characteristic_value_too_long ... ok
Nov 18 19:24:34.563 INFO [stdout] test write_characteristic_descriptor ... ok
Nov 18 19:24:34.563 INFO [stdout] test update_characteristic_value ... ok
Nov 18 19:24:34.563 INFO [stdout] test set_security_permission ... ok
Nov 18 19:24:34.563 INFO [stdout] 
Nov 18 19:24:34.564 INFO [stdout] test result: ok. 73 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 19:24:34.564 INFO [stdout] 
Nov 18 19:24:34.564 INFO [stdout] 
Nov 18 19:24:34.564 INFO [stdout] running 12 tests
Nov 18 19:24:34.570 INFO [stdout] test get_firmware_revision ... ok
Nov 18 19:24:34.571 INFO [stdout] test config_data ... ok
Nov 18 19:24:34.571 INFO [stdout] test set_tx_power_level ... ok
Nov 18 19:24:34.571 INFO [stdout] test device_standby ... ok
Nov 18 19:24:34.571 INFO [stdout] test read_config_data ... ok
Nov 18 19:24:34.571 INFO [stdout] test get_tx_test_packet_count ... ok
Nov 18 19:24:34.571 INFO [stdout] test get_link_status ... ok
Nov 18 19:24:34.571 INFO [stdout] test stop_tone ... ok
Nov 18 19:24:34.571 INFO [stdout] test get_anchor_period ... ok
Nov 18 19:24:34.575 INFO [stdout] test start_tone ... ok
Nov 18 19:24:34.575 INFO [stdout] test start_tone_invalid ... ok
Nov 18 19:24:34.575 INFO [stdout] test write_config_data ... ok
Nov 18 19:24:34.575 INFO [stdout] 
Nov 18 19:24:34.575 INFO [stdout] test result: ok. 12 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 19:24:34.575 INFO [stdout] 
Nov 18 19:24:34.579 INFO [stderr]      Running /target/debug/deps/l2cap-f637cd8c0581c7d4
Nov 18 19:24:34.586 INFO [stdout] 
Nov 18 19:24:34.586 INFO [stdout] running 2 tests
Nov 18 19:24:34.587 INFO [stdout] test connection_parameter_update_request ... ok
Nov 18 19:24:34.587 INFO [stdout] test connection_parameter_update_response ... ok
Nov 18 19:24:34.587 INFO [stdout] 
Nov 18 19:24:34.587 INFO [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 19:24:34.587 INFO [stdout] 
Nov 18 19:24:34.587 INFO [stderr]    Doc-tests bluenrg
Nov 18 19:24:35.383 INFO [stdout] 
Nov 18 19:24:35.383 INFO [stdout] running 0 tests
Nov 18 19:24:35.383 INFO [stdout] 
Nov 18 19:24:35.383 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 19:24:35.383 INFO [stdout] 
Nov 18 19:24:35.395 INFO [stderr] su: No module specific data is present
Nov 18 19:24:35.660 INFO running `"docker" "inspect" "5b64c9cdee24e0dbf3ce6d7e5a2baf103cab06c5ee7d2f65e762f6ad94127fab"`
Nov 18 19:24:35.766 INFO running `"docker" "rm" "-f" "5b64c9cdee24e0dbf3ce6d7e5a2baf103cab06c5ee7d2f65e762f6ad94127fab"`
Nov 18 19:24:35.888 INFO [stdout] 5b64c9cdee24e0dbf3ce6d7e5a2baf103cab06c5ee7d2f65e762f6ad94127fab