Dec 14 16:54:40.867 INFO testing timely-0.8.0 against try#aa49d8ef14939ddec0e34b346b60174a5673d48f for pr-56550
Dec 14 16:54:40.867 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-5/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/pr-56550/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/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 +aa49d8ef14939ddec0e34b346b60174a5673d48f-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 14 16:54:41.123 INFO [stdout] a170110fa683b04c14ed16771f2e05651cefc45c12e0ebcab0486787ac11baf0
Dec 14 16:54:41.127 INFO running `"docker" "start" "-a" "a170110fa683b04c14ed16771f2e05651cefc45c12e0ebcab0486787ac11baf0"`
Dec 14 16:54:41.945 INFO [stderr] usermod: no changes
Dec 14 16:54:42.033 INFO [stderr]    Compiling timely_logging v0.7.0
Dec 14 16:54:42.033 INFO [stderr]    Compiling abomonation v0.7.0
Dec 14 16:54:42.035 INFO [stderr]    Compiling timely_bytes v0.7.0
Dec 14 16:54:42.043 INFO [stderr]    Compiling abomonation_derive v0.3.0
Dec 14 16:54:45.071 INFO [stderr]    Compiling timely_communication v0.8.0
Dec 14 16:54:48.099 INFO [stderr]    Compiling timely v0.8.0 (/source)
Dec 14 16:54:53.667 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 11.69s
Dec 14 16:54:53.674 INFO [stderr] su: No module specific data is present
Dec 14 16:54:54.142 INFO running `"docker" "inspect" "a170110fa683b04c14ed16771f2e05651cefc45c12e0ebcab0486787ac11baf0"`
Dec 14 16:54:54.295 INFO running `"docker" "rm" "-f" "a170110fa683b04c14ed16771f2e05651cefc45c12e0ebcab0486787ac11baf0"`
Dec 14 16:54:54.409 INFO [stdout] a170110fa683b04c14ed16771f2e05651cefc45c12e0ebcab0486787ac11baf0
Dec 14 16:54:54.411 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-5/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/pr-56550/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/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 +aa49d8ef14939ddec0e34b346b60174a5673d48f-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 14 16:54:54.647 INFO [stdout] 402b260ab1ebb49496f2e31c11d1297ab002e6ce92717c8375358c991e884f70
Dec 14 16:54:54.648 INFO running `"docker" "start" "-a" "402b260ab1ebb49496f2e31c11d1297ab002e6ce92717c8375358c991e884f70"`
Dec 14 16:54:55.227 INFO [stderr] usermod: no changes
Dec 14 16:54:55.329 INFO [stderr]    Compiling timely_sort v0.1.6
Dec 14 16:54:56.067 INFO [stderr]    Compiling timely v0.8.0 (/source)
Dec 14 16:57:31.508 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 2m 36s
Dec 14 16:57:31.535 INFO [stderr] su: No module specific data is present
Dec 14 16:57:31.996 INFO running `"docker" "inspect" "402b260ab1ebb49496f2e31c11d1297ab002e6ce92717c8375358c991e884f70"`
Dec 14 16:57:32.199 INFO running `"docker" "rm" "-f" "402b260ab1ebb49496f2e31c11d1297ab002e6ce92717c8375358c991e884f70"`
Dec 14 16:57:32.427 INFO [stdout] 402b260ab1ebb49496f2e31c11d1297ab002e6ce92717c8375358c991e884f70
Dec 14 16:57:32.429 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-5/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/pr-56550/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/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 +aa49d8ef14939ddec0e34b346b60174a5673d48f-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 14 16:57:32.788 INFO [stdout] 732c0a850edbcbe63e5a2384ff5b3a0572486954f55407020db724bde3991620
Dec 14 16:57:32.793 INFO running `"docker" "start" "-a" "732c0a850edbcbe63e5a2384ff5b3a0572486954f55407020db724bde3991620"`
Dec 14 16:57:34.410 INFO [stderr] usermod: no changes
Dec 14 16:57:34.555 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.10s
Dec 14 16:57:34.571 INFO [stderr]      Running /target/debug/deps/timely-29cda928ad73f266
Dec 14 16:57:34.583 INFO [stdout] 
Dec 14 16:57:34.583 INFO [stdout] running 5 tests
Dec 14 16:57:34.583 INFO [stdout] test dataflow::operators::generic::notificator::notificator_delivers_notifications_in_topo_order ... ok
Dec 14 16:57:34.591 INFO [stdout] test dataflow::operators::generic::builder_rc::tests::correct_capabilities ... ok
Dec 14 16:57:34.591 INFO [stderr] thread 'worker thread 0' panicked at 'Attempted to open output session with invalid capability', src/dataflow/operators/generic/handles.rs:212:9
Dec 14 16:57:34.591 INFO [stderr] stack backtrace:
Dec 14 16:57:34.607 INFO [stdout] test dataflow::operators::probe::tests::probe ... ok
Dec 14 16:57:34.651 INFO [stderr]    0:     0x55840dc97473 - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::h2c40bdfb30f25bdd
Dec 14 16:57:34.695 INFO [stderr]                                at src/libstd/sys/unix/backtrace/tracing/gcc_s.rs:49
Dec 14 16:57:34.695 INFO [stderr]    1:     0x55840dc90ed8 - std::sys_common::backtrace::_print::he11ceebc70626ac9
Dec 14 16:57:34.695 INFO [stderr]                                at src/libstd/sys_common/backtrace.rs:71
Dec 14 16:57:34.695 INFO [stderr]    2:     0x55840dc956d2 - std::panicking::default_hook::{{closure}}::h4fde3ffb19c01413
Dec 14 16:57:34.695 INFO [stderr]                                at src/libstd/sys_common/backtrace.rs:59
Dec 14 16:57:34.695 INFO [stderr]                                at src/libstd/panicking.rs:210
Dec 14 16:57:34.695 INFO [stderr]    3:     0x55840dc95444 - std::panicking::default_hook::hf0c15a583008ed0d
Dec 14 16:57:34.695 INFO [stderr]                                at src/libstd/panicking.rs:224
Dec 14 16:57:34.695 INFO [stderr]    4:     0x55840dc95de0 - std::panicking::rust_panic_with_hook::hc1663f6bc7813185
Dec 14 16:57:34.695 INFO [stderr]                                at src/libstd/panicking.rs:487
Dec 14 16:57:34.695 INFO [stderr]    5:     0x55840dc82de7 - std::panicking::begin_panic::h8f789e5e0447eda5
Dec 14 16:57:34.695 INFO [stderr]                                at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libstd/panicking.rs:421
Dec 14 16:57:34.695 INFO [stderr]    6:     0x55840db7690c - <timely::dataflow::operators::generic::handles::OutputHandle<'a, T, D, P>>::session::h6cbeffa360efdb3b
Dec 14 16:57:34.715 INFO [stderr]                                at src/dataflow/operators/generic/handles.rs:212
Dec 14 16:57:34.715 INFO [stderr]    7:     0x55840dada3dd - timely::dataflow::operators::generic::builder_rc::tests::incorrect_capabilities::{{closure}}::{{closure}}::{{closure}}::hedd9ca741e54346a
Dec 14 16:57:34.715 INFO [stderr]                                at src/dataflow/operators/generic/builder_rc.rs:200
Dec 14 16:57:34.715 INFO [stderr]    8:     0x55840da6cca3 - <timely::dataflow::operators::generic::builder_rc::OperatorBuilder<G>>::build::{{closure}}::h558b44ae681de942
Dec 14 16:57:34.723 INFO [stderr]                                at src/dataflow/operators/generic/builder_rc.rs:136
Dec 14 16:57:34.723 INFO [stderr]    9:     0x55840da86a80 - <timely::dataflow::operators::generic::builder_raw::OperatorCore<T, PEP, PIP> as timely::progress::operate::Operate<T>>::pull_internal_progress::hf4e7ac4c845e5e07
Dec 14 16:57:34.723 INFO [stderr]                                at src/dataflow/operators/generic/builder_raw.rs:219
Dec 14 16:57:34.723 INFO [stderr]   10:     0x55840dbce6d0 - <timely::progress::nested::subgraph::PerOperatorState<T>>::exchange_progress::h5e4569e799d1d694
Dec 14 16:57:34.723 INFO [stderr]                                at src/progress/nested/subgraph.rs:803
Dec 14 16:57:34.723 INFO [stderr]   11:     0x55840dbc899a - <timely::progress::nested::subgraph::Subgraph<TOuter, TInner> as timely::progress::operate::Operate<TOuter>>::pull_internal_progress::he336ed87e0abc2ce
Dec 14 16:57:34.723 INFO [stderr]                                at src/progress/nested/subgraph.rs:536
Dec 14 16:57:34.723 INFO [stderr]   12:     0x55840dbb0dea - timely::worker::Wrapper::step::{{closure}}::hbbb507f7840f579f
Dec 14 16:57:34.728 INFO [stderr]                                at src/worker.rs:197
Dec 14 16:57:34.728 INFO [stderr]   13:     0x55840da764b4 - <core::option::Option<T>>::map::h6d71856f7e5542a9
Dec 14 16:57:34.728 INFO [stderr]                                at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libcore/option.rs:424
Dec 14 16:57:34.728 INFO [stderr]   14:     0x55840dbb0ca3 - timely::worker::Wrapper::step::h763463dff932adb4
Dec 14 16:57:34.728 INFO [stderr]                                at src/worker.rs:197
Dec 14 16:57:34.728 INFO [stderr]   15:     0x55840dbaddc3 - <timely::worker::Worker<A>>::step::h954e3824d431bcf0
Dec 14 16:57:34.728 INFO [stderr]                                at src/worker.rs:68
Dec 14 16:57:34.728 INFO [stderr]   16:     0x55840da9c82e - timely::execute::example::{{closure}}::h8ef8a07b47c83cef
Dec 14 16:57:34.729 INFO [stderr]                                at src/execute.rs:59
Dec 14 16:57:34.729 INFO [stderr]   17:     0x55840da58d50 - timely_communication::initialize::initialize_from::{{closure}}::hb7e80bc8ca48cbcf
Dec 14 16:57:34.729 INFO [stderr]                                at /cargo-home/registry/src/github.com-1ecc6299db9ec823/timely_communication-0.8.0/src/initialize.rs:261
Dec 14 16:57:34.729 INFO [stderr]   18:     0x55840db3c1f2 - std::sys_common::backtrace::__rust_begin_short_backtrace::h8bcdbd18caf6e2c8
Dec 14 16:57:34.743 INFO [stderr]                                at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libstd/sys_common/backtrace.rs:136
Dec 14 16:57:34.743 INFO [stderr]   19:     0x55840db58ef4 - std::thread::Builder::spawn_unchecked::{{closure}}::{{closure}}::h3220b2018784e253
Dec 14 16:57:34.743 INFO [stderr]                                at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libstd/thread/mod.rs:479
Dec 14 16:57:34.743 INFO [stderr]   20:     0x55840db53924 - <std::panic::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::hffa10862e7997b3f
Dec 14 16:57:34.743 INFO [stderr]                                at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libstd/panic.rs:319
Dec 14 16:57:34.743 INFO [stderr]   21:     0x55840db59637 - std::panicking::try::do_call::hb694f06aeaeea871
Dec 14 16:57:34.743 INFO [stderr]                                at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libstd/panicking.rs:306
Dec 14 16:57:34.743 INFO [stderr]   22:     0x55840dca5899 - __rust_maybe_catch_panic
Dec 14 16:57:34.743 INFO [stderr]                                at src/libpanic_unwind/lib.rs:102
Dec 14 16:57:34.743 INFO [stderr]   23:     0x55840db59348 - std::panicking::try::he80c2637450b03eb
Dec 14 16:57:34.743 INFO [stderr]                                at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libstd/panicking.rs:285
Dec 14 16:57:34.743 INFO [stderr]   24:     0x55840db56e04 - std::panic::catch_unwind::h2cfc560a8a422ce4
Dec 14 16:57:34.743 INFO [stderr]                                at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libstd/panic.rs:398
Dec 14 16:57:34.743 INFO [stderr]   25:     0x55840db585bd - std::thread::Builder::spawn_unchecked::{{closure}}::h88c9ea10a483e398
Dec 14 16:57:34.743 INFO [stderr]                                at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libstd/thread/mod.rs:478
Dec 14 16:57:34.743 INFO [stderr]   26:     0x55840db6fff2 - <F as alloc::boxed::FnBox<A>>::call_box::hcb2f9b1eca0c5d09
Dec 14 16:57:34.743 INFO [stderr]                                at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/liballoc/boxed.rs:673
Dec 14 16:57:34.743 INFO [stderr]   27:     0x55840dc997bd - std::sys::unix::thread::Thread::new::thread_start::h42c32b292fdd228f
Dec 14 16:57:34.743 INFO [stderr]                                at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/liballoc/boxed.rs:683
Dec 14 16:57:34.743 INFO [stderr]                                at src/libstd/sys_common/thread.rs:24
Dec 14 16:57:34.743 INFO [stderr]                                at src/libstd/sys/unix/thread.rs:91
Dec 14 16:57:34.743 INFO [stderr]   28:     0x7f4a93e826b9 - start_thread
Dec 14 16:57:34.743 INFO [stderr]   29:     0x7f4a939a241c - clone
Dec 14 16:57:34.743 INFO [stderr]   30:                0x0 - <unknown>
Dec 14 16:57:34.774 INFO [stdout] test dataflow::operators::generic::builder_rc::tests::incorrect_capabilities ... ok
Dec 14 16:57:36.458 INFO [stdout] test dataflow::operators::capture::event::link::avoid_stack_overflow_in_drop ... ok
Dec 14 16:57:36.458 INFO [stderr]      Running /target/debug/deps/barrier-186956f9d53ab5ab
Dec 14 16:57:36.458 INFO [stdout] 
Dec 14 16:57:36.458 INFO [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 14 16:57:36.458 INFO [stdout] 
Dec 14 16:57:36.458 INFO [stdout] 
Dec 14 16:57:36.458 INFO [stdout] running 3 tests
Dec 14 16:57:36.487 INFO [stdout] test barrier_sync_1w ... ok
Dec 14 16:57:37.662 INFO [stdout] test barrier_sync_3w ... ok
Dec 14 16:57:37.664 INFO [stdout] test barrier_sync_2w ... ok
Dec 14 16:57:37.664 INFO [stdout] 
Dec 14 16:57:37.664 INFO [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 14 16:57:37.664 INFO [stdout] 
Dec 14 16:57:37.665 INFO [stderr]      Running /target/debug/deps/skeptic-a01f409a5a8d6dad
Dec 14 16:57:37.671 INFO [stderr]    Doc-tests timely
Dec 14 16:57:37.671 INFO [stdout] 
Dec 14 16:57:37.671 INFO [stdout] running 0 tests
Dec 14 16:57:37.671 INFO [stdout] 
Dec 14 16:57:37.671 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 14 16:57:37.671 INFO [stdout] 
Dec 14 16:57:39.223 INFO [stdout] 
Dec 14 16:57:39.223 INFO [stdout] running 104 tests
Dec 14 16:58:12.987 INFO [stdout] test src/dataflow/mod.rs - dataflow (line 7) ... ok
Dec 14 16:58:14.635 INFO [stdout] test src/dataflow/operators/aggregation/aggregate.rs - dataflow::operators::aggregation::aggregate::Aggregate::aggregate (line 26) ... ok
Dec 14 16:58:17.619 INFO [stdout] test src/dataflow/operators/branch.rs - dataflow::operators::branch::BranchWhen::branch_when (line 81) ... ok
Dec 14 16:58:20.339 INFO [stdout] test src/dataflow/operators/aggregation/state_machine.rs - dataflow::operators::aggregation::state_machine::StateMachine::state_machine (line 28) ... ok
Dec 14 16:58:21.063 INFO [stdout] test src/dataflow/operators/broadcast.rs - dataflow::operators::broadcast::Broadcast::broadcast (line 22) ... ok
Dec 14 16:58:21.939 INFO [stdout] test src/dataflow/operators/capture/capture.rs - dataflow::operators::capture::capture::Capture::capture_into (line 31) ... ok
Dec 14 16:58:22.033 INFO [stdout] test src/dataflow/operators/branch.rs - dataflow::operators::branch::Branch::branch (line 19) ... ok
Dec 14 16:58:23.864 INFO [stdout] test src/dataflow/operators/aggregation/aggregate.rs - dataflow::operators::aggregation::aggregate::Aggregate::aggregate (line 47) ... ok
Dec 14 16:58:46.767 INFO [stdout] test src/dataflow/operators/capture/mod.rs - dataflow::operators::capture (line 21) ... ok
Dec 14 16:58:48.331 INFO [stdout] test src/dataflow/operators/capture/extract.rs - dataflow::operators::capture::extract::Extract::extract (line 14) ... ok
Dec 14 16:58:49.288 INFO [stdout] test src/dataflow/operators/concat.rs - dataflow::operators::concat::Concat::concat (line 13) ... ok
Dec 14 16:58:50.239 INFO [stdout] test src/dataflow/operators/capture/capture.rs - dataflow::operators::capture::capture::Capture::capture_into (line 70) ... ok
Dec 14 16:58:51.371 INFO [stdout] test src/dataflow/operators/capture/mod.rs - dataflow::operators::capture (line 48) ... ok
Dec 14 16:58:54.991 INFO [stdout] test src/dataflow/operators/count.rs - dataflow::operators::count::Accumulate::accumulate (line 17) ... ok
Dec 14 16:58:55.138 INFO [stdout] test src/dataflow/operators/concat.rs - dataflow::operators::concat::Concatenate::concatenate (line 37) ... ok
Dec 14 16:59:00.237 INFO [stdout] test src/dataflow/operators/count.rs - dataflow::operators::count::Accumulate::count (line 35) ... ok
Dec 14 16:59:19.797 INFO [stdout] test src/dataflow/operators/delay.rs - dataflow::operators::delay::Delay::delay (line 25) ... ok
Dec 14 16:59:26.083 INFO [stdout] test src/dataflow/operators/delay.rs - dataflow::operators::delay::Delay::delay_total (line 52) ... ok
Dec 14 16:59:28.119 INFO [stdout] test src/dataflow/operators/delay.rs - dataflow::operators::delay::Delay::delay_batch (line 80) ... ok
Dec 14 16:59:28.823 INFO [stdout] test src/dataflow/operators/enterleave.rs - dataflow::operators::enterleave (line 8) ... ok
Dec 14 16:59:30.302 INFO [stdout] test src/dataflow/operators/enterleave.rs - dataflow::operators::enterleave::Enter::enter (line 45) ... ok
Dec 14 16:59:35.489 INFO [stdout] test src/dataflow/operators/exchange.rs - dataflow::operators::exchange::Exchange::exchange (line 15) ... ok
Dec 14 16:59:36.095 INFO [stdout] test src/dataflow/operators/enterleave.rs - dataflow::operators::enterleave::Leave::leave (line 107) ... ok
Dec 14 16:59:40.159 INFO [stdout] test src/dataflow/operators/enterleave.rs - dataflow::operators::enterleave::EnterAt::enter_at (line 66) ... ok
Dec 14 16:59:57.810 INFO [stdout] test src/dataflow/operators/feedback.rs - dataflow::operators::feedback::ConnectLoop::connect_loop (line 145) ... ok
Dec 14 16:59:59.442 INFO [stdout] test src/dataflow/operators/filter.rs - dataflow::operators::filter::Filter::filter (line 13) ... ok
Dec 14 17:00:06.251 INFO [stdout] test src/dataflow/operators/feedback.rs - dataflow::operators::feedback::Feedback::feedback (line 29) ... ok
Dec 14 17:00:16.643 INFO [stdout] test src/dataflow/operators/generic/handles.rs - dataflow::operators::generic::handles::FrontieredInputHandle<'a, T, D, P>::for_each (line 110) ... ok
Dec 14 17:00:18.159 INFO [stdout] test src/dataflow/operators/generic/handles.rs - dataflow::operators::generic::handles::InputHandle<T, D, P>::for_each (line 63) ... ok
Dec 14 17:00:22.499 INFO [stdout] test src/dataflow/operators/feedback.rs - dataflow::operators::feedback::LoopVariable::loop_variable (line 55) ... ok
Dec 14 17:00:24.283 INFO [stdout] test src/dataflow/operators/generic/handles.rs - dataflow::operators::generic::handles::OutputHandle<'a, T, D, P>::session (line 194) ... ok
Dec 14 17:00:30.315 INFO [stdout] test src/dataflow/operators/flow_controlled.rs - dataflow::operators::flow_controlled::iterator_source (line 36) ... test src/dataflow/operators/flow_controlled.rs - dataflow::operators::flow_controlled::iterator_source (line 36) has been running for over 60 seconds
Dec 14 17:00:30.351 INFO [stdout] test src/dataflow/operators/flow_controlled.rs - dataflow::operators::flow_controlled::iterator_source (line 36) ... ok
Dec 14 17:00:56.289 INFO [stdout] test src/dataflow/operators/generic/notificator.rs - dataflow::operators::generic::notificator::FrontierNotificator<T>::notify_at (line 262) ... ok
Dec 14 17:00:57.819 INFO [stdout] test src/dataflow/operators/generic/notificator.rs - dataflow::operators::generic::notificator::FrontierNotificator (line 186) ... test src/dataflow/operators/generic/notificator.rs - dataflow::operators::generic::notificator::FrontierNotificator (line 186) has been running for over 60 seconds
Dec 14 17:01:06.253 INFO [stdout] test src/dataflow/operators/generic/notificator.rs - dataflow::operators::generic::notificator::FrontierNotificator<T>::pending (line 378) ... test src/dataflow/operators/generic/notificator.rs - dataflow::operators::generic::notificator::FrontierNotificator<T>::pending (line 378) has been running for over 60 seconds
Dec 14 17:01:06.253 INFO [stdout] test src/dataflow/operators/generic/notificator.rs - dataflow::operators::generic::notificator::FrontierNotificator (line 186) ... ok
Dec 14 17:01:13.127 INFO [stdout] test src/dataflow/operators/generic/notificator.rs - dataflow::operators::generic::notificator::FrontierNotificator<T>::pending (line 378) ... ok
Dec 14 17:01:16.647 INFO [stdout] test src/dataflow/operators/generic/notificator.rs - dataflow::operators::generic::notificator::Notificator<'a, T>::notify_at (line 52) ... test src/dataflow/operators/generic/notificator.rs - dataflow::operators::generic::notificator::Notificator<'a, T>::notify_at (line 52) has been running for over 60 seconds
Dec 14 17:01:18.167 INFO [stdout] test src/dataflow/operators/generic/operator.rs - dataflow::operators::generic::operator::Operator::binary (line 260) ... test src/dataflow/operators/generic/operator.rs - dataflow::operators::generic::operator::Operator::binary (line 260) has been running for over 60 seconds
Dec 14 17:01:22.511 INFO [stdout] test src/dataflow/operators/generic/operator.rs - dataflow::operators::generic::operator::Operator::binary_frontier (line 144) ... test src/dataflow/operators/generic/operator.rs - dataflow::operators::generic::operator::Operator::binary_frontier (line 144) has been running for over 60 seconds
Dec 14 17:01:23.543 INFO [stdout] test src/dataflow/operators/generic/operator.rs - dataflow::operators::generic::operator::Operator::binary (line 260) ... ok
Dec 14 17:01:24.203 INFO [stdout] test src/dataflow/operators/generic/notificator.rs - dataflow::operators::generic::notificator::Notificator<'a, T>::notify_at (line 52) ... ok
Dec 14 17:01:24.287 INFO [stdout] test src/dataflow/operators/generic/operator.rs - dataflow::operators::generic::operator::Operator::binary_notify (line 205) ... test src/dataflow/operators/generic/operator.rs - dataflow::operators::generic::operator::Operator::binary_notify (line 205) has been running for over 60 seconds
Dec 14 17:01:28.475 INFO [stdout] test src/dataflow/operators/generic/operator.rs - dataflow::operators::generic::operator::Operator::binary_notify (line 205) ... ok
Dec 14 17:01:29.987 INFO [stdout] test src/dataflow/operators/generic/operator.rs - dataflow::operators::generic::operator::Operator::binary_frontier (line 144) ... ok
Dec 14 17:01:30.358 INFO [stdout] test src/dataflow/operators/generic/operator.rs - dataflow::operators::generic::operator::Operator::sink (line 305) ... test src/dataflow/operators/generic/operator.rs - dataflow::operators::generic::operator::Operator::sink (line 305) has been running for over 60 seconds
Dec 14 17:01:33.215 INFO [stdout] test src/dataflow/operators/generic/operator.rs - dataflow::operators::generic::operator::Operator::sink (line 305) ... ok
Dec 14 17:01:56.327 INFO [stdout] test src/dataflow/operators/generic/operator.rs - dataflow::operators::generic::operator::Operator::unary (line 108) ... test src/dataflow/operators/generic/operator.rs - dataflow::operators::generic::operator::Operator::unary (line 108) has been running for over 60 seconds
Dec 14 17:02:00.055 INFO [stdout] test src/dataflow/operators/generic/operator.rs - dataflow::operators::generic::operator::Operator::unary (line 108) ... ok
Dec 14 17:02:06.303 INFO [stdout] test src/dataflow/operators/generic/operator.rs - dataflow::operators::generic::operator::Operator::unary_frontier (line 25) ... test src/dataflow/operators/generic/operator.rs - dataflow::operators::generic::operator::Operator::unary_frontier (line 25) has been running for over 60 seconds
Dec 14 17:02:12.595 INFO [stdout] test src/dataflow/operators/generic/operator.rs - dataflow::operators::generic::operator::Operator::unary_frontier (line 25) ... ok
Dec 14 17:02:13.147 INFO [stdout] test src/dataflow/operators/generic/operator.rs - dataflow::operators::generic::operator::Operator::unary_notify (line 72) ... test src/dataflow/operators/generic/operator.rs - dataflow::operators::generic::operator::Operator::unary_notify (line 72) has been running for over 60 seconds
Dec 14 17:02:15.054 INFO [stdout] test src/dataflow/operators/generic/operator.rs - dataflow::operators::generic::operator::Operator::unary_notify (line 72) ... ok
Dec 14 17:02:16.873 INFO [stdout] test src/dataflow/operators/generic/operator.rs - dataflow::operators::generic::operator::empty (line 592) ... ok
Dec 14 17:02:18.231 INFO [stdout] test src/dataflow/operators/generic/operator.rs - dataflow::operators::generic::operator::source (line 533) ... ok
Dec 14 17:02:26.467 INFO [stdout] test src/dataflow/operators/input.rs - dataflow::operators::input::Handle<T, D>::to_stream (line 204) ... ok
Dec 14 17:02:26.719 INFO [stdout] test src/dataflow/operators/input.rs - dataflow::operators::input::Handle<T, D>::new (line 168) ... ok
Dec 14 17:02:29.635 INFO [stdout] test src/dataflow/operators/input.rs - dataflow::operators::input::Input::input_from (line 67) ... ok
Dec 14 17:02:52.760 INFO [stdout] test src/dataflow/operators/input.rs - dataflow::operators::input::Input::new_input (line 36) ... ok
Dec 14 17:02:58.299 INFO [stdout] test src/dataflow/operators/inspect.rs - dataflow::operators::inspect::Inspect::inspect (line 13) ... ok
Dec 14 17:03:00.667 INFO [stdout] test src/dataflow/operators/inspect.rs - dataflow::operators::inspect::Inspect::inspect_batch (line 49) ... ok
Dec 14 17:03:02.383 INFO [stdout] test src/dataflow/operators/map.rs - dataflow::operators::map::Map::flat_map (line 39) ... ok
Dec 14 17:03:02.900 INFO [stdout] test src/dataflow/operators/inspect.rs - dataflow::operators::inspect::Inspect::inspect_time (line 30) ... ok
Dec 14 17:03:07.235 INFO [stdout] test src/dataflow/operators/map.rs - dataflow::operators::map::Map::map (line 13) ... ok
Dec 14 17:03:09.239 INFO [stdout] test src/dataflow/operators/map.rs - dataflow::operators::map::Map::map_in_place (line 26) ... ok
Dec 14 17:03:11.959 INFO [stdout] test src/dataflow/operators/partition.rs - dataflow::operators::partition::Partition::partition (line 22) ... ok
Dec 14 17:03:14.845 INFO [stdout] test src/dataflow/operators/probe.rs - dataflow::operators::probe::Handle<T>::with_frontier (line 159) ... ok
Dec 14 17:03:41.059 INFO [stdout] test src/dataflow/operators/probe.rs - dataflow::operators::probe::Probe::probe (line 23) ... ok
Dec 14 17:03:45.091 INFO [stdout] test src/dataflow/operators/probe.rs - dataflow::operators::probe::Probe::probe_with (line 52) ... ok
Dec 14 17:03:46.391 INFO [stdout] test src/dataflow/operators/reclock.rs - dataflow::operators::reclock::Reclock::reclock (line 21) ... ok
Dec 14 17:03:47.174 INFO [stdout] test src/dataflow/operators/to_stream.rs - dataflow::operators::to_stream::ToStream::to_stream (line 16) ... ok
Dec 14 17:03:54.775 INFO [stdout] test src/dataflow/scopes/mod.rs - dataflow::scopes::Scope::region (line 136) ... ok
Dec 14 17:03:55.570 INFO [stdout] test src/dataflow/operators/unordered_input.rs - dataflow::operators::unordered_input::UnorderedInput::new_unordered_input (line 39) ... ok
Dec 14 17:03:58.479 INFO [stdout] test src/dataflow/scopes/mod.rs - dataflow::scopes::Scope::iterative (line 106) ... ok
Dec 14 17:03:58.479 INFO [stdout] test src/execute.rs - execute::execute_from_args (line 221) ... ignored
Dec 14 17:03:58.479 INFO [stdout] test src/execute.rs - execute::execute_from_args (line 227) ... ignored
Dec 14 17:04:04.431 INFO [stdout] test src/dataflow/scopes/mod.rs - dataflow::scopes::Scope::scoped (line 78) ... ok
Dec 14 17:04:22.783 INFO [stdout] test src/execute.rs - execute::example (line 38) ... ok
Dec 14 17:04:26.627 INFO [stdout] test src/progress/change_batch.rs - progress::change_batch::ChangeBatch<T>::clear (line 164) ... ok
Dec 14 17:04:30.923 INFO [stdout] test src/execute.rs - execute::execute (line 81) ... ok
Dec 14 17:04:33.491 INFO [stdout] test src/execute.rs - execute::execute (line 97) ... ok
Dec 14 17:04:34.139 INFO [stdout] test src/execute.rs - execute::example (line 24) ... ok
Dec 14 17:04:39.787 INFO [stdout] test src/execute.rs - execute::execute_from (line 246) ... ok
Dec 14 17:04:41.927 INFO [stdout] test src/execute.rs - execute::execute_from_args (line 210) ... ok
Dec 14 17:04:42.071 INFO [stdout] test src/lib.rs -  (line 25) ... ok
Dec 14 17:04:51.429 INFO [stdout] test src/progress/change_batch.rs - progress::change_batch::ChangeBatch<T>::drain (line 142) ... ok
Dec 14 17:04:51.503 INFO [stdout] test src/progress/change_batch.rs - progress::change_batch::ChangeBatch<T>::extend (line 85) ... ok
Dec 14 17:04:53.818 INFO [stdout] test src/progress/change_batch.rs - progress::change_batch::ChangeBatch<T>::drain_into (line 220) ... ok
Dec 14 17:04:56.743 INFO [stdout] test src/progress/change_batch.rs - progress::change_batch::ChangeBatch<T>::is_empty (line 186) ... ok
Dec 14 17:04:58.311 INFO [stdout] test src/progress/change_batch.rs - progress::change_batch::ChangeBatch<T>::into_inner (line 103) ... ok
Dec 14 17:05:00.751 INFO [stdout] test src/progress/change_batch.rs - progress::change_batch::ChangeBatch<T>::iter (line 118) ... ok
Dec 14 17:05:02.563 INFO [stdout] test src/progress/change_batch.rs - progress::change_batch::ChangeBatch<T>::new (line 26) ... ok
Dec 14 17:05:04.727 INFO [stdout] test src/progress/change_batch.rs - progress::change_batch::ChangeBatch<T>::new_from (line 43) ... ok
Dec 14 17:05:12.385 INFO [stdout] test src/progress/frontier.rs - progress::frontier::AntichainRef<'a, T>::iter (line 400) ... ok
Dec 14 17:05:12.919 INFO [stdout] test src/progress/frontier.rs - progress::frontier::AntichainRef<'a, T>::is_empty (line 385) ... ok
Dec 14 17:05:14.811 INFO [stdout] test src/progress/change_batch.rs - progress::change_batch::ChangeBatch<T>::update (line 69) ... ok
Dec 14 17:05:16.511 INFO [stdout] test src/progress/frontier.rs - progress::frontier::MutableAntichain<T>::clear (line 118) ... ok
Dec 14 17:05:18.449 INFO [stdout] test src/progress/frontier.rs - progress::frontier::AntichainRef<'a, T>::less_equal (line 433) ... ok
Dec 14 17:05:18.823 INFO [stdout] test src/progress/frontier.rs - progress::frontier::AntichainRef<'a, T>::less_than (line 416) ... ok
Dec 14 17:05:21.187 INFO [stdout] test src/progress/frontier.rs - progress::frontier::MutableAntichain<T>::frontier (line 143) ... ok
Dec 14 17:05:23.187 INFO [stdout] test src/progress/frontier.rs - progress::frontier::MutableAntichain<T>::is_empty (line 179) ... ok
Dec 14 17:05:28.391 INFO [stdout] test src/progress/frontier.rs - progress::frontier::MutableAntichain<T>::less_equal (line 213) ... ok
Dec 14 17:05:30.959 INFO [stdout] test src/progress/frontier.rs - progress::frontier::MutableAntichain<T>::less_than (line 195) ... ok
Dec 14 17:05:31.623 INFO [stdout] test src/progress/frontier.rs - progress::frontier::MutableAntichain<T>::new (line 98) ... ok
Dec 14 17:05:33.570 INFO [stdout] test src/progress/frontier.rs - progress::frontier::MutableAntichain<T>::update_iter (line 245) ... ok
Dec 14 17:05:35.615 INFO [stdout] test src/progress/frontier.rs - progress::frontier::MutableAntichain<T>::new_bottom (line 159) ... ok
Dec 14 17:05:38.883 INFO [stdout] test src/progress/frontier.rs - progress::frontier::MutableAntichain<T>::update_iter_and (line 268) ... ok
Dec 14 17:05:39.786 INFO [stdout] test src/progress/nested/reachability.rs - progress::nested::reachability::Builder (line 72) ... ok
Dec 14 17:05:41.839 INFO [stdout] test src/progress/nested/reachability.rs - progress::nested::reachability (line 10) ... ok
Dec 14 17:05:44.503 INFO [stdout] test src/progress/timestamp.rs - progress::timestamp::PathSummary::results_in (line 30) ... ok
Dec 14 17:05:45.128 INFO [stdout] test src/progress/nested/reachability.rs - progress::nested::reachability::Tracker (line 299) ... ok
Dec 14 17:05:45.604 INFO [stdout] test src/progress/nested/reachability_neu.rs - progress::nested::reachability_neu::Builder (line 107) ... ok
Dec 14 17:05:45.655 INFO [stdout] test src/progress/timestamp.rs - progress::timestamp::PathSummary::followed_by (line 51) ... ok
Dec 14 17:05:45.656 INFO [stdout] test src/progress/nested/reachability_neu.rs - progress::nested::reachability_neu (line 10) ... ok
Dec 14 17:05:45.656 INFO [stdout] 
Dec 14 17:05:45.656 INFO [stdout] test result: ok. 102 passed; 0 failed; 2 ignored; 0 measured; 0 filtered out
Dec 14 17:05:45.656 INFO [stdout] 
Dec 14 17:05:45.693 INFO [stderr] su: No module specific data is present
Dec 14 17:05:45.969 INFO running `"docker" "inspect" "732c0a850edbcbe63e5a2384ff5b3a0572486954f55407020db724bde3991620"`
Dec 14 17:05:46.159 INFO running `"docker" "rm" "-f" "732c0a850edbcbe63e5a2384ff5b3a0572486954f55407020db724bde3991620"`
Dec 14 17:05:46.311 INFO [stdout] 732c0a850edbcbe63e5a2384ff5b3a0572486954f55407020db724bde3991620