Nov 18 03:27:32.491 INFO testing portus-0.3.3 against try#2e026d6e5d7621634147ad4c8074f1d964dbb1be for pr-55982
Nov 18 03:27:32.491 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-0/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-0/pr-55982/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/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 +2e026d6e5d7621634147ad4c8074f1d964dbb1be-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"`
Nov 18 03:27:32.635 INFO [stdout] 5f2a268989b2f0cc62cdc41c27307fdbfd2c347d75e9ec141eaf66b71b175940
Nov 18 03:27:32.637 INFO running `"docker" "start" "-a" "5f2a268989b2f0cc62cdc41c27307fdbfd2c347d75e9ec141eaf66b71b175940"`
Nov 18 03:27:32.978 INFO [stderr] usermod: no changes
Nov 18 03:27:33.105 INFO [stderr]    Compiling nix v0.9.0
Nov 18 03:27:33.105 INFO [stderr]    Compiling syn v0.13.11
Nov 18 03:27:53.379 INFO [stderr]    Compiling portus v0.3.3 (/source)
Nov 18 03:28:14.003 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 41.00s
Nov 18 03:28:14.015 INFO [stderr] su: No module specific data is present
Nov 18 03:28:14.504 INFO running `"docker" "inspect" "5f2a268989b2f0cc62cdc41c27307fdbfd2c347d75e9ec141eaf66b71b175940"`
Nov 18 03:28:14.590 INFO running `"docker" "rm" "-f" "5f2a268989b2f0cc62cdc41c27307fdbfd2c347d75e9ec141eaf66b71b175940"`
Nov 18 03:28:14.674 INFO [stdout] 5f2a268989b2f0cc62cdc41c27307fdbfd2c347d75e9ec141eaf66b71b175940
Nov 18 03:28:14.676 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-0/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-0/pr-55982/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/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 +2e026d6e5d7621634147ad4c8074f1d964dbb1be-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"`
Nov 18 03:28:14.855 INFO [stdout] 2f7e9ab089527a49b9e076f7e921d8513aa2f073c6cc1a635b137091c67ab53e
Nov 18 03:28:14.857 INFO running `"docker" "start" "-a" "2f7e9ab089527a49b9e076f7e921d8513aa2f073c6cc1a635b137091c67ab53e"`
Nov 18 03:28:15.302 INFO [stderr] usermod: no changes
Nov 18 03:28:15.365 INFO [stderr]    Compiling portus v0.3.3 (/source)
Nov 18 03:28:27.519 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 12.21s
Nov 18 03:28:27.537 INFO [stderr] su: No module specific data is present
Nov 18 03:28:27.878 INFO running `"docker" "inspect" "2f7e9ab089527a49b9e076f7e921d8513aa2f073c6cc1a635b137091c67ab53e"`
Nov 18 03:28:27.981 INFO running `"docker" "rm" "-f" "2f7e9ab089527a49b9e076f7e921d8513aa2f073c6cc1a635b137091c67ab53e"`
Nov 18 03:28:28.091 INFO [stdout] 2f7e9ab089527a49b9e076f7e921d8513aa2f073c6cc1a635b137091c67ab53e
Nov 18 03:28:28.095 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-0/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-0/pr-55982/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/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 +2e026d6e5d7621634147ad4c8074f1d964dbb1be-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"`
Nov 18 03:28:28.399 INFO [stdout] bce454eed55a017eca10da252ad3d1ba47c031c755b8e938c2fbe11d7c5dc140
Nov 18 03:28:28.405 INFO running `"docker" "start" "-a" "bce454eed55a017eca10da252ad3d1ba47c031c755b8e938c2fbe11d7c5dc140"`
Nov 18 03:28:28.883 INFO [stderr] usermod: no changes
Nov 18 03:28:28.981 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.07s
Nov 18 03:28:28.998 INFO [stderr]      Running /target/debug/deps/portus-55aaeea8fb9097a5
Nov 18 03:28:29.000 INFO [stdout] 
Nov 18 03:28:29.000 INFO [stdout] running 68 tests
Nov 18 03:28:29.000 INFO [stdout] test lang::ast::tests::atom_0 ... ok
Nov 18 03:28:29.001 INFO [stdout] test lang::ast::tests::atom_5 ... ok
Nov 18 03:28:29.001 INFO [stdout] test ipc::chan::tests::basic ... ok
Nov 18 03:28:29.002 INFO [stdout] test lang::ast::tests::atom_7 ... ok
Nov 18 03:28:29.002 INFO [stdout] test lang::ast::tests::atom_8 ... ok
Nov 18 03:28:29.002 INFO [stdout] test lang::ast::tests::bool_ops ... ok
Nov 18 03:28:29.003 INFO [stdout] test lang::ast::tests::commands ... ok
Nov 18 03:28:29.003 INFO [stdout] test lang::ast::tests::comments ... ok
Nov 18 03:28:29.003 INFO [stdout] test ipc::test::test_unix ... ok
Nov 18 03:28:29.007 INFO [stdout] test lang::ast::tests::atom_6 ... ok
Nov 18 03:28:29.007 INFO [stdout] test lang::ast::tests::atom_1 ... ok
Nov 18 03:28:29.008 INFO [stdout] test lang::ast::tests::maxtest ... ok
Nov 18 03:28:29.008 INFO [stdout] test ipc::test::test_chan ... ok
Nov 18 03:28:29.008 INFO [stdout] test lang::ast::tests::atom_3 ... ok
Nov 18 03:28:29.009 INFO [stdout] test lang::ast::tests::atom_2 ... ok
Nov 18 03:28:29.009 INFO [stdout] test lang::ast::tests::atom_4 ... ok
Nov 18 03:28:29.009 INFO [stdout] test lang::ast::tests::expr_leftover ... ok
Nov 18 03:28:29.010 INFO [stdout] test lang::ast::tests::old_syntax ... ok
Nov 18 03:28:29.010 INFO [stdout] test lang::ast::tests::whitespace ... ok
Nov 18 03:28:29.010 INFO [stdout] test lang::ast::tests::simple_exprs ... ok
Nov 18 03:28:29.010 INFO [stdout] test lang::datapath::tests::control_def ... ok
Nov 18 03:28:29.011 INFO [stdout] test lang::datapath::tests::commands ... ok
Nov 18 03:28:29.011 INFO [stdout] test lang::datapath::tests::bool_ops ... ok
Nov 18 03:28:29.011 INFO [stdout] test lang::datapath::tests::ewma ... ok
Nov 18 03:28:29.012 INFO [stdout] test lang::datapath::tests::intermediate ... ok
Nov 18 03:28:29.012 INFO [stdout] test lang::datapath::tests::multiple_events ... ok
Nov 18 03:28:29.012 INFO [stdout] test lang::datapath::tests::infinity_if ... ok
Nov 18 03:28:29.012 INFO [stdout] test lang::prog::tests::def_infinity ... ok
Nov 18 03:28:29.013 INFO [stdout] test lang::ast::tests::tree ... ok
Nov 18 03:28:29.013 INFO [stdout] test lang::prog::tests::event ... ok
Nov 18 03:28:29.013 INFO [stdout] test lang::datapath::tests::primitives ... ok
Nov 18 03:28:29.013 INFO [stdout] test lang::datapath::tests::control_if_definition ... ok
Nov 18 03:28:29.014 INFO [stdout] test lang::prog::tests::simple_event ... ok
Nov 18 03:28:29.014 INFO [stdout] test lang::prog::tests::defs ... ok
Nov 18 03:28:29.014 INFO [stdout] test lang::prog::tests::combined ... ok
Nov 18 03:28:29.015 INFO [stdout] test lang::serialize::tests::do_ser_max_imm ... ok
Nov 18 03:28:29.015 INFO [stdout] test lang::datapath::tests::underscored_var ... ok
Nov 18 03:28:29.015 INFO [stdout] test lang::serialize::tests::do_ser_def_max_imm ... ok
Nov 18 03:28:29.015 INFO [stdout] test lang::serialize::tests::do_ser ... ok
Nov 18 03:28:29.016 INFO [stdout] test lang::tests::bench_1_line_compileonly ... ok
Nov 18 03:28:29.016 INFO [stdout] test lang::tests::bench_2_line ... ok
Nov 18 03:28:29.016 INFO [stdout] test lang::tests::bench_1_line ... ok
Nov 18 03:28:29.019 INFO [stdout] test serialize::changeprog::tests::serialize_changeprog_msg ... ok
Nov 18 03:28:29.023 INFO [stdout] test serialize::create::tests::bench_flip_create ... ok
Nov 18 03:28:29.023 INFO [stdout] test lang::prog::tests::reserved_names ... ok
Nov 18 03:28:29.023 INFO [stdout] test serialize::create::tests::test_create_1 ... ok
Nov 18 03:28:29.025 INFO [stdout] test lang::tests::bench_if ... ok
Nov 18 03:28:29.025 INFO [stdout] test lang::prog::tests::events ... ok
Nov 18 03:28:29.025 INFO [stdout] test lang::tests::bench_3_line ... ok
Nov 18 03:28:29.025 INFO [stdout] test lang::datapath::tests::reg ... ok
Nov 18 03:28:29.025 INFO [stdout] test serialize::install::tests::serialize_install_msg ... ok
Nov 18 03:28:29.025 INFO [stdout] test lang::datapath::tests::prog_reset_tmps ... ok
Nov 18 03:28:29.025 INFO [stdout] test serialize::tests::test_from_u32 ... ok
Nov 18 03:28:29.025 INFO [stdout] test serialize::tests::test_from_u16 ... ok
Nov 18 03:28:29.025 INFO [stdout] test serialize::measure::tests::test_measure_3 ... ok
Nov 18 03:28:29.026 INFO [stdout] test lang::tests::bench_ewma ... ok
Nov 18 03:28:29.027 INFO [stdout] test serialize::measure::tests::test_measure_2 ... ok
Nov 18 03:28:29.027 INFO [stdout] test serialize::tests::test_from_u64 ... ok
Nov 18 03:28:29.027 INFO [stdout] test serialize::tests::test_multi_msg ... ok
Nov 18 03:28:29.028 INFO [stdout] test serialize::tests::test_other_msg ... ok
Nov 18 03:28:29.028 INFO [stdout] test serialize::measure::tests::test_measure_1 ... ok
Nov 18 03:28:29.028 INFO [stdout] test serialize::tests::test_to_u16 ... ok
Nov 18 03:28:29.028 INFO [stdout] test serialize::tests::test_to_u32 ... ok
Nov 18 03:28:29.028 INFO [stdout] test serialize::update_field::tests::serialize_update_msg ... ok
Nov 18 03:28:29.028 INFO [stdout] test test::bench_ser_over_ipc ... ok
Nov 18 03:28:29.034 INFO [stdout] test serialize::tests::test_to_u64_1 ... ok
Nov 18 03:28:29.034 INFO [stdout] test serialize::tests::test_to_u64_0 ... ok
Nov 18 03:28:29.034 INFO [stdout] test test::test_ser_over_ipc ... ok
Nov 18 03:28:29.034 INFO [stdout] 
Nov 18 03:28:29.034 INFO [stdout] test result: ok. 68 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 03:28:29.034 INFO [stdout] 
Nov 18 03:28:29.034 INFO [stderr]      Running /target/debug/deps/cargo_compile_fast_path-f68257d7b031a7d9
Nov 18 03:28:29.036 INFO [stdout] 
Nov 18 03:28:29.036 INFO [stdout] running 0 tests
Nov 18 03:28:29.036 INFO [stdout] 
Nov 18 03:28:29.036 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 03:28:29.036 INFO [stdout] 
Nov 18 03:28:29.037 INFO [stderr]      Running /target/debug/deps/dump_fold-a5c0ba974b4a927f
Nov 18 03:28:29.040 INFO [stdout] 
Nov 18 03:28:29.040 INFO [stdout] running 0 tests
Nov 18 03:28:29.040 INFO [stdout] 
Nov 18 03:28:29.040 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 03:28:29.040 INFO [stdout] 
Nov 18 03:28:29.040 INFO [stderr]      Running /target/debug/deps/ipc_latency-8f5bd9d8ec594111
Nov 18 03:28:29.042 INFO [stdout] 
Nov 18 03:28:29.042 INFO [stdout] running 0 tests
Nov 18 03:28:29.042 INFO [stdout] 
Nov 18 03:28:29.042 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 03:28:29.042 INFO [stdout] 
Nov 18 03:28:29.043 INFO [stderr]      Running /target/debug/deps/kptest-04d10d6ddaa67822
Nov 18 03:28:29.050 INFO [stderr]      Running /target/debug/deps/nltest-de6ebad9f820e4ea
Nov 18 03:28:29.051 INFO [stdout] 
Nov 18 03:28:29.051 INFO [stderr]    Doc-tests portus
Nov 18 03:28:29.051 INFO [stdout] running 0 tests
Nov 18 03:28:29.051 INFO [stdout] 
Nov 18 03:28:29.051 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 03:28:29.051 INFO [stdout] 
Nov 18 03:28:29.051 INFO [stdout] 
Nov 18 03:28:29.051 INFO [stdout] running 0 tests
Nov 18 03:28:29.051 INFO [stdout] 
Nov 18 03:28:29.051 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 03:28:29.051 INFO [stdout] 
Nov 18 03:28:31.694 INFO [stdout] 
Nov 18 03:28:31.694 INFO [stdout] running 3 tests
Nov 18 03:28:32.739 INFO [stdout] test src/lib.rs - CongAlg::init_programs (line 307) ... ok
Nov 18 03:28:36.103 INFO [stdout] test src/lib.rs -  (line 19) ... ok
Nov 18 03:28:36.723 INFO [stdout] test src/lang/mod.rs - lang (line 60) ... ok
Nov 18 03:28:36.723 INFO [stdout] 
Nov 18 03:28:36.724 INFO [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 03:28:36.724 INFO [stdout] 
Nov 18 03:28:36.751 INFO [stderr] su: No module specific data is present
Nov 18 03:28:37.037 INFO running `"docker" "inspect" "bce454eed55a017eca10da252ad3d1ba47c031c755b8e938c2fbe11d7c5dc140"`
Nov 18 03:28:37.313 INFO running `"docker" "rm" "-f" "bce454eed55a017eca10da252ad3d1ba47c031c755b8e938c2fbe11d7c5dc140"`
Nov 18 03:28:37.507 INFO [stdout] bce454eed55a017eca10da252ad3d1ba47c031c755b8e938c2fbe11d7c5dc140