[INFO] crate heapless 0.5.1 is already in cache
[INFO] testing heapless-0.5.1 against beta-2019-09-28 for beta-1.39-1
[INFO] extracting crate heapless 0.5.1 into work/builds/worker-2/source
[INFO] validating manifest of crates.io crate heapless 0.5.1 on toolchain beta-2019-09-28
[INFO] running `"/big/crater/work/cargo-home/bin/cargo" "+beta-2019-09-28" "read-manifest" "--manifest-path" "Cargo.toml"`
[INFO] started tweaking crates.io crate heapless 0.5.1
[INFO] finished tweaking crates.io crate heapless 0.5.1
[INFO] tweaked toml for crates.io crate heapless 0.5.1 written to work/builds/worker-2/source/Cargo.toml
[INFO] running `"/big/crater/work/cargo-home/bin/cargo" "+beta-2019-09-28" "generate-lockfile" "--manifest-path" "Cargo.toml" "-Zno-index-update"`
[INFO] running `"/big/crater/work/cargo-home/bin/cargo" "+beta-2019-09-28" "fetch" "--locked" "--manifest-path" "Cargo.toml"`
[INFO] running `"docker" "create" "-v" "/big/crater/work/builds/worker-2/target:/opt/rustwide/target:rw,Z" "-v" "/big/crater/work/builds/worker-2/source:/opt/rustwide/workdir:ro,Z" "-v" "/big/crater/work/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/big/crater/work/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "MAP_USER_ID=1000" "-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" "--network" "none" "rustops/crates-build-env" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2019-09-28" "build" "--frozen"`
[INFO] [stdout] 09c3ecf33c473bd370936f3cee805eabbc8be46b8bf9a7dda78409799a3c12a3
[INFO] running `"docker" "start" "-a" "09c3ecf33c473bd370936f3cee805eabbc8be46b8bf9a7dda78409799a3c12a3"`
[INFO] [stderr]    Compiling heapless v0.5.1 (/opt/rustwide/workdir)
[INFO] [stderr]    Compiling generic-array v0.13.2
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 2.24s
[INFO] running `"docker" "inspect" "09c3ecf33c473bd370936f3cee805eabbc8be46b8bf9a7dda78409799a3c12a3"`
[INFO] running `"docker" "rm" "-f" "09c3ecf33c473bd370936f3cee805eabbc8be46b8bf9a7dda78409799a3c12a3"`
[INFO] [stdout] 09c3ecf33c473bd370936f3cee805eabbc8be46b8bf9a7dda78409799a3c12a3
[INFO] running `"docker" "create" "-v" "/big/crater/work/builds/worker-2/target:/opt/rustwide/target:rw,Z" "-v" "/big/crater/work/builds/worker-2/source:/opt/rustwide/workdir:ro,Z" "-v" "/big/crater/work/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/big/crater/work/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "MAP_USER_ID=1000" "-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" "--network" "none" "rustops/crates-build-env" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2019-09-28" "test" "--frozen" "--no-run"`
[INFO] [stdout] 64e0a1ee3161ebd9bbc84a5ff710c98d3e6bcd4385a5b4752c994b47a3b19d77
[INFO] running `"docker" "start" "-a" "64e0a1ee3161ebd9bbc84a5ff710c98d3e6bcd4385a5b4752c994b47a3b19d77"`
[INFO] [stderr]    Compiling heapless v0.5.1 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 2.52s
[INFO] running `"docker" "inspect" "64e0a1ee3161ebd9bbc84a5ff710c98d3e6bcd4385a5b4752c994b47a3b19d77"`
[INFO] running `"docker" "rm" "-f" "64e0a1ee3161ebd9bbc84a5ff710c98d3e6bcd4385a5b4752c994b47a3b19d77"`
[INFO] [stdout] 64e0a1ee3161ebd9bbc84a5ff710c98d3e6bcd4385a5b4752c994b47a3b19d77
[INFO] running `"docker" "create" "-v" "/big/crater/work/builds/worker-2/target:/opt/rustwide/target:rw,Z" "-v" "/big/crater/work/builds/worker-2/source:/opt/rustwide/workdir:ro,Z" "-v" "/big/crater/work/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/big/crater/work/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "MAP_USER_ID=1000" "-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" "--network" "none" "rustops/crates-build-env" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2019-09-28" "test" "--frozen"`
[INFO] [stdout] 0bfefd50960bd8dbf24e7f4f90c4a8e7f5e78b2628d181fae35f323a291b84dd
[INFO] running `"docker" "start" "-a" "0bfefd50960bd8dbf24e7f4f90c4a8e7f5e78b2628d181fae35f323a291b84dd"`
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.09s
[INFO] [stderr]      Running /opt/rustwide/target/debug/deps/heapless-2a0c4f79c0b3595c
[INFO] [stdout] 
[INFO] [stdout] running 65 tests
[INFO] [stdout] test binary_heap::tests::static_new ... ok
[INFO] [stdout] test binary_heap::tests::max ... ok
[INFO] [stdout] test binary_heap::tests::min ... ok
[INFO] [stdout] test indexmap::tests::partial_eq ... ok
[INFO] [stdout] test indexmap::tests::size ... ok
[INFO] [stdout] test linear_map::test::static_new ... ok
[INFO] [stdout] test linear_map::test::partial_eq ... ok
[INFO] [stdout] test mpmc::tests::sanity ... ok
[INFO] [stdout] test pool::singleton::tests::destructors ... ok
[INFO] [stdout] test pool::singleton::tests::sanity ... ok
[INFO] [stdout] test pool::tests::destructors ... ok
[INFO] [stdout] test pool::tests::grow ... ok
[INFO] [stdout] test pool::tests::grow_exact ... ok
[INFO] [stdout] test pool::tests::sanity ... ok
[INFO] [stdout] test spsc::split::tests::sanity ... ok
[INFO] [stdout] test spsc::tests::clone ... ok
[INFO] [stdout] test spsc::tests::drop ... ok
[INFO] [stdout] test spsc::tests::eq ... ok
[INFO] [stdout] test spsc::tests::full ... ok
[INFO] [stdout] test spsc::tests::hash_equality ... ok
[INFO] [stdout] test spsc::tests::iter ... ok
[INFO] [stdout] test spsc::tests::iter_mut ... ok
[INFO] [stdout] test spsc::tests::ready_flag ... ok
[INFO] [stdout] test spsc::tests::sanity ... ok
[INFO] [stdout] test spsc::tests::static_new ... ok
[INFO] [stdout] test spsc::tests::wrap_around ... ok
[INFO] [stdout] test string::tests::as_bytes ... ok
[INFO] [stdout] test string::tests::as_mut_str ... ok
[INFO] [stdout] test string::tests::clear ... ok
[INFO] [stdout] test string::tests::as_str ... ok
[INFO] [stdout] test string::tests::clone ... ok
[INFO] [stdout] test string::tests::debug ... ok
[INFO] [stdout] test string::tests::empty ... ok
[INFO] [stdout] test string::tests::from ... ok
[INFO] [stdout] test string::tests::display ... ok
[INFO] [stdout] test string::tests::from_num ... ok
[INFO] [stdout] test string::tests::from_str ... ok
[INFO] [stdout] test string::tests::from_utf8 ... ok
[INFO] [stdout] test string::tests::from_utf8_uenc ... ok
[INFO] [stdout] test string::tests::into_bytes ... ok
[INFO] [stdout] test string::tests::from_utf8_uenc_err ... ok
[INFO] [stdout] test string::tests::is_empty ... ok
[INFO] [stdout] test string::tests::pop ... ok
[INFO] [stdout] test string::tests::pop_uenc ... ok
[INFO] [stdout] test string::tests::push ... ok
[INFO] [stdout] test string::tests::from_utf8_unchecked ... ok
[INFO] [stdout] test string::tests::static_new ... ok
[INFO] [stdout] test string::tests::truncate ... ok
[INFO] [stdout] test string::tests::push_str ... ok
[INFO] [stdout] test vec::tests::collect_from_iter ... ok
[INFO] [stdout] test vec::tests::drop ... ok
[INFO] [stdout] test vec::tests::iter_move ... ok
[INFO] [stdout] test vec::tests::eq ... ok
[INFO] [stdout] test vec::tests::full ... ok
[INFO] [stdout] test vec::tests::iter ... ok
[INFO] [stdout] test vec::tests::iter_move_drop ... ok
[INFO] [stdout] test vec::tests::iter_mut ... ok
[INFO] [stdout] test vec::tests::resize_contents ... ok
[INFO] [stdout] test vec::tests::push_and_pop ... ok
[INFO] [stdout] test vec::tests::resize_default ... ok
[INFO] [stdout] test vec::tests::resize_size_limit ... ok
[INFO] [stdout] test vec::tests::static_new ... ok
[INFO] [stdout] test vec::tests::resize_length_cases ... ok
[INFO] [stdout] test vec::tests::collect_from_iter_overfull ... ok
[INFO] [stdout] test string::tests::from_panic ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 65 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
[INFO] [stdout] 
[INFO] [stderr]      Running /opt/rustwide/target/debug/deps/tsan-270b82dff75a498d
[INFO] [stdout] 
[INFO] [stdout] running 8 tests
[INFO] [stdout] test iterator_properly_wraps ... ok
[INFO] [stdout] test len_properly_wraps ... ok
[INFO] [stdout] test once ... ok
[INFO] [stdout] test twice ... ok
[INFO] [stdout] test scoped ... ok
[INFO] [stdout] test mpmc_contention ... ok
[INFO] [stdout] producer: 2096128
[INFO] [stdout] consumer: 261120
[INFO] [stdout] test unchecked ... ok
[INFO] [stdout] test contention ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 8 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests heapless
[INFO] [stdout] 
[INFO] [stdout] running 89 tests
[INFO] [stdout] test src/cfail.rs - cfail (line 61) ... ok
[INFO] [stdout] test src/cfail.rs - cfail (line 26) ... ok
[INFO] [stdout] test src/cfail.rs - cfail (line 38) ... ok
[INFO] [stdout] test src/cfail.rs - cfail (line 50) ... ok
[INFO] [stdout] test src/cfail.rs - cfail (line 76) ... ok
[INFO] [stdout] test src/binary_heap.rs - binary_heap::BinaryHeap<T, N, K>::len (line 147) ... ok
[INFO] [stdout] test src/binary_heap.rs - binary_heap::BinaryHeap<T, N, K>::push (line 305) ... ok
[INFO] [stdout] test src/binary_heap.rs - binary_heap::BinaryHeap<T, N, K>::clear (line 127) ... ok
[INFO] [stdout] test src/binary_heap.rs - binary_heap::BinaryHeap<T, N, K>::new (line 104) ... ok
[INFO] [stdout] test src/cfail.rs - cfail (line 7) ... ok
[INFO] [stdout] test src/binary_heap.rs - binary_heap::BinaryHeap<T, N, K>::peek_mut (line 240) ... ok
[INFO] [stdout] test src/binary_heap.rs - binary_heap::BinaryHeap<T, N, K>::peek (line 214) ... ok
[INFO] [stdout] test src/binary_heap.rs - binary_heap::BinaryHeap<T, N, K>::is_empty (line 163) ... ok
[INFO] [stdout] test src/binary_heap.rs - binary_heap::BinaryHeap<T, N, K>::pop (line 271) ... ok
[INFO] [stdout] test src/binary_heap.rs - binary_heap::BinaryHeap (line 48) ... ok
[INFO] [stdout] test src/binary_heap.rs - binary_heap::BinaryHeap<T, N, K>::iter (line 183) ... ok
[INFO] [stdout] test src/indexmap.rs - indexmap::IndexMap<K, V, N, S>::get_mut (line 592) ... ok
[INFO] [stdout] test src/indexmap.rs - indexmap::IndexMap<K, V, N, S>::values (line 389) ... ok
[INFO] [stdout] test src/indexmap.rs - indexmap::IndexMap<K, V, N, S>::keys (line 370) ... ok
[INFO] [stdout] test src/indexmap.rs - indexmap::IndexMap<K, V, N, S>::get (line 539) ... ok
[INFO] [stdout] test src/indexmap.rs - indexmap::IndexMap<K, V, N, S>::contains_key (line 566) ... ok
[INFO] [stdout] test src/indexmap.rs - indexmap::IndexMap<K, V, N, S>::insert (line 631) ... ok
[INFO] [stdout] test src/indexmap.rs - indexmap::IndexMap<K, V, N, S>::len (line 482) ... ok
[INFO] [stdout] test src/indexmap.rs - indexmap::IndexMap<K, V, N, S>::is_empty (line 499) ... ok
[INFO] [stdout] test src/indexmap.rs - indexmap::IndexMap<K, V, N, S>::clear (line 516) ... ok
[INFO] [stdout] test src/indexmap.rs - indexmap::IndexMap (line 293) ... ok
[INFO] [stdout] test src/indexmap.rs - indexmap::IndexMap<K, V, N, S>::iter (line 431) ... ok
[INFO] [stdout] test src/indexmap.rs - indexmap::IndexMap<K, V, N, S>::iter_mut (line 452) ... ok
[INFO] [stdout] test src/indexset.rs - indexset::IndexSet<T, N, S>::capacity (line 76) ... ok
[INFO] [stdout] test src/indexmap.rs - indexmap::IndexMap<K, V, N, S>::values_mut (line 408) ... ok
[INFO] [stdout] test src/indexset.rs - indexset::IndexSet (line 17) ... ok
[INFO] [stdout] test src/indexmap.rs - indexmap::IndexMap<K, V, N, S>::remove (line 664) ... ok
[INFO] [stdout] test src/indexset.rs - indexset::IndexSet<T, N, S>::insert (line 411) ... ok
[INFO] [stdout] test src/indexset.rs - indexset::IndexSet<T, N, S>::clear (line 286) ... ok
[INFO] [stdout] test src/indexset.rs - indexset::IndexSet<T, N, S>::contains (line 306) ... ok
[INFO] [stdout] test src/indexset.rs - indexset::IndexSet<T, N, S>::is_empty (line 269) ... ok
[INFO] [stdout] test src/indexset.rs - indexset::IndexSet<T, N, S>::difference (line 115) ... ok
[INFO] [stdout] test src/indexset.rs - indexset::IndexSet<T, N, S>::is_disjoint (line 327) ... ok
[INFO] [stdout] test src/indexset.rs - indexset::IndexSet<T, N, S>::is_superset (line 379) ... ok
[INFO] [stdout] test src/indexset.rs - indexset::IndexSet<T, N, S>::iter (line 91) ... ok
[INFO] [stdout] test src/indexset.rs - indexset::IndexSet<T, N, S>::intersection (line 188) ... ok
[INFO] [stdout] test src/indexset.rs - indexset::IndexSet<T, N, S>::is_subset (line 353) ... ok
[INFO] [stdout] test src/indexset.rs - indexset::IndexSet<T, N, S>::len (line 252) ... ok
[INFO] [stdout] test src/indexset.rs - indexset::IndexSet<T, N, S>::remove (line 435) ... ok
[INFO] [stdout] test src/linear_map.rs - linear_map::LinearMap<K, V, N>::capacity (line 60) ... ok
[INFO] [stdout] test src/lib.rs -  (line 11) ... ok
[INFO] [stdout] test src/indexset.rs - indexset::IndexSet<T, N, S>::union (line 222) ... ok
[INFO] [stdout] test src/mpmc.rs - mpmc (line 8) ... ignored
[INFO] [stdout] test src/pool/mod.rs - pool (line 10) ... ignored
[INFO] [stdout] test src/pool/mod.rs - pool (line 70) ... ignored
[INFO] [stdout] test src/indexset.rs - indexset::IndexSet<T, N, S>::symmetric_difference (line 154) ... ok
[INFO] [stdout] test src/linear_map.rs - linear_map::LinearMap<K, V, N>::clear (line 77) ... ok
[INFO] [stdout] test src/linear_map.rs - linear_map::LinearMap<K, V, N>::insert (line 190) ... ok
[INFO] [stdout] test src/linear_map.rs - linear_map::LinearMap<K, V, N>::get (line 115) ... ok
[INFO] [stdout] test src/linear_map.rs - linear_map::LinearMap<K, V, N>::get_mut (line 140) ... ok
[INFO] [stdout] test src/linear_map.rs - linear_map::LinearMap<K, V, N>::iter (line 235) ... ok
[INFO] [stdout] test src/linear_map.rs - linear_map::LinearMap<K, V, N>::contains_key (line 96) ... ok
[INFO] [stdout] test src/linear_map.rs - linear_map::LinearMap<K, V, N>::is_empty (line 218) ... ok
[INFO] [stdout] test src/linear_map.rs - linear_map::LinearMap<K, V, N>::keys (line 287) ... ok
[INFO] [stdout] test src/linear_map.rs - linear_map::LinearMap<K, V, N>::iter_mut (line 259) ... ok
[INFO] [stdout] test src/linear_map.rs - linear_map::LinearMap<K, V, N>::new (line 40) ... ok
[INFO] [stdout] test src/linear_map.rs - linear_map::LinearMap<K, V, N>::len (line 167) ... ok
[INFO] [stdout] test src/linear_map.rs - linear_map::LinearMap<K, V, N>::remove (line 311) ... ok
[INFO] [stdout] test src/spsc/mod.rs - spsc (line 24) ... ok
[INFO] [stdout] test src/linear_map.rs - linear_map::LinearMap<K, V, N>::values (line 338) ... ok
[INFO] [stdout] test src/spsc/mod.rs - spsc::Queue<T, N, u8, C>::peek (line 352) ... ok
[INFO] [stdout] test src/linear_map.rs - linear_map::LinearMap<K, V, N>::values_mut (line 359) ... ok
[INFO] [stdout] test src/spsc/split.rs - spsc::split::Consumer::peek (line 90) ... ok
[INFO] [stdout] test src/spsc/mod.rs - spsc::Queue<T, N, u16, C>::peek (line 352) ... ok
[INFO] [stdout] test src/spsc/mod.rs - spsc (line 7) ... ok
[INFO] [stdout] test src/spsc/mod.rs - spsc::Queue<T, N, usize, C>::peek (line 352) ... ok
[INFO] [stdout] test src/string.rs - string::String<N>::as_mut_str (line 168) ... ok
[INFO] [stdout] test src/spsc/split.rs - spsc::split::Consumer::peek (line 90) ... ok
[INFO] [stdout] test src/spsc/split.rs - spsc::split::Consumer::peek (line 90) ... ok
[INFO] [stdout] test src/string.rs - string::String<N>::clear (line 335) ... ok
[INFO] [stdout] test src/string.rs - string::String<N>::capacity (line 210) ... ok
[INFO] [stdout] test src/string.rs - string::String<N>::as_str (line 147) ... ok
[INFO] [stdout] test src/string.rs - string::String<N>::from_utf8 (line 71) ... ok
[INFO] [stdout] test src/string.rs - string::String<N>::from_utf8 (line 85) ... ok
[INFO] [stdout] test src/string.rs - string::String<N>::new (line 42) ... ok
[INFO] [stdout] test src/string.rs - string::String<N>::into_bytes (line 126) ... ok
[INFO] [stdout] test src/vec.rs - vec::Vec<T, N>::new (line 186) ... ok
[INFO] [stdout] test src/string.rs - string::String<N>::pop (line 301) ... ok
[INFO] [stdout] test src/vec.rs - vec::Vec<T, N>::extend_from_slice (line 218) ... ok
[INFO] [stdout] test src/string.rs - string::String<N>::push (line 230) ... ok
[INFO] [stdout] test src/string.rs - string::String<N>::push_str (line 187) ... ok
[INFO] [stdout] test src/string.rs - string::String<N>::truncate (line 273) ... ok
[INFO] [stdout] test src/vec.rs - vec::Vec (line 138) ... ok
[INFO] [stdout] test src/vec.rs - vec::Vec<T, N>::swap_remove (line 320) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 86 passed; 0 failed; 3 ignored; 0 measured; 0 filtered out
[INFO] [stdout] 
[INFO] running `"docker" "inspect" "0bfefd50960bd8dbf24e7f4f90c4a8e7f5e78b2628d181fae35f323a291b84dd"`
[INFO] running `"docker" "rm" "-f" "0bfefd50960bd8dbf24e7f4f90c4a8e7f5e78b2628d181fae35f323a291b84dd"`
[INFO] [stdout] 0bfefd50960bd8dbf24e7f4f90c4a8e7f5e78b2628d181fae35f323a291b84dd