Dec 06 00:31:30.458 INFO testing emk/wasm-bloat against try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468 for pr-56514
Dec 06 00:31:30.459 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-4/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/pr-56514/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468-alt build --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 06 00:31:30.900 INFO [stdout] e3bf0a2254446674d6b1372bdfeac109b12a4fa197e5e5e28448bd601a03af37
Dec 06 00:31:30.908 INFO running `"docker" "start" "-a" "e3bf0a2254446674d6b1372bdfeac109b12a4fa197e5e5e28448bd601a03af37"`
Dec 06 00:31:32.500 INFO [stderr] usermod: no changes
Dec 06 00:31:32.720 INFO [stderr]    Compiling num-traits v0.1.41
Dec 06 00:31:32.720 INFO [stderr]    Compiling termcolor v0.3.3
Dec 06 00:31:32.720 INFO [stderr]    Compiling atty v0.2.6
Dec 06 00:31:32.720 INFO [stderr]    Compiling log v0.4.0
Dec 06 00:31:32.720 INFO [stderr]    Compiling parking_lot_core v0.2.8
Dec 06 00:31:32.720 INFO [stderr]    Compiling synstructure v0.6.1
Dec 06 00:31:32.720 INFO [stderr]    Compiling structopt-derive v0.1.6
Dec 06 00:31:32.720 INFO [stderr]    Compiling backtrace-sys v0.1.16
Dec 06 00:31:33.153 INFO [stderr]    Compiling regex v0.2.3
Dec 06 00:31:33.246 INFO [stderr]    Compiling backtrace v0.3.4
Dec 06 00:31:38.192 INFO [stderr]    Compiling clap v2.29.0
Dec 06 00:31:38.192 INFO [stderr]    Compiling log v0.3.9
Dec 06 00:31:38.200 INFO [stderr]    Compiling num-integer v0.1.35
Dec 06 00:31:38.280 INFO [stderr]    Compiling parking_lot v0.4.8
Dec 06 00:31:38.887 INFO [stderr]    Compiling failure_derive v0.1.1
Dec 06 00:31:39.604 INFO [stderr]    Compiling num-iter v0.1.34
Dec 06 00:31:40.476 INFO [stderr]    Compiling num v0.1.41
Dec 06 00:31:40.968 INFO [stderr]    Compiling parity-wasm v0.19.0
Dec 06 00:31:40.976 INFO [stderr]    Compiling chrono v0.4.0
Dec 06 00:31:41.080 INFO [stderr]    Compiling failure v0.1.1
Dec 06 00:31:47.504 INFO [stderr]    Compiling common_failures v0.1.0
Dec 06 00:31:49.378 INFO [stderr]    Compiling env_logger v0.5.0-rc.1
Dec 06 00:32:05.760 INFO [stderr]    Compiling structopt v0.1.6
Dec 06 00:32:05.920 INFO [stderr]    Compiling wasm-bloat v0.1.0 (/source)
Dec 06 00:32:06.266 INFO [stderr] warning: unused import: `std::iter`
Dec 06 00:32:06.266 INFO [stderr]  --> src/name_section.rs:1:5
Dec 06 00:32:06.266 INFO [stderr]   |
Dec 06 00:32:06.266 INFO [stderr] 1 | use std::iter;
Dec 06 00:32:06.266 INFO [stderr]   |     ^^^^^^^^^
Dec 06 00:32:06.266 INFO [stderr]   |
Dec 06 00:32:06.266 INFO [stderr]   = note: #[warn(unused_imports)] on by default
Dec 06 00:32:06.266 INFO [stderr] 
Dec 06 00:32:06.512 INFO [stderr] warning: method is never used: `iter`
Dec 06 00:32:06.512 INFO [stderr]    --> src/index_map.rs:117:5
Dec 06 00:32:06.512 INFO [stderr]     |
Dec 06 00:32:06.512 INFO [stderr] 117 |     fn iter(&self) -> Iter<T> {
Dec 06 00:32:06.512 INFO [stderr]     |     ^^^^^^^^^^^^^^^^^^^^^^^^^
Dec 06 00:32:06.512 INFO [stderr]     |
Dec 06 00:32:06.512 INFO [stderr]     = note: #[warn(dead_code)] on by default
Dec 06 00:32:06.512 INFO [stderr] 
Dec 06 00:32:06.512 INFO [stderr] warning: method is never used: `new`
Dec 06 00:32:06.512 INFO [stderr]   --> src/name_section.rs:95:5
Dec 06 00:32:06.512 INFO [stderr]    |
Dec 06 00:32:06.512 INFO [stderr] 95 |     pub fn new<S: Into<String>>(name: S) -> ModuleNameSection {
Dec 06 00:32:06.512 INFO [stderr]    |     ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Dec 06 00:32:06.512 INFO [stderr] 
Dec 06 00:32:06.512 INFO [stderr] warning: method is never used: `name`
Dec 06 00:32:06.512 INFO [stderr]    --> src/name_section.rs:100:5
Dec 06 00:32:06.512 INFO [stderr]     |
Dec 06 00:32:06.512 INFO [stderr] 100 |     pub fn name(&self) -> &str {
Dec 06 00:32:06.512 INFO [stderr]     |     ^^^^^^^^^^^^^^^^^^^^^^^^^^
Dec 06 00:32:06.512 INFO [stderr] 
Dec 06 00:32:06.512 INFO [stderr] warning: method is never used: `name_mut`
Dec 06 00:32:06.512 INFO [stderr]    --> src/name_section.rs:105:5
Dec 06 00:32:06.512 INFO [stderr]     |
Dec 06 00:32:06.512 INFO [stderr] 105 |     pub fn name_mut(&mut self) -> &mut String {
Dec 06 00:32:06.512 INFO [stderr]     |     ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Dec 06 00:32:06.512 INFO [stderr] 
Dec 06 00:32:06.512 INFO [stderr] warning: method is never used: `names_mut`
Dec 06 00:32:06.512 INFO [stderr]    --> src/name_section.rs:140:5
Dec 06 00:32:06.512 INFO [stderr]     |
Dec 06 00:32:06.512 INFO [stderr] 140 |     pub fn names_mut(&mut self) -> &mut NameMap {
Dec 06 00:32:06.512 INFO [stderr]     |     ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Dec 06 00:32:06.512 INFO [stderr] 
Dec 06 00:32:06.512 INFO [stderr] warning: method is never used: `local_names`
Dec 06 00:32:06.512 INFO [stderr]    --> src/name_section.rs:170:5
Dec 06 00:32:06.512 INFO [stderr]     |
Dec 06 00:32:06.512 INFO [stderr] 170 |     pub fn local_names(&self) -> &IndexMap<NameMap> {
Dec 06 00:32:06.512 INFO [stderr]     |     ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Dec 06 00:32:06.512 INFO [stderr] 
Dec 06 00:32:06.512 INFO [stderr] warning: method is never used: `local_names_mut`
Dec 06 00:32:06.512 INFO [stderr]    --> src/name_section.rs:176:5
Dec 06 00:32:06.512 INFO [stderr]     |
Dec 06 00:32:06.512 INFO [stderr] 176 |     pub fn local_names_mut(&mut self) -> &mut IndexMap<NameMap> {
Dec 06 00:32:06.512 INFO [stderr]     |     ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Dec 06 00:32:06.512 INFO [stderr] 
Dec 06 00:32:09.411 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 36.87s
Dec 06 00:32:09.413 INFO [stderr] su: No module specific data is present
Dec 06 00:32:09.850 INFO running `"docker" "inspect" "e3bf0a2254446674d6b1372bdfeac109b12a4fa197e5e5e28448bd601a03af37"`
Dec 06 00:32:10.030 INFO running `"docker" "rm" "-f" "e3bf0a2254446674d6b1372bdfeac109b12a4fa197e5e5e28448bd601a03af37"`
Dec 06 00:32:10.219 INFO [stdout] e3bf0a2254446674d6b1372bdfeac109b12a4fa197e5e5e28448bd601a03af37
Dec 06 00:32:10.222 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-4/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/pr-56514/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468-alt test --frozen --no-run" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 06 00:32:10.562 INFO [stdout] 7f09aa935df5c50f982deb8f4469e6c94653244949d8246ac001f34250d55846
Dec 06 00:32:10.572 INFO running `"docker" "start" "-a" "7f09aa935df5c50f982deb8f4469e6c94653244949d8246ac001f34250d55846"`
Dec 06 00:32:11.433 INFO [stderr] usermod: no changes
Dec 06 00:32:11.533 INFO [stderr]    Compiling cli_test_dir v0.1.4
Dec 06 00:32:11.996 INFO [stderr]    Compiling wasm-bloat v0.1.0 (/source)
Dec 06 00:32:12.424 INFO [stderr] warning: unused import: `std::iter`
Dec 06 00:32:12.424 INFO [stderr]  --> src/name_section.rs:1:5
Dec 06 00:32:12.424 INFO [stderr]   |
Dec 06 00:32:12.424 INFO [stderr] 1 | use std::iter;
Dec 06 00:32:12.424 INFO [stderr]   |     ^^^^^^^^^
Dec 06 00:32:12.424 INFO [stderr]   |
Dec 06 00:32:12.424 INFO [stderr]   = note: #[warn(unused_imports)] on by default
Dec 06 00:32:12.424 INFO [stderr] 
Dec 06 00:32:12.836 INFO [stderr] warning: method is never used: `name`
Dec 06 00:32:12.837 INFO [stderr]    --> src/name_section.rs:100:5
Dec 06 00:32:12.837 INFO [stderr]     |
Dec 06 00:32:12.837 INFO [stderr] 100 |     pub fn name(&self) -> &str {
Dec 06 00:32:12.837 INFO [stderr]     |     ^^^^^^^^^^^^^^^^^^^^^^^^^^
Dec 06 00:32:12.837 INFO [stderr]     |
Dec 06 00:32:12.837 INFO [stderr]     = note: #[warn(dead_code)] on by default
Dec 06 00:32:12.837 INFO [stderr] 
Dec 06 00:32:12.837 INFO [stderr] warning: method is never used: `name_mut`
Dec 06 00:32:12.837 INFO [stderr]    --> src/name_section.rs:105:5
Dec 06 00:32:12.837 INFO [stderr]     |
Dec 06 00:32:12.837 INFO [stderr] 105 |     pub fn name_mut(&mut self) -> &mut String {
Dec 06 00:32:12.837 INFO [stderr]     |     ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Dec 06 00:32:12.837 INFO [stderr] 
Dec 06 00:32:12.837 INFO [stderr] warning: method is never used: `local_names`
Dec 06 00:32:12.837 INFO [stderr]    --> src/name_section.rs:170:5
Dec 06 00:32:12.837 INFO [stderr]     |
Dec 06 00:32:12.837 INFO [stderr] 170 |     pub fn local_names(&self) -> &IndexMap<NameMap> {
Dec 06 00:32:12.837 INFO [stderr]     |     ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Dec 06 00:32:12.837 INFO [stderr] 
Dec 06 00:32:15.376 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 3.93s
Dec 06 00:32:15.384 INFO [stderr] su: No module specific data is present
Dec 06 00:32:16.004 INFO running `"docker" "inspect" "7f09aa935df5c50f982deb8f4469e6c94653244949d8246ac001f34250d55846"`
Dec 06 00:32:16.208 INFO running `"docker" "rm" "-f" "7f09aa935df5c50f982deb8f4469e6c94653244949d8246ac001f34250d55846"`
Dec 06 00:32:16.421 INFO [stdout] 7f09aa935df5c50f982deb8f4469e6c94653244949d8246ac001f34250d55846
Dec 06 00:32:16.429 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-4/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/pr-56514/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468-alt test --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 06 00:32:16.720 INFO [stdout] 867a7e6ad650098f1776165402c41493da0d024092036cb0eb37042cdde3f37d
Dec 06 00:32:16.727 INFO running `"docker" "start" "-a" "867a7e6ad650098f1776165402c41493da0d024092036cb0eb37042cdde3f37d"`
Dec 06 00:32:17.697 INFO [stderr] usermod: no changes
Dec 06 00:32:17.847 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.11s
Dec 06 00:32:17.865 INFO [stderr]      Running /target/debug/deps/wasm_bloat-aa443c2329947b74
Dec 06 00:32:17.868 INFO [stdout] 
Dec 06 00:32:17.868 INFO [stdout] running 17 tests
Dec 06 00:32:17.868 INFO [stdout] test index_map::tests::insert_behaves_like_other_maps ... ok
Dec 06 00:32:17.868 INFO [stdout] test index_map::tests::iterators_are_well_behaved ... ok
Dec 06 00:32:17.868 INFO [stdout] test index_map::tests::get_returns_elements_that_are_there_but_nothing_else ... ok
Dec 06 00:32:17.868 INFO [stdout] test index_map::tests::from_iterator_builds_a_map ... ok
Dec 06 00:32:17.868 INFO [stdout] test index_map::tests::deserialize_requires_elements_to_be_in_order ... ok
Dec 06 00:32:17.868 INFO [stdout] test index_map::tests::default_is_empty_no_matter_how_we_look_at_it ... ok
Dec 06 00:32:17.868 INFO [stdout] test index_map::tests::partial_eq_is_smart_about_none_values_at_the_end ... ok
Dec 06 00:32:17.868 INFO [stdout] test index_map::tests::partial_eq_works_as_expected_in_simple_cases ... ok
Dec 06 00:32:17.868 INFO [stdout] test index_map::tests::remove_behaves_like_other_maps ... ok
Dec 06 00:32:17.868 INFO [stdout] test index_map::tests::serialize_and_deserialize ... ok
Dec 06 00:32:17.868 INFO [stdout] test index_map::tests::with_capacity_creates_empty_map ... ok
Dec 06 00:32:17.868 INFO [stdout] test index_map::tests::contains_key_returns_true_when_a_key_is_present ... ok
Dec 06 00:32:17.868 INFO [stdout] test name_section::tests::serialize_and_deserialize_function_names ... ok
Dec 06 00:32:17.869 INFO [stdout] test name_section::tests::serialize_and_deserialize_local_names ... ok
Dec 06 00:32:17.869 INFO [stdout] test name_section::tests::serialize_and_deserialize_module_name ... ok
Dec 06 00:32:17.869 INFO [stdout] test name_section::tests::serialize_and_deserialize_unparsed ... ok
Dec 06 00:32:17.876 INFO [stdout] test index_map::tests::clear_removes_all_values ... ok
Dec 06 00:32:17.876 INFO [stdout] 
Dec 06 00:32:17.876 INFO [stdout] test result: ok. 17 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 06 00:32:17.876 INFO [stdout] 
Dec 06 00:32:17.884 INFO [stderr]      Running /target/debug/deps/tests-cd00fef59c152c80
Dec 06 00:32:17.884 INFO [stdout] 
Dec 06 00:32:17.884 INFO [stdout] running 1 test
Dec 06 00:32:17.884 INFO [stdout] test analyze_wasm_file ... ok
Dec 06 00:32:17.884 INFO [stdout] 
Dec 06 00:32:17.884 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 06 00:32:17.884 INFO [stdout] 
Dec 06 00:32:17.892 INFO [stderr] su: No module specific data is present
Dec 06 00:32:18.877 INFO running `"docker" "inspect" "867a7e6ad650098f1776165402c41493da0d024092036cb0eb37042cdde3f37d"`
Dec 06 00:32:19.131 INFO running `"docker" "rm" "-f" "867a7e6ad650098f1776165402c41493da0d024092036cb0eb37042cdde3f37d"`
Dec 06 00:32:19.373 INFO [stdout] 867a7e6ad650098f1776165402c41493da0d024092036cb0eb37042cdde3f37d