[INFO] fetching crate chematic-chem 0.4.27...
[INFO] testing chematic-chem-0.4.27 against 1.98.0-beta.1 for beta-1.98-1
[INFO] extracting crate chematic-chem 0.4.27 into /workspace/builds/worker-5-tc2/source
[INFO] started tweaking crates.io crate chematic-chem 0.4.27
[INFO] removed 0 missing examples
[INFO] removed 0 missing tests
[INFO] finished tweaking crates.io crate chematic-chem 0.4.27
[INFO] tweaked toml for crates.io crate chematic-chem 0.4.27 written to /workspace/builds/worker-5-tc2/source/Cargo.toml
[INFO] validating manifest of crates.io crate chematic-chem 0.4.27 on toolchain 1.98.0-beta.1
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.98.0-beta.1" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate crates.io crate chematic-chem 0.4.27 already has a lockfile, it will not be regenerated
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.98.0-beta.1" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/target:/opt/rustwide/target:rw,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" "-m" "1610612736" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:3d5ced03c013a94a2f102a4510f48a6e9184255caf5fd8244f58017bde7f5210" "sleep" "infinity", kill_on_drop: false }`
[INFO] [stdout] 0792f0e3254a6d05a1aac48e87e4bfe1a6a7dc20f2411c2fbbe802a0a2e5c702
[INFO] running `Command { std: "docker" "start" "0792f0e3254a6d05a1aac48e87e4bfe1a6a7dc20f2411c2fbbe802a0a2e5c702", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "0792f0e3254a6d05a1aac48e87e4bfe1a6a7dc20f2411c2fbbe802a0a2e5c702", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-w" "/opt/rustwide/workdir" "--user" "0:0" "0792f0e3254a6d05a1aac48e87e4bfe1a6a7dc20f2411c2fbbe802a0a2e5c702" "/opt/rustwide/cargo-home/bin/cargo" "+1.98.0-beta.1" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "0792f0e3254a6d05a1aac48e87e4bfe1a6a7dc20f2411c2fbbe802a0a2e5c702", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-w" "/opt/rustwide/workdir" "--user" "0:0" "0792f0e3254a6d05a1aac48e87e4bfe1a6a7dc20f2411c2fbbe802a0a2e5c702" "/opt/rustwide/cargo-home/bin/cargo" "+1.98.0-beta.1" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling chematic-core v0.4.27
[INFO] [stderr]    Compiling rustc-hash v2.1.2
[INFO] [stderr]    Compiling chematic-perception v0.4.27
[INFO] [stderr]    Compiling chematic-smiles v0.4.27
[INFO] [stderr]    Compiling chematic-smarts v0.4.27
[INFO] [stderr]    Compiling chematic-iupac v0.4.27
[INFO] [stderr]    Compiling chematic-rxn v0.4.27
[INFO] [stderr]    Compiling chematic-fp v0.4.27
[INFO] [stderr]    Compiling chematic-chem v0.4.27 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 17.75s
[INFO] running `Command { std: "docker" "inspect" "0792f0e3254a6d05a1aac48e87e4bfe1a6a7dc20f2411c2fbbe802a0a2e5c702", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-w" "/opt/rustwide/workdir" "--user" "0:0" "0792f0e3254a6d05a1aac48e87e4bfe1a6a7dc20f2411c2fbbe802a0a2e5c702" "/opt/rustwide/cargo-home/bin/cargo" "+1.98.0-beta.1" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling zerocopy v0.8.52
[INFO] [stderr]    Compiling syn v2.0.118
[INFO] [stderr]    Compiling cc v1.2.65
[INFO] [stderr]    Compiling ciborium-io v0.2.2
[INFO] [stderr]    Compiling memchr v2.8.2
[INFO] [stderr]    Compiling clap_lex v1.1.0
[INFO] [stderr]    Compiling anstyle v1.0.14
[INFO] [stderr]    Compiling regex-automata v0.4.14
[INFO] [stderr]    Compiling itertools v0.13.0
[INFO] [stderr]    Compiling clap_builder v4.6.0
[INFO] [stderr]    Compiling page_size v0.6.0
[INFO] [stderr]    Compiling rayon v1.12.0
[INFO] [stderr]    Compiling anes v0.1.6
[INFO] [stderr]    Compiling serde_json v1.0.150
[INFO] [stderr]    Compiling alloca v0.4.0
[INFO] [stderr]    Compiling criterion-plot v0.8.2
[INFO] [stderr]    Compiling regex v1.12.4
[INFO] [stderr]    Compiling clap v4.6.1
[INFO] [stderr]    Compiling zerocopy-derive v0.8.52
[INFO] [stderr]    Compiling serde_derive v1.0.228
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling tinytemplate v1.2.1
[INFO] [stderr]    Compiling half v2.7.1
[INFO] [stderr]    Compiling ciborium-ll v0.2.2
[INFO] [stderr]    Compiling ciborium v0.2.2
[INFO] [stderr]    Compiling criterion v0.8.2
[INFO] [stderr]    Compiling chematic-chem v0.4.27 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 57.15s
[INFO] running `Command { std: "docker" "inspect" "0792f0e3254a6d05a1aac48e87e4bfe1a6a7dc20f2411c2fbbe802a0a2e5c702", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-w" "/opt/rustwide/workdir" "--user" "0:0" "0792f0e3254a6d05a1aac48e87e4bfe1a6a7dc20f2411c2fbbe802a0a2e5c702" "/opt/rustwide/cargo-home/bin/cargo" "+1.98.0-beta.1" "test" "--frozen", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.15s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/chematic_chem-11fe03ebb01694a4)
[INFO] [stdout] running 669 tests
[INFO] [stdout] test abbreviations::tests::test_abbreviations_slice_non_empty ... ok
[INFO] [stdout] test abbreviations::tests::test_expand_cf3 ... ok
[INFO] [stdout] test abbreviations::tests::test_expand_me ... ok
[INFO] [stdout] test abbreviations::tests::test_expand_ph ... ok
[INFO] [stdout] test abbreviations::tests::test_unknown_returns_none ... ok
[INFO] [stdout] test activity_cliff::tests::test_dissimilar_molecules_no_cliff_despite_activity_gap ... ok
[INFO] [stdout] test activity_cliff::tests::test_empty_or_single_molecule ... ok
[INFO] [stdout] test activity_cliff::tests::test_identical_molecules_no_cliff ... ok
[INFO] [stdout] test activity_cliff::tests::test_no_cliff_when_sim_below_threshold ... ok
[INFO] [stdout] test activity_cliff::tests::test_sorted_by_similarity_descending ... ok
[INFO] [stdout] test activity_cliff::tests::test_mul_mol_series_low_threshold ... ok
[INFO] [stdout] test admet::tests::test_ames_clean_molecule ... ok
[INFO] [stdout] test activity_cliff::tests::test_large_activity_gap_is_cliff_at_low_threshold ... ok
[INFO] [stdout] test admet::tests::test_ames_n_nitroso ... ok
[INFO] [stdout] test admet::tests::test_ames_nitro_aromatic ... ok
[INFO] [stdout] test admet::tests::test_admet_profile_benzene ... ok
[INFO] [stdout] test admet::tests::test_ames_primary_aromatic_amine ... ok
[INFO] [stdout] test admet::tests::test_bbb_rule_metformin_fails ... ok
[INFO] [stdout] test admet::tests::test_bbb_benzene_passes ... ok
[INFO] [stdout] test activity_cliff::tests::test_no_cliff_when_activity_delta_below_threshold ... ok
[INFO] [stdout] test admet::tests::test_boiled_egg_zone_keys ... ok
[INFO] [stdout] test admet::tests::test_boiled_egg_aspirin_gi_absorbed ... ok
[INFO] [stdout] test admet::tests::test_bbb_aspirin_passes ... ok
[INFO] [stdout] test admet::tests::test_bbb_score_high_tpsa_fails ... ok
[INFO] [stdout] test admet::tests::test_caco2_aspirin ... ok
[INFO] [stdout] test admet::tests::test_caco2_nonpolar_high ... ok
[INFO] [stdout] test admet::tests::test_caco2_polar_low ... ok
[INFO] [stdout] test admet::tests::test_clearance_returns_valid_class ... ok
[INFO] [stdout] test admet::tests::test_cyp3a4_benzene_low ... ok
[INFO] [stdout] test admet::tests::test_cyp3a4_score_range ... ok
[INFO] [stdout] test admet::tests::test_admet_profile_aspirin ... ok
[INFO] [stdout] test admet::tests::test_admet_profile_has_new_fields ... ok
[INFO] [stdout] test admet::tests::test_herg_score_range ... ok
[INFO] [stdout] test admet::tests::test_admet_profile_glucose ... ok
[INFO] [stdout] test admet::tests::test_herg_benzene_low ... ok
[INFO] [stdout] test admet::tests::test_ppb_hydrophilic_molecule ... ok
[INFO] [stdout] test admet::tests::test_ppb_lipophilic_molecule ... ok
[INFO] [stdout] test alerts::tests::test_aldehyde_formaldehyde_matches ... ok
[INFO] [stdout] test alerts::tests::test_aldehyde_acetaldehyde_matches ... ok
[INFO] [stdout] test admet::tests::test_ppb_range ... ok
[INFO] [stdout] test admet::tests::test_clearance_score_range ... ok
[INFO] [stdout] test admet::tests::test_cyp3a4_large_het_ar_high ... ok
[INFO] [stdout] test admet::tests::test_herg_basic_lipophilic_high ... ok
[INFO] [stdout] test alerts::tests::test_brenk_count ... ok
[INFO] [stdout] test alerts::tests::test_brenk_aniline_fails ... ok
[INFO] [stdout] test alerts::tests::test_brenk_phenol_fails ... ok
[INFO] [stdout] test activity_cliff::tests::test_panic_on_mismatched_lengths - should panic ... ok
[INFO] [stdout] test alerts::tests::test_brenk_thiol_fails ... ok
[INFO] [stdout] test atropisomer::tests::assign_atropisomer_chirality_preserves_atoms ... ok
[INFO] [stdout] test atropisomer::tests::assign_atropisomer_chirality_preserves_bonds ... ok
[INFO] [stdout] test atropisomer::tests::detect_atropisomers_biaryl ... ok
[INFO] [stdout] test atropisomer::tests::detect_atropisomers_none ... ok
[INFO] [stdout] test brics::mmp_probe::core_smiles_equal_for_ethylbenzene_and_propylbenzene ... ok
[INFO] [stdout] test brics::tests::test_brics_bonds_amide ... ok
[INFO] [stdout] test brics::tests::test_brics_bonds_aspirin ... ok
[INFO] [stdout] test brics::tests::test_brics_bonds_benzene_zero ... ok
[INFO] [stdout] test brics::tests::test_brics_bonds_ester ... ok
[INFO] [stdout] test brics::tests::test_brics_bonds_ethane_zero ... ok
[INFO] [stdout] test brics::tests::test_brics_bonds_ethylbenzene_one ... ok
[INFO] [stdout] test brics::tests::test_brics_bonds_propane_zero ... ok
[INFO] [stdout] test brics::tests::test_brics_bonds_butane_one ... ok
[INFO] [stdout] test brics::tests::test_brics_fragments_all_valid_range ... ok
[INFO] [stdout] test brics::tests::test_brics_bonds_toluene_one ... ok
[INFO] [stdout] test brics::tests::test_brics_fragments_aspirin_multiple ... ok
[INFO] [stdout] test brics::tests::test_brics_fragments_atom_count_conservation ... ok
[INFO] [stdout] test brics::tests::test_brics_fragments_benzene_no_cut ... ok
[INFO] [stdout] test cache::tests::cache_clear ... ok
[INFO] [stdout] test brics::tests::test_brics_fragments_butane_two_pieces ... ok
[INFO] [stdout] test cache::tests::cache_eviction ... ok
[INFO] [stdout] test cache::tests::cache_miss ... ok
[INFO] [stdout] test cache::tests::cache_put_get ... ok
[INFO] [stdout] test canonical::tests::backbone_strips_charge_and_isotope ... ok
[INFO] [stdout] test canonical::tests::normal_preserves_stereo ... ok
[INFO] [stdout] test canonical::tests::nostereo_enantiomers_identical ... ok
[INFO] [stdout] test canonical::tests::nostereo_removes_stereo ... ok
[INFO] [stdout] test canonical::tests::nostereo_tautomer_combines_both ... ok
[INFO] [stdout] test canonical::tests::all_modes_return_valid_smiles ... ok
[INFO] [stdout] test cip::tests::test_allene_no_stereo_no_assignment ... ok
[INFO] [stdout] test canonical::tests::tautomer_mode_normalises ... ok
[INFO] [stdout] test cip::tests::test_allene_two_enantiomers_differ ... ok
[INFO] [stdout] test cip::tests::test_assignment_get ... ok
[INFO] [stdout] test cip::tests::test_chfclbr_r ... ok
[INFO] [stdout] test cip::tests::test_canonical_preserves_ez ... ok
[INFO] [stdout] test cip::tests::test_chfclbr_s ... ok
[INFO] [stdout] test cip::tests::test_cip_assignment_methane ... ok
[INFO] [stdout] test cip::tests::test_cip_assignment_struct ... ok
[INFO] [stdout] test cip::tests::test_cip_atomic_mass_tiebreaker_infrastructure ... ok
[INFO] [stdout] test cip::tests::test_cip_decalin_assignment ... ok
[INFO] [stdout] test cip::tests::test_cip_fused_ring_no_crash ... ok
[INFO] [stdout] test cip::tests::test_cip_enantiomers_consistent_with_mass_tiebreaker ... ok
[INFO] [stdout] test cip::tests::test_cip_naphthalene_assignment ... ok
[INFO] [stdout] test cip::tests::test_cip_tied_substituents_no_assignment ... ok
[INFO] [stdout] test cip::tests::test_d_alanine_r ... ok
[INFO] [stdout] test alerts::tests::test_pains_count_at_most_480 ... ok
[INFO] [stdout] test cip::tests::test_ez_terminal_no_crash ... ok
[INFO] [stdout] test cip::tests::test_fceccl_e ... ok
[INFO] [stdout] test cip::tests::test_fceccl_z ... ok
[INFO] [stdout] test cip::tests::test_l_alanine_s ... ok
[INFO] [stdout] test cip::tests::test_multiple_chiral_centers ... ok
[INFO] [stdout] test cip::tests::test_no_chirality ... ok
[INFO] [stdout] test cip::tests::test_no_ez_no_stereo_bond ... ok
[INFO] [stdout] test cip::tests::test_non_allene_not_detected ... ok
[INFO] [stdout] test cip::tests::test_r_lactic_acid_gives_answer ... ok
[INFO] [stdout] test cip::tests::test_r_s_are_consistent ... ok
[INFO] [stdout] test cip::tests::test_symmetric_center_none ... ok
[INFO] [stdout] test cip::tests::test_trans_2_butene_e ... ok
[INFO] [stdout] test condensed::tests::test_acetic_acid_ch3cooh ... ok
[INFO] [stdout] test condensed::tests::test_ammonia ... ok
[INFO] [stdout] test condensed::tests::test_butane ... ok
[INFO] [stdout] test condensed::tests::test_digit_repeat_cap_rejects_large_count ... ok
[INFO] [stdout] test condensed::tests::test_empty_input ... ok
[INFO] [stdout] test cip::tests::test_cis_2_butene_z ... ok
[INFO] [stdout] test cip::tests::test_e_z_are_consistent ... ok
[INFO] [stdout] test condensed::tests::test_hexane_linear ... ok
[INFO] [stdout] test condensed::tests::test_ethane ... ok
[INFO] [stdout] test condensed::tests::test_methane_with_h_count ... ok
[INFO] [stdout] test condensed::tests::test_methanol_ch3oh ... ok
[INFO] [stdout] test condensed::tests::test_multi_char_atom_repeat_br2 ... ok
[INFO] [stdout] test condensed::tests::test_multi_char_atom_repeat_cl2 ... ok
[INFO] [stdout] test condensed::tests::test_propane ... ok
[INFO] [stdout] test condensed::tests::test_methane ... ok
[INFO] [stdout] test condensed::tests::test_water ... ok
[INFO] [stdout] test condensed::tests::test_simple_branched ... ok
[INFO] [stdout] test condensed::tests::test_unknown_element ... ok
[INFO] [stdout] test condensed::tests::test_with_functional_group ... ok
[INFO] [stdout] test descriptors::tests::all_crippen_smarts_parse ... ok
[INFO] [stdout] test descriptors::tests::bcut2d_hi_ge_lo ... ok
[INFO] [stdout] test descriptors::tests::bcut2d_single_atom_zero ... ok
[INFO] [stdout] test descriptors::tests::carbon_types_acetylene ... ok
[INFO] [stdout] test descriptors::tests::bcut2d_all_finite ... ok
[INFO] [stdout] test descriptors::tests::carbon_types_ethane ... ok
[INFO] [stdout] test descriptors::tests::carbon_types_methane ... ok
[INFO] [stdout] test descriptors::tests::carbon_types_propane ... ok
[INFO] [stdout] test descriptors::tests::geary_finite_for_mixed_molecule ... ok
[INFO] [stdout] test descriptors::tests::geary_len_is_7 ... ok
[INFO] [stdout] test descriptors::tests::geary_single_atom_returns_ones ... ok
[INFO] [stdout] test descriptors::tests::information_content_benzene_zero_ic ... ok
[INFO] [stdout] test descriptors::tests::information_content_propane_nonzero ... ok
[INFO] [stdout] test descriptors::tests::information_content_single_atom ... ok
[INFO] [stdout] test descriptors::tests::carbon_types_benzene ... ok
[INFO] [stdout] test descriptors::tests::mde_carbon_all_finite ... ok
[INFO] [stdout] test descriptors::tests::bcut2d_hi_strictly_greater_than_lo ... ok
[INFO] [stdout] test descriptors::tests::moran_finite_for_mixed_molecule ... ok
[INFO] [stdout] test descriptors::tests::moran_len_is_7 ... ok
[INFO] [stdout] test descriptors::tests::moran_single_atom_returns_zeros ... ok
[INFO] [stdout] test descriptors::tests::moran_uniform_valence_is_zero ... ok
[INFO] [stdout] test alerts::tests::test_pains_rhodanine_fails ... ok
[INFO] [stdout] test descriptors::tests::mde_carbon_propane_mdec11 ... ok
[INFO] [stdout] test descriptors::tests::test_arc_bench_bridged_benzenes ... ok
[INFO] [stdout] test descriptors::tests::test_arc_bench_no_bridge ... ok
[INFO] [stdout] test descriptors::tests::test_aromatic_ring_count_acridine ... ok
[INFO] [stdout] test descriptors::tests::test_arc_bench_lactone_benzene ... ok
[INFO] [stdout] test descriptors::tests::test_arc_bench_steroid_benzene ... ok
[INFO] [stdout] test descriptors::tests::test_aromatic_ring_count_benzene ... ok
[INFO] [stdout] test descriptors::tests::test_aromatic_ring_count_aspirin ... ok
[INFO] [stdout] test descriptors::tests::test_aromatic_ring_count_cyclohexane ... ok
[INFO] [stdout] test descriptors::tests::test_aromatic_ring_count_anthracene ... ok
[INFO] [stdout] test descriptors::tests::test_aromatic_ring_count_pyrene ... ok
[INFO] [stdout] test descriptors::tests::test_aromatic_ring_count_naphthalene ... ok
[INFO] [stdout] test descriptors::tests::test_autocorr_2d_benzene ... ok
[INFO] [stdout] test descriptors::tests::test_autocorr_2d_ethane ... ok
[INFO] [stdout] test descriptors::tests::test_autocorr_2d_propane ... ok
[INFO] [stdout] test descriptors::tests::test_autocorr_2d_single_atom ... ok
[INFO] [stdout] test descriptors::tests::test_balaban_j_benzene ... ok
[INFO] [stdout] test descriptors::tests::test_aromatic_ring_count_triphenylene ... ok
[INFO] [stdout] test descriptors::tests::test_balaban_j_matches_rdkit ... ok
[INFO] [stdout] test descriptors::tests::test_balaban_j_single_atom_zero ... ok
[INFO] [stdout] test descriptors::tests::test_balaban_j_ethane ... ok
[INFO] [stdout] test descriptors::tests::test_calc_mol_formula_benzene ... ok
[INFO] [stdout] test descriptors::tests::test_calc_mol_formula_ethane ... ok
[INFO] [stdout] test descriptors::tests::test_calc_mol_formula_water ... ok
[INFO] [stdout] test descriptors::tests::test_calc_mol_formula_acetic_acid ... ok
[INFO] [stdout] test descriptors::tests::test_cns_mpo_small_cns_drug_high_score ... ok
[INFO] [stdout] test descriptors::tests::test_cns_mpo_score_range ... ok
[INFO] [stdout] test descriptors::tests::test_exact_mass_benzene ... ok
[INFO] [stdout] test descriptors::tests::test_exact_mass_ethanol ... ok
[INFO] [stdout] test descriptors::tests::test_exact_mass_methane ... ok
[INFO] [stdout] test alerts::tests::test_pains_catechol_fails ... ok
[INFO] [stdout] test descriptors::tests::test_formal_charge_neutral_aspirin ... ok
[INFO] [stdout] test alerts::tests::test_pains_mannich_base_fails ... ok
[INFO] [stdout] test descriptors::tests::test_egan_aspirin_passes ... ok
[INFO] [stdout] test descriptors::tests::test_formal_charge_per_atom_neutral ... ok
[INFO] [stdout] test descriptors::tests::test_formal_charge_zwitterion ... ok
[INFO] [stdout] test descriptors::tests::test_fraction_rotatable_bonds_benzene ... ok
[INFO] [stdout] test descriptors::tests::test_fraction_rotatable_bonds_in_range ... ok
[INFO] [stdout] test descriptors::tests::test_fsp3_aspirin ... ok
[INFO] [stdout] test descriptors::tests::test_fsp3_benzene ... ok
[INFO] [stdout] test descriptors::tests::test_fsp3_cyclohexane ... ok
[INFO] [stdout] test descriptors::tests::test_fsp3_no_carbon ... ok
[INFO] [stdout] test descriptors::tests::test_formal_charge_per_atom_charged ... ok
[INFO] [stdout] test descriptors::tests::test_hac_aspirin ... ok
[INFO] [stdout] test descriptors::tests::test_hac_benzene ... ok
[INFO] [stdout] test descriptors::tests::test_hac_ethanol ... ok
[INFO] [stdout] test descriptors::tests::test_hall_kier_alpha_benzene ... ok
[INFO] [stdout] test descriptors::tests::test_hall_kier_alpha_ethane ... ok
[INFO] [stdout] test descriptors::tests::test_hall_kier_alpha_methane ... ok
[INFO] [stdout] test descriptors::tests::test_hba_aniline ... ok
[INFO] [stdout] test descriptors::tests::test_hba_aspirin ... ok
[INFO] [stdout] test descriptors::tests::test_hba_count_lipinski_aniline ... ok
[INFO] [stdout] test descriptors::tests::test_hba_count_lipinski_aspirin ... ok
[INFO] [stdout] test descriptors::tests::test_hba_count_lipinski_ge_ertl ... ok
[INFO] [stdout] test descriptors::tests::test_hba_ethanol ... ok
[INFO] [stdout] test descriptors::tests::test_hba_metformin ... ok
[INFO] [stdout] test descriptors::tests::test_hbd_aniline ... ok
[INFO] [stdout] test descriptors::tests::test_hbd_benzene ... ok
[INFO] [stdout] test descriptors::tests::test_hbd_ethanol ... ok
[INFO] [stdout] test descriptors::tests::test_hybridization_per_atom_acetaldehyde_mixed ... ok
[INFO] [stdout] test descriptors::tests::test_hybridization_per_atom_acetylene_sp ... ok
[INFO] [stdout] test descriptors::tests::test_hybridization_per_atom_benzene_all_sp2 ... ok
[INFO] [stdout] test descriptors::tests::test_hybridization_per_atom_ethane_all_sp3 ... ok
[INFO] [stdout] test descriptors::tests::test_implicit_hcount_per_atom_ethane ... ok
[INFO] [stdout] test descriptors::tests::test_implicit_hcount_per_atom_sum ... ok
[INFO] [stdout] test descriptors::tests::test_ipc_benzene ... ok
[INFO] [stdout] test descriptors::tests::test_ipc_ethane ... ok
[INFO] [stdout] test descriptors::tests::test_ipc_single_atom_zero ... ok
[INFO] [stdout] test descriptors::tests::test_lead_like_ibuprofen_passes ... ok
[INFO] [stdout] test descriptors::tests::test_lead_like_large_drug_fails ... ok
[INFO] [stdout] test descriptors::tests::test_aromatic_ring_count_fluoranthene ... ok
[INFO] [stdout] test descriptors::tests::test_lipinski_benzene ... ok
[INFO] [stdout] test descriptors::tests::test_logp_1_phenylpropene_ar_adjacent ... ok
[INFO] [stdout] test descriptors::tests::test_logp_aspirin_is_reasonable ... ok
[INFO] [stdout] test descriptors::tests::test_logp_chalcone_enone ... ok
[INFO] [stdout] test descriptors::tests::test_formal_charge_quaternary_n ... ok
[INFO] [stdout] test descriptors::tests::test_logp_crotonate_internal_enone ... ok
[INFO] [stdout] test descriptors::tests::test_lipinski_aspirin ... ok
[INFO] [stdout] test descriptors::tests::test_ghose_aspirin_range ... ok
[INFO] [stdout] test descriptors::tests::test_logp_ethylene_terminal ... ok
[INFO] [stdout] test descriptors::tests::test_logp_enone_vs_plain_alkene ... ok
[INFO] [stdout] test descriptors::tests::test_logp_mvk_enone_vinyl ... ok
[INFO] [stdout] test descriptors::tests::test_ghose_ibuprofen_passes ... ok
[INFO] [stdout] test descriptors::tests::test_logp_styrene_splits_correctly ... ok
[INFO] [stdout] test descriptors::tests::test_logp_propene_terminal_internal ... ok
[INFO] [stdout] test descriptors::tests::test_logp_curcumin_reference ... ok
[INFO] [stdout] test descriptors::tests::test_mcf_rhodanine_fails_pains ... ok
[INFO] [stdout] test descriptors::tests::test_logp_complex_molecules_xlogp3_preferred ... ok
[INFO] [stdout] test descriptors::tests::logp_and_mr_matches_individual_functions ... ok
[INFO] [stdout] test descriptors::tests::test_mmff94_charges_charged_species ... ok
[INFO] [stdout] test descriptors::tests::test_mmff94_charges_ethane ... ok
[INFO] [stdout] test descriptors::tests::test_mmff94_charges_length ... ok
[INFO] [stdout] test descriptors::tests::test_mmff94_charges_water ... ok
[INFO] [stdout] test descriptors::tests::test_mqn_aspirin ... ok
[INFO] [stdout] test descriptors::tests::test_mqn_benzene ... ok
[INFO] [stdout] test descriptors::tests::test_mqn_ethane ... ok
[INFO] [stdout] test descriptors::tests::test_mqn_length ... ok
[INFO] [stdout] test descriptors::tests::test_mqn_single_carbon ... ok
[INFO] [stdout] test descriptors::tests::test_mr_aspirin_range ... ok
[INFO] [stdout] test descriptors::tests::test_mr_benzene_range ... ok
[INFO] [stdout] test descriptors::tests::test_mr_chlorobenzene_higher_than_benzene ... ok
[INFO] [stdout] test descriptors::tests::test_mw_aspirin ... ok
[INFO] [stdout] test descriptors::tests::test_mw_benzene ... ok
[INFO] [stdout] test descriptors::tests::test_mw_ethanol ... ok
[INFO] [stdout] test descriptors::tests::test_mw_methane ... ok
[INFO] [stdout] test descriptors::tests::test_mw_water ... ok
[INFO] [stdout] test descriptors::tests::test_num_aliphatic_rings_benzene_zero ... ok
[INFO] [stdout] test descriptors::tests::test_num_aliphatic_rings_cyclohexane ... ok
[INFO] [stdout] test descriptors::tests::test_num_amide_bonds_acetamide ... ok
[INFO] [stdout] test descriptors::tests::test_num_amide_bonds_none ... ok
[INFO] [stdout] test descriptors::tests::test_num_amide_bonds_primary_amide ... ok
[INFO] [stdout] test descriptors::tests::test_num_amide_bonds_urea ... ok
[INFO] [stdout] test descriptors::tests::test_num_bridgehead_atoms_acyclic ... ok
[INFO] [stdout] test descriptors::tests::test_num_bridgehead_atoms_naphthalene_fused ... ok
[INFO] [stdout] test descriptors::tests::test_num_bridgehead_atoms_norbornane ... ok
[INFO] [stdout] test descriptors::tests::test_num_bridgehead_atoms_single_ring ... ok
[INFO] [stdout] test descriptors::tests::test_num_carbons_ethane ... ok
[INFO] [stdout] test descriptors::tests::test_num_ester_bonds_acetic_acid_methyl_ester ... ok
[INFO] [stdout] test descriptors::tests::test_num_ester_bonds_aspirin ... ok
[INFO] [stdout] test descriptors::tests::test_num_ester_bonds_methyl_formate ... ok
[INFO] [stdout] test descriptors::tests::test_num_ester_bonds_none ... ok
[INFO] [stdout] test descriptors::tests::test_num_halogens ... ok
[INFO] [stdout] test descriptors::tests::test_num_heteroatoms_aspirin ... ok
[INFO] [stdout] test descriptors::tests::test_num_heteroatoms_benzene_zero ... ok
[INFO] [stdout] test descriptors::tests::test_num_hydrogens_ethane ... ok
[INFO] [stdout] test descriptors::tests::test_num_hydrogens_methane ... ok
[INFO] [stdout] test descriptors::tests::test_num_hydrogens_water ... ok
[INFO] [stdout] test descriptors::tests::test_num_nitrogens_methylamine ... ok
[INFO] [stdout] test descriptors::tests::test_num_oxygens_methanol ... ok
[INFO] [stdout] test descriptors::tests::test_num_saturated_rings_benzene_zero ... ok
[INFO] [stdout] test descriptors::tests::test_num_saturated_rings_cyclohexane ... ok
[INFO] [stdout] test descriptors::tests::test_num_spiro_atoms_single_ring ... ok
[INFO] [stdout] test descriptors::tests::test_num_stereocenters_achiral_zero ... ok
[INFO] [stdout] test descriptors::tests::test_num_stereocenters_alanine ... ok
[INFO] [stdout] test descriptors::tests::test_num_stereocenters_bridgehead_quaternary ... ok
[INFO] [stdout] test descriptors::tests::test_mcf_toluene_passes ... ok
[INFO] [stdout] test descriptors::tests::test_num_stereocenters_ring_adjacent ... ok
[INFO] [stdout] test descriptors::tests::test_pfizer_3_75_risky_compound_fails ... ok
[INFO] [stdout] test alerts::tests::test_pains_caffeine_passes ... ok
[INFO] [stdout] test descriptors::tests::test_pfizer_3_75_safe_compound_passes ... ok
[INFO] [stdout] test descriptors::tests::test_reos_aspirin_passes ... ok
[INFO] [stdout] test descriptors::tests::test_ring_count_acyclic_zero ... ok
[INFO] [stdout] test descriptors::tests::test_reos_ibuprofen_passes ... ok
[INFO] [stdout] test descriptors::tests::test_ring_count_naphthalene ... ok
[INFO] [stdout] test descriptors::tests::test_ring_system_count_acyclic_zero ... ok
[INFO] [stdout] test descriptors::tests::test_ring_system_count_benzene ... ok
[INFO] [stdout] test descriptors::tests::test_ring_system_count_biphenyl_two_systems ... ok
[INFO] [stdout] test descriptors::tests::test_ring_count_benzene ... ok
[INFO] [stdout] test descriptors::tests::test_ring_system_count_naphthalene_one_system ... ok
[INFO] [stdout] test descriptors::tests::test_ro3_ethanol_passes ... ok
[INFO] [stdout] test descriptors::tests::test_rot_alkyne_adjacent_excluded ... ok
[INFO] [stdout] test descriptors::tests::test_rot_allene_excluded ... ok
[INFO] [stdout] test descriptors::tests::test_ro3_lipinski_drug_fails ... ok
[INFO] [stdout] test descriptors::tests::test_rot_aspirin ... ok
[INFO] [stdout] test descriptors::tests::test_rot_benzene ... ok
[INFO] [stdout] test descriptors::tests::test_tpsa_aniline ... ok
[INFO] [stdout] test descriptors::tests::test_tpsa_aspirin_positive ... ok
[INFO] [stdout] test descriptors::tests::test_tpsa_n_oxide_ionic ... ok
[INFO] [stdout] test descriptors::tests::test_reos_diazepam_passes ... ok
[INFO] [stdout] test descriptors::tests::test_rot_butane ... ok
[INFO] [stdout] test descriptors::tests::test_tpsa_per_atom_carbon_contributes_zero ... ok
[INFO] [stdout] test descriptors::tests::test_tpsa_per_atom_sum_equals_tpsa ... ok
[INFO] [stdout] test descriptors::tests::test_usrcat_aromatic ... ok
[INFO] [stdout] test descriptors::tests::test_tpsa_quaternary_n_zero ... ok
[INFO] [stdout] test descriptors::tests::test_tpsa_water ... ok
[INFO] [stdout] test descriptors::tests::test_usrcat_charged ... ok
[INFO] [stdout] test descriptors::tests::test_usrcat_donors_acceptors ... ok
[INFO] [stdout] test descriptors::tests::test_usrcat_shape ... ok
[INFO] [stdout] test descriptors::tests::test_veber_aspirin_passes ... ok
[INFO] [stdout] test diversity::tests::butina_all_molecules_assigned ... ok
[INFO] [stdout] test diversity::tests::butina_identical_molecules_one_cluster ... ok
[INFO] [stdout] test diversity::tests::butina_dissimilar_molecules_separate_clusters ... ok
[INFO] [stdout] test diversity::tests::maxmin_picks_correct_count ... ok
[INFO] [stdout] test diversity::tests::maxmin_clamps_to_mol_count ... ok
[INFO] [stdout] test descriptors::tests::test_veber_large_flexible_fails ... ok
[INFO] [stdout] test drug_score::tests::drug_score_factors_independent ... ok
[INFO] [stdout] test descriptors::tests::test_mcf_aspirin_fails_brenk_active_ester ... ok
[INFO] [stdout] test esol::tests::test_aromatic_reduces_solubility ... ok
[INFO] [stdout] test esol::tests::test_benzene_moderate_solubility ... ok
[INFO] [stdout] test esol::tests::test_lipophilic_molecule_low_solubility ... ok
[INFO] [stdout] test esol::tests::test_returns_finite ... ok
[INFO] [stdout] test esol::tests::test_water_highly_soluble ... ok
[INFO] [stdout] test estate::tests::acetic_acid_oxygen_higher_than_carbon ... ok
[INFO] [stdout] test estate::tests::estate_all_matches_individual ... ok
[INFO] [stdout] test descriptors::tests::test_mcf_caffeine_passes ... ok
[INFO] [stdout] test estate::tests::estate_indices_length_matches_atom_count ... ok
[INFO] [stdout] test estate::tests::estate_indices_match_rdkit_ethanol ... ok
[INFO] [stdout] test estate::tests::ethane_symmetric_estate ... ok
[INFO] [stdout] test estate::tests::pyridine_nitrogen_highest_estate ... ok
[INFO] [stdout] test estate::tests::max_min_estate_ordering ... ok
[INFO] [stdout] test estate::tests::sum_estate_positive ... ok
[INFO] [stdout] test formula::tests::test_ammonium_ion_bracket ... ok
[INFO] [stdout] test formula::tests::test_benzene ... ok
[INFO] [stdout] test formula::tests::test_calcium_hydroxide ... ok
[INFO] [stdout] test formula::tests::test_empty_formula_error ... ok
[INFO] [stdout] test formula::tests::test_ethanol ... ok
[INFO] [stdout] test formula::tests::test_formula_roundtrip_consistency ... ok
[INFO] [stdout] test formula::tests::test_glucose ... ok
[INFO] [stdout] test formula::tests::test_methane ... ok
[INFO] [stdout] test formula::tests::test_nested_parens ... ok
[INFO] [stdout] test formula::tests::test_single_atom ... ok
[INFO] [stdout] test formula::tests::test_sulfuric_acid ... ok
[INFO] [stdout] test formula::tests::test_iron_iii_chloride ... ok
[INFO] [stdout] test formula::tests::test_trailing_charge_sign_stripped ... ok
[INFO] [stdout] test formula::tests::test_water ... ok
[INFO] [stdout] test gasteiger::tests::charge_sum_near_zero_neutral_molecule ... ok
[INFO] [stdout] test gasteiger::tests::electronegative_atoms_negative ... ok
[INFO] [stdout] test gasteiger::tests::methanol_oxygen_more_negative_than_carbon ... ok
[INFO] [stdout] test gasteiger::tests::water_oxygen_negative ... ok
[INFO] [stdout] test hash::tests::are_identical_aspirin ... ok
[INFO] [stdout] test hash::tests::are_identical_false_for_different ... ok
[INFO] [stdout] test hash::tests::are_identical_true_for_same_structure ... ok
[INFO] [stdout] test gasteiger::tests::aspirin_charges_vector_length ... ok
[INFO] [stdout] test hash::tests::mol_hash_different_smiles_likely_different_hash ... ok
[INFO] [stdout] test hash::tests::mol_hash_same_smiles_same_hash ... ok
[INFO] [stdout] test hydrogen::tests::add_h_benzene ... ok
[INFO] [stdout] test hash::tests::mol_hash_deterministic ... ok
[INFO] [stdout] test hydrogen::tests::add_h_ethane ... ok
[INFO] [stdout] test hydrogen::tests::add_h_methane_atom_count ... ok
[INFO] [stdout] test hydrogen::tests::add_h_methane_bond_count ... ok
[INFO] [stdout] test hydrogen::tests::add_h_water ... ok
[INFO] [stdout] test hydrogen::tests::add_h_preserves_element_distribution ... ok
[INFO] [stdout] test hydrogen::tests::add_remove_roundtrip_aspirin ... ok
[INFO] [stdout] test hydrogen::tests::add_remove_roundtrip_ethanol ... ok
[INFO] [stdout] test ifg::tests::acetic_acid_one_group ... ok
[INFO] [stdout] test ifg::tests::aniline_two_groups ... ok
[INFO] [stdout] test ifg::tests::aspirin_multiple_groups ... ok
[INFO] [stdout] test ifg::tests::chlorobenzene_one_group ... ok
[INFO] [stdout] test hydrogen::tests::remove_h_no_h_atoms_unchanged ... ok
[INFO] [stdout] test ifg::tests::hexane_no_functional_groups ... ok
[INFO] [stdout] test ifg::tests::methane_no_groups ... ok
[INFO] [stdout] test isotope_distribution::tests::test_benzene_monoisotopic ... ok
[INFO] [stdout] test isotope_distribution::tests::test_bromobenzene_roughly_equal_peaks ... ok
[INFO] [stdout] test isotope_distribution::tests::test_chloromethane_two_major_peaks ... ok
[INFO] [stdout] test ifg::tests::pyridine_one_group_containing_n ... ok
[INFO] [stdout] test isotope_distribution::tests::test_normalised_base_peak_is_one ... ok
[INFO] [stdout] test isotope_distribution::tests::test_resolution_merging ... ok
[INFO] [stdout] test iupac_stereo::tests::achiral_butane_no_prefix ... ok
[INFO] [stdout] test iupac_stereo::tests::achiral_cyclohexane_no_prefix ... ok
[INFO] [stdout] test iupac_stereo::tests::r_2_butanol_has_r_prefix ... ok
[INFO] [stdout] test isotope_distribution::tests::test_water_monoisotopic_dominant ... ok
[INFO] [stdout] test logd::tests::test_acid_logd_lower_at_high_ph ... ok
[INFO] [stdout] test logd::tests::test_logd_profile_zero_steps ... ok
[INFO] [stdout] test logd::tests::test_logd_profile_length ... ok
[INFO] [stdout] test logd::tests::test_neutral_logd_equals_logp ... ok
[INFO] [stdout] test mlp::tests::test_output_finite_and_plausible ... ok
[INFO] [stdout] test mlp::tests::test_water_more_soluble_than_octane ... ok
[INFO] [stdout] test mmff94_bci::tests::test_bci_acetate ... ok
[INFO] [stdout] test mmff94_bci::tests::test_bci_acetic_acid ... ok
[INFO] [stdout] test mmff94_bci::tests::test_bci_acetone ... ok
[INFO] [stdout] test mmff94_bci::tests::test_bci_amide_vs_amine_bci ... ok
[INFO] [stdout] test mmff94_bci::tests::test_bci_ammonium ... ok
[INFO] [stdout] test mmff94_bci::tests::test_bci_chloromethane ... ok
[INFO] [stdout] test mmff94_bci::tests::test_bci_ethanol ... ok
[INFO] [stdout] test mmff94_bci::tests::test_bci_imidazole ... ok
[INFO] [stdout] test mmff94_bci::tests::test_bci_methylamine ... ok
[INFO] [stdout] test mmff94_bci::tests::test_typed_aromatic_nitrogen_types ... ok
[INFO] [stdout] test mmff94_bci::tests::test_typed_atom_type_discrimination ... ok
[INFO] [stdout] test mmff94_bci::tests::test_typed_charge_conservation ... ok
[INFO] [stdout] test mmff94_bci::tests::test_typed_ester_o_charge_differs_from_ether_o ... ok
[INFO] [stdout] test mmff94_bci::tests::test_typed_ester_vs_ether_oxygen ... ok
[INFO] [stdout] test mmff94_bci::tests::test_typed_vs_element_bci_carbonyl ... ok
[INFO] [stdout] test mmp::tests::mmp_dedup_direction ... ok
[INFO] [stdout] test mlp::tests::test_placeholder_matches_esol ... ok
[INFO] [stdout] test mmp::tests::mmp_no_pairs_for_single_molecule ... ok
[INFO] [stdout] test mmp::tests::mmp_no_pairs_when_no_brics_bonds ... ok
[INFO] [stdout] test mmp::tests::mmp_ethylbenzene_propylbenzene ... ok
[INFO] [stdout] test mmp::tests::mmp_three_molecules_correct_count ... ok
[INFO] [stdout] test mmp::tests::mms_no_brics_bonds_no_series ... ok
[INFO] [stdout] test mmp::tests::mms_three_alkylbenzenes_one_series ... ok
[INFO] [stdout] test mmp::tests::mms_member_mw_excludes_wildcard ... ok
[INFO] [stdout] test mmp::tests::mms_two_molecules_no_series ... ok
[INFO] [stdout] test named_groups::tests::test_all_named_group_patterns_parse ... ok
[INFO] [stdout] test named_groups::tests::test_carbonyl_acetone ... ok
[INFO] [stdout] test named_groups::tests::test_amine_methylamine ... ok
[INFO] [stdout] test named_groups::tests::test_halogen_chlorobenzene ... ok
[INFO] [stdout] test named_groups::tests::test_carboxyl_acetic_acid ... ok
[INFO] [stdout] test named_groups::tests::test_nitrile_acetonitrile ... ok
[INFO] [stdout] test pka::tests::test_acetic_acid_pka ... ok
[INFO] [stdout] test named_groups::tests::test_hydroxyl_ethanol ... ok
[INFO] [stdout] test pka::tests::test_aniline_pka ... ok
[INFO] [stdout] test pka::tests::test_aspirin_has_acid ... ok
[INFO] [stdout] test pka::tests::test_amphoteric_glycine ... ok
[INFO] [stdout] test pka::tests::test_carboxylic_vs_phenol_priority ... ok
[INFO] [stdout] test pka::tests::test_hydroxamic_acid_pka ... ok
[INFO] [stdout] test pka::tests::test_benzene_no_sites ... ok
[INFO] [stdout] test pka::tests::test_morpholine_lower_than_piperidine ... ok
[INFO] [stdout] test pka::tests::test_phenol_pka ... ok
[INFO] [stdout] test pka::tests::test_piperazine_nh_pka ... ok
[INFO] [stdout] test pka::tests::test_primary_amine_pka ... ok
[INFO] [stdout] test pka::tests::test_piperidine_pka ... ok
[INFO] [stdout] test pka::tests::test_pyridine_pka ... ok
[INFO] [stdout] test pka::tests::test_site_type_classification ... ok
[INFO] [stdout] test pka::tests::test_tetrazole_is_acidic ... ok
[INFO] [stdout] test qed::tests::test_ads_function_smoke ... ok
[INFO] [stdout] test pka::tests::test_thiol_pka ... ok
[INFO] [stdout] test qed::tests::test_qed_benzene_range ... ok
[INFO] [stdout] test qed::tests::test_qed_aspirin_rdkit_range ... ok
[INFO] [stdout] test qed::tests::test_qed_caffeine_rdkit_range ... ok
[INFO] [stdout] test qed::tests::test_qed_ibuprofen_rdkit_range ... ok
[INFO] [stdout] test qed::tests::test_qed_paracetamol_rdkit_range ... ok
[INFO] [stdout] test qed::tests::test_structural_alert_count_aspirin ... ok
[INFO] [stdout] test qed::tests::test_structural_alert_nitro_compound ... ok
[INFO] [stdout] test qed::tests::test_structural_alert_queries_loaded ... ok
[INFO] [stdout] test drug_score::tests::drug_score_in_range_aspirin ... ok
[INFO] [stdout] test recap::tests::test_recap_alkane_not_breakable ... ok
[INFO] [stdout] test recap::tests::test_recap_amide_breakable ... ok
[INFO] [stdout] test recap::tests::test_recap_ether_breakable ... ok
[INFO] [stdout] test descriptors::tests::test_mcf_ibuprofen_fails_brenk_acetal_ketal ... ok
[INFO] [stdout] test rgroup::tests::test_rgroup_different_heteroatom_substituents ... ok
[INFO] [stdout] test rgroup::tests::test_rgroup_invalid_smarts ... ok
[INFO] [stdout] test rgroup::tests::test_rgroup_mol_idx_preserved ... ok
[INFO] [stdout] test rgroup::tests::test_rgroup_two_substituents ... ok
[INFO] [stdout] test sa_score::tests::methane_is_easy ... ok
[INFO] [stdout] test sa_score::tests::sa_score_in_range_1_to_10 ... ok
[INFO] [stdout] test rgroup::tests::test_rgroup_monosubstituted_benzene ... ok
[INFO] [stdout] test rgroup::tests::test_rgroup_no_substituents ... ok
[INFO] [stdout] test scaffold::tests::generic_murcko_benzene_all_carbon_single ... ok
[INFO] [stdout] test scaffold::tests::murcko_acyclic_returns_empty ... ok
[INFO] [stdout] test scaffold::tests::murcko_benzene_preserves_all_atoms ... ok
[INFO] [stdout] test sa_score::tests::simple_molecule_lower_than_complex ... ok
[INFO] [stdout] test sa_score::tests::taxol_harder_than_aspirin ... ok
[INFO] [stdout] test scaffold::tests::murcko_toluene_removes_methyl ... ok
[INFO] [stdout] test scaffold::tests::scaffold_network_with_counts_duplicate_scaffolds ... ok
[INFO] [stdout] test scaffold::tests::murcko_ethylbenzene_removes_chain ... ok
[INFO] [stdout] test scaffold::tests::murcko_biphenyl_keeps_all_ring_atoms ... ok
[INFO] [stdout] test scaffold::tests::scaffold_network_with_counts_multiple_molecules ... ok
[INFO] [stdout] test scaffold::tests::scaffold_network_with_counts_empty_input ... ok
[INFO] [stdout] test scaffold::tests::scaffold_network_with_counts_parent_relationships ... ok
[INFO] [stdout] test standardize::tests::bug3_ionic_pair_neutralize_before_largest_fragment ... ok
[INFO] [stdout] test scaffold::tests::scaffold_network_with_counts_single_molecule ... ok
[INFO] [stdout] test standardize::tests::clean_stereo_groups_drops_empty_groups ... ok
[INFO] [stdout] test standardize::tests::clean_stereo_groups_drops_non_chiral_atoms ... ok
[INFO] [stdout] test standardize::tests::clean_stereo_groups_preserves_valid_groups ... ok
[INFO] [stdout] test standardize::tests::largest_fragment_ionic_pair_keeps_one_atom ... ok
[INFO] [stdout] test standardize::tests::largest_fragment_two_fragments_picks_larger ... ok
[INFO] [stdout] test standardize::tests::largest_fragment_keeps_benzene_over_ethane ... ok
[INFO] [stdout] test qed::tests::test_qed_valid_range_for_common_molecules ... ok
[INFO] [stdout] test standardize::tests::largest_fragment_single_fragment_unchanged ... ok
[INFO] [stdout] test standardize::tests::normalize_groups_mixed_nitro_and_azide ... ok
[INFO] [stdout] test standardize::tests::neutralize_acetate_oxygen ... ok
[INFO] [stdout] test standardize::tests::normalize_groups_nitro ... ok
[INFO] [stdout] test standardize::tests::normalize_groups_sulfoxide ... ok
[INFO] [stdout] test standardize::tests::pipeline_report_disconnects_metal_bonds ... ok
[INFO] [stdout] test standardize::tests::pipeline_report_marks_unchanged_clean_molecule ... ok
[INFO] [stdout] test standardize::tests::neutralize_neutral_molecule_unchanged ... ok
[INFO] [stdout] test standardize::tests::parent_variant_step_names_distinct ... ok
[INFO] [stdout] test standardize::tests::normalize_groups_azide ... ok
[INFO] [stdout] test standardize::tests::prefer_organic_keeps_organic_if_no_inorganic ... ok
[INFO] [stdout] test standardize::tests::prefer_organic_falls_back_to_largest ... ok
[INFO] [stdout] test standardize::tests::pipeline_report_tracks_enabled_stage_changes ... ok
[INFO] [stdout] test standardize::tests::prefer_organic_removes_inorganic_salts ... ok
[INFO] [stdout] test standardize::tests::reionize_protects_amide_nitrogen ... ok
[INFO] [stdout] test standardize::tests::reionize_protects_thioamide_nitrogen ... ok
[INFO] [stdout] test standardize::tests::reionize_deprotonates_carboxylic_acids ... ok
[INFO] [stdout] test standardize::tests::reionize_protonates_amines ... ok
[INFO] [stdout] test standardize::tests::remove_isotopes_preserves_structure ... ok
[INFO] [stdout] test standardize::tests::remove_isotopes_strips_isotope_labels ... ok
[INFO] [stdout] test standardize::tests::remove_stereo_clears_stereo_groups ... ok
[INFO] [stdout] test standardize::tests::remove_stereo_strips_chirality ... ok
[INFO] [stdout] test standardize::tests::standardize_skip_largest_fragment ... ok
[INFO] [stdout] test standardize::tests::uncharge_neutralizes_all_charges ... ok
[INFO] [stdout] test standardize::tests::standardize_with_defaults ... ok
[INFO] [stdout] test stereo::tests::assign_complete_stereochemistry_preserves_structure ... ok
[INFO] [stdout] test standardize::tests::remove_stereo_converts_wedge_bonds_to_single ... ok
[INFO] [stdout] test stereo::tests::assign_complete_stereochemistry_no_panic ... ok
[INFO] [stdout] test stereo::tests::enumerate_stereoisomers_no_centers ... ok
[INFO] [stdout] test stereo::tests::assign_complete_stereochemistry_simple ... ok
[INFO] [stdout] test stereo::tests::enumerate_stereoisomers_single_center ... ok
[INFO] [stdout] test stereo::tests::invert_stereocenter_r_to_s ... ok
[INFO] [stdout] test tautomer::tests::imine_ez_stereo_preserved_in_tautomer_enumeration ... ok
[INFO] [stdout] test tautomer::tests::test_15_shift_beta_diketone ... ok
[INFO] [stdout] test tautomer::tests::test_15_shift_c_to_o_with_heteroatom ... ok
[INFO] [stdout] test tautomer::tests::test_15_shift_canonical_idempotent ... ok
[INFO] [stdout] test tautomer::tests::test_15_shift_config_selectivity ... ok
[INFO] [stdout] test stereo::tests::enumerate_stereoisomers_too_many ... ok
[INFO] [stdout] test tautomer::tests::test_15_shift_enol_imine ... ok
[INFO] [stdout] test tautomer::tests::test_15_shift_n_bridge_diketone ... ok
[INFO] [stdout] test tautomer::tests::test_15_shift_n_to_n_with_bridge ... ok
[INFO] [stdout] test tautomer::tests::test_15_shift_no_false_positives ... ok
[INFO] [stdout] test standardize::tests::remove_stereo_preserves_structure ... ok
[INFO] [stdout] test tautomer::tests::test_15_shift_heteroatom_enumeration ... ok
[INFO] [stdout] test tautomer::tests::test_15_shift_s_bridge ... ok
[INFO] [stdout] test tautomer::tests::test_alanine_stereo_trivially_preserved ... ok
[INFO] [stdout] test tautomer::tests::test_blocking_all_atoms_preserves_input ... ok
[INFO] [stdout] test tautomer::tests::test_15_shift_multiple_donors_acceptors ... ok
[INFO] [stdout] test tautomer::tests::test_canonical_acetylacetone_stable ... ok
[INFO] [stdout] test tautomer::tests::test_canonical_amide_unchanged ... ok
[INFO] [stdout] test tautomer::tests::test_canonical_idempotent ... ok
[INFO] [stdout] test tautomer::tests::test_canonical_keto_unchanged ... ok
[INFO] [stdout] test tautomer::tests::test_canonical_no_tautomers ... ok
[INFO] [stdout] test tautomer::tests::test_canonical_pyrazole_normalization ... ok
[INFO] [stdout] test tautomer::tests::test_canonical_acetylacetone_enol ... ok
[INFO] [stdout] test tautomer::tests::test_blocked_stereo_preserved_with_zone_blocking ... ok
[INFO] [stdout] test tautomer::tests::test_blocking_donor_suppresses_keto_enol ... ok
[INFO] [stdout] test tautomer::tests::test_config_empty_enabled_rules_equals_all ... ok
[INFO] [stdout] test tautomer::tests::test_config_enabled_rules_subset ... ok
[INFO] [stdout] test tautomer::tests::test_config_max_iter_one_limits_convergence ... ok
[INFO] [stdout] test tautomer::tests::test_config_max_tautomers_caps_enumerate ... ok
[INFO] [stdout] test tautomer::tests::test_enumerate_all_atoms_blocked_returns_singleton ... ok
[INFO] [stdout] test tautomer::tests::test_empty_blocked_sets_identical_to_default ... ok
[INFO] [stdout] test tautomer::tests::test_config_default_same_as_no_config ... ok
[INFO] [stdout] test tautomer::tests::test_enumerate_cap ... ok
[INFO] [stdout] test tautomer::tests::test_enumerate_single_no_match ... ok
[INFO] [stdout] test tautomer::tests::test_enumerate_pyrazole_12_shift ... ok
[INFO] [stdout] test tautomer::tests::test_enumerate_tautomers_remote_stereo_preserved ... ok
[INFO] [stdout] test tautomer::tests::test_enumerate_with_blocking_leq_enumerate_without ... ok
[INFO] [stdout] test tautomer::tests::test_glucose_all_stereocenters_preserved ... ok
[INFO] [stdout] test tautomer::tests::test_enumerate_vinyl_alcohol ... ok
[INFO] [stdout] test tautomer::tests::test_enumerate_includes_original ... ok
[INFO] [stdout] test tautomer::tests::test_hydrazone_ez_stereo_preserved_in_canonical_tautomer ... ignored, known: canonical_tautomer loses E/Z stereo on hydrazones/imines (RDKit PR #9128). mol_fingerprint() does not include Up/Down bond orders so both E and Z forms hash identically; the canonical tautomer selection then returns the same SMILES for both. Fix requires either including stereo in mol_fingerprint or re-applying input E/Z stereo to the canonical tautomer output after selection.
[INFO] [stdout] test tautomer::tests::test_out_of_range_atom_index_is_safe ... ok
[INFO] [stdout] test tautomer::tests::test_pyrazole_no_phantom_chirality ... ok
[INFO] [stdout] test tautomer::tests::test_rule_count_and_names ... ok
[INFO] [stdout] test tautomer::tests::test_stereo_at_donor_does_not_panic ... ok
[INFO] [stdout] test tautomer::tests::test_remote_stereo_preserved_keto_enol ... ok
[INFO] [stdout] test tautomer::tests::test_tetrazole_2h_enumerates_two_forms ... ok
[INFO] [stdout] test tautomer::tests::test_tetrazole_canonical_from_1h_and_2h_agrees ... ok
[INFO] [stdout] test topo_descriptors::tests::bertz_ct_ethane_less_than_propane ... ok
[INFO] [stdout] test topo_descriptors::tests::bertz_ct_increases_with_complexity ... ok
[INFO] [stdout] test tautomer::tests::test_tetrazole_1h_enumerates_two_forms ... ok
[INFO] [stdout] test topo_descriptors::tests::chi0_benzene ... ok
[INFO] [stdout] test topo_descriptors::tests::bertz_ct_methane ... ok
[INFO] [stdout] test topo_descriptors::tests::chi0_propane ... ok
[INFO] [stdout] test topo_descriptors::tests::chi0v_benzene ... ok
[INFO] [stdout] test topo_descriptors::tests::chi1_benzene ... ok
[INFO] [stdout] test topo_descriptors::tests::chi1_propane ... ok
[INFO] [stdout] test tautomer::tests::test_tetrazole_canonical_preserves_aromaticity ... ok
[INFO] [stdout] test topo_descriptors::tests::bertz_ct_benzene ... ok
[INFO] [stdout] test topo_descriptors::tests::chi1v_benzene ... ok
[INFO] [stdout] test topo_descriptors::tests::chi_increases_with_chain ... ok
[INFO] [stdout] test topo_descriptors::tests::eccentricities_propane ... ok
[INFO] [stdout] test topo_descriptors::tests::eci_propane ... ok
[INFO] [stdout] test topo_descriptors::tests::distance_matrix_propane ... ok
[INFO] [stdout] test topo_descriptors::tests::chi_all_matches_individual ... ok
[INFO] [stdout] test topo_descriptors::tests::graph_diameter_propane ... ok
[INFO] [stdout] test topo_descriptors::tests::distance_matrix_ethane ... ok
[INFO] [stdout] test topo_descriptors::tests::graph_radius_propane ... ok
[INFO] [stdout] test topo_descriptors::tests::gravitational_index_positive ... ok
[INFO] [stdout] test topo_descriptors::tests::gravitational_index_ethane ... ok
[INFO] [stdout] test topo_descriptors::tests::gutman_mti_ethane ... ok
[INFO] [stdout] test topo_descriptors::tests::gutman_mti_propane ... ok
[INFO] [stdout] test topo_descriptors::tests::hosoya_butane ... ok
[INFO] [stdout] test topo_descriptors::tests::hosoya_benzene ... ok
[INFO] [stdout] test topo_descriptors::tests::hosoya_ethane ... ok
[INFO] [stdout] test topo_descriptors::tests::gravitational_index_single_atom ... ok
[INFO] [stdout] test alerts::tests::test_pains_azo_fails ... ok
[INFO] [stdout] test topo_descriptors::tests::kappa1_single_atom_zero ... ok
[INFO] [stdout] test topo_descriptors::tests::kappa1_propane ... ok
[INFO] [stdout] test topo_descriptors::tests::kappa1_benzene ... ok
[INFO] [stdout] test topo_descriptors::tests::hosoya_methane ... ok
[INFO] [stdout] test topo_descriptors::tests::hosoya_propane ... ok
[INFO] [stdout] test topo_descriptors::tests::kappa3_benzene ... ok
[INFO] [stdout] test topo_descriptors::tests::kappa3_propane_zero ... ok
[INFO] [stdout] test topo_descriptors::tests::labute_asa_aromatic_reduces_vs_saturated ... ok
[INFO] [stdout] test topo_descriptors::tests::labute_asa_matches_rdkit_aspirin ... ok
[INFO] [stdout] test topo_descriptors::tests::labute_asa_monotone_with_size ... ok
[INFO] [stdout] test topo_descriptors::tests::labute_asa_per_atom_excludes_pooled_h_term ... ok
[INFO] [stdout] test topo_descriptors::tests::labute_asa_positive ... ok
[INFO] [stdout] test topo_descriptors::tests::labute_asa_single_oxygen ... ok
[INFO] [stdout] test topo_descriptors::tests::petitjean_benzene ... ok
[INFO] [stdout] test topo_descriptors::tests::petitjean_propane ... ok
[INFO] [stdout] test topo_descriptors::tests::petitjean_single_atom ... ok
[INFO] [stdout] test topo_descriptors::tests::pi_benzene ... ok
[INFO] [stdout] test topo_descriptors::tests::pi_butane ... ok
[INFO] [stdout] test topo_descriptors::tests::pi_ethane ... ok
[INFO] [stdout] test topo_descriptors::tests::pi_linear_chain_formula ... ok
[INFO] [stdout] test topo_descriptors::tests::pi_propane ... ok
[INFO] [stdout] test topo_descriptors::tests::pi_single_atom ... ok
[INFO] [stdout] test topo_descriptors::tests::randic_index_ethane ... ok
[INFO] [stdout] test topo_descriptors::tests::schultz_mti_empty ... ok
[INFO] [stdout] test topo_descriptors::tests::kappa2_benzene ... ok
[INFO] [stdout] test topo_descriptors::tests::schultz_mti_propane ... ok
[INFO] [stdout] test topo_descriptors::tests::vabc_increases_with_size ... ok
[INFO] [stdout] test topo_descriptors::tests::vabc_methane ... ok
[INFO] [stdout] test topo_descriptors::tests::vabc_water ... ok
[INFO] [stdout] test topo_descriptors::tests::wiener_benzene ... ok
[INFO] [stdout] test topo_descriptors::tests::wiener_ethane ... ok
[INFO] [stdout] test topo_descriptors::tests::wiener_increases_with_chain_length ... ok
[INFO] [stdout] test topo_descriptors::tests::kappa_alpha_corrected_matches_rdkit_aspirin ... ok
[INFO] [stdout] test topo_descriptors::tests::wiener_propane ... ok
[INFO] [stdout] test topo_descriptors::tests::schultz_mti_ethane ... ok
[INFO] [stdout] test topo_descriptors::tests::wiener_single_atom_zero ... ok
[INFO] [stdout] test topo_descriptors::tests::zagreb_m1_ethane ... ok
[INFO] [stdout] test topo_descriptors::tests::kappa_all_matches_individual ... ok
[INFO] [stdout] test topo_descriptors::tests::zagreb_m2_benzene ... ok
[INFO] [stdout] test topo_descriptors::tests::zagreb_m2_ethane ... ok
[INFO] [stdout] test topo_descriptors::tests::zagreb_m2_ge_m1_for_branched ... ok
[INFO] [stdout] test topo_descriptors::tests::zagreb_m2_propane ... ok
[INFO] [stdout] test topo_descriptors::tests::kappa2_propane ... ok
[INFO] [stdout] test vsa::tests::estate_vsa_sum_equals_labute_asa_minus_h_pool ... ok
[INFO] [stdout] test vsa::tests::estate_vsa_nonzero_for_diverse_molecules ... ok
[INFO] [stdout] test vsa::tests::estate_vsa_length_is_11 ... ok
[INFO] [stdout] test vsa::tests::peoe_vsa_sum_equals_labute_asa_minus_h_pool ... ok
[INFO] [stdout] test vsa::tests::slogp_vsa_length_is_12 ... ok
[INFO] [stdout] test vsa::tests::smr_vsa_length_is_10 ... ok
[INFO] [stdout] test vsa::tests::empty_molecule_zero_bins ... ok
[INFO] [stdout] test vsa::tests::peoe_vsa_length_is_14 ... ok
[INFO] [stdout] test workflow::tests::compare_molecules_needs_two_inputs ... ok
[INFO] [stdout] test vsa::tests::slogp_vsa_sum_equals_labute_asa_minus_h_pool ... ok
[INFO] [stdout] test workflow::tests::limits_reject_large_molecule ... ok
[INFO] [stdout] test vsa::tests::smr_vsa_sum_equals_labute_asa_minus_h_pool ... ok
[INFO] [stdout] test workflow::tests::molecule_report_charge_species ... ok
[INFO] [stdout] test workflow::tests::molecule_report_aromatic_nitrogen ... ok
[INFO] [stdout] test workflow::tests::molecule_report_halogenated ... ok
[INFO] [stdout] test workflow::tests::molecule_report_invalid_smiles_returns_structured_error ... ok
[INFO] [stdout] test workflow::tests::compare_molecules_identical_molecules ... ok
[INFO] [stdout] test drug_score::tests::drug_score_clean_molecule_scores_well ... ok
[INFO] [stdout] test workflow::tests::compare_molecules_returns_pairwise_and_mcs ... ok
[INFO] [stdout] test workflow::tests::screen_smiles_empty_batch ... ok
[INFO] [stdout] test xlogp3::tests::test_xlogp3_benzene_positive ... ok
[INFO] [stdout] test xlogp3::tests::test_xlogp3_ethanol_less_than_hexane ... ok
[INFO] [stdout] test xlogp3::tests::test_xlogp3_methane_positive ... ok
[INFO] [stdout] test xlogp3::tests::test_xlogp3_per_atom_len ... ok
[INFO] [stdout] test xlogp3::tests::test_xlogp3_water_negative ... ok
[INFO] [stdout] test workflow::tests::molecule_report_sulfur_compound ... ok
[INFO] [stdout] test workflow::tests::screen_smiles_keeps_invalid_records_and_original_indices ... ok
[INFO] [stdout] test workflow::tests::molecule_report_aspirin_has_core_fields ... ok
[INFO] [stdout] test workflow::tests::molecule_report_complex_aromatic ... ok
[INFO] [stdout] test drug_score::tests::drug_score_large_molecule_penalised ... ok
[INFO] [stdout] test descriptors::tests::test_mcf_very_large_mol_fails_lipinski ... ok
[INFO] [stdout] test workflow::tests::molecule_report_large_valid_molecule ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 668 passed; 0 failed; 1 ignored; 0 measured; 0 filtered out; finished in 4.02s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/rdkit_reference.rs (/opt/rustwide/target/debug/deps/rdkit_reference-41114b9d16e57f57)
[INFO] [stdout] 
[INFO] [stdout] running 119 tests
[INFO] [stdout] test aromatic_ring_count_benzene ... ok
[INFO] [stderr] error: test failed, to rerun pass `--test rdkit_reference`
[INFO] [stdout] test aromatic_ring_count_naphthalene ... ok
[INFO] [stdout] test brics_fragments_acetanilide ... ok
[INFO] [stdout] test brics_fragments_aspirin ... ok
[INFO] [stdout] test brics_fragments_ibuprofen ... ok
[INFO] [stdout] test exact_mass_aspirin ... ok
[INFO] [stdout] test formal_charge_sum_chloride ... ok
[INFO] [stdout] test formal_charge_sum_aspirin ... ok
[INFO] [stdout] test hac_benzene ... ok
[INFO] [stdout] test hac_caffeine ... ok
[INFO] [stdout] test hac_ethanol ... ok
[INFO] [stdout] test hba_acetic_acid ... ok
[INFO] [stdout] test hba_aspirin ... ok
[INFO] [stdout] test hba_carbon_disulfide ... ok
[INFO] [stdout] test fsp3_benzene ... ok
[INFO] [stdout] test hba_nitroso_n_is_hba ... ok
[INFO] [stdout] test hba_paracetamol ... ok
[INFO] [stdout] test hba_pyridine ... ok
[INFO] [stdout] test hba_radical_n_excluded ... ok
[INFO] [stdout] test hba_ring_guanidine_counts_all_n ... ok
[INFO] [stdout] test hba_caffeine ... ok
[INFO] [stdout] test hba_urea ... ok
[INFO] [stdout] test fsp3_cyclohexane ... ok
[INFO] [stdout] test hba_water_zero ... ok
[INFO] [stdout] test hbd_aspirin ... ok
[INFO] [stdout] test hbd_caffeine ... ok
[INFO] [stdout] test hba_indole ... ok
[INFO] [stdout] test hbd_glucose ... ok
[INFO] [stdout] test hbd_paracetamol ... ok
[INFO] [stdout] test hba_sulfonamide_n_excluded ... ok
[INFO] [stdout] test hba_thioamide_n_excluded ... ok
[INFO] [stdout] test hac_aspirin ... ok
[INFO] [stdout] test lipinski_cyclosporine_fails ... ok
[INFO] [stdout] test logp_acetic_acid ... ok
[INFO] [stdout] test logp_aniline ... ok
[INFO] [stdout] test logp_acetone ... ok
[INFO] [stdout] test logp_arginine_guanidinium ... ok
[INFO] [stdout] test lipinski_aspirin_passes ... ok
[INFO] [stdout] test lipinski_caffeine_passes ... ok
[INFO] [stdout] test logp_catechol ... ok
[INFO] [stdout] test logp_benzene ... ok
[INFO] [stdout] test logp_caffeine ... ok
[INFO] [stdout] test logp_chlorobenzene ... ok
[INFO] [stdout] test logp_aspirin ... ok
[INFO] [stdout] test logp_dichloromethane ... ok
[INFO] [stdout] test logp_ethanol ... ok
[INFO] [stdout] test logp_methanol ... ok
[INFO] [stdout] test logp_dimethyl_sulfide ... ok
[INFO] [stdout] test logp_metformin ... ok
[INFO] [stdout] test logp_pyridine ... ok
[INFO] [stdout] test logp_ethylbenzene ... ok
[INFO] [stdout] test logp_pyrimidine ... ok
[INFO] [stdout] test logp_phenol ... ok
[INFO] [stdout] test logp_thiophene ... ok
[INFO] [stdout] test logp_trimethyl_phosphate ... ok
[INFO] [stdout] test logp_salicylic_acid ... ok
[INFO] [stdout] test logp_toluene ... ok
[INFO] [stdout] test logp_thiophenol ... ok
[INFO] [stdout] test logp_tetrahydrofuran ... ok
[INFO] [stdout] test mqn_alanine ... ok
[INFO] [stdout] test mqn_benzene ... ok
[INFO] [stdout] test mqn_aspirin ... ok
[INFO] [stdout] test mqn_caffeine ... ok
[INFO] [stdout] test mw_aspirin ... ok
[INFO] [stdout] test mw_benzene ... ok
[INFO] [stdout] test mw_glucose ... ok
[INFO] [stdout] test mw_glycine ... ok
[INFO] [stdout] test mw_ibuprofen ... ok
[INFO] [stdout] test mw_indole ... ok
[INFO] [stdout] test mw_lidocaine ... ok
[INFO] [stdout] test mw_methotrexate ... ok
[INFO] [stdout] test mw_naphthalene ... ok
[INFO] [stdout] test mw_paracetamol ... ok
[INFO] [stdout] test mw_warfarin ... ok
[INFO] [stdout] test ring_descs_benzene ... ok
[INFO] [stdout] test ring_descs_morpholine ... ok
[INFO] [stdout] test molar_refractivity_aspirin ... ok
[INFO] [stdout] test ring_descs_naphthalene_no_spiro ... ok
[INFO] [stdout] test mw_naproxen ... ok
[INFO] [stdout] test ring_descs_pyridine ... ok
[INFO] [stdout] test ring_descs_piperidine ... ok
[INFO] [stdout] test mw_caffeine ... ok
[INFO] [stdout] test ring_descs_norbornane ... ok
[INFO] [stdout] test rotatable_bond_count_aspirin ... ignored, known bug: got 2 rotatable bonds, expected 3 (RDKit reference); never ran in CI before this test file was wired into ci.yml/check.sh, needs its own fix
[INFO] [stdout] test ring_descs_spiro_decane ... ok
[INFO] [stdout] test rotatable_bond_count_ethanol ... ok
[INFO] [stdout] test tpsa_4_nitrophenol ... ok
[INFO] [stdout] test tpsa_arginine ... ok
[INFO] [stdout] test tpsa_aspirin_no_ester_overcounting ... ok
[INFO] [stdout] test tpsa_aspirin ... ok
[INFO] [stdout] test tpsa_benzene ... ok
[INFO] [stdout] test tpsa_caffeine ... ok
[INFO] [stdout] test tpsa_aniline ... ok
[INFO] [stdout] test tpsa_clonazepam ... ok
[INFO] [stdout] test tpsa_diazepam ... ok
[INFO] [stdout] test tpsa_carboxylate_anion ... ok
[INFO] [stdout] test tpsa_dimethyl_sulfone ... ok
[INFO] [stdout] test tpsa_dimethyl_sulfoxide ... ok
[INFO] [stdout] test tpsa_furan ... ok
[INFO] [stdout] test tpsa_glucose ... ok
[INFO] [stdout] test tpsa_indole ... ok
[INFO] [stdout] test tpsa_lidocaine ... ok
[INFO] [stdout] test tpsa_metformin ... ok
[INFO] [stdout] test tpsa_methanesulfonic_acid ... ok
[INFO] [stdout] test tpsa_methotrexate ... ok
[INFO] [stdout] test tpsa_n_substituted_aromatic_n ... ok
[INFO] [stdout] test tpsa_nitrile ... ok
[INFO] [stdout] test tpsa_nitrobenzene ... ok
[INFO] [stdout] test tpsa_paracetamol ... ok
[INFO] [stdout] test tpsa_pyridine ... ok
[INFO] [stdout] test tpsa_ring_junction_n ... ok
[INFO] [stdout] test tpsa_thiazole ... ok
[INFO] [stdout] test tpsa_thiophene ... ok
[INFO] [stdout] test tpsa_trimethyl_phosphate ... ok
[INFO] [stdout] test hac_all_tsv_reference ... FAILED
[INFO] [stdout] test logp_all_tsv_reference ... FAILED
[INFO] [stdout] test mw_all_tsv_reference ... FAILED
[INFO] [stdout] test tpsa_all_tsv_reference ... FAILED
[INFO] [stdout] test hbd_all_tsv_reference ... FAILED
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- hac_all_tsv_reference stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'hac_all_tsv_reference' (1463) panicked at tests/rdkit_reference.rs:1237:54:
[INFO] [stdout] cannot read /opt/scripts/rdkit_ref_properties.tsv: No such file or directory (os error 2)
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x60d996931101 - std[73adb7dc35730857]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x60d996931101 - std[73adb7dc35730857]::backtrace_rs::backtrace::trace_unsynchronized::<std[73adb7dc35730857]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x60d996931101 - std[73adb7dc35730857]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x60d996931101 - <<std[73adb7dc35730857]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[6883ba1bc0fe4ed1]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x60d99694560a - <core[6883ba1bc0fe4ed1]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x60d99694560a - core[6883ba1bc0fe4ed1]::fmt::write
[INFO] [stdout]    6:     0x60d9969355dc - std[73adb7dc35730857]::io::default_write_fmt::<alloc[55a36b64bcbf2c0d]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/io/mod.rs:626:11
[INFO] [stdout]    7:     0x60d9969355dc - <alloc[55a36b64bcbf2c0d]::vec::Vec<u8> as std[73adb7dc35730857]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/io/mod.rs:1730:13
[INFO] [stdout]    8:     0x60d99690f2f6 - <std[73adb7dc35730857]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x60d99690f2f6 - std[73adb7dc35730857]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x60d996928fb9 - std[73adb7dc35730857]::panicking::default_hook
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x60d996806160 - <alloc[55a36b64bcbf2c0d]::boxed::Box<dyn for<'a, 'b> core[6883ba1bc0fe4ed1]::ops::function::Fn<(&'a std[73adb7dc35730857]::panic::PanicHookInfo<'b>,), Output = ()> + core[6883ba1bc0fe4ed1]::marker::Send + core[6883ba1bc0fe4ed1]::marker::Sync> as core[6883ba1bc0fe4ed1]::ops::function::Fn<(&std[73adb7dc35730857]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/alloc/src/boxed.rs:2333:9
[INFO] [stdout]   12:     0x60d996806160 - test[980ffaebd391d06d]::test_main_inner::<test[980ffaebd391d06d]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:155:21
[INFO] [stdout]   13:     0x60d996929172 - <alloc[55a36b64bcbf2c0d]::boxed::Box<dyn for<'a, 'b> core[6883ba1bc0fe4ed1]::ops::function::Fn<(&'a std[73adb7dc35730857]::panic::PanicHookInfo<'b>,), Output = ()> + core[6883ba1bc0fe4ed1]::marker::Send + core[6883ba1bc0fe4ed1]::marker::Sync> as core[6883ba1bc0fe4ed1]::ops::function::Fn<(&std[73adb7dc35730857]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/alloc/src/boxed.rs:2333:9
[INFO] [stdout]   14:     0x60d996929172 - std[73adb7dc35730857]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:823:13
[INFO] [stdout]   15:     0x60d99690f3a2 - std[73adb7dc35730857]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:688:13
[INFO] [stdout]   16:     0x60d996906b79 - std[73adb7dc35730857]::sys::backtrace::__rust_end_short_backtrace::<std[73adb7dc35730857]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x60d99690ff9d - __rustc[a7b7b02e776dd976]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:679:5
[INFO] [stdout]   18:     0x60d996945ddc - core[6883ba1bc0fe4ed1]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x60d9967ebecc - rdkit_reference[847fa629ef9fd55e]::read_tsv::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/rdkit_reference.rs:1237:54
[INFO] [stdout]   20:     0x60d9967e99d1 - <core[6883ba1bc0fe4ed1]::result::Result<alloc[55a36b64bcbf2c0d]::string::String, core[6883ba1bc0fe4ed1]::io::error::Error>>::unwrap_or_else::<rdkit_reference[847fa629ef9fd55e]::read_tsv::{closure#0}>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/result.rs:1620:23
[INFO] [stdout]   21:     0x60d9967ec530 - rdkit_reference[847fa629ef9fd55e]::read_tsv
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/rdkit_reference.rs:1237:35
[INFO] [stdout]   22:     0x60d9967f1295 - rdkit_reference[847fa629ef9fd55e]::hac_all_tsv_reference
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/rdkit_reference.rs:1277:17
[INFO] [stdout]   23:     0x60d9967eb627 - rdkit_reference[847fa629ef9fd55e]::hac_all_tsv_reference::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/rdkit_reference.rs:1275:27
[INFO] [stdout]   24:     0x60d9967f89e6 - <rdkit_reference[847fa629ef9fd55e]::hac_all_tsv_reference::{closure#0} as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   25:     0x60d9967f949b - <fn() -> core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String> as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   26:     0x60d9967f949b - test[980ffaebd391d06d]::__rust_begin_short_backtrace::<core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String>, fn() -> core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:724:18
[INFO] [stdout]   27:     0x60d996806ae5 - test[980ffaebd391d06d]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:747:74
[INFO] [stdout]   28:     0x60d996806ae5 - <core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<test[980ffaebd391d06d]::run_test_in_process::{closure#0}> as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   29:     0x60d996806ae5 - std[73adb7dc35730857]::panicking::catch_unwind::do_call::<core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<test[980ffaebd391d06d]::run_test_in_process::{closure#0}>, core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:576:43
[INFO] [stdout]   30:     0x60d996806ae5 - std[73adb7dc35730857]::panicking::catch_unwind::<core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String>, core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<test[980ffaebd391d06d]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:544:19
[INFO] [stdout]   31:     0x60d996806ae5 - std[73adb7dc35730857]::panic::catch_unwind::<core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<test[980ffaebd391d06d]::run_test_in_process::{closure#0}>, core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panic.rs:359:14
[INFO] [stdout]   32:     0x60d996806ae5 - test[980ffaebd391d06d]::run_test_in_process
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:747:27
[INFO] [stdout]   33:     0x60d996806ae5 - test[980ffaebd391d06d]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:668:43
[INFO] [stdout]   34:     0x60d996801594 - test[980ffaebd391d06d]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:698:41
[INFO] [stdout]   35:     0x60d996801594 - std[73adb7dc35730857]::sys::backtrace::__rust_begin_short_backtrace::<test[980ffaebd391d06d]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   36:     0x60d996809c32 - std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked::<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   37:     0x60d996809c32 - <core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   38:     0x60d996809c32 - std[73adb7dc35730857]::panicking::catch_unwind::do_call::<core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:576:43
[INFO] [stdout]   39:     0x60d996809c32 - std[73adb7dc35730857]::panicking::catch_unwind::<(), core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:544:19
[INFO] [stdout]   40:     0x60d996809c32 - std[73adb7dc35730857]::panic::catch_unwind::<core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panic.rs:359:14
[INFO] [stdout]   41:     0x60d996809c32 - std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked::<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   42:     0x60d996809c32 - <std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1} as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   43:     0x60d99693061f - <alloc[55a36b64bcbf2c0d]::boxed::Box<dyn core[6883ba1bc0fe4ed1]::ops::function::FnOnce<(), Output = ()> + core[6883ba1bc0fe4ed1]::marker::Send> as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/alloc/src/boxed.rs:2319:9
[INFO] [stdout]   44:     0x60d99693061f - <std[73adb7dc35730857]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/thread/unix.rs:123:17
[INFO] [stdout]   45:     0x76a93254faa4 - <unknown>
[INFO] [stdout]   46:     0x76a9325dca64 - clone
[INFO] [stdout]   47:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- logp_all_tsv_reference stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'logp_all_tsv_reference' (1492) panicked at tests/rdkit_reference.rs:1192:29:
[INFO] [stdout] cannot read /opt/scripts/rdkit_ref_properties.tsv: No such file or directory (os error 2)
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x60d996931101 - std[73adb7dc35730857]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x60d996931101 - std[73adb7dc35730857]::backtrace_rs::backtrace::trace_unsynchronized::<std[73adb7dc35730857]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x60d996931101 - std[73adb7dc35730857]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x60d996931101 - <<std[73adb7dc35730857]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[6883ba1bc0fe4ed1]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x60d99694560a - <core[6883ba1bc0fe4ed1]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x60d99694560a - core[6883ba1bc0fe4ed1]::fmt::write
[INFO] [stdout]    6:     0x60d9969355dc - std[73adb7dc35730857]::io::default_write_fmt::<alloc[55a36b64bcbf2c0d]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/io/mod.rs:626:11
[INFO] [stdout]    7:     0x60d9969355dc - <alloc[55a36b64bcbf2c0d]::vec::Vec<u8> as std[73adb7dc35730857]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/io/mod.rs:1730:13
[INFO] [stdout]    8:     0x60d99690f2f6 - <std[73adb7dc35730857]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x60d99690f2f6 - std[73adb7dc35730857]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x60d996928fb9 - std[73adb7dc35730857]::panicking::default_hook
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x60d996806160 - <alloc[55a36b64bcbf2c0d]::boxed::Box<dyn for<'a, 'b> core[6883ba1bc0fe4ed1]::ops::function::Fn<(&'a std[73adb7dc35730857]::panic::PanicHookInfo<'b>,), Output = ()> + core[6883ba1bc0fe4ed1]::marker::Send + core[6883ba1bc0fe4ed1]::marker::Sync> as core[6883ba1bc0fe4ed1]::ops::function::Fn<(&std[73adb7dc35730857]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/alloc/src/boxed.rs:2333:9
[INFO] [stdout]   12:     0x60d996806160 - test[980ffaebd391d06d]::test_main_inner::<test[980ffaebd391d06d]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:155:21
[INFO] [stdout]   13:     0x60d996929172 - <alloc[55a36b64bcbf2c0d]::boxed::Box<dyn for<'a, 'b> core[6883ba1bc0fe4ed1]::ops::function::Fn<(&'a std[73adb7dc35730857]::panic::PanicHookInfo<'b>,), Output = ()> + core[6883ba1bc0fe4ed1]::marker::Send + core[6883ba1bc0fe4ed1]::marker::Sync> as core[6883ba1bc0fe4ed1]::ops::function::Fn<(&std[73adb7dc35730857]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/alloc/src/boxed.rs:2333:9
[INFO] [stdout]   14:     0x60d996929172 - std[73adb7dc35730857]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:823:13
[INFO] [stdout]   15:     0x60d99690f3a2 - std[73adb7dc35730857]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:688:13
[INFO] [stdout]   16:     0x60d996906b79 - std[73adb7dc35730857]::sys::backtrace::__rust_end_short_backtrace::<std[73adb7dc35730857]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x60d99690ff9d - __rustc[a7b7b02e776dd976]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:679:5
[INFO] [stdout]   18:     0x60d996945ddc - core[6883ba1bc0fe4ed1]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x60d9967ec02c - rdkit_reference[847fa629ef9fd55e]::logp_all_tsv_reference::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/rdkit_reference.rs:1192:29
[INFO] [stdout]   20:     0x60d9967e9a51 - <core[6883ba1bc0fe4ed1]::result::Result<alloc[55a36b64bcbf2c0d]::string::String, core[6883ba1bc0fe4ed1]::io::error::Error>>::unwrap_or_else::<rdkit_reference[847fa629ef9fd55e]::logp_all_tsv_reference::{closure#0}>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/result.rs:1620:23
[INFO] [stdout]   21:     0x60d9967f2e97 - rdkit_reference[847fa629ef9fd55e]::logp_all_tsv_reference
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/rdkit_reference.rs:1192:10
[INFO] [stdout]   22:     0x60d9967eb7a7 - rdkit_reference[847fa629ef9fd55e]::logp_all_tsv_reference::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/rdkit_reference.rs:1182:28
[INFO] [stdout]   23:     0x60d9967f8be6 - <rdkit_reference[847fa629ef9fd55e]::logp_all_tsv_reference::{closure#0} as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   24:     0x60d9967f949b - <fn() -> core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String> as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   25:     0x60d9967f949b - test[980ffaebd391d06d]::__rust_begin_short_backtrace::<core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String>, fn() -> core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:724:18
[INFO] [stdout]   26:     0x60d996806ae5 - test[980ffaebd391d06d]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:747:74
[INFO] [stdout]   27:     0x60d996806ae5 - <core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<test[980ffaebd391d06d]::run_test_in_process::{closure#0}> as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   28:     0x60d996806ae5 - std[73adb7dc35730857]::panicking::catch_unwind::do_call::<core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<test[980ffaebd391d06d]::run_test_in_process::{closure#0}>, core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:576:43
[INFO] [stdout]   29:     0x60d996806ae5 - std[73adb7dc35730857]::panicking::catch_unwind::<core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String>, core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<test[980ffaebd391d06d]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:544:19
[INFO] [stdout]   30:     0x60d996806ae5 - std[73adb7dc35730857]::panic::catch_unwind::<core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<test[980ffaebd391d06d]::run_test_in_process::{closure#0}>, core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panic.rs:359:14
[INFO] [stdout]   31:     0x60d996806ae5 - test[980ffaebd391d06d]::run_test_in_process
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:747:27
[INFO] [stdout]   32:     0x60d996806ae5 - test[980ffaebd391d06d]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:668:43
[INFO] [stdout]   33:     0x60d996801594 - test[980ffaebd391d06d]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:698:41
[INFO] [stdout]   34:     0x60d996801594 - std[73adb7dc35730857]::sys::backtrace::__rust_begin_short_backtrace::<test[980ffaebd391d06d]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   35:     0x60d996809c32 - std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked::<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   36:     0x60d996809c32 - <core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   37:     0x60d996809c32 - std[73adb7dc35730857]::panicking::catch_unwind::do_call::<core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:576:43
[INFO] [stdout]   38:     0x60d996809c32 - std[73adb7dc35730857]::panicking::catch_unwind::<(), core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:544:19
[INFO] [stdout]   39:     0x60d996809c32 - std[73adb7dc35730857]::panic::catch_unwind::<core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panic.rs:359:14
[INFO] [stdout]   40:     0x60d996809c32 - std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked::<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   41:     0x60d996809c32 - <std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1} as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   42:     0x60d99693061f - <alloc[55a36b64bcbf2c0d]::boxed::Box<dyn core[6883ba1bc0fe4ed1]::ops::function::FnOnce<(), Output = ()> + core[6883ba1bc0fe4ed1]::marker::Send> as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/alloc/src/boxed.rs:2319:9
[INFO] [stdout]   43:     0x60d99693061f - <std[73adb7dc35730857]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/thread/unix.rs:123:17
[INFO] [stdout]   44:     0x76a93254faa4 - <unknown>
[INFO] [stdout]   45:     0x76a9325dca64 - clone
[INFO] [stdout]   46:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- mw_all_tsv_reference stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'mw_all_tsv_reference' (1520) panicked at tests/rdkit_reference.rs:1237:54:
[INFO] [stdout] cannot read /opt/scripts/rdkit_ref_properties.tsv: No such file or directory (os error 2)
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x60d996931101 - std[73adb7dc35730857]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x60d996931101 - std[73adb7dc35730857]::backtrace_rs::backtrace::trace_unsynchronized::<std[73adb7dc35730857]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x60d996931101 - std[73adb7dc35730857]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x60d996931101 - <<std[73adb7dc35730857]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[6883ba1bc0fe4ed1]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x60d99694560a - <core[6883ba1bc0fe4ed1]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x60d99694560a - core[6883ba1bc0fe4ed1]::fmt::write
[INFO] [stdout]    6:     0x60d9969355dc - std[73adb7dc35730857]::io::default_write_fmt::<alloc[55a36b64bcbf2c0d]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/io/mod.rs:626:11
[INFO] [stdout]    7:     0x60d9969355dc - <alloc[55a36b64bcbf2c0d]::vec::Vec<u8> as std[73adb7dc35730857]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/io/mod.rs:1730:13
[INFO] [stdout]    8:     0x60d99690f2f6 - <std[73adb7dc35730857]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x60d99690f2f6 - std[73adb7dc35730857]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x60d996928fb9 - std[73adb7dc35730857]::panicking::default_hook
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x60d996806160 - <alloc[55a36b64bcbf2c0d]::boxed::Box<dyn for<'a, 'b> core[6883ba1bc0fe4ed1]::ops::function::Fn<(&'a std[73adb7dc35730857]::panic::PanicHookInfo<'b>,), Output = ()> + core[6883ba1bc0fe4ed1]::marker::Send + core[6883ba1bc0fe4ed1]::marker::Sync> as core[6883ba1bc0fe4ed1]::ops::function::Fn<(&std[73adb7dc35730857]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/alloc/src/boxed.rs:2333:9
[INFO] [stdout]   12:     0x60d996806160 - test[980ffaebd391d06d]::test_main_inner::<test[980ffaebd391d06d]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:155:21
[INFO] [stdout]   13:     0x60d996929172 - <alloc[55a36b64bcbf2c0d]::boxed::Box<dyn for<'a, 'b> core[6883ba1bc0fe4ed1]::ops::function::Fn<(&'a std[73adb7dc35730857]::panic::PanicHookInfo<'b>,), Output = ()> + core[6883ba1bc0fe4ed1]::marker::Send + core[6883ba1bc0fe4ed1]::marker::Sync> as core[6883ba1bc0fe4ed1]::ops::function::Fn<(&std[73adb7dc35730857]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/alloc/src/boxed.rs:2333:9
[INFO] [stdout]   14:     0x60d996929172 - std[73adb7dc35730857]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:823:13
[INFO] [stdout]   15:     0x60d99690f3a2 - std[73adb7dc35730857]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:688:13
[INFO] [stdout]   16:     0x60d996906b79 - std[73adb7dc35730857]::sys::backtrace::__rust_end_short_backtrace::<std[73adb7dc35730857]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x60d99690ff9d - __rustc[a7b7b02e776dd976]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:679:5
[INFO] [stdout]   18:     0x60d996945ddc - core[6883ba1bc0fe4ed1]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x60d9967ebecc - rdkit_reference[847fa629ef9fd55e]::read_tsv::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/rdkit_reference.rs:1237:54
[INFO] [stdout]   20:     0x60d9967e99d1 - <core[6883ba1bc0fe4ed1]::result::Result<alloc[55a36b64bcbf2c0d]::string::String, core[6883ba1bc0fe4ed1]::io::error::Error>>::unwrap_or_else::<rdkit_reference[847fa629ef9fd55e]::read_tsv::{closure#0}>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/result.rs:1620:23
[INFO] [stdout]   21:     0x60d9967ec530 - rdkit_reference[847fa629ef9fd55e]::read_tsv
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/rdkit_reference.rs:1237:35
[INFO] [stdout]   22:     0x60d9967f07e5 - rdkit_reference[847fa629ef9fd55e]::mw_all_tsv_reference
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/rdkit_reference.rs:1244:17
[INFO] [stdout]   23:     0x60d9967eb5c7 - rdkit_reference[847fa629ef9fd55e]::mw_all_tsv_reference::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/rdkit_reference.rs:1241:26
[INFO] [stdout]   24:     0x60d9967f8966 - <rdkit_reference[847fa629ef9fd55e]::mw_all_tsv_reference::{closure#0} as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   25:     0x60d9967f949b - <fn() -> core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String> as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   26:     0x60d9967f949b - test[980ffaebd391d06d]::__rust_begin_short_backtrace::<core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String>, fn() -> core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:724:18
[INFO] [stdout]   27:     0x60d996806ae5 - test[980ffaebd391d06d]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:747:74
[INFO] [stdout]   28:     0x60d996806ae5 - <core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<test[980ffaebd391d06d]::run_test_in_process::{closure#0}> as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   29:     0x60d996806ae5 - std[73adb7dc35730857]::panicking::catch_unwind::do_call::<core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<test[980ffaebd391d06d]::run_test_in_process::{closure#0}>, core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:576:43
[INFO] [stdout]   30:     0x60d996806ae5 - std[73adb7dc35730857]::panicking::catch_unwind::<core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String>, core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<test[980ffaebd391d06d]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:544:19
[INFO] [stdout]   31:     0x60d996806ae5 - std[73adb7dc35730857]::panic::catch_unwind::<core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<test[980ffaebd391d06d]::run_test_in_process::{closure#0}>, core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panic.rs:359:14
[INFO] [stdout]   32:     0x60d996806ae5 - test[980ffaebd391d06d]::run_test_in_process
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:747:27
[INFO] [stdout]   33:     0x60d996806ae5 - test[980ffaebd391d06d]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:668:43
[INFO] [stdout]   34:     0x60d996801594 - test[980ffaebd391d06d]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:698:41
[INFO] [stdout]   35:     0x60d996801594 - std[73adb7dc35730857]::sys::backtrace::__rust_begin_short_backtrace::<test[980ffaebd391d06d]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   36:     0x60d996809c32 - std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked::<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   37:     0x60d996809c32 - <core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   38:     0x60d996809c32 - std[73adb7dc35730857]::panicking::catch_unwind::do_call::<core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:576:43
[INFO] [stdout]   39:     0x60d996809c32 - std[73adb7dc35730857]::panicking::catch_unwind::<(), core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:544:19
[INFO] [stdout]   40:     0x60d996809c32 - std[73adb7dc35730857]::panic::catch_unwind::<core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panic.rs:359:14
[INFO] [stdout]   41:     0x60d996809c32 - std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked::<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   42:     0x60d996809c32 - <std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1} as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   43:     0x60d99693061f - <alloc[55a36b64bcbf2c0d]::boxed::Box<dyn core[6883ba1bc0fe4ed1]::ops::function::FnOnce<(), Output = ()> + core[6883ba1bc0fe4ed1]::marker::Send> as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/alloc/src/boxed.rs:2319:9
[INFO] [stdout]   44:     0x60d99693061f - <std[73adb7dc35730857]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/thread/unix.rs:123:17
[INFO] [stdout]   45:     0x76a93254faa4 - <unknown>
[INFO] [stdout]   46:     0x76a9325dca64 - clone
[INFO] [stdout]   47:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- tpsa_all_tsv_reference stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'tpsa_all_tsv_reference' (1543) panicked at tests/rdkit_reference.rs:1139:29:
[INFO] [stdout] cannot read /opt/scripts/rdkit_ref_properties.tsv: No such file or directory (os error 2)
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x60d996931101 - std[73adb7dc35730857]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x60d996931101 - std[73adb7dc35730857]::backtrace_rs::backtrace::trace_unsynchronized::<std[73adb7dc35730857]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x60d996931101 - std[73adb7dc35730857]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x60d996931101 - <<std[73adb7dc35730857]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[6883ba1bc0fe4ed1]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x60d99694560a - <core[6883ba1bc0fe4ed1]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x60d99694560a - core[6883ba1bc0fe4ed1]::fmt::write
[INFO] [stdout]    6:     0x60d9969355dc - std[73adb7dc35730857]::io::default_write_fmt::<alloc[55a36b64bcbf2c0d]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/io/mod.rs:626:11
[INFO] [stdout]    7:     0x60d9969355dc - <alloc[55a36b64bcbf2c0d]::vec::Vec<u8> as std[73adb7dc35730857]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/io/mod.rs:1730:13
[INFO] [stdout]    8:     0x60d99690f2f6 - <std[73adb7dc35730857]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x60d99690f2f6 - std[73adb7dc35730857]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x60d996928fb9 - std[73adb7dc35730857]::panicking::default_hook
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x60d996806160 - <alloc[55a36b64bcbf2c0d]::boxed::Box<dyn for<'a, 'b> core[6883ba1bc0fe4ed1]::ops::function::Fn<(&'a std[73adb7dc35730857]::panic::PanicHookInfo<'b>,), Output = ()> + core[6883ba1bc0fe4ed1]::marker::Send + core[6883ba1bc0fe4ed1]::marker::Sync> as core[6883ba1bc0fe4ed1]::ops::function::Fn<(&std[73adb7dc35730857]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/alloc/src/boxed.rs:2333:9
[INFO] [stdout]   12:     0x60d996806160 - test[980ffaebd391d06d]::test_main_inner::<test[980ffaebd391d06d]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:155:21
[INFO] [stdout]   13:     0x60d996929172 - <alloc[55a36b64bcbf2c0d]::boxed::Box<dyn for<'a, 'b> core[6883ba1bc0fe4ed1]::ops::function::Fn<(&'a std[73adb7dc35730857]::panic::PanicHookInfo<'b>,), Output = ()> + core[6883ba1bc0fe4ed1]::marker::Send + core[6883ba1bc0fe4ed1]::marker::Sync> as core[6883ba1bc0fe4ed1]::ops::function::Fn<(&std[73adb7dc35730857]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/alloc/src/boxed.rs:2333:9
[INFO] [stdout]   14:     0x60d996929172 - std[73adb7dc35730857]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:823:13
[INFO] [stdout]   15:     0x60d99690f3a2 - std[73adb7dc35730857]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:688:13
[INFO] [stdout]   16:     0x60d996906b79 - std[73adb7dc35730857]::sys::backtrace::__rust_end_short_backtrace::<std[73adb7dc35730857]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x60d99690ff9d - __rustc[a7b7b02e776dd976]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:679:5
[INFO] [stdout]   18:     0x60d996945ddc - core[6883ba1bc0fe4ed1]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x60d9967ec15c - rdkit_reference[847fa629ef9fd55e]::tpsa_all_tsv_reference::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/rdkit_reference.rs:1139:29
[INFO] [stdout]   20:     0x60d9967e9ad1 - <core[6883ba1bc0fe4ed1]::result::Result<alloc[55a36b64bcbf2c0d]::string::String, core[6883ba1bc0fe4ed1]::io::error::Error>>::unwrap_or_else::<rdkit_reference[847fa629ef9fd55e]::tpsa_all_tsv_reference::{closure#0}>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/result.rs:1620:23
[INFO] [stdout]   21:     0x60d9967f3a37 - rdkit_reference[847fa629ef9fd55e]::tpsa_all_tsv_reference
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/rdkit_reference.rs:1139:10
[INFO] [stdout]   22:     0x60d9967eb7d7 - rdkit_reference[847fa629ef9fd55e]::tpsa_all_tsv_reference::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/rdkit_reference.rs:1129:28
[INFO] [stdout]   23:     0x60d9967f8c26 - <rdkit_reference[847fa629ef9fd55e]::tpsa_all_tsv_reference::{closure#0} as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   24:     0x60d9967f949b - <fn() -> core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String> as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   25:     0x60d9967f949b - test[980ffaebd391d06d]::__rust_begin_short_backtrace::<core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String>, fn() -> core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:724:18
[INFO] [stdout]   26:     0x60d996806ae5 - test[980ffaebd391d06d]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:747:74
[INFO] [stdout]   27:     0x60d996806ae5 - <core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<test[980ffaebd391d06d]::run_test_in_process::{closure#0}> as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   28:     0x60d996806ae5 - std[73adb7dc35730857]::panicking::catch_unwind::do_call::<core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<test[980ffaebd391d06d]::run_test_in_process::{closure#0}>, core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:576:43
[INFO] [stdout]   29:     0x60d996806ae5 - std[73adb7dc35730857]::panicking::catch_unwind::<core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String>, core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<test[980ffaebd391d06d]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:544:19
[INFO] [stdout]   30:     0x60d996806ae5 - std[73adb7dc35730857]::panic::catch_unwind::<core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<test[980ffaebd391d06d]::run_test_in_process::{closure#0}>, core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panic.rs:359:14
[INFO] [stdout]   31:     0x60d996806ae5 - test[980ffaebd391d06d]::run_test_in_process
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:747:27
[INFO] [stdout]   32:     0x60d996806ae5 - test[980ffaebd391d06d]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:668:43
[INFO] [stdout]   33:     0x60d996801594 - test[980ffaebd391d06d]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:698:41
[INFO] [stdout]   34:     0x60d996801594 - std[73adb7dc35730857]::sys::backtrace::__rust_begin_short_backtrace::<test[980ffaebd391d06d]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   35:     0x60d996809c32 - std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked::<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   36:     0x60d996809c32 - <core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   37:     0x60d996809c32 - std[73adb7dc35730857]::panicking::catch_unwind::do_call::<core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:576:43
[INFO] [stdout]   38:     0x60d996809c32 - std[73adb7dc35730857]::panicking::catch_unwind::<(), core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:544:19
[INFO] [stdout]   39:     0x60d996809c32 - std[73adb7dc35730857]::panic::catch_unwind::<core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panic.rs:359:14
[INFO] [stdout]   40:     0x60d996809c32 - std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked::<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   41:     0x60d996809c32 - <std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1} as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   42:     0x60d99693061f - <alloc[55a36b64bcbf2c0d]::boxed::Box<dyn core[6883ba1bc0fe4ed1]::ops::function::FnOnce<(), Output = ()> + core[6883ba1bc0fe4ed1]::marker::Send> as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/alloc/src/boxed.rs:2319:9
[INFO] [stdout]   43:     0x60d99693061f - <std[73adb7dc35730857]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/thread/unix.rs:123:17
[INFO] [stdout]   44:     0x76a93254faa4 - <unknown>
[INFO] [stdout]   45:     0x76a9325dca64 - clone
[INFO] [stdout]   46:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- hbd_all_tsv_reference stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'hbd_all_tsv_reference' (1482) panicked at tests/rdkit_reference.rs:1237:54:
[INFO] [stdout] cannot read /opt/scripts/rdkit_ref_properties.tsv: No such file or directory (os error 2)
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x60d996931101 - std[73adb7dc35730857]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x60d996931101 - std[73adb7dc35730857]::backtrace_rs::backtrace::trace_unsynchronized::<std[73adb7dc35730857]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x60d996931101 - std[73adb7dc35730857]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x60d996931101 - <<std[73adb7dc35730857]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[6883ba1bc0fe4ed1]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x60d99694560a - <core[6883ba1bc0fe4ed1]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x60d99694560a - core[6883ba1bc0fe4ed1]::fmt::write
[INFO] [stdout]    6:     0x60d9969355dc - std[73adb7dc35730857]::io::default_write_fmt::<alloc[55a36b64bcbf2c0d]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/io/mod.rs:626:11
[INFO] [stdout]    7:     0x60d9969355dc - <alloc[55a36b64bcbf2c0d]::vec::Vec<u8> as std[73adb7dc35730857]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/io/mod.rs:1730:13
[INFO] [stdout]    8:     0x60d99690f2f6 - <std[73adb7dc35730857]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x60d99690f2f6 - std[73adb7dc35730857]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x60d996928fb9 - std[73adb7dc35730857]::panicking::default_hook
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x60d996806160 - <alloc[55a36b64bcbf2c0d]::boxed::Box<dyn for<'a, 'b> core[6883ba1bc0fe4ed1]::ops::function::Fn<(&'a std[73adb7dc35730857]::panic::PanicHookInfo<'b>,), Output = ()> + core[6883ba1bc0fe4ed1]::marker::Send + core[6883ba1bc0fe4ed1]::marker::Sync> as core[6883ba1bc0fe4ed1]::ops::function::Fn<(&std[73adb7dc35730857]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/alloc/src/boxed.rs:2333:9
[INFO] [stdout]   12:     0x60d996806160 - test[980ffaebd391d06d]::test_main_inner::<test[980ffaebd391d06d]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:155:21
[INFO] [stdout]   13:     0x60d996929172 - <alloc[55a36b64bcbf2c0d]::boxed::Box<dyn for<'a, 'b> core[6883ba1bc0fe4ed1]::ops::function::Fn<(&'a std[73adb7dc35730857]::panic::PanicHookInfo<'b>,), Output = ()> + core[6883ba1bc0fe4ed1]::marker::Send + core[6883ba1bc0fe4ed1]::marker::Sync> as core[6883ba1bc0fe4ed1]::ops::function::Fn<(&std[73adb7dc35730857]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/alloc/src/boxed.rs:2333:9
[INFO] [stdout]   14:     0x60d996929172 - std[73adb7dc35730857]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:823:13
[INFO] [stdout]   15:     0x60d99690f3a2 - std[73adb7dc35730857]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:688:13
[INFO] [stdout]   16:     0x60d996906b79 - std[73adb7dc35730857]::sys::backtrace::__rust_end_short_backtrace::<std[73adb7dc35730857]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x60d99690ff9d - __rustc[a7b7b02e776dd976]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:679:5
[INFO] [stdout]   18:     0x60d996945ddc - core[6883ba1bc0fe4ed1]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x60d9967ebecc - rdkit_reference[847fa629ef9fd55e]::read_tsv::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/rdkit_reference.rs:1237:54
[INFO] [stdout]   20:     0x60d9967e99d1 - <core[6883ba1bc0fe4ed1]::result::Result<alloc[55a36b64bcbf2c0d]::string::String, core[6883ba1bc0fe4ed1]::io::error::Error>>::unwrap_or_else::<rdkit_reference[847fa629ef9fd55e]::read_tsv::{closure#0}>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/result.rs:1620:23
[INFO] [stdout]   21:     0x60d9967ec530 - rdkit_reference[847fa629ef9fd55e]::read_tsv
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/rdkit_reference.rs:1237:35
[INFO] [stdout]   22:     0x60d9967f1bd5 - rdkit_reference[847fa629ef9fd55e]::hbd_all_tsv_reference
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/rdkit_reference.rs:1307:17
[INFO] [stdout]   23:     0x60d9967eb657 - rdkit_reference[847fa629ef9fd55e]::hbd_all_tsv_reference::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/rdkit_reference.rs:1305:27
[INFO] [stdout]   24:     0x60d9967f8a26 - <rdkit_reference[847fa629ef9fd55e]::hbd_all_tsv_reference::{closure#0} as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   25:     0x60d9967f949b - <fn() -> core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String> as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   26:     0x60d9967f949b - test[980ffaebd391d06d]::__rust_begin_short_backtrace::<core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String>, fn() -> core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:724:18
[INFO] [stdout]   27:     0x60d996806ae5 - test[980ffaebd391d06d]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:747:74
[INFO] [stdout]   28:     0x60d996806ae5 - <core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<test[980ffaebd391d06d]::run_test_in_process::{closure#0}> as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   29:     0x60d996806ae5 - std[73adb7dc35730857]::panicking::catch_unwind::do_call::<core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<test[980ffaebd391d06d]::run_test_in_process::{closure#0}>, core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:576:43
[INFO] [stdout]   30:     0x60d996806ae5 - std[73adb7dc35730857]::panicking::catch_unwind::<core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String>, core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<test[980ffaebd391d06d]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:544:19
[INFO] [stdout]   31:     0x60d996806ae5 - std[73adb7dc35730857]::panic::catch_unwind::<core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<test[980ffaebd391d06d]::run_test_in_process::{closure#0}>, core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panic.rs:359:14
[INFO] [stdout]   32:     0x60d996806ae5 - test[980ffaebd391d06d]::run_test_in_process
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:747:27
[INFO] [stdout]   33:     0x60d996806ae5 - test[980ffaebd391d06d]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:668:43
[INFO] [stdout]   34:     0x60d996801594 - test[980ffaebd391d06d]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:698:41
[INFO] [stdout]   35:     0x60d996801594 - std[73adb7dc35730857]::sys::backtrace::__rust_begin_short_backtrace::<test[980ffaebd391d06d]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   36:     0x60d996809c32 - std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked::<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   37:     0x60d996809c32 - <core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   38:     0x60d996809c32 - std[73adb7dc35730857]::panicking::catch_unwind::do_call::<core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:576:43
[INFO] [stdout]   39:     0x60d996809c32 - std[73adb7dc35730857]::panicking::catch_unwind::<(), core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:544:19
[INFO] [stdout]   40:     0x60d996809c32 - std[73adb7dc35730857]::panic::catch_unwind::<core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panic.rs:359:14
[INFO] [stdout]   41:     0x60d996809c32 - std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked::<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   42:     0x60d996809c32 - <std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1} as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   43:     0x60d99693061f - <alloc[55a36b64bcbf2c0d]::boxed::Box<dyn core[6883ba1bc0fe4ed1]::ops::function::FnOnce<(), Output = ()> + core[6883ba1bc0fe4ed1]::marker::Send> as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/alloc/src/boxed.rs:2319:9
[INFO] [stdout]   44:     0x60d99693061f - <std[73adb7dc35730857]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/thread/unix.rs:123:17
[INFO] [stdout]   45:     0x76a93254faa4 - <unknown>
[INFO] [stdout]   46:     0x76a9325dca64 - clone
[INFO] [stdout]   47:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     hac_all_tsv_reference
[INFO] [stdout]     hbd_all_tsv_reference
[INFO] [stdout]     logp_all_tsv_reference
[INFO] [stdout]     mw_all_tsv_reference
[INFO] [stdout]     tpsa_all_tsv_reference
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 113 passed; 5 failed; 1 ignored; 0 measured; 0 filtered out; finished in 0.06s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "0792f0e3254a6d05a1aac48e87e4bfe1a6a7dc20f2411c2fbbe802a0a2e5c702", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "0792f0e3254a6d05a1aac48e87e4bfe1a6a7dc20f2411c2fbbe802a0a2e5c702", kill_on_drop: false }`
[INFO] [stdout] 0792f0e3254a6d05a1aac48e87e4bfe1a6a7dc20f2411c2fbbe802a0a2e5c702
