[INFO] cloning repository https://github.com/aeubanks/itgrate
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/aeubanks/itgrate" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Faeubanks%2Fitgrate", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Faeubanks%2Fitgrate'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] 9b36ec2cfe96eebe938f2d29e7701b62087d857b
[INFO] testing aeubanks/itgrate against beta-2022-02-22 for beta-1.60-1
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Faeubanks%2Fitgrate" "/workspace/builds/worker-18/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-18/source'...
[INFO] [stderr] done.
[INFO] validating manifest of git repo https://github.com/aeubanks/itgrate on toolchain beta-2022-02-22
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+beta-2022-02-22" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] started tweaking git repo https://github.com/aeubanks/itgrate
[INFO] finished tweaking git repo https://github.com/aeubanks/itgrate
[INFO] tweaked toml for git repo https://github.com/aeubanks/itgrate written to /workspace/builds/worker-18/source/Cargo.toml
[INFO] crate git repo https://github.com/aeubanks/itgrate already has a lockfile, it will not be regenerated
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+beta-2022-02-22" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded structopt-derive v0.4.12
[INFO] [stderr]   Downloaded gnuplot v0.0.37
[INFO] [stderr]   Downloaded structopt v0.3.19
[INFO] [stderr]   Downloaded daggy v0.7.0
[INFO] [stderr]   Downloaded oxigen v2.1.0
[INFO] [stderr]   Downloaded noisy_float v0.1.13
[INFO] [stderr]   Downloaded historian v4.0.3
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-18/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-18/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:0cd99ca24d8e8c98e67c542213511d985b8778b5bdcbb160e038429496686047" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2022-02-22" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] c5e4ed5b7870c303539401b7d374b02ea34d4f222075a74132d52840a9536622
[INFO] running `Command { std: "docker" "start" "-a" "c5e4ed5b7870c303539401b7d374b02ea34d4f222075a74132d52840a9536622", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "c5e4ed5b7870c303539401b7d374b02ea34d4f222075a74132d52840a9536622", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "c5e4ed5b7870c303539401b7d374b02ea34d4f222075a74132d52840a9536622", kill_on_drop: false }`
[INFO] [stdout] c5e4ed5b7870c303539401b7d374b02ea34d4f222075a74132d52840a9536622
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-18/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-18/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:0cd99ca24d8e8c98e67c542213511d985b8778b5bdcbb160e038429496686047" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2022-02-22" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 037a6f90eb31526d08383fa722f033f5e5d2baa1e274b1538bad02e0efff3189
[INFO] running `Command { std: "docker" "start" "-a" "037a6f90eb31526d08383fa722f033f5e5d2baa1e274b1538bad02e0efff3189", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]    Compiling libc v0.2.79
[INFO] [stderr]    Compiling cfg-if v0.1.10
[INFO] [stderr]    Compiling lazy_static v1.4.0
[INFO] [stderr]    Compiling maybe-uninit v2.0.0
[INFO] [stderr]    Compiling proc-macro2 v1.0.24
[INFO] [stderr]    Compiling version_check v0.9.2
[INFO] [stderr]    Compiling unicode-xid v0.2.1
[INFO] [stderr]    Compiling getrandom v0.1.15
[INFO] [stderr]    Compiling rayon-core v1.8.1
[INFO] [stderr]    Compiling syn v1.0.42
[INFO] [stderr]    Compiling scopeguard v1.1.0
[INFO] [stderr]    Compiling memchr v2.3.3
[INFO] [stderr]    Compiling unicode-segmentation v1.6.0
[INFO] [stderr]    Compiling byteorder v1.3.4
[INFO] [stderr]    Compiling unicode-width v0.1.8
[INFO] [stderr]    Compiling hashbrown v0.9.1
[INFO] [stderr]    Compiling ppv-lite86 v0.2.9
[INFO] [stderr]    Compiling anyhow v1.0.33
[INFO] [stderr]    Compiling vec_map v0.8.2
[INFO] [stderr]    Compiling strsim v0.8.0
[INFO] [stderr]    Compiling either v1.6.1
[INFO] [stderr]    Compiling ansi_term v0.11.0
[INFO] [stderr]    Compiling fixedbitset v0.2.0
[INFO] [stderr]    Compiling regex-syntax v0.6.18
[INFO] [stderr]    Compiling historian v4.0.3
[INFO] [stderr]    Compiling crossbeam-utils v0.7.2
[INFO] [stderr]    Compiling memoffset v0.5.6
[INFO] [stderr]    Compiling crossbeam-epoch v0.8.2
[INFO] [stderr]    Compiling indexmap v1.6.0
[INFO] [stderr]    Compiling rayon v1.4.1
[INFO] [stderr]    Compiling num-traits v0.2.12
[INFO] [stderr]    Compiling aho-corasick v0.7.13
[INFO] [stderr]    Compiling textwrap v0.11.0
[INFO] [stderr]    Compiling noisy_float v0.1.13
[INFO] [stderr]    Compiling thread_local v1.0.1
[INFO] [stderr]    Compiling proc-macro-error-attr v1.0.4
[INFO] [stderr]    Compiling proc-macro-error v1.0.4
[INFO] [stderr]    Compiling heck v0.3.1
[INFO] [stderr]    Compiling num_cpus v1.13.0
[INFO] [stderr]    Compiling atty v0.2.14
[INFO] [stderr]    Compiling gnuplot v0.0.37
[INFO] [stderr]    Compiling quote v1.0.7
[INFO] [stderr]    Compiling regex v1.3.9
[INFO] [stderr]    Compiling clap v2.33.3
[INFO] [stderr]    Compiling crossbeam-channel v0.4.4
[INFO] [stderr]    Compiling petgraph v0.5.1
[INFO] [stderr]    Compiling rand_core v0.5.1
[INFO] [stderr]    Compiling rand_chacha v0.2.2
[INFO] [stderr]    Compiling rand_pcg v0.2.1
[INFO] [stderr]    Compiling crossbeam-deque v0.7.3
[INFO] [stderr]    Compiling rand v0.7.3
[INFO] [stderr]    Compiling daggy v0.7.0
[INFO] [stderr]    Compiling oxigen v2.1.0
[INFO] [stderr]    Compiling structopt-derive v0.4.12
[INFO] [stderr]    Compiling structopt v0.3.19
[INFO] [stderr]    Compiling itgrate v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 6m 13s
[INFO] running `Command { std: "docker" "inspect" "037a6f90eb31526d08383fa722f033f5e5d2baa1e274b1538bad02e0efff3189", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "037a6f90eb31526d08383fa722f033f5e5d2baa1e274b1538bad02e0efff3189", kill_on_drop: false }`
[INFO] [stdout] 037a6f90eb31526d08383fa722f033f5e5d2baa1e274b1538bad02e0efff3189
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-18/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-18/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:0cd99ca24d8e8c98e67c542213511d985b8778b5bdcbb160e038429496686047" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2022-02-22" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 5f1acba41ffaae9af2e3e818181edfd98312b2d6fef97cb3d9e501466e0ac885
[INFO] running `Command { std: "docker" "start" "-a" "5f1acba41ffaae9af2e3e818181edfd98312b2d6fef97cb3d9e501466e0ac885", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]    Compiling serde v1.0.116
[INFO] [stderr]    Compiling ryu v1.0.5
[INFO] [stderr]    Compiling serde_json v1.0.58
[INFO] [stderr]    Compiling itoa v0.4.6
[INFO] [stderr]    Compiling serde_derive v1.0.116
[INFO] [stderr]    Compiling half v1.6.0
[INFO] [stderr]    Compiling same-file v1.0.6
[INFO] [stderr]    Compiling oorandom v11.1.2
[INFO] [stderr]    Compiling itertools v0.9.0
[INFO] [stderr]    Compiling cast v0.2.3
[INFO] [stderr]    Compiling regex-automata v0.1.9
[INFO] [stderr]    Compiling csv-core v0.1.10
[INFO] [stderr]    Compiling plotters v0.2.15
[INFO] [stderr]    Compiling walkdir v2.3.1
[INFO] [stderr]    Compiling criterion-plot v0.4.3
[INFO] [stderr]    Compiling bstr v0.2.13
[INFO] [stderr]    Compiling serde_cbor v0.11.1
[INFO] [stderr]    Compiling csv v1.1.3
[INFO] [stderr]    Compiling tinytemplate v1.1.0
[INFO] [stderr]    Compiling criterion v0.3.3
[INFO] [stderr]    Compiling itgrate v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 2m 57s
[INFO] running `Command { std: "docker" "inspect" "5f1acba41ffaae9af2e3e818181edfd98312b2d6fef97cb3d9e501466e0ac885", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "5f1acba41ffaae9af2e3e818181edfd98312b2d6fef97cb3d9e501466e0ac885", kill_on_drop: false }`
[INFO] [stdout] 5f1acba41ffaae9af2e3e818181edfd98312b2d6fef97cb3d9e501466e0ac885
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-18/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-18/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:0cd99ca24d8e8c98e67c542213511d985b8778b5bdcbb160e038429496686047" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2022-02-22" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 69ce94bd7a2977c40ca61eb4746535b3cdb3de17a374f992cd878636a346707a
[INFO] running `Command { std: "docker" "start" "-a" "69ce94bd7a2977c40ca61eb4746535b3cdb3de17a374f992cd878636a346707a", kill_on_drop: false }`
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 0.05s
[INFO] [stderr]      Running unittests (/opt/rustwide/target/debug/deps/itgrate-5281b023e1e97938)
[INFO] [stdout] 
[INFO] [stdout] running 13 tests
[INFO] [stdout] test note::test_pos_distance ... ok
[INFO] [stdout] test note_pos::test_col_to_pos ... ok
[INFO] [stdout] test rate::state::test_state_step ... ok
[INFO] [stdout] test rate::test_descendents ... ok
[INFO] [stdout] test rate::test_create_descendents ... ok
[INFO] [stdout] test rate::test_get_ancestor ... ok
[INFO] [stdout] test smparser::test_bpm_measure_to_time ... ok
[INFO] [stdout] test rate::test_find_best_nodes ... ok
[INFO] [stdout] test rate::test_remove_all_descendents ... ok
[INFO] [stdout] test smparser::test_lines ... ok
[INFO] [stdout] test smparser::test_parse_sm ... ok
[INFO] [stdout] test smparser::test_full_name ... ok
[INFO] [stdout] Generation	Solutions	Last progress	Progress avg	Progress std	Progress max	Progress min	Progress p10	Progress p25	Progress median	Progress p75	Progress p90	Fitness avg	Fitness std	Fitness max	Fitness min	Fitness p10	Fitness p25	Fitness median	Fitness p75	Fitness p90
[INFO] [stdout] 1	0	-1.3968932628631592	-1.3968932628631592	NaN	-1.3968932628631592	-1.3968932628631592	1.3869108535242765	1.3869108535242765	1.3869108535242765	1.3869108535242765	1.3869108535242765	-1.4391181655228138	0.0031102171092754092	-1.3968932628631592	-1.464773416519165	1.4108997064172097	1.4351296512898744	1.4351296512898744	1.4351296512898744	1.4351296512898744
[INFO] [stdout] 2	0	0.09474015235900879	-0.6510765552520752	1.0547441029480442	0.09474015235900879	-1.3968932628631592	0.09417428370521042	0.09417428370521042	0.09417428370521042	1.3869108535242765	1.3869108535242765	-1.4260111264884472	0.005796797444746681	-1.3021531105041504	-1.4587911367416382	1.3869108535242765	1.4108997064172097	1.4351296512898744	1.4351296512898744	1.4351296512898744
[INFO] [stdout] test optimize::sanity ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 13 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.24s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/basic.rs (/opt/rustwide/target/debug/deps/basic-61f2992ed64acc0b)
[INFO] [stdout] 
[INFO] [stdout] running 8 tests
[INFO] [stdout] test empty ... ok
[INFO] [stdout] test common::test_create_notes ... ok
[INFO] [stdout] test more_is_harder ... ok
[INFO] [stdout] test fatigue_keeps_increasing_in_unbroken_stream ... ok
[INFO] [stdout] test rating_at_notes_same_len_as_notes ... ok
[INFO] [stdout] test one_note_after_break_doesnt_affect_difficulty ... ok
[INFO] [stdout] test faster_is_harder ... ok
[INFO] [stdout] test farther_is_harder ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 8 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.14s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/chart_comparisons.rs (/opt/rustwide/target/debug/deps/chart_comparisons-865975bf53144b28)
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test two_measures_of_240_is_harder_than_eight_measures_of_120 ... ignored
[INFO] [stdout] test common::test_create_notes ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 1 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/common.rs (/opt/rustwide/target/debug/deps/common-02c9853636a42a5e)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test test_create_notes ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests itgrate
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "69ce94bd7a2977c40ca61eb4746535b3cdb3de17a374f992cd878636a346707a", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "69ce94bd7a2977c40ca61eb4746535b3cdb3de17a374f992cd878636a346707a", kill_on_drop: false }`
[INFO] [stdout] 69ce94bd7a2977c40ca61eb4746535b3cdb3de17a374f992cd878636a346707a