[INFO] cloning repository https://github.com/Mec-iS/arrowspace-rs [INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/Mec-iS/arrowspace-rs" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2FMec-iS%2Farrowspace-rs", kill_on_drop: false }` [INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2FMec-iS%2Farrowspace-rs'... [INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }` [INFO] [stdout] 650a2b3832eaf2445881d681a2b3244942155b52 [INFO] testing Mec-iS/arrowspace-rs against try#db823df02fd0c2cf67b43025ac3fef3f2d743245 for pr-150681 [INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2FMec-iS%2Farrowspace-rs" "/workspace/builds/worker-3-tc2/source", kill_on_drop: false }` [INFO] [stderr] Cloning into '/workspace/builds/worker-3-tc2/source'... [INFO] [stderr] done. [INFO] started tweaking git repo https://github.com/Mec-iS/arrowspace-rs [INFO] finished tweaking git repo https://github.com/Mec-iS/arrowspace-rs [INFO] tweaked toml for git repo https://github.com/Mec-iS/arrowspace-rs written to /workspace/builds/worker-3-tc2/source/Cargo.toml [INFO] validating manifest of git repo https://github.com/Mec-iS/arrowspace-rs on toolchain db823df02fd0c2cf67b43025ac3fef3f2d743245 [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+db823df02fd0c2cf67b43025ac3fef3f2d743245" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }` [INFO] crate git repo https://github.com/Mec-iS/arrowspace-rs 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" "+db823df02fd0c2cf67b43025ac3fef3f2d743245" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] [stderr] warning: /workspace/builds/worker-3-tc2/source/Cargo.toml: unused manifest key: examples [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:845e597a41426bbf2703be69acdb67d10b6de511142d05cba7bbe119c898b2c7" "/opt/rustwide/cargo-home/bin/cargo" "+db823df02fd0c2cf67b43025ac3fef3f2d743245" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] ceb2573ff972d53c12a92a4f545aa56aab6d12c3455247061ba0bbfce5c7ea90 [INFO] running `Command { std: "docker" "start" "-a" "ceb2573ff972d53c12a92a4f545aa56aab6d12c3455247061ba0bbfce5c7ea90", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "ceb2573ff972d53c12a92a4f545aa56aab6d12c3455247061ba0bbfce5c7ea90", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "ceb2573ff972d53c12a92a4f545aa56aab6d12c3455247061ba0bbfce5c7ea90", kill_on_drop: false }` [INFO] [stdout] ceb2573ff972d53c12a92a4f545aa56aab6d12c3455247061ba0bbfce5c7ea90 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:845e597a41426bbf2703be69acdb67d10b6de511142d05cba7bbe119c898b2c7" "/opt/rustwide/cargo-home/bin/cargo" "+db823df02fd0c2cf67b43025ac3fef3f2d743245" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 94280a803d4247b17b94876396e80c689c1f49e1e58c090139829a5df8f16726 [INFO] running `Command { std: "docker" "start" "-a" "94280a803d4247b17b94876396e80c689c1f49e1e58c090139829a5df8f16726", kill_on_drop: false }` [INFO] [stderr] warning: /opt/rustwide/workdir/Cargo.toml: unused manifest key: examples [INFO] [stderr] Compiling autocfg v1.5.0 [INFO] [stderr] Compiling libm v0.2.15 [INFO] [stderr] Compiling libc v0.2.178 [INFO] [stderr] Compiling cfg-if v1.0.4 [INFO] [stderr] Compiling getrandom v0.3.4 [INFO] [stderr] Compiling zerocopy v0.8.31 [INFO] [stderr] Compiling proc-macro2 v1.0.103 [INFO] [stderr] Compiling memchr v2.7.6 [INFO] [stderr] Compiling quote v1.0.42 [INFO] [stderr] Compiling rayon-core v1.13.0 [INFO] [stderr] Compiling regex-syntax v0.8.8 [INFO] [stderr] Compiling crossbeam-deque v0.8.6 [INFO] [stderr] Compiling smallvec v1.15.1 [INFO] [stderr] Compiling num-traits v0.2.19 [INFO] [stderr] Compiling num-complex v0.2.4 [INFO] [stderr] Compiling matrixmultiply v0.3.10 [INFO] [stderr] Compiling parking_lot_core v0.9.12 [INFO] [stderr] Compiling rawpointer v0.2.1 [INFO] [stderr] Compiling anstyle-parse v0.2.7 [INFO] [stderr] Compiling aho-corasick v1.1.4 [INFO] [stderr] Compiling syn v2.0.111 [INFO] [stderr] Compiling rand_core v0.9.3 [INFO] [stderr] Compiling anstyle-query v1.1.5 [INFO] [stderr] Compiling anstyle v1.0.13 [INFO] [stderr] Compiling is_terminal_polyfill v1.70.2 [INFO] [stderr] Compiling scopeguard v1.2.0 [INFO] [stderr] Compiling either v1.15.0 [INFO] [stderr] Compiling regex-automata v0.4.13 [INFO] [stderr] Compiling colorchoice v1.0.4 [INFO] [stderr] Compiling rand_core v0.6.4 [INFO] [stderr] Compiling rayon v1.11.0 [INFO] [stderr] Compiling ppv-lite86 v0.2.21 [INFO] [stderr] Compiling rand v0.8.5 [INFO] [stderr] Compiling num-integer v0.1.46 [INFO] [stderr] Compiling num-complex v0.4.6 [INFO] [stderr] Compiling num-bigint v0.4.6 [INFO] [stderr] Compiling num-iter v0.1.45 [INFO] [stderr] Compiling rand_chacha v0.9.0 [INFO] [stderr] Compiling approx v0.3.2 [INFO] [stderr] Compiling ndarray v0.17.1 [INFO] [stderr] Compiling alga v0.9.3 [INFO] [stderr] Compiling rand v0.9.2 [INFO] [stderr] Compiling ordered-float v5.1.0 [INFO] [stderr] Compiling approx v0.5.1 [INFO] [stderr] Compiling anstream v0.6.21 [INFO] [stderr] Compiling serde_derive v1.0.228 [INFO] [stderr] Compiling lock_api v0.4.14 [INFO] [stderr] Compiling num-rational v0.4.2 [INFO] [stderr] Compiling serde v1.0.228 [INFO] [stderr] Compiling num_cpus v1.17.0 [INFO] [stderr] Compiling regex v1.12.2 [INFO] [stderr] Compiling num v0.4.3 [INFO] [stderr] Compiling jiff v0.2.16 [INFO] [stderr] Compiling hashbrown v0.14.5 [INFO] [stderr] Compiling env_filter v0.1.4 [INFO] [stderr] Compiling once_cell v1.21.3 [INFO] [stderr] Compiling smartcore v0.4.8 [INFO] [stderr] Compiling dashmap v6.1.0 [INFO] [stderr] Compiling rand_distr v0.5.1 [INFO] [stderr] Compiling sprs v0.11.4 [INFO] [stderr] Compiling env_logger v0.11.8 [INFO] [stderr] Compiling arrowspace v0.24.6 (/opt/rustwide/workdir) [INFO] [stderr] Finished `dev` profile [unoptimized + debuginfo] target(s) in 1m 12s [INFO] running `Command { std: "docker" "inspect" "94280a803d4247b17b94876396e80c689c1f49e1e58c090139829a5df8f16726", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "94280a803d4247b17b94876396e80c689c1f49e1e58c090139829a5df8f16726", kill_on_drop: false }` [INFO] [stdout] 94280a803d4247b17b94876396e80c689c1f49e1e58c090139829a5df8f16726 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:845e597a41426bbf2703be69acdb67d10b6de511142d05cba7bbe119c898b2c7" "/opt/rustwide/cargo-home/bin/cargo" "+db823df02fd0c2cf67b43025ac3fef3f2d743245" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] cf1e32446c4fda1e55cced501e2606003af6d394450843c4085f3800ff4b8b5c [INFO] running `Command { std: "docker" "start" "-a" "cf1e32446c4fda1e55cced501e2606003af6d394450843c4085f3800ff4b8b5c", kill_on_drop: false }` [INFO] [stderr] warning: /opt/rustwide/workdir/Cargo.toml: unused manifest key: examples [INFO] [stderr] Compiling proc-macro2 v1.0.103 [INFO] [stderr] Compiling quote v1.0.42 [INFO] [stderr] Compiling zerocopy v0.8.31 [INFO] [stderr] Compiling serde_core v1.0.228 [INFO] [stderr] Compiling serde v1.0.228 [INFO] [stderr] Compiling getrandom v0.2.16 [INFO] [stderr] Compiling typeid v1.0.3 [INFO] [stderr] Compiling either v1.15.0 [INFO] [stderr] Compiling rand_core v0.6.4 [INFO] [stderr] Compiling erased-serde v0.4.9 [INFO] [stderr] Compiling serde_json v1.0.145 [INFO] [stderr] Compiling typetag v0.2.21 [INFO] [stderr] Compiling inventory v0.3.21 [INFO] [stderr] Compiling rayon v1.11.0 [INFO] [stderr] Compiling rustix v1.1.2 [INFO] [stderr] Compiling clap_lex v0.7.6 [INFO] [stderr] Compiling plotters-backend v0.3.7 [INFO] [stderr] Compiling ciborium-io v0.2.2 [INFO] [stderr] Compiling itertools v0.13.0 [INFO] [stderr] Compiling same-file v1.0.6 [INFO] [stderr] Compiling clap_builder v4.5.53 [INFO] [stderr] Compiling linux-raw-sys v0.11.0 [INFO] [stderr] Compiling bitflags v2.10.0 [INFO] [stderr] Compiling plotters-svg v0.3.7 [INFO] [stderr] Compiling cast v0.3.0 [INFO] [stderr] Compiling plotters v0.3.7 [INFO] [stderr] Compiling syn v2.0.111 [INFO] [stderr] Compiling walkdir v2.5.0 [INFO] [stderr] Compiling anes v0.1.6 [INFO] [stderr] Compiling fastrand v2.3.0 [INFO] [stderr] Compiling oorandom v11.1.5 [INFO] [stderr] Compiling rand_pcg v0.9.0 [INFO] [stderr] Compiling rand_xoshiro v0.7.0 [INFO] [stderr] Compiling criterion-plot v0.6.0 [INFO] [stderr] Compiling tempfile v3.23.0 [INFO] [stderr] Compiling sprs v0.11.4 [INFO] [stderr] Compiling clap v4.5.53 [INFO] [stderr] Compiling zerocopy-derive v0.8.31 [INFO] [stderr] Compiling serde_derive v1.0.228 [INFO] [stderr] Compiling typetag-impl v0.2.21 [INFO] [stderr] Compiling ppv-lite86 v0.2.21 [INFO] [stderr] Compiling half v2.7.1 [INFO] [stderr] Compiling tinytemplate v1.2.1 [INFO] [stderr] Compiling ciborium-ll v0.2.2 [INFO] [stderr] Compiling ciborium v0.2.2 [INFO] [stderr] Compiling rand_chacha v0.3.1 [INFO] [stderr] Compiling rand_chacha v0.9.0 [INFO] [stderr] Compiling rand v0.9.2 [INFO] [stderr] Compiling rand v0.8.5 [INFO] [stderr] Compiling criterion v0.7.0 [INFO] [stderr] Compiling rand_distr v0.5.1 [INFO] [stderr] Compiling rand_distr v0.4.3 [INFO] [stderr] Compiling smartcore v0.4.8 [INFO] [stderr] Compiling arrowspace v0.24.6 (/opt/rustwide/workdir) [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 1m 30s [INFO] running `Command { std: "docker" "inspect" "cf1e32446c4fda1e55cced501e2606003af6d394450843c4085f3800ff4b8b5c", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "cf1e32446c4fda1e55cced501e2606003af6d394450843c4085f3800ff4b8b5c", kill_on_drop: false }` [INFO] [stdout] cf1e32446c4fda1e55cced501e2606003af6d394450843c4085f3800ff4b8b5c [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:845e597a41426bbf2703be69acdb67d10b6de511142d05cba7bbe119c898b2c7" "/opt/rustwide/cargo-home/bin/cargo" "+db823df02fd0c2cf67b43025ac3fef3f2d743245" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] 9f0fa57faeb51310ae85a315cabc5199a4449301161c6a473f7eb93704ecc8ea [INFO] running `Command { std: "docker" "start" "-a" "9f0fa57faeb51310ae85a315cabc5199a4449301161c6a473f7eb93704ecc8ea", kill_on_drop: false }` [INFO] [stderr] warning: /opt/rustwide/workdir/Cargo.toml: unused manifest key: examples [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 0.28s [INFO] [stderr] Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/arrowspace-8a02b84072cd9dc0) [INFO] [stdout] [INFO] [stdout] running 225 tests [INFO] [stdout] test subgraphs::tests::test_subg_centroids::test_recluster_centroids_properties ... ok [INFO] [stdout] test subgraphs::tests::test_subg_centroids::test_centroid_hierarchy_advanced ... ok [INFO] [stdout] test subgraphs::tests::test_subg_centroids::test_centroid_subgraphs_two_levels ... ok [INFO] [stdout] test subgraphs::tests::test_subg_centroids::test_centroid_subgraphs_min_centroids_cutoff ... ok [INFO] [stdout] test subgraphs::tests::test_subg_centroids::test_centroid_subgraphs_three_levels ... ok [INFO] [stdout] test subgraphs::tests::test_subg_centroids::test_centroid_subgraphs_basic has been running for over 60 seconds [INFO] [stdout] test subgraphs::tests::test_subg_centroids::test_centroid_subgraphs_flat_vs_hierarchy has been running for over 60 seconds [INFO] [stdout] test subgraphs::tests::test_subg_motives::test_spot_subgraphs_energy_basic has been running for over 60 seconds [INFO] [stdout] test subgraphs::tests::test_subg_motives::test_spot_subgraphs_energy_multi_motifs has been running for over 60 seconds [INFO] [stdout] test subgraphs::tests::test_subg_centroids::test_centroid_subgraphs_basic ... ok [INFO] [stdout] test subgraphs::tests::test_subg_centroids::test_centroid_subgraphs_flat_vs_hierarchy ... ok [INFO] [stdout] test subgraphs::tests::test_subg_motives::test_spot_subgraphs_energy_multi_motifs ... ok [INFO] [stdout] test subgraphs::tests::test_subg_motives::test_subgraph_rayleigh_computation ... ok [INFO] [stdout] test subgraphs::tests::test_subg_parallel::test_centroid_hierarchy_parallel_determinism ... ok [INFO] [stdout] test subgraphs::tests::test_subg_motives::test_spot_subgraphs_energy_basic ... ok [INFO] [stdout] test subgraphs::tests::test_subg_motives::test_subgraph_from_parent ... ok [INFO] [stdout] test subgraphs::tests::test_subg_parallel::test_recluster_centroids_deterministic ... ok [INFO] [stdout] test subgraphs::tests::test_subg_parallel::test_recluster_centroids_means_correctness ... ok [INFO] [stdout] test subgraphs::tests::test_subg_motives::test_spot_subgraphs_energy_with_item_mapping has been running for over 60 seconds [INFO] [stdout] test subgraphs::tests::test_subg_motives::test_subgraph_energy_rayleigh_filter has been running for over 60 seconds [INFO] [stdout] test subgraphs::tests::test_subg_parallel::test_motif_subgraphs_no_loss_or_duplication ... ok [INFO] [stdout] test tests::test_arrow::test_arrowspace_config_typed_with_projection ... ok [INFO] [stdout] test subgraphs::tests::test_subg_parallel::test_concurrent_hierarchy_builds ... ok [INFO] [stdout] test tests::test_arrow::test_builder_clustering_produces_valid_assignments ... ok [INFO] [stdout] test tests::test_arrow::test_arrowspace_config_typed_without_projection ... ok [INFO] [stdout] test tests::test_arrow::test_builder_cluster_radius_impact ... ok [INFO] [stdout] test tests::test_arrow::test_builder_lambda_computation_with_different_tau_modes ... ok [INFO] [stdout] test subgraphs::tests::test_subg_motives::test_subgraph_structure_clique_data has been running for over 60 seconds [INFO] [stdout] test tests::test_arrow::test_builder_direction_vs_magnitude_sensitivity ... ok [INFO] [stdout] test tests::test_arrow::test_builder_lambda_statistics ... ok [INFO] [stdout] test tests::test_arrow::test_builder_spectral_laplacian_computation ... ok [INFO] [stdout] test subgraphs::tests::test_subg_motives::test_subgraph_energy_rayleigh_filter ... FAILED [INFO] [stdout] test subgraphs::tests::test_subg_parallel::test_motif_subgraphs_parallel_correctness ... FAILED [INFO] [stdout] test subgraphs::tests::test_subg_motives::test_subgraph_structure_clique_data ... ok [INFO] [stdout] test tests::test_arrow::test_empty_with_projection_panics_on_extra_reduced_dim_true - should panic ... ok [INFO] [stdout] test tests::test_arrow::test_empty_with_projection_path ... ok [INFO] [stdout] test tests::test_arrow::test_empty_with_projection_none_path ... ok [INFO] [stdout] test tests::test_arrow::test_builder_normalisation_flag_is_preserved ... ok [INFO] [stdout] test tests::test_arrow::test_builder_with_normalized_vs_unnormalized_items ... ok [INFO] [stdout] test subgraphs::tests::test_subg_parallel::test_parallel_stress_large_dataset ... ok [INFO] [stdout] test tests::test_builder::simple_build ... ok [INFO] [stdout] test subgraphs::tests::test_subg_motives::test_spot_subgraphs_energy_with_item_mapping ... ok [INFO] [stdout] test tests::test_builder::test_builder_unit_norm_diagonal_similarity ... ok [INFO] [stdout] test tests::test_builder::lambda_graph_shape_matches_rows ... ok [INFO] [stdout] test tests::test_arrow::test_builder_with_inline_sampling ... ok [INFO] [stdout] test tests::test_builder::test_density_adaptive_high_rate ... ok [INFO] [stdout] test tests::test_builder::test_density_adaptive_sampling_basic ... ok [INFO] [stdout] test tests::test_builder::test_constant_sampler_preserves_outliers ... ok [INFO] [stdout] test tests::test_builder::test_density_adaptive_aggressive_sampling ... ok [INFO] [stdout] test tests::test_builder::test_density_adaptive_with_duplicates ... ok [INFO] [stdout] test tests::test_builder::build_from_rows_with_lambda_graph ... ok [INFO] [stdout] test tests::test_builder::test_simple_random_high_rate ... ignored, flaky, depends on how sampling happens [INFO] [stdout] test tests::test_builder::test_simple_random_vs_density_adaptive ... ignored, flaky, depends on what happens in clustering [INFO] [stdout] test tests::test_builder::test_simple_random_aggressive_sampling ... ok [INFO] [stdout] test tests::test_builder::test_density_adaptive_with_uniform_data ... ok [INFO] [stdout] test tests::test_clustering::test_calinski_harabasz_three_clusters ... ok [INFO] [stdout] test tests::test_clustering::test_calinski_harabasz_single_cluster ... ok [INFO] [stdout] test tests::test_clustering::test_clustering_heuristic_trait_interface ... ok [INFO] [stdout] test tests::test_clustering::test_consistent_results_with_seed ... ok [INFO] [stdout] test tests::test_clustering::test_euclidean_dist_basic ... ok [INFO] [stdout] test tests::test_clustering::test_euclidean_dist_identity ... ok [INFO] [stdout] test tests::test_clustering::test_euclidean_dist_one_dimensional ... ok [INFO] [stdout] test tests::test_clustering::test_intrinsic_dimension_full_space ... ok [INFO] [stdout] test tests::test_clustering::test_intrinsic_dimension_line ... ok [INFO] [stdout] test tests::test_clustering::test_intrinsic_dimension_plane ... ok [INFO] [stdout] test tests::test_clustering::test_intrinsic_dimension_small_n ... ok [INFO] [stdout] test tests::test_clustering::test_kmeans_convergence_early_stop ... ok [INFO] [stdout] test tests::test_clustering::test_kmeans_empty_cluster_recovery ... ok [INFO] [stdout] test tests::test_clustering::test_kmeans_k_equals_zero - should panic ... ok [INFO] [stdout] test tests::test_clustering::test_kmeans_k_greater_than_n ... ok [INFO] [stdout] test tests::test_clustering::test_kmeans_lloyd_gaussian_blobs ... ok [INFO] [stdout] test tests::test_clustering::test_kmeans_lloyd_k_equals_n ... ok [INFO] [stdout] test tests::test_clustering::test_kmeans_single_row - should panic ... ok [INFO] [stdout] test tests::test_clustering::test_nearest_centroid_middle ... ok [INFO] [stdout] test tests::test_clustering::test_nearest_centroid_single ... ok [INFO] [stdout] test tests::test_clustering::test_optimal_k_heuristic_degenerate_identical ... ok [INFO] [stdout] test tests::test_clustering::test_optimal_k_heuristic_high_dimensional_random ... ok [INFO] [stdout] test tests::test_clustering::test_optimal_k_heuristic_single_feature ... ok [INFO] [stdout] test tests::test_clustering::test_optimal_k_heuristic_small_n ... ok [INFO] [stdout] test tests::test_clustering::test_optimal_k_heuristic_spherical_clusters ... ok [INFO] [stdout] test tests::test_builder::test_density_adaptive_vs_no_sampling ... ok [INFO] [stdout] test tests::test_builder::test_with_deterministic_seed ... ok [INFO] [stdout] test tests::test_clustering::test_optimal_k_minimum_viable_dataset ... ok [INFO] [stdout] test tests::test_clustering::test_optimal_k_performance_large_dataset ... ignored [INFO] [stdout] test tests::test_clustering::test_optimal_k_mixed_scale_features ... ok [INFO] [stdout] test tests::test_arrow::test_builder_dimensionality_reduction ... ok [INFO] [stdout] test tests::test_clustering::test_optimal_k_heuristic_synthetic_three_clusters ... ok [INFO] [stdout] test tests::test_clustering::test_step1_bounds_large_n_small_f ... ok [INFO] [stdout] test tests::test_clustering::test_step1_bounds_high_dimensional ... ok [INFO] [stdout] test tests::test_clustering::test_calinski_harabasz_well_separated ... ok [INFO] [stdout] test tests::test_clustering::test_step1_bounds_small_dataset ... ok [INFO] [stdout] test tests::test_clustering::test_threshold_all_points_identical ... ok [INFO] [stdout] test tests::test_clustering::test_readme_example ... ok [INFO] [stdout] test tests::test_clustering::test_threshold_from_pilot_large_variance ... ok [INFO] [stdout] test tests::test_clustering::test_optimal_k_very_high_dimensional ... ok [INFO] [stdout] test tests::test_clustering::test_threshold_from_pilot_single_point_per_cluster ... ok [INFO] [stdout] test tests::test_clustering::test_threshold_very_tight_clusters ... ok [INFO] [stdout] test tests::test_clustering::test_threshold_from_pilot_two_clusters ... ok [INFO] [stdout] test tests::test_clustering::test_threshold_zero_variance_clusters ... ok [INFO] [stdout] test tests::test_eigenmaps::test_eigenmaps_stages_produce_valid_state ... ok [INFO] [stdout] test tests::test_eigenmaps::test_eigenmaps_with_dim_reduction ... ok [INFO] [stdout] test tests::test_eigenmaps::test_eigenmaps_vs_build_basic ... ok [INFO] [stdout] test tests::test_eigenmaps::test_eigenmaps_vs_build_different_taumode ... ok [INFO] [stdout] test tests::test_energy_builder::test_build_energy_requires_dims_reduction - should panic ... ok [INFO] [stdout] test tests::test_energy_builder::test_build_energy_dimensionality_reduction ... ok [INFO] [stdout] test tests::test_eigenmaps::test_search_without_taumode_panics - should panic ... ok [INFO] [stdout] test tests::test_energy_builder::test_energy_build_custom_params ... ok [INFO] [stdout] test tests::test_eigenmaps::test_eigenmaps_non_sensical_undecidable_query - should panic ... ok [INFO] [stdout] test tests::test_energy_builder::test_energy_build_diffusion_splits ... ok [INFO] [stdout] test tests::test_builder::test_density_adaptive_maintains_lambda_quality ... ok [INFO] [stdout] test tests::test_energy_builder::test_energy_build_basic ... ok [INFO] [stdout] test tests::test_builder::build_with_lambda_graph_over_product_like_rows ... ok [INFO] [stdout] test tests::test_energy_builder::test_energy_laplacian_properties ... ok [INFO] [stdout] test tests::test_builder::test_density_adaptive_sampling_statistics has been running for over 60 seconds [INFO] [stdout] test tests::test_energy_search::test_energy_no_cosine_dependence ... ok [INFO] [stdout] test tests::test_eigenmaps::test_eigenmaps_vs_build_with_spectral ... ok [INFO] [stdout] test tests::test_energy_builder::test_energy_build_with_projection ... ok [INFO] [stdout] test tests::test_energy_builder::test_energy_build_lambda_statistics ... ok [INFO] [stdout] test tests::test_energy_search::test_energy_search_empty_k ... ok [INFO] [stdout] test tests::test_energy_builder::test_energy_build_taumode_consistency ... ok [INFO] [stdout] test tests::test_builder::test_density_adaptive_sampling_statistics ... ok [INFO] [stdout] test tests::test_energy_search::test_energy_search_high_dimensional ... ok [INFO] [stdout] test tests::test_energy_search::test_energy_search_k_scaling ... ok [INFO] [stdout] test tests::test_energy_search::test_energy_search_basic ... ok [INFO] [stdout] test tests::test_energy_builder::test_energy_build_with_optical_compression ... ok [INFO] [stdout] test tests::test_energy_search::test_energy_search_score_monotonicity ... ok [INFO] [stdout] test tests::test_energy_search::test_energy_search_single ... ok [INFO] [stdout] test tests::test_energy_search::test_energy_search_weight_tuning ... ok [INFO] [stdout] test tests::test_energy_search::test_energy_search_self_retrieval ... ok [INFO] [stdout] test tests::test_energy_search::test_energy_search_lambda_proximity ... ok [INFO] [stdout] test tests::test_energy_search::test_energy_search_optical_compression ... ok [INFO] [stdout] test tests::test_energy_search::test_energy_vs_standard_lambda_distribution ... ok [INFO] [stdout] test tests::test_graph_factory::test_builder_basic_clustering_with_synthetic_data ... ok [INFO] [stdout] test tests::test_energy_search::test_energy_search_optimized ... ok [INFO] [stdout] test tests::test_graph_factory::test_builder_laplacian_diagonal_properties ... ok [INFO] [stdout] test tests::test_energy_search::test_energy_vs_energy_extra_dims_reduction_recall_at_k ... ok [INFO] [stdout] test tests::test_energy_search::test_energy_vs_standard_build_time ... ok [INFO] [stdout] test tests::test_graph_factory::test_builder_minimum_items ... ok [INFO] [stdout] test tests::test_graph_factory::test_builder_lambda_values_are_nonnegative ... ok [INFO] [stdout] test tests::test_graph_factory::test_builder_parameter_preservation ... ok [INFO] [stdout] test tests::test_graph_factory::test_builder_laplacian_symmetry ... ok [INFO] [stdout] test tests::test_graph_factory::test_builder_scale_invariance_with_normalization ... ok [INFO] [stdout] test tests::test_graph_factory::test_builder_normalization_effects ... ok [INFO] [stdout] test tests::test_laplacian::test_arrowspace_integration_pattern_sparse ... ok [INFO] [stdout] test tests::test_laplacian::test_basic_laplacian_construction ... ok [INFO] [stdout] test tests::test_laplacian::test_cosine_similarity_based_construction ... ok [INFO] [stdout] test tests::test_energy_search::test_energy_vs_standard_search_overlap ... ok [INFO] [stdout] test tests::test_laplacian::test_insufficient_data_panics - should panic ... ok [INFO] [stdout] test tests::test_laplacian::test_eps_parameter_constraint ... ok [INFO] [stdout] test tests::test_laplacian::test_k_parameter_constraint ... ok [INFO] [stdout] test tests::test_laplacian::test_laplacian_mathematical_properties ... ok [INFO] [stdout] test tests::test_laplacian::test_numerical_stability ... ok [INFO] [stdout] test tests::test_laplacian::test_with_adjacency_output ... ok [INFO] [stdout] test tests::test_laplacian::test_optimized_sparse_matrix_laplacian ... ok [INFO] [stdout] test tests::test_graph_factory::test_builder_with_high_noise ... ok [INFO] [stdout] test tests::test_laplacian_unnormalised::test_builder_normalized_vs_unnormalized_clustering ... ok [INFO] [stdout] test tests::test_laplacian_unnormalised::test_cosine_similarity_scale_invariance ... ok [INFO] [stdout] test tests::test_laplacian_unnormalised::test_hybrid_similarity_components ... ok [INFO] [stdout] test tests::test_laplacian_unnormalised::test_hybrid_similarity_scale_sensitivity ... ok [INFO] [stdout] test tests::test_laplacian_unnormalised::test_magnitude_penalty_computation ... ok [INFO] [stdout] test tests::test_graph_factory::test_builder_with_different_dimensions ... ok [INFO] [stdout] test tests::test_laplacian_unnormalised::test_builder_graph_params_preservation ... ok [INFO] [stdout] test tests::test_graph_factory::test_builder_spectral_laplacian_shape ... ok [INFO] [stdout] test tests::test_motives::test_motives_basic ... ok [INFO] [stdout] test tests::test_energy_search::test_energy_vs_standard_graph_structure ... ok [INFO] [stdout] test tests::test_motives::test_motives_energy_basic ... ok [INFO] [stdout] test tests::test_laplacian_unnormalised::test_builder_lambda_comparison_normalized_vs_unnormalized ... ok [INFO] [stdout] test tests::test_laplacian_unnormalised::test_with_deterministic_clustering ... ok [INFO] [stdout] test tests::test_motives::test_motives_basic_2 ... ok [INFO] [stdout] test tests::test_motives::test_motives_eigen_vs_energy_consistency ... ok [INFO] [stdout] test tests::test_energy_search::test_energy_vs_standard_precision_at_k ... ok [INFO] [stdout] test tests::test_energy_search::test_energy_vs_standard_recall_at_k has been running for over 60 seconds [INFO] [stdout] test tests::test_querying_proj::test_project_query_consistency ... ok [INFO] [stdout] test tests::test_querying_proj::test_project_query_linearity ... ok [INFO] [stdout] test tests::test_querying_proj::test_project_query_different_queries_differ ... ok [INFO] [stdout] test tests::test_querying_proj::test_prepare_query_item_consistency ... ok [INFO] [stdout] test tests::test_querying_proj::test_project_query_preserves_dot_product_sign ... ok [INFO] [stdout] test tests::test_querying_proj::test_projection_preserves_relative_distances ... ok [INFO] [stdout] test tests::test_querying_proj::test_project_query_zero_vector ... ok [INFO] [stdout] test tests::test_querying_proj::test_project_query_no_projection ... ok [INFO] [stdout] test tests::test_querying_proj::test_lambda_values_reasonable_range ... ok [INFO] [stdout] test tests::test_querying_proj::test_project_query_preserves_scale_approximately ... ok [INFO] [stdout] test tests::test_querying_proj::test_query_with_nan_values - should panic ... ok [INFO] [stdout] test tests::test_querying_proj::test_query_dimension_mismatch_panics - should panic ... ok [INFO] [stdout] test tests::test_reduction::test_full_pipeline_implicit_projection ... ok [INFO] [stdout] test tests::test_reduction::test_implicit_projection_creates ... ok [INFO] [stdout] test tests::test_reduction::test_implicit_projection_deterministic ... ok [INFO] [stdout] test tests::test_reduction::test_implicit_projection_different_seeds ... ok [INFO] [stdout] test tests::test_reduction::test_implicit_projection_dimensions ... ok [INFO] [stdout] test tests::test_reduction::test_implicit_projection_linearity ... ok [INFO] [stdout] test tests::test_reduction::test_implicit_projection_non_trivial ... ok [INFO] [stdout] test tests::test_reduction::test_implicit_projection_preserves_scale ... ok [INFO] [stdout] test tests::test_reduction::test_implicit_projection_zero_vector ... ok [INFO] [stdout] test tests::test_reduction::test_jl_dimension_basic ... ok [INFO] [stdout] test tests::test_reduction::test_jl_dimension_edge_case_small_epsilon ... ok [INFO] [stdout] test tests::test_reduction::test_jl_dimension_formula_correctness ... ok [INFO] [stdout] test tests::test_reduction::test_jl_dimension_grows_with_n ... ok [INFO] [stdout] test tests::test_reduction::test_jl_dimension_inversely_proportional_epsilon ... ok [INFO] [stdout] test tests::test_reduction::test_jl_dimension_large_dataset ... ok [INFO] [stdout] test tests::test_reduction::test_jl_dimension_minimum_bound ... ok [INFO] [stdout] test tests::test_reduction::test_jl_dimension_reasonable_range ... ok [INFO] [stdout] test tests::test_reduction::test_jl_dimension_tight_epsilon ... ok [INFO] [stdout] test tests::test_reduction::test_memory_efficiency ... ok [INFO] [stdout] test tests::test_reduction::test_project_matrix_different_rows_different_projections ... ok [INFO] [stdout] test tests::test_reduction::test_project_matrix_dimensions ... ok [INFO] [stdout] test tests::test_reduction::test_project_matrix_preserves_rows ... ok [INFO] [stdout] test tests::test_reduction::test_project_matrix_zero_matrix ... ok [INFO] [stdout] test tests::test_sparsification::test_sfgrass_basic ... ok [INFO] [stdout] test tests::test_sparsification::test_sfgrass_larger ... ok [INFO] [stdout] test tests::test_sparsification::test_sfgrass_vs_no_sparsification ... ignored, depends on number of nodes [INFO] [stdout] test tests::test_querying_proj::test_search_returns_top_k_exactly ... ok [INFO] [stdout] test tests::test_taumode::test_builder_compute_lambdas_basic ... ok [INFO] [stdout] test tests::test_taumode::test_builder_lambdas_consistency_properties ... ok [INFO] [stdout] test tests::test_querying_proj::test_search_lambda_aware_hybrid ... ok [INFO] [stdout] test tests::test_querying_proj::test_query_with_projection_enabled ... ok [INFO] [stdout] test tests::test_taumode::test_builder_lambdas_invariants ... ok [INFO] [stdout] test tests::test_energy_search::test_energy_vs_standard_recall_at_k ... ok [INFO] [stdout] test tests::test_taumode::test_energy_dimension_consistency ... ok [INFO] [stdout] test tests::test_taumode::test_energy_lambda_computation_bounds ... ok [INFO] [stdout] test tests::test_taumode::test_select_tau_fixed ... ok [INFO] [stdout] test tests::test_taumode::test_select_tau_floor_enforcement ... ok [INFO] [stdout] test tests::test_taumode::test_select_tau_mean ... ok [INFO] [stdout] test tests::test_taumode::test_select_tau_median ... ok [INFO] [stdout] test tests::test_taumode::test_select_tau_percentile ... ok [INFO] [stdout] test tests::test_taumode::test_tau_floor_constant ... ok [INFO] [stdout] test tests::test_taumode::test_rayleigh_quotient_scale_invariance ... ok [INFO] [stdout] test tests::test_querying_proj::test_search_lambda_aware_alpha_effect ... ok [INFO] [stdout] test tests::test_querying_proj::test_range_search_with_query_lambda ... ok [INFO] [stdout] test tests::test_taumode::test_builder_lambdas_different_tau_modes ... ok [INFO] [stdout] test tests::test_taumode::test_taumode_consistency_with_projection ... ok [INFO] [stdout] test tests::test_querying_proj::test_query_without_projection ... ok [INFO] [stdout] test tests::test_taumode::test_taumode_energy_consistency_with_projection ... ok [INFO] [stdout] test tests::test_taumode::test_builder_lambdas_nondeterministic_with_projection ... ok [INFO] [stdout] test tests::test_taumode::test_builder_lambdas_with_larger_dataset ... ok [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] [INFO] [stdout] ---- subgraphs::tests::test_subg_motives::test_subgraph_energy_rayleigh_filter stdout ---- [INFO] [stdout] [2026-01-06T10:24:37Z INFO arrowspace::builder] Initializing new ArrowSpaceBuilder [INFO] [stdout] [2026-01-06T10:24:37Z DEBUG arrowspace::builder] Creating ArrowSpaceBuilder with default parameters [INFO] [stdout] [2026-01-06T10:24:37Z INFO arrowspace::builder] Configuring lambda graph: eps=0.4, k=12, p=2, sigma=None [INFO] [stdout] [2026-01-06T10:24:37Z DEBUG arrowspace::builder] Lambda graph will use raw item magnitudes for normalization [INFO] [stdout] [2026-01-06T10:24:37Z INFO arrowspace::builder] Setting custom clustering seed: 999 [INFO] [stdout] [2026-01-06T10:24:37Z INFO arrowspace::builder] Building ArrowSpace from 300 items with 100 features [INFO] [stdout] [2026-01-06T10:24:37Z DEBUG arrowspace::builder] Build configuration: eps=0.4, k=12, p=2, sigma=None, normalise=false, synthesis=Median [INFO] [stdout] [2026-01-06T10:24:37Z INFO arrowspace::builder] EigenMaps::start_clustering: N=300 items, F=100 features [INFO] [stdout] [2026-01-06T10:24:37Z DEBUG arrowspace::builder] Creating ArrowSpace with taumode: Median [INFO] [stdout] [2026-01-06T10:24:37Z INFO arrowspace::sampling] Simple random sampler with keep rate 100.0% [INFO] [stdout] [2026-01-06T10:24:37Z INFO arrowspace::builder] Computing optimal clustering parameters [INFO] [stdout] [2026-01-06T10:24:37Z INFO arrowspace::clustering] Computing optimal K for clustering: N=300, F=100 [INFO] [stdout] [2026-01-06T10:24:41Z DEBUG arrowspace::clustering] Two-NN mean ratio: 1.0300, estimated ID: 34 [INFO] [stdout] [2026-01-06T10:24:41Z DEBUG arrowspace::clustering] Intrinsic dimension estimate: 34 [INFO] [stdout] [2026-01-06T10:24:41Z DEBUG arrowspace::clustering] Testing K in range [6, 17] with step 2 [INFO] [stdout] [2026-01-06T10:25:20Z DEBUG arrowspace::clustering] Best K=6 with penalized score=-26.3328 [INFO] [stdout] [2026-01-06T10:25:24Z DEBUG arrowspace::clustering] Standard threshold: radius=4723.120421 [INFO] [stdout] [2026-01-06T10:25:24Z DEBUG arrowspace::builder] Optimal clustering: K=6, radius=4723.120421, intrinsic_dim=34 [INFO] [stdout] [2026-01-06T10:25:24Z INFO arrowspace::builder] Running incremental clustering: max_clusters=6, radius=4723.120421 [INFO] [stdout] [2026-01-06T10:25:24Z INFO arrowspace::clustering] Starting incremental clustering with inline sampling [INFO] [stdout] [2026-01-06T10:25:24Z DEBUG arrowspace::clustering] Parameters: max_clusters=6, radius=4723.1204 [INFO] [stdout] [2026-01-06T10:25:24Z DEBUG arrowspace::clustering] Row 104: DROPPED as outlier (dist²=142381.522979 > relaxed 7084.680632, len=6 >= max=6) [INFO] [stdout] [2026-01-06T10:25:24Z DEBUG arrowspace::clustering] Row 167: DROPPED as outlier (dist²=135856.221584 > relaxed 7084.680632, len=6 >= max=6) [INFO] [stdout] [2026-01-06T10:25:24Z DEBUG arrowspace::clustering] Row 203: DROPPED as outlier (dist²=161407.776396 > relaxed 7084.680632, len=6 >= max=6) [INFO] [stdout] [2026-01-06T10:25:24Z DEBUG arrowspace::clustering] Row 220: DROPPED as outlier (dist²=138638.931093 > relaxed 7084.680632, len=6 >= max=6) [INFO] [stdout] [2026-01-06T10:25:24Z DEBUG arrowspace::clustering] Row 227: DROPPED as outlier (dist²=134358.539358 > relaxed 7084.680632, len=6 >= max=6) [INFO] [stdout] [2026-01-06T10:25:24Z DEBUG arrowspace::clustering] Row 254: DROPPED as outlier (dist²=142403.362689 > relaxed 7084.680632, len=6 >= max=6) [INFO] [stdout] [2026-01-06T10:25:24Z DEBUG arrowspace::clustering] Row 258: DROPPED as outlier (dist²=137092.477213 > relaxed 7084.680632, len=6 >= max=6) [INFO] [stdout] [2026-01-06T10:25:24Z DEBUG arrowspace::clustering] Row 262: DROPPED as outlier (dist²=135972.634582 > relaxed 7084.680632, len=6 >= max=6) [INFO] [stdout] [2026-01-06T10:25:24Z DEBUG arrowspace::clustering] Row 288: DROPPED as outlier (dist²=136793.174294 > relaxed 7084.680632, len=6 >= max=6) [INFO] [stdout] [2026-01-06T10:25:24Z DEBUG arrowspace::clustering] Row 294: DROPPED as outlier (dist²=141857.388924 > relaxed 7084.680632, len=6 >= max=6) [INFO] [stdout] [2026-01-06T10:25:24Z DEBUG arrowspace::clustering] Row 298: DROPPED as outlier (dist²=125670.855862 > relaxed 7084.680632, len=6 >= max=6) [INFO] [stdout] [2026-01-06T10:25:24Z DEBUG arrowspace::clustering] Inline sampling complete: 300 kept (100.00%), 0 discarded [INFO] [stdout] [2026-01-06T10:25:24Z DEBUG arrowspace::clustering] Clustering produced 6 centroids from 300 rows (100% sampling) [INFO] [stdout] [2026-01-06T10:25:24Z INFO arrowspace::builder] Clustering complete: 6 centroids, 289 items assigned [INFO] [stdout] [2026-01-06T10:25:24Z DEBUG arrowspace::builder] JL projection disabled or dimension too small [INFO] [stdout] [2026-01-06T10:25:24Z INFO arrowspace::eigenmaps] EigenMaps::eigenmaps: Building Laplacian from 6 centroids × 100 features [INFO] [stdout] [2026-01-06T10:25:24Z DEBUG arrowspace::eigenmaps] λ-graph parameters: eps=0.4, k=12, topk=8, p=2, sigma=None, normalize=false [INFO] [stdout] [2026-01-06T10:25:24Z INFO arrowspace::graph] Building Laplacian matrix for K cluster: 6 clusters [INFO] [stdout] [2026-01-06T10:25:24Z DEBUG arrowspace::graph] Laplacian parameters: eps=0.4, k=12, p=2, sigma=None, normalise=false [INFO] [stdout] [2026-01-06T10:25:24Z INFO arrowspace::laplacian] Building Laplacian matrix for 6 items with 100 features [INFO] [stdout] [2026-01-06T10:25:24Z DEBUG arrowspace::laplacian] Graph parameters: eps=0.4, k=12, p=2, sigma=None, normalise=false [INFO] [stdout] [2026-01-06T10:25:24Z DEBUG arrowspace::laplacian] Skipping normalization - using raw item magnitudes [INFO] [stdout] [2026-01-06T10:25:24Z INFO arrowspace::laplacian] Building CosinePair data structure [INFO] [stdout] [2026-01-06T10:25:24Z DEBUG arrowspace::laplacian] CosinePair structure built for 100 items [INFO] [stdout] [2026-01-06T10:25:24Z INFO arrowspace::laplacian] Computing degrees for inline sparsification [INFO] [stdout] [2026-01-06T10:25:26Z DEBUG arrowspace::laplacian] Skipping sparsification (avg degree 3.0) [INFO] [stdout] [2026-01-06T10:25:26Z INFO arrowspace::laplacian] Computing k-NN with CosinePair: k=9 [INFO] [stdout] [2026-01-06T10:25:45Z DEBUG arrowspace::laplacian] Built adjacency rows for 100 items [INFO] [stdout] [2026-01-06T10:25:45Z INFO arrowspace::laplacian] Converting adjacency to sparse Laplacian matrix (DashMap batched) [INFO] [stdout] [2026-01-06T10:25:45Z DEBUG arrowspace::laplacian] Total triplets: 676, edges: 288 [INFO] [stdout] [2026-01-06T10:25:45Z DEBUG arrowspace::laplacian] Inserted triplets in 23.64µs [INFO] [stdout] [2026-01-06T10:25:45Z INFO arrowspace::laplacian] Sparse Laplacian construction time: 12.196009ms [INFO] [stdout] [2026-01-06T10:25:45Z INFO arrowspace::laplacian] Total Laplacian construction time: 20.808227196s [INFO] [stdout] [2026-01-06T10:25:45Z INFO arrowspace::laplacian] Successfully built sparse Laplacian matrix (6x6) with 676 non-zeros [INFO] [stdout] [2026-01-06T10:25:45Z INFO arrowspace::graph] Laplacian matrix built: 100×100 with 300 nodes, 676 non-zeros [INFO] [stdout] [2026-01-06T10:25:45Z DEBUG arrowspace::graph] Matrix has 676 non-zero entries [INFO] [stdout] [2026-01-06T10:25:45Z INFO arrowspace::eigenmaps] Laplacian construction complete: 100×100 matrix, 676 non-zeros, 93.24% sparse [INFO] [stdout] [2026-01-06T10:25:45Z INFO arrowspace::builder] Computing taumode lambdas with synthesis: Median [INFO] [stdout] [2026-01-06T10:25:45Z INFO arrowspace::eigenmaps] EigenMaps::compute_taumode: Computing λ values for 300 items using Median [INFO] [stdout] [2026-01-06T10:25:45Z DEBUG arrowspace::eigenmaps] Laplacian: 300 nodes, 676 non-zeros [INFO] [stdout] [2026-01-06T10:25:45Z INFO arrowspace::taumode] ╔═════════════════════════════════════════════════════════════╗ [INFO] [stdout] [2026-01-06T10:25:45Z INFO arrowspace::taumode] ║ Parallel TauMode Lambda Computation ║ [INFO] [stdout] [2026-01-06T10:25:45Z INFO arrowspace::taumode] ╠═════════════════════════════════════════════════════════════╣ [INFO] [stdout] [2026-01-06T10:25:45Z INFO arrowspace::taumode] ║ Configuration: ║ [INFO] [stdout] [2026-01-06T10:25:45Z INFO arrowspace::taumode] ║ Items: 300 ║ [INFO] [stdout] [2026-01-06T10:25:45Z INFO arrowspace::taumode] ║ Features: 100 ║ [INFO] [stdout] [2026-01-06T10:25:45Z INFO arrowspace::taumode] ║ Threads: 8 ║ [INFO] [stdout] [2026-01-06T10:25:45Z INFO arrowspace::taumode] ║ TauMode: Median ║ [INFO] [stdout] [2026-01-06T10:25:45Z DEBUG arrowspace::taumode] compute_taumode_lambdas_parallel: NO signals [INFO] [stdout] [2026-01-06T10:25:45Z INFO arrowspace::taumode] ║ Graph Source: Laplacian Matrix ║ [INFO] [stdout] [2026-01-06T10:25:45Z INFO arrowspace::taumode] ║ Graph Shape: 100×100 ║ [INFO] [stdout] [2026-01-06T10:25:45Z INFO arrowspace::taumode] ║ Graph NNZ: 676 ║ [INFO] [stdout] [2026-01-06T10:25:45Z INFO arrowspace::taumode] ║ Graph Sparsity: 0.067600 ║ [INFO] [stdout] [2026-01-06T10:25:45Z INFO arrowspace::taumode] ╚═════════════════════════════════════════════════════════════╝ [INFO] [stdout] [2026-01-06T10:25:45Z INFO arrowspace::taumode] Starting parallel lambda computation... [INFO] [stdout] [2026-01-06T10:25:48Z INFO arrowspace::taumode] ╔═════════════════════════════════════════════════════════════╗ [INFO] [stdout] [2026-01-06T10:25:48Z INFO arrowspace::taumode] ║ Computation Statistics ║ [INFO] [stdout] [2026-01-06T10:25:48Z INFO arrowspace::taumode] ╠═════════════════════════════════════════════════════════════╣ [INFO] [stdout] [2026-01-06T10:25:48Z INFO arrowspace::taumode] ║ Sequential Items: 0 ║ [INFO] [stdout] [2026-01-06T10:25:48Z INFO arrowspace::taumode] ║ Parallel Items: 0 ║ [INFO] [stdout] [2026-01-06T10:25:48Z INFO arrowspace::taumode] ║ Compute Time: 3.428s ║ [INFO] [stdout] [2026-01-06T10:25:48Z INFO arrowspace::core] Updating lambdas with 300 new values [INFO] [stdout] [2026-01-06T10:25:48Z INFO arrowspace::core] Normalized lambdas to [0, 1] range (original spread: 2.276576) [INFO] [stdout] [2026-01-06T10:25:48Z DEBUG arrowspace::core] Lambda update: old range [0.000000, 0.000000] -> new range [0.000000, 1.000000] [INFO] [stdout] [2026-01-06T10:25:48Z INFO arrowspace::taumode] ║ Update Time: 128.600µs ║ [INFO] [stdout] [2026-01-06T10:25:48Z INFO arrowspace::taumode] ║ Total Time: 3.429s ║ [INFO] [stdout] [2026-01-06T10:25:48Z INFO arrowspace::taumode] ║ Throughput: 87 items/sec ║ [INFO] [stdout] [2026-01-06T10:25:48Z INFO arrowspace::taumode] ╠═════════════════════════════════════════════════════════════╣ [INFO] [stdout] [2026-01-06T10:25:48Z INFO arrowspace::taumode] ║ Lambda Statistics ║ [INFO] [stdout] [2026-01-06T10:25:48Z INFO arrowspace::taumode] ╠═════════════════════════════════════════════════════════════╣ [INFO] [stdout] [2026-01-06T10:25:48Z INFO arrowspace::taumode] ║ Min: 0.000000 ║ [INFO] [stdout] [2026-01-06T10:25:48Z INFO arrowspace::taumode] ║ Max: 1.000000 ║ [INFO] [stdout] [2026-01-06T10:25:48Z INFO arrowspace::taumode] ║ Mean: 0.052004 ║ [INFO] [stdout] [2026-01-06T10:25:48Z INFO arrowspace::taumode] ║ Std Dev: 0.155652 ║ [INFO] [stdout] [2026-01-06T10:25:48Z INFO arrowspace::taumode] ║ Range: 1.000000 ║ [INFO] [stdout] [2026-01-06T10:25:48Z INFO arrowspace::taumode] ╚═════════════════════════════════════════════════════════════╝ [INFO] [stdout] [2026-01-06T10:25:48Z INFO arrowspace::taumode] ✓ Parallel taumode lambda computation completed successfully [INFO] [stdout] [2026-01-06T10:25:48Z INFO arrowspace::eigenmaps] λ computation complete: min=0.000000, max=1.000000, mean=0.052004 [INFO] [stdout] [2026-01-06T10:25:48Z DEBUG arrowspace::builder] Lambda computation completed - min: 0.000000, max: 1.000000, mean: 0.052004 [INFO] [stdout] [2026-01-06T10:25:48Z INFO arrowspace::builder] Total ArrowSpaceBuilder construction time: 71.08623359s [INFO] [stdout] [2026-01-06T10:25:48Z DEBUG arrowspace::builder] ArrowSpaceBuilder configuration: prebuilt_spectral=false, lambda_eps=0.4, lambda_k=12, lambda_topk=8, lambda_p=2, lambda_sigma=None, normalise=false, sparsity_check=false, sampling=Simple(0.6), synthesis=Median, cluster_max_clusters=6, cluster_radius=4723.120421228749, clustering_seed=999, deterministic_clustering=true, use_dims_reduction=false, rp_eps=0.3, persistence=None [INFO] [stdout] [2026-01-06T10:25:48Z INFO arrowspace::builder] ArrowSpace build completed successfully [INFO] [stdout] [2026-01-06T10:25:48Z INFO arrowspace::subgraphs::sg_from_motives] Spotting subgraphs with motives: topl=18, mintri=3, minsize=5 [INFO] [stdout] [2026-01-06T10:25:48Z INFO arrowspace::motives] Spotting energy motifs: top_l=18, min_tri=3, min_clust=0.35, max_size=30, n_sc=100 [INFO] [stdout] [2026-01-06T10:25:48Z DEBUG arrowspace::motives] Energy triangle stats: max_tri=19, max_clust=1.000 [INFO] [stdout] [2026-01-06T10:25:48Z DEBUG arrowspace::motives] Energy motifs seeds (subcentroids): [42, 36, 39, 45, 34, 53, 57, 41, 51, 63, 59, 61, 49, 37, 40, 50] [INFO] [stdout] [2026-01-06T10:25:54Z INFO arrowspace::motives] Energy motifs: 2 subcentroid motifs found [INFO] [stdout] [2026-01-06T10:25:54Z INFO arrowspace::subgraphs::sg_from_motives] Motif detection returned 2 item-space candidates [INFO] [stdout] [2026-01-06T10:26:01Z DEBUG arrowspace::subgraphs::sg_from_motives] After Rayleigh filter (max=0.150): 2 subgraphs remain [INFO] [stdout] [2026-01-06T10:26:01Z INFO arrowspace::subgraphs::sg_from_motives] Extracted 2 motives subgraphs (min_size=5, rayleigh_max=Some(0.15)) [INFO] [stdout] [2026-01-06T10:26:01Z INFO arrowspace::subgraphs::sg_from_motives] Spotting subgraphs with motives: topl=18, mintri=3, minsize=5 [INFO] [stdout] [2026-01-06T10:26:01Z INFO arrowspace::motives] Spotting energy motifs: top_l=18, min_tri=3, min_clust=0.35, max_size=30, n_sc=100 [INFO] [stdout] [2026-01-06T10:26:04Z DEBUG arrowspace::motives] Energy triangle stats: max_tri=19, max_clust=1.000 [INFO] [stdout] [2026-01-06T10:26:04Z DEBUG arrowspace::motives] Energy motifs seeds (subcentroids): [42, 36, 39, 45, 34, 53, 57, 41, 51, 63, 59, 61, 49, 37, 40, 50] [INFO] [stdout] [2026-01-06T10:26:04Z INFO arrowspace::motives] Energy motifs: 1 subcentroid motifs found [INFO] [stdout] [2026-01-06T10:26:04Z INFO arrowspace::subgraphs::sg_from_motives] Motif detection returned 1 item-space candidates [INFO] [stdout] [2026-01-06T10:26:04Z DEBUG arrowspace::subgraphs::sg_from_motives] Building motif subgraph with 4 centroids from parent with 6 centroids (F=100) [INFO] [stdout] [2026-01-06T10:26:04Z INFO arrowspace::laplacian] Building Laplacian matrix for 4 items with 100 features [INFO] [stdout] [2026-01-06T10:26:04Z DEBUG arrowspace::laplacian] Graph parameters: eps=0.4, k=12, p=2, sigma=None, normalise=false [INFO] [stdout] [2026-01-06T10:26:04Z DEBUG arrowspace::laplacian] Skipping normalization - using raw item magnitudes [INFO] [stdout] [2026-01-06T10:26:04Z INFO arrowspace::laplacian] Building CosinePair data structure [INFO] [stdout] [2026-01-06T10:26:04Z DEBUG arrowspace::laplacian] CosinePair structure built for 100 items [INFO] [stdout] [2026-01-06T10:26:04Z INFO arrowspace::laplacian] Computing degrees for inline sparsification [INFO] [stdout] [2026-01-06T10:26:04Z DEBUG arrowspace::laplacian] Skipping sparsification (avg degree 5.4) [INFO] [stdout] [2026-01-06T10:26:04Z INFO arrowspace::laplacian] Computing k-NN with CosinePair: k=9 [INFO] [stdout] [2026-01-06T10:26:04Z DEBUG arrowspace::laplacian] Built adjacency rows for 100 items [INFO] [stdout] [2026-01-06T10:26:04Z INFO arrowspace::laplacian] Converting adjacency to sparse Laplacian matrix (DashMap batched) [INFO] [stdout] [2026-01-06T10:26:05Z DEBUG arrowspace::laplacian] Total triplets: 1126, edges: 513 [INFO] [stdout] [2026-01-06T10:26:05Z DEBUG arrowspace::laplacian] Inserted triplets in 43.03µs [INFO] [stdout] [2026-01-06T10:26:05Z INFO arrowspace::laplacian] Sparse Laplacian construction time: 32.822947ms [INFO] [stdout] [2026-01-06T10:26:05Z INFO arrowspace::laplacian] Total Laplacian construction time: 373.184782ms [INFO] [stdout] [2026-01-06T10:26:05Z INFO arrowspace::laplacian] Successfully built sparse Laplacian matrix (4x4) with 1126 non-zeros [INFO] [stdout] [2026-01-06T10:26:05Z DEBUG arrowspace::subgraphs::sg_from_motives] Subgraph feature Laplacian built: 4 motif centroids, 100 features, 1126 nnz [INFO] [stdout] [2026-01-06T10:26:05Z DEBUG arrowspace::graph] Rayleigh quotient: numerator=-0.000000, denominator=100.000000, result=-0.000000 [INFO] [stdout] [2026-01-06T10:26:05Z DEBUG arrowspace::subgraphs::sg_from_motives] Subgraph Rayleigh cohesion (feature-space): -0.000000 over 100 dims [INFO] [stdout] [2026-01-06T10:26:05Z DEBUG arrowspace::subgraphs::sg_from_motives] After Rayleigh filter (max=0.500): 1 subgraphs remain [INFO] [stdout] [2026-01-06T10:26:05Z INFO arrowspace::subgraphs::sg_from_motives] Extracted 1 motives subgraphs (min_size=5, rayleigh_max=Some(0.5)) [INFO] [stdout] [INFO] [stdout] thread 'subgraphs::tests::test_subg_motives::test_subgraph_energy_rayleigh_filter' (35) panicked at src/subgraphs/tests/test_subg_motives.rs:322:5: [INFO] [stdout] Relaxed Rayleigh filter should yield at least as many subgraphs as strict filter [INFO] [stdout] stack backtrace: [INFO] [stdout] 0: 0x59acd995d662 - std[3f03f8b59f91bef1]::backtrace_rs::backtrace::libunwind::trace [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9 [INFO] [stdout] 1: 0x59acd995d662 - std[3f03f8b59f91bef1]::backtrace_rs::backtrace::trace_unsynchronized:: [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14 [INFO] [stdout] 2: 0x59acd995d662 - std[3f03f8b59f91bef1]::sys::backtrace::_print_fmt [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/std/src/sys/backtrace.rs:74:9 [INFO] [stdout] 3: 0x59acd995d662 - <::print::DisplayBacktrace as core[585f66e14d78f9ba]::fmt::Display>::fmt [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/std/src/sys/backtrace.rs:44:26 [INFO] [stdout] 4: 0x59acd99757ba - ::fmt [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/core/src/fmt/rt.rs:152:76 [INFO] [stdout] 5: 0x59acd99757ba - core[585f66e14d78f9ba]::fmt::write [INFO] [stdout] 6: 0x59acd9963b26 - std[3f03f8b59f91bef1]::io::default_write_fmt::> [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/std/src/io/mod.rs:639:11 [INFO] [stdout] 7: 0x59acd9963b26 - as std[3f03f8b59f91bef1]::io::Write>::write_fmt [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/std/src/io/mod.rs:1994:13 [INFO] [stdout] 8: 0x59acd993b8df - ::print [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/std/src/sys/backtrace.rs:47:9 [INFO] [stdout] 9: 0x59acd993b8df - std[3f03f8b59f91bef1]::panicking::default_hook::{closure#0} [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/std/src/panicking.rs:292:27 [INFO] [stdout] 10: 0x59acd9955649 - std[3f03f8b59f91bef1]::panicking::default_hook [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/std/src/panicking.rs:316:9 [INFO] [stdout] 11: 0x59acd981230e - core[585f66e14d78f9ba]::ops::function::Fn<(&'a std[3f03f8b59f91bef1]::panic::PanicHookInfo<'b>,), Output = ()> + core[585f66e14d78f9ba]::marker::Sync + core[585f66e14d78f9ba]::marker::Send> as core[585f66e14d78f9ba]::ops::function::Fn<(&std[3f03f8b59f91bef1]::panic::PanicHookInfo,)>>::call [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/alloc/src/boxed.rs:2220:9 [INFO] [stdout] 12: 0x59acd981230e - test[30224e0cb089acd2]::test_main_with_exit_callback::::{closure#0} [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/test/src/lib.rs:145:21 [INFO] [stdout] 13: 0x59acd99558b2 - core[585f66e14d78f9ba]::ops::function::Fn<(&'a std[3f03f8b59f91bef1]::panic::PanicHookInfo<'b>,), Output = ()> + core[585f66e14d78f9ba]::marker::Sync + core[585f66e14d78f9ba]::marker::Send> as core[585f66e14d78f9ba]::ops::function::Fn<(&std[3f03f8b59f91bef1]::panic::PanicHookInfo,)>>::call [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/alloc/src/boxed.rs:2220:9 [INFO] [stdout] 14: 0x59acd99558b2 - std[3f03f8b59f91bef1]::panicking::panic_with_hook [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/std/src/panicking.rs:833:13 [INFO] [stdout] 15: 0x59acd993b9ca - std[3f03f8b59f91bef1]::panicking::panic_handler::{closure#0} [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/std/src/panicking.rs:691:13 [INFO] [stdout] 16: 0x59acd99309a9 - std[3f03f8b59f91bef1]::sys::backtrace::__rust_end_short_backtrace:: [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/std/src/sys/backtrace.rs:182:18 [INFO] [stdout] 17: 0x59acd993c88d - __rustc[caa99d26c7bae192]::rust_begin_unwind [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/std/src/panicking.rs:689:5 [INFO] [stdout] 18: 0x59acd9975fac - core[585f66e14d78f9ba]::panicking::panic_fmt [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/core/src/panicking.rs:80:14 [INFO] [stdout] 19: 0x59acd94d7819 - arrowspace[704496a3d79eaf1a]::subgraphs::tests::test_subg_motives::test_subgraph_energy_rayleigh_filter [INFO] [stdout] at /opt/rustwide/workdir/src/subgraphs/tests/test_subg_motives.rs:322:5 [INFO] [stdout] 20: 0x59acd94ca937 - arrowspace[704496a3d79eaf1a]::subgraphs::tests::test_subg_motives::test_subgraph_energy_rayleigh_filter::{closure#0} [INFO] [stdout] at /opt/rustwide/workdir/src/subgraphs/tests/test_subg_motives.rs:288:42 [INFO] [stdout] 21: 0x59acd9520376 - >::call_once [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 22: 0x59acd98066bb - core[585f66e14d78f9ba]::result::Result<(), alloc[15e7b27aba85d2e2]::string::String> as core[585f66e14d78f9ba]::ops::function::FnOnce<()>>::call_once [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 23: 0x59acd98066bb - test[30224e0cb089acd2]::__rust_begin_short_backtrace::, fn() -> core[585f66e14d78f9ba]::result::Result<(), alloc[15e7b27aba85d2e2]::string::String>> [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/test/src/lib.rs:663:18 [INFO] [stdout] 24: 0x59acd9812f1a - test[30224e0cb089acd2]::run_test_in_process::{closure#0} [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/test/src/lib.rs:686:74 [INFO] [stdout] 25: 0x59acd9812f1a - as core[585f66e14d78f9ba]::ops::function::FnOnce<()>>::call_once [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/core/src/panic/unwind_safe.rs:274:9 [INFO] [stdout] 26: 0x59acd9812f1a - std[3f03f8b59f91bef1]::panicking::catch_unwind::do_call::, core[585f66e14d78f9ba]::result::Result<(), alloc[15e7b27aba85d2e2]::string::String>> [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/std/src/panicking.rs:581:40 [INFO] [stdout] 27: 0x59acd9812f1a - std[3f03f8b59f91bef1]::panicking::catch_unwind::, core[585f66e14d78f9ba]::panic::unwind_safe::AssertUnwindSafe> [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/std/src/panicking.rs:544:19 [INFO] [stdout] 28: 0x59acd9812f1a - std[3f03f8b59f91bef1]::panic::catch_unwind::, core[585f66e14d78f9ba]::result::Result<(), alloc[15e7b27aba85d2e2]::string::String>> [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/std/src/panic.rs:359:14 [INFO] [stdout] 29: 0x59acd9812f1a - test[30224e0cb089acd2]::run_test_in_process [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/test/src/lib.rs:686:27 [INFO] [stdout] 30: 0x59acd9812f1a - test[30224e0cb089acd2]::run_test::{closure#0} [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/test/src/lib.rs:607:43 [INFO] [stdout] 31: 0x59acd980d3e4 - test[30224e0cb089acd2]::run_test::{closure#1} [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/test/src/lib.rs:637:41 [INFO] [stdout] 32: 0x59acd980d3e4 - std[3f03f8b59f91bef1]::sys::backtrace::__rust_begin_short_backtrace:: [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/std/src/sys/backtrace.rs:166:18 [INFO] [stdout] 33: 0x59acd9815a12 - std[3f03f8b59f91bef1]::thread::lifecycle::spawn_unchecked::::{closure#1}::{closure#0} [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/std/src/thread/lifecycle.rs:91:13 [INFO] [stdout] 34: 0x59acd9815a12 - ::{closure#1}::{closure#0}> as core[585f66e14d78f9ba]::ops::function::FnOnce<()>>::call_once [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/core/src/panic/unwind_safe.rs:274:9 [INFO] [stdout] 35: 0x59acd9815a12 - std[3f03f8b59f91bef1]::panicking::catch_unwind::do_call::::{closure#1}::{closure#0}>, ()> [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/std/src/panicking.rs:581:40 [INFO] [stdout] 36: 0x59acd9815a12 - std[3f03f8b59f91bef1]::panicking::catch_unwind::<(), core[585f66e14d78f9ba]::panic::unwind_safe::AssertUnwindSafe::{closure#1}::{closure#0}>> [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/std/src/panicking.rs:544:19 [INFO] [stdout] 37: 0x59acd9815a12 - std[3f03f8b59f91bef1]::panic::catch_unwind::::{closure#1}::{closure#0}>, ()> [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/std/src/panic.rs:359:14 [INFO] [stdout] 38: 0x59acd9815a12 - std[3f03f8b59f91bef1]::thread::lifecycle::spawn_unchecked::::{closure#1} [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/std/src/thread/lifecycle.rs:89:26 [INFO] [stdout] 39: 0x59acd9815a12 - ::{closure#1} as core[585f66e14d78f9ba]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0} [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 40: 0x59acd995cecf - + core[585f66e14d78f9ba]::marker::Send> as core[585f66e14d78f9ba]::ops::function::FnOnce<()>>::call_once [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/alloc/src/boxed.rs:2206:9 [INFO] [stdout] 41: 0x59acd995cecf - ::new::thread_start [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/std/src/sys/thread/unix.rs:119:17 [INFO] [stdout] 42: 0x7a8b28e4aaa4 - [INFO] [stdout] 43: 0x7a8b28ed7a64 - clone [INFO] [stdout] 44: 0x0 - [INFO] [stdout] [INFO] [stdout] ---- subgraphs::tests::test_subg_parallel::test_motif_subgraphs_parallel_correctness stdout ---- [INFO] [stdout] [2026-01-06T10:25:21Z INFO arrowspace::builder] Initializing new ArrowSpaceBuilder [INFO] [stdout] [2026-01-06T10:25:21Z DEBUG arrowspace::builder] Creating ArrowSpaceBuilder with default parameters [INFO] [stdout] [2026-01-06T10:25:21Z INFO arrowspace::builder] Configuring lambda graph: eps=0.35, k=12, p=2, sigma=None [INFO] [stdout] [2026-01-06T10:25:21Z DEBUG arrowspace::builder] Lambda graph will use raw item magnitudes for normalization [INFO] [stdout] [2026-01-06T10:25:21Z INFO arrowspace::builder] Setting custom clustering seed: 999 [INFO] [stdout] [2026-01-06T10:25:21Z INFO arrowspace::builder] Building ArrowSpace from 200 items with 100 features [INFO] [stdout] [2026-01-06T10:25:21Z DEBUG arrowspace::builder] Build configuration: eps=0.35, k=12, p=2, sigma=None, normalise=false, synthesis=Median [INFO] [stdout] [2026-01-06T10:25:21Z INFO arrowspace::builder] EigenMaps::start_clustering: N=200 items, F=100 features [INFO] [stdout] [2026-01-06T10:25:21Z DEBUG arrowspace::builder] Creating ArrowSpace with taumode: Median [INFO] [stdout] [2026-01-06T10:25:21Z INFO arrowspace::sampling] Simple random sampler with keep rate 100.0% [INFO] [stdout] [2026-01-06T10:25:21Z INFO arrowspace::builder] Computing optimal clustering parameters [INFO] [stdout] [2026-01-06T10:25:21Z INFO arrowspace::clustering] Computing optimal K for clustering: N=200, F=100 [INFO] [stdout] [2026-01-06T10:25:24Z DEBUG arrowspace::clustering] Two-NN mean ratio: 1.0284, estimated ID: 36 [INFO] [stdout] [2026-01-06T10:25:24Z DEBUG arrowspace::clustering] Intrinsic dimension estimate: 36 [INFO] [stdout] [2026-01-06T10:25:24Z DEBUG arrowspace::clustering] Testing K in range [5, 14] with step 2 [INFO] [stdout] [2026-01-06T10:25:54Z DEBUG arrowspace::clustering] Best K=5 with penalized score=-18.0343 [INFO] [stdout] [2026-01-06T10:25:55Z DEBUG arrowspace::clustering] Standard threshold: radius=2300.822747 [INFO] [stdout] [2026-01-06T10:25:55Z DEBUG arrowspace::builder] Optimal clustering: K=5, radius=2300.822747, intrinsic_dim=36 [INFO] [stdout] [2026-01-06T10:25:55Z INFO arrowspace::builder] Running incremental clustering: max_clusters=5, radius=2300.822747 [INFO] [stdout] [2026-01-06T10:25:55Z INFO arrowspace::clustering] Starting incremental clustering with inline sampling [INFO] [stdout] [2026-01-06T10:25:55Z DEBUG arrowspace::clustering] Parameters: max_clusters=5, radius=2300.8227 [INFO] [stdout] [2026-01-06T10:25:55Z DEBUG arrowspace::clustering] Row 49: DROPPED as outlier (dist²=103511.870166 > relaxed 3451.234121, len=5 >= max=5) [INFO] [stdout] [2026-01-06T10:25:55Z DEBUG arrowspace::clustering] Row 52: DROPPED as outlier (dist²=99634.669526 > relaxed 3451.234121, len=5 >= max=5) [INFO] [stdout] [2026-01-06T10:25:55Z DEBUG arrowspace::clustering] Row 68: DROPPED as outlier (dist²=82644.328797 > relaxed 3451.234121, len=5 >= max=5) [INFO] [stdout] [2026-01-06T10:25:55Z DEBUG arrowspace::clustering] Row 95: DROPPED as outlier (dist²=77357.482727 > relaxed 3451.234121, len=5 >= max=5) [INFO] [stdout] [2026-01-06T10:25:55Z DEBUG arrowspace::clustering] Row 128: DROPPED as outlier (dist²=93398.282754 > relaxed 3451.234121, len=5 >= max=5) [INFO] [stdout] [2026-01-06T10:25:55Z DEBUG arrowspace::clustering] Row 129: DROPPED as outlier (dist²=98470.365173 > relaxed 3451.234121, len=5 >= max=5) [INFO] [stdout] [2026-01-06T10:25:55Z DEBUG arrowspace::clustering] Row 199: DROPPED as outlier (dist²=113967.360228 > relaxed 3451.234121, len=5 >= max=5) [INFO] [stdout] [2026-01-06T10:25:55Z DEBUG arrowspace::clustering] Inline sampling complete: 200 kept (100.00%), 0 discarded [INFO] [stdout] [2026-01-06T10:25:55Z DEBUG arrowspace::clustering] Clustering produced 5 centroids from 200 rows (100% sampling) [INFO] [stdout] [2026-01-06T10:25:55Z INFO arrowspace::builder] Clustering complete: 5 centroids, 193 items assigned [INFO] [stdout] [2026-01-06T10:25:55Z DEBUG arrowspace::builder] JL projection disabled or dimension too small [INFO] [stdout] [2026-01-06T10:25:55Z INFO arrowspace::eigenmaps] EigenMaps::eigenmaps: Building Laplacian from 5 centroids × 100 features [INFO] [stdout] [2026-01-06T10:25:55Z DEBUG arrowspace::eigenmaps] λ-graph parameters: eps=0.35, k=12, topk=8, p=2, sigma=None, normalize=false [INFO] [stdout] [2026-01-06T10:25:55Z INFO arrowspace::graph] Building Laplacian matrix for K cluster: 5 clusters [INFO] [stdout] [2026-01-06T10:25:55Z DEBUG arrowspace::graph] Laplacian parameters: eps=0.35, k=12, p=2, sigma=None, normalise=false [INFO] [stdout] [2026-01-06T10:25:55Z INFO arrowspace::laplacian] Building Laplacian matrix for 5 items with 100 features [INFO] [stdout] [2026-01-06T10:25:55Z DEBUG arrowspace::laplacian] Graph parameters: eps=0.35, k=12, p=2, sigma=None, normalise=false [INFO] [stdout] [2026-01-06T10:25:55Z DEBUG arrowspace::laplacian] Skipping normalization - using raw item magnitudes [INFO] [stdout] [2026-01-06T10:25:55Z INFO arrowspace::laplacian] Building CosinePair data structure [INFO] [stdout] [2026-01-06T10:25:56Z DEBUG arrowspace::laplacian] CosinePair structure built for 100 items [INFO] [stdout] [2026-01-06T10:25:56Z INFO arrowspace::laplacian] Computing degrees for inline sparsification [INFO] [stdout] [2026-01-06T10:25:57Z DEBUG arrowspace::laplacian] Skipping sparsification (avg degree 2.0) [INFO] [stdout] [2026-01-06T10:25:57Z INFO arrowspace::laplacian] Computing k-NN with CosinePair: k=9 [INFO] [stdout] [2026-01-06T10:25:57Z DEBUG arrowspace::laplacian] Built adjacency rows for 100 items [INFO] [stdout] [2026-01-06T10:25:57Z INFO arrowspace::laplacian] Converting adjacency to sparse Laplacian matrix (DashMap batched) [INFO] [stdout] [2026-01-06T10:25:57Z DEBUG arrowspace::laplacian] Total triplets: 480, edges: 190 [INFO] [stdout] [2026-01-06T10:25:57Z DEBUG arrowspace::laplacian] Inserted triplets in 18.98µs [INFO] [stdout] [2026-01-06T10:25:57Z INFO arrowspace::laplacian] Sparse Laplacian construction time: 3.25735ms [INFO] [stdout] [2026-01-06T10:25:57Z INFO arrowspace::laplacian] Total Laplacian construction time: 2.232971426s [INFO] [stdout] [2026-01-06T10:25:57Z INFO arrowspace::laplacian] Successfully built sparse Laplacian matrix (5x5) with 480 non-zeros [INFO] [stdout] [2026-01-06T10:25:57Z INFO arrowspace::graph] Laplacian matrix built: 100×100 with 200 nodes, 480 non-zeros [INFO] [stdout] [2026-01-06T10:25:57Z DEBUG arrowspace::graph] Matrix has 480 non-zero entries [INFO] [stdout] [2026-01-06T10:25:57Z INFO arrowspace::eigenmaps] Laplacian construction complete: 100×100 matrix, 480 non-zeros, 95.20% sparse [INFO] [stdout] [2026-01-06T10:25:57Z INFO arrowspace::builder] Computing taumode lambdas with synthesis: Median [INFO] [stdout] [2026-01-06T10:25:57Z INFO arrowspace::eigenmaps] EigenMaps::compute_taumode: Computing λ values for 200 items using Median [INFO] [stdout] [2026-01-06T10:25:57Z DEBUG arrowspace::eigenmaps] Laplacian: 200 nodes, 480 non-zeros [INFO] [stdout] [2026-01-06T10:25:57Z INFO arrowspace::taumode] ╔═════════════════════════════════════════════════════════════╗ [INFO] [stdout] [2026-01-06T10:25:57Z INFO arrowspace::taumode] ║ Parallel TauMode Lambda Computation ║ [INFO] [stdout] [2026-01-06T10:25:57Z INFO arrowspace::taumode] ╠═════════════════════════════════════════════════════════════╣ [INFO] [stdout] [2026-01-06T10:25:57Z INFO arrowspace::taumode] ║ Configuration: ║ [INFO] [stdout] [2026-01-06T10:25:57Z INFO arrowspace::taumode] ║ Items: 200 ║ [INFO] [stdout] [2026-01-06T10:25:57Z INFO arrowspace::taumode] ║ Features: 100 ║ [INFO] [stdout] [2026-01-06T10:25:57Z INFO arrowspace::taumode] ║ Threads: 8 ║ [INFO] [stdout] [2026-01-06T10:25:57Z INFO arrowspace::taumode] ║ TauMode: Median ║ [INFO] [stdout] [2026-01-06T10:25:57Z DEBUG arrowspace::taumode] compute_taumode_lambdas_parallel: NO signals [INFO] [stdout] [2026-01-06T10:25:57Z INFO arrowspace::taumode] ║ Graph Source: Laplacian Matrix ║ [INFO] [stdout] [2026-01-06T10:25:57Z INFO arrowspace::taumode] ║ Graph Shape: 100×100 ║ [INFO] [stdout] [2026-01-06T10:25:57Z INFO arrowspace::taumode] ║ Graph NNZ: 480 ║ [INFO] [stdout] [2026-01-06T10:25:57Z INFO arrowspace::taumode] ║ Graph Sparsity: 0.048000 ║ [INFO] [stdout] [2026-01-06T10:25:57Z INFO arrowspace::taumode] ╚═════════════════════════════════════════════════════════════╝ [INFO] [stdout] [2026-01-06T10:25:57Z INFO arrowspace::taumode] Starting parallel lambda computation... [INFO] [stdout] [2026-01-06T10:26:01Z INFO arrowspace::taumode] ╔═════════════════════════════════════════════════════════════╗ [INFO] [stdout] [2026-01-06T10:26:01Z INFO arrowspace::taumode] ║ Computation Statistics ║ [INFO] [stdout] [2026-01-06T10:26:01Z INFO arrowspace::taumode] ╠═════════════════════════════════════════════════════════════╣ [INFO] [stdout] [2026-01-06T10:26:01Z INFO arrowspace::taumode] ║ Sequential Items: 0 ║ [INFO] [stdout] [2026-01-06T10:26:01Z INFO arrowspace::taumode] ║ Parallel Items: 0 ║ [INFO] [stdout] [2026-01-06T10:26:01Z INFO arrowspace::taumode] ║ Compute Time: 3.762s ║ [INFO] [stdout] [2026-01-06T10:26:01Z INFO arrowspace::core] Updating lambdas with 200 new values [INFO] [stdout] [2026-01-06T10:26:01Z INFO arrowspace::core] Normalized lambdas to [0, 1] range (original spread: 1.519285) [INFO] [stdout] [2026-01-06T10:26:01Z DEBUG arrowspace::core] Lambda update: old range [0.000000, 0.000000] -> new range [0.000000, 1.000000] [INFO] [stdout] [2026-01-06T10:26:01Z INFO arrowspace::taumode] ║ Update Time: 111.700µs ║ [INFO] [stdout] [2026-01-06T10:26:01Z INFO arrowspace::taumode] ║ Total Time: 3.763s ║ [INFO] [stdout] [2026-01-06T10:26:01Z INFO arrowspace::taumode] ║ Throughput: 53 items/sec ║ [INFO] [stdout] [2026-01-06T10:26:01Z INFO arrowspace::taumode] ╠═════════════════════════════════════════════════════════════╣ [INFO] [stdout] [2026-01-06T10:26:01Z INFO arrowspace::taumode] ║ Lambda Statistics ║ [INFO] [stdout] [2026-01-06T10:26:01Z INFO arrowspace::taumode] ╠═════════════════════════════════════════════════════════════╣ [INFO] [stdout] [2026-01-06T10:26:01Z INFO arrowspace::taumode] ║ Min: 0.000000 ║ [INFO] [stdout] [2026-01-06T10:26:01Z INFO arrowspace::taumode] ║ Max: 1.000000 ║ [INFO] [stdout] [2026-01-06T10:26:01Z INFO arrowspace::taumode] ║ Mean: 0.060675 ║ [INFO] [stdout] [2026-01-06T10:26:01Z INFO arrowspace::taumode] ║ Std Dev: 0.159064 ║ [INFO] [stdout] [2026-01-06T10:26:01Z INFO arrowspace::taumode] ║ Range: 1.000000 ║ [INFO] [stdout] [2026-01-06T10:26:01Z INFO arrowspace::taumode] ╚═════════════════════════════════════════════════════════════╝ [INFO] [stdout] [2026-01-06T10:26:01Z INFO arrowspace::taumode] ✓ Parallel taumode lambda computation completed successfully [INFO] [stdout] [2026-01-06T10:26:01Z INFO arrowspace::eigenmaps] λ computation complete: min=0.000000, max=1.000000, mean=0.060675 [INFO] [stdout] [2026-01-06T10:26:01Z DEBUG arrowspace::builder] Lambda computation completed - min: 0.000000, max: 1.000000, mean: 0.060675 [INFO] [stdout] [2026-01-06T10:26:01Z INFO arrowspace::builder] Total ArrowSpaceBuilder construction time: 39.727867131s [INFO] [stdout] [2026-01-06T10:26:01Z DEBUG arrowspace::builder] ArrowSpaceBuilder configuration: prebuilt_spectral=false, lambda_eps=0.35, lambda_k=12, lambda_topk=8, lambda_p=2, lambda_sigma=None, normalise=false, sparsity_check=false, sampling=Simple(0.6), synthesis=Median, cluster_max_clusters=5, cluster_radius=2300.8227471473374, clustering_seed=999, deterministic_clustering=true, use_dims_reduction=false, rp_eps=0.3, persistence=None [INFO] [stdout] [2026-01-06T10:26:01Z INFO arrowspace::builder] ArrowSpace build completed successfully [INFO] [stdout] [2026-01-06T10:26:01Z INFO arrowspace::subgraphs::sg_from_motives] Spotting subgraphs with motives: topl=18, mintri=3, minsize=5 [INFO] [stdout] [2026-01-06T10:26:01Z INFO arrowspace::motives] Spotting energy motifs: top_l=18, min_tri=3, min_clust=0.35, max_size=30, n_sc=100 [INFO] [stdout] [2026-01-06T10:26:01Z DEBUG arrowspace::motives] Energy triangle stats: max_tri=35, max_clust=1.000 [INFO] [stdout] [2026-01-06T10:26:01Z DEBUG arrowspace::motives] Energy motifs seeds (subcentroids): [17, 18, 40, 9, 10, 1, 72, 74] [INFO] [stdout] [2026-01-06T10:26:01Z INFO arrowspace::motives] Energy motifs: 2 subcentroid motifs found [INFO] [stdout] [2026-01-06T10:26:01Z INFO arrowspace::subgraphs::sg_from_motives] Motif detection returned 2 item-space candidates [INFO] [stdout] [2026-01-06T10:26:04Z INFO arrowspace::subgraphs::sg_from_motives] Extracted 2 motives subgraphs (min_size=5, rayleigh_max=None) [INFO] [stdout] [2026-01-06T10:26:04Z INFO arrowspace::subgraphs::sg_from_motives] Spotting subgraphs with motives: topl=18, mintri=3, minsize=5 [INFO] [stdout] [2026-01-06T10:26:04Z INFO arrowspace::motives] Spotting energy motifs: top_l=18, min_tri=3, min_clust=0.35, max_size=30, n_sc=100 [INFO] [stdout] [2026-01-06T10:26:04Z DEBUG arrowspace::motives] Energy triangle stats: max_tri=35, max_clust=1.000 [INFO] [stdout] [2026-01-06T10:26:04Z DEBUG arrowspace::motives] Energy motifs seeds (subcentroids): [17, 18, 40, 9, 10, 1, 72, 74] [INFO] [stdout] [2026-01-06T10:26:04Z INFO arrowspace::motives] Energy motifs: 1 subcentroid motifs found [INFO] [stdout] [2026-01-06T10:26:04Z INFO arrowspace::subgraphs::sg_from_motives] Motif detection returned 1 item-space candidates [INFO] [stdout] [2026-01-06T10:26:04Z DEBUG arrowspace::subgraphs::sg_from_motives] Building motif subgraph with 4 centroids from parent with 5 centroids (F=100) [INFO] [stdout] [2026-01-06T10:26:04Z INFO arrowspace::laplacian] Building Laplacian matrix for 4 items with 100 features [INFO] [stdout] [2026-01-06T10:26:04Z DEBUG arrowspace::laplacian] Graph parameters: eps=0.35, k=12, p=2, sigma=None, normalise=false [INFO] [stdout] [2026-01-06T10:26:04Z DEBUG arrowspace::laplacian] Skipping normalization - using raw item magnitudes [INFO] [stdout] [2026-01-06T10:26:04Z INFO arrowspace::laplacian] Building CosinePair data structure [INFO] [stdout] [2026-01-06T10:26:05Z DEBUG arrowspace::laplacian] CosinePair structure built for 100 items [INFO] [stdout] [2026-01-06T10:26:05Z INFO arrowspace::laplacian] Computing degrees for inline sparsification [INFO] [stdout] [2026-01-06T10:26:05Z DEBUG arrowspace::laplacian] Skipping sparsification (avg degree 4.5) [INFO] [stdout] [2026-01-06T10:26:05Z INFO arrowspace::laplacian] Computing k-NN with CosinePair: k=9 [INFO] [stdout] [2026-01-06T10:26:05Z DEBUG arrowspace::laplacian] Built adjacency rows for 100 items [INFO] [stdout] [2026-01-06T10:26:05Z INFO arrowspace::laplacian] Converting adjacency to sparse Laplacian matrix (DashMap batched) [INFO] [stdout] [2026-01-06T10:26:05Z DEBUG arrowspace::laplacian] Total triplets: 954, edges: 427 [INFO] [stdout] [2026-01-06T10:26:05Z DEBUG arrowspace::laplacian] Inserted triplets in 37.25µs [INFO] [stdout] [2026-01-06T10:26:05Z INFO arrowspace::laplacian] Sparse Laplacian construction time: 2.44124ms [INFO] [stdout] [2026-01-06T10:26:05Z INFO arrowspace::laplacian] Total Laplacian construction time: 445.579138ms [INFO] [stdout] [2026-01-06T10:26:05Z INFO arrowspace::laplacian] Successfully built sparse Laplacian matrix (4x4) with 954 non-zeros [INFO] [stdout] [2026-01-06T10:26:05Z DEBUG arrowspace::subgraphs::sg_from_motives] Subgraph feature Laplacian built: 4 motif centroids, 100 features, 954 nnz [INFO] [stdout] [2026-01-06T10:26:05Z INFO arrowspace::subgraphs::sg_from_motives] Extracted 1 motives subgraphs (min_size=5, rayleigh_max=None) [INFO] [stdout] [INFO] [stdout] thread 'subgraphs::tests::test_subg_parallel::test_motif_subgraphs_parallel_correctness' (42) panicked at src/subgraphs/tests/test_subg_parallel.rs:84:5: [INFO] [stdout] assertion `left == right` failed: Should produce same number of subgraphs [INFO] [stdout] left: 2 [INFO] [stdout] right: 1 [INFO] [stdout] stack backtrace: [INFO] [stdout] 0: 0x59acd995d662 - std[3f03f8b59f91bef1]::backtrace_rs::backtrace::libunwind::trace [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9 [INFO] [stdout] 1: 0x59acd995d662 - std[3f03f8b59f91bef1]::backtrace_rs::backtrace::trace_unsynchronized:: [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14 [INFO] [stdout] 2: 0x59acd995d662 - std[3f03f8b59f91bef1]::sys::backtrace::_print_fmt [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/std/src/sys/backtrace.rs:74:9 [INFO] [stdout] 3: 0x59acd995d662 - <::print::DisplayBacktrace as core[585f66e14d78f9ba]::fmt::Display>::fmt [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/std/src/sys/backtrace.rs:44:26 [INFO] [stdout] 4: 0x59acd99757ba - ::fmt [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/core/src/fmt/rt.rs:152:76 [INFO] [stdout] 5: 0x59acd99757ba - core[585f66e14d78f9ba]::fmt::write [INFO] [stdout] 6: 0x59acd9963b26 - std[3f03f8b59f91bef1]::io::default_write_fmt::> [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/std/src/io/mod.rs:639:11 [INFO] [stdout] 7: 0x59acd9963b26 - as std[3f03f8b59f91bef1]::io::Write>::write_fmt [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/std/src/io/mod.rs:1994:13 [INFO] [stdout] 8: 0x59acd993b8df - ::print [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/std/src/sys/backtrace.rs:47:9 [INFO] [stdout] 9: 0x59acd993b8df - std[3f03f8b59f91bef1]::panicking::default_hook::{closure#0} [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/std/src/panicking.rs:292:27 [INFO] [stdout] 10: 0x59acd9955649 - std[3f03f8b59f91bef1]::panicking::default_hook [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/std/src/panicking.rs:316:9 [INFO] [stdout] 11: 0x59acd981230e - core[585f66e14d78f9ba]::ops::function::Fn<(&'a std[3f03f8b59f91bef1]::panic::PanicHookInfo<'b>,), Output = ()> + core[585f66e14d78f9ba]::marker::Sync + core[585f66e14d78f9ba]::marker::Send> as core[585f66e14d78f9ba]::ops::function::Fn<(&std[3f03f8b59f91bef1]::panic::PanicHookInfo,)>>::call [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/alloc/src/boxed.rs:2220:9 [INFO] [stdout] 12: 0x59acd981230e - test[30224e0cb089acd2]::test_main_with_exit_callback::::{closure#0} [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/test/src/lib.rs:145:21 [INFO] [stdout] 13: 0x59acd99558b2 - core[585f66e14d78f9ba]::ops::function::Fn<(&'a std[3f03f8b59f91bef1]::panic::PanicHookInfo<'b>,), Output = ()> + core[585f66e14d78f9ba]::marker::Sync + core[585f66e14d78f9ba]::marker::Send> as core[585f66e14d78f9ba]::ops::function::Fn<(&std[3f03f8b59f91bef1]::panic::PanicHookInfo,)>>::call [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/alloc/src/boxed.rs:2220:9 [INFO] [stdout] 14: 0x59acd99558b2 - std[3f03f8b59f91bef1]::panicking::panic_with_hook [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/std/src/panicking.rs:833:13 [INFO] [stdout] 15: 0x59acd993b998 - std[3f03f8b59f91bef1]::panicking::panic_handler::{closure#0} [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/std/src/panicking.rs:698:13 [INFO] [stdout] 16: 0x59acd99309a9 - std[3f03f8b59f91bef1]::sys::backtrace::__rust_end_short_backtrace:: [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/std/src/sys/backtrace.rs:182:18 [INFO] [stdout] 17: 0x59acd993c88d - __rustc[caa99d26c7bae192]::rust_begin_unwind [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/std/src/panicking.rs:689:5 [INFO] [stdout] 18: 0x59acd9975fac - core[585f66e14d78f9ba]::panicking::panic_fmt [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/core/src/panicking.rs:80:14 [INFO] [stdout] 19: 0x59acd9975e93 - core[585f66e14d78f9ba]::panicking::assert_failed_inner [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/core/src/panicking.rs:434:23 [INFO] [stdout] 20: 0x59acd99707bd - core[585f66e14d78f9ba]::panicking::assert_failed:: [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/core/src/panicking.rs:394:5 [INFO] [stdout] 21: 0x59acd94db8d4 - arrowspace[704496a3d79eaf1a]::subgraphs::tests::test_subg_parallel::test_motif_subgraphs_parallel_correctness [INFO] [stdout] at /opt/rustwide/workdir/src/subgraphs/tests/test_subg_parallel.rs:84:5 [INFO] [stdout] 22: 0x59acd94caaa7 - arrowspace[704496a3d79eaf1a]::subgraphs::tests::test_subg_parallel::test_motif_subgraphs_parallel_correctness::{closure#0} [INFO] [stdout] at /opt/rustwide/workdir/src/subgraphs/tests/test_subg_parallel.rs:58:47 [INFO] [stdout] 23: 0x59acd95204f6 - >::call_once [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 24: 0x59acd98066bb - core[585f66e14d78f9ba]::result::Result<(), alloc[15e7b27aba85d2e2]::string::String> as core[585f66e14d78f9ba]::ops::function::FnOnce<()>>::call_once [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 25: 0x59acd98066bb - test[30224e0cb089acd2]::__rust_begin_short_backtrace::, fn() -> core[585f66e14d78f9ba]::result::Result<(), alloc[15e7b27aba85d2e2]::string::String>> [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/test/src/lib.rs:663:18 [INFO] [stdout] 26: 0x59acd9812f1a - test[30224e0cb089acd2]::run_test_in_process::{closure#0} [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/test/src/lib.rs:686:74 [INFO] [stdout] 27: 0x59acd9812f1a - as core[585f66e14d78f9ba]::ops::function::FnOnce<()>>::call_once [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/core/src/panic/unwind_safe.rs:274:9 [INFO] [stdout] 28: 0x59acd9812f1a - std[3f03f8b59f91bef1]::panicking::catch_unwind::do_call::, core[585f66e14d78f9ba]::result::Result<(), alloc[15e7b27aba85d2e2]::string::String>> [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/std/src/panicking.rs:581:40 [INFO] [stdout] 29: 0x59acd9812f1a - std[3f03f8b59f91bef1]::panicking::catch_unwind::, core[585f66e14d78f9ba]::panic::unwind_safe::AssertUnwindSafe> [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/std/src/panicking.rs:544:19 [INFO] [stdout] 30: 0x59acd9812f1a - std[3f03f8b59f91bef1]::panic::catch_unwind::, core[585f66e14d78f9ba]::result::Result<(), alloc[15e7b27aba85d2e2]::string::String>> [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/std/src/panic.rs:359:14 [INFO] [stdout] 31: 0x59acd9812f1a - test[30224e0cb089acd2]::run_test_in_process [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/test/src/lib.rs:686:27 [INFO] [stdout] 32: 0x59acd9812f1a - test[30224e0cb089acd2]::run_test::{closure#0} [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/test/src/lib.rs:607:43 [INFO] [stdout] 33: 0x59acd980d3e4 - test[30224e0cb089acd2]::run_test::{closure#1} [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/test/src/lib.rs:637:41 [INFO] [stdout] 34: 0x59acd980d3e4 - std[3f03f8b59f91bef1]::sys::backtrace::__rust_begin_short_backtrace:: [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/std/src/sys/backtrace.rs:166:18 [INFO] [stdout] 35: 0x59acd9815a12 - std[3f03f8b59f91bef1]::thread::lifecycle::spawn_unchecked::::{closure#1}::{closure#0} [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/std/src/thread/lifecycle.rs:91:13 [INFO] [stdout] 36: 0x59acd9815a12 - ::{closure#1}::{closure#0}> as core[585f66e14d78f9ba]::ops::function::FnOnce<()>>::call_once [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/core/src/panic/unwind_safe.rs:274:9 [INFO] [stdout] 37: 0x59acd9815a12 - std[3f03f8b59f91bef1]::panicking::catch_unwind::do_call::::{closure#1}::{closure#0}>, ()> [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/std/src/panicking.rs:581:40 [INFO] [stdout] 38: 0x59acd9815a12 - std[3f03f8b59f91bef1]::panicking::catch_unwind::<(), core[585f66e14d78f9ba]::panic::unwind_safe::AssertUnwindSafe::{closure#1}::{closure#0}>> [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/std/src/panicking.rs:544:19 [INFO] [stdout] 39: 0x59acd9815a12 - std[3f03f8b59f91bef1]::panic::catch_unwind::::{closure#1}::{closure#0}>, ()> [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/std/src/panic.rs:359:14 [INFO] [stdout] 40: 0x59acd9815a12 - std[3f03f8b59f91bef1]::thread::lifecycle::spawn_unchecked::::{closure#1} [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/std/src/thread/lifecycle.rs:89:26 [INFO] [stdout] 41: 0x59acd9815a12 - ::{closure#1} as core[585f66e14d78f9ba]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0} [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 42: 0x59acd995cecf - + core[585f66e14d78f9ba]::marker::Send> as core[585f66e14d78f9ba]::ops::function::FnOnce<()>>::call_once [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/alloc/src/boxed.rs:2206:9 [INFO] [stdout] 43: 0x59acd995cecf - ::new::thread_start [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/std/src/sys/thread/unix.rs:119:17 [INFO] [stdout] 44: 0x7a8b28e4aaa4 - [INFO] [stdout] 45: 0x7a8b28ed7a64 - clone [INFO] [stdout] 46: 0x0 - [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] subgraphs::tests::test_subg_motives::test_subgraph_energy_rayleigh_filter [INFO] [stdout] subgraphs::tests::test_subg_parallel::test_motif_subgraphs_parallel_correctness [INFO] [stdout] [INFO] [stdout] test result: FAILED. 219 passed; 2 failed; 4 ignored; 0 measured; 0 filtered out; finished in 365.89s [INFO] [stdout] [INFO] [stderr] error: test failed, to rerun pass `--lib` [INFO] running `Command { std: "docker" "inspect" "9f0fa57faeb51310ae85a315cabc5199a4449301161c6a473f7eb93704ecc8ea", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "9f0fa57faeb51310ae85a315cabc5199a4449301161c6a473f7eb93704ecc8ea", kill_on_drop: false }` [INFO] [stdout] 9f0fa57faeb51310ae85a315cabc5199a4449301161c6a473f7eb93704ecc8ea