Dec 06 20:07:14.739 INFO testing timely-0.8.0 against try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468 for pr-56514
Dec 06 20:07:14.739 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-3/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/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 20:07:14.875 INFO [stdout] 275aeb9eb582972616bd73613d35b2b3cadc060f58a0997c9b8fbd23ad8c7e5d
Dec 06 20:07:14.877 INFO running `"docker" "start" "-a" "275aeb9eb582972616bd73613d35b2b3cadc060f58a0997c9b8fbd23ad8c7e5d"`
Dec 06 20:07:15.223 INFO [stderr] usermod: no changes
Dec 06 20:07:15.294 INFO [stderr]    Compiling timely_bytes v0.7.0
Dec 06 20:07:15.294 INFO [stderr]    Compiling timely_logging v0.7.0
Dec 06 20:07:15.294 INFO [stderr]    Compiling abomonation v0.7.0
Dec 06 20:07:15.295 INFO [stderr]    Compiling abomonation_derive v0.3.0
Dec 06 20:07:18.593 INFO [stderr]    Compiling timely_communication v0.8.0
Dec 06 20:07:21.544 INFO [stderr]    Compiling timely v0.8.0 (/source)
Dec 06 20:07:26.746 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 11.50s
Dec 06 20:07:26.751 INFO [stderr] su: No module specific data is present
Dec 06 20:07:26.999 INFO running `"docker" "inspect" "275aeb9eb582972616bd73613d35b2b3cadc060f58a0997c9b8fbd23ad8c7e5d"`
Dec 06 20:07:27.111 INFO running `"docker" "rm" "-f" "275aeb9eb582972616bd73613d35b2b3cadc060f58a0997c9b8fbd23ad8c7e5d"`
Dec 06 20:07:27.263 INFO [stdout] 275aeb9eb582972616bd73613d35b2b3cadc060f58a0997c9b8fbd23ad8c7e5d
Dec 06 20:07:27.268 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-3/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/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 20:07:27.398 INFO [stdout] b24c47228619f8a82bbfe1383103d0d5c1100ec3487703c17eb17eb4a195bb64
Dec 06 20:07:27.401 INFO running `"docker" "start" "-a" "b24c47228619f8a82bbfe1383103d0d5c1100ec3487703c17eb17eb4a195bb64"`
Dec 06 20:07:27.976 INFO [stderr] usermod: no changes
Dec 06 20:07:28.065 INFO [stderr]    Compiling timely_sort v0.1.6
Dec 06 20:07:28.594 INFO [stderr]    Compiling timely v0.8.0 (/source)
Dec 06 20:09:58.923 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 2m 30s
Dec 06 20:09:58.952 INFO [stderr] su: No module specific data is present
Dec 06 20:09:59.315 INFO running `"docker" "inspect" "b24c47228619f8a82bbfe1383103d0d5c1100ec3487703c17eb17eb4a195bb64"`
Dec 06 20:09:59.462 INFO running `"docker" "rm" "-f" "b24c47228619f8a82bbfe1383103d0d5c1100ec3487703c17eb17eb4a195bb64"`
Dec 06 20:09:59.566 INFO [stdout] b24c47228619f8a82bbfe1383103d0d5c1100ec3487703c17eb17eb4a195bb64
Dec 06 20:09:59.570 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-3/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/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 20:09:59.719 INFO [stdout] 7da15096d450a6240b7817fe9603fa4222032d5bc71b659909cefc3474c16171
Dec 06 20:09:59.726 INFO running `"docker" "start" "-a" "7da15096d450a6240b7817fe9603fa4222032d5bc71b659909cefc3474c16171"`
Dec 06 20:10:00.179 INFO [stderr] usermod: no changes
Dec 06 20:10:00.281 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.07s
Dec 06 20:10:00.301 INFO [stderr]      Running /target/debug/deps/timely-ce1eed45ff9a7b89
Dec 06 20:10:00.303 INFO [stdout] 
Dec 06 20:10:00.303 INFO [stdout] running 5 tests
Dec 06 20:10:00.304 INFO [stdout] test dataflow::operators::generic::builder_rc::tests::correct_capabilities ... ok
Dec 06 20:10:00.304 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 06 20:10:00.304 INFO [stderr] stack backtrace:
Dec 06 20:10:00.308 INFO [stdout] test dataflow::operators::generic::notificator::notificator_delivers_notifications_in_topo_order ... ok
Dec 06 20:10:00.310 INFO [stdout] test dataflow::operators::probe::tests::probe ... ok
Dec 06 20:10:00.340 INFO [stderr]    0:     0x55a84802dd83 - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::h82c998a0e635a5c4
Dec 06 20:10:00.354 INFO [stderr]                                at src/libstd/sys/unix/backtrace/tracing/gcc_s.rs:49
Dec 06 20:10:00.354 INFO [stderr]    1:     0x55a848027e68 - std::sys_common::backtrace::_print::h5fbc0bc3cf12d9bf
Dec 06 20:10:00.354 INFO [stderr]                                at src/libstd/sys_common/backtrace.rs:71
Dec 06 20:10:00.354 INFO [stderr]    2:     0x55a84802c742 - std::panicking::default_hook::{{closure}}::h42b81c3dd1a50e32
Dec 06 20:10:00.354 INFO [stderr]                                at src/libstd/sys_common/backtrace.rs:59
Dec 06 20:10:00.354 INFO [stderr]                                at src/libstd/panicking.rs:211
Dec 06 20:10:00.354 INFO [stderr]    3:     0x55a84802c4ad - std::panicking::default_hook::h6c8d8e1c12a669e5
Dec 06 20:10:00.354 INFO [stderr]                                at src/libstd/panicking.rs:227
Dec 06 20:10:00.354 INFO [stderr]    4:     0x55a84802ce50 - std::panicking::rust_panic_with_hook::hce93a56e38c2b0aa
Dec 06 20:10:00.354 INFO [stderr]                                at src/libstd/panicking.rs:491
Dec 06 20:10:00.354 INFO [stderr]    5:     0x55a848012627 - std::panicking::begin_panic::hb1b19dae59bf4767
Dec 06 20:10:00.354 INFO [stderr]                                at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/panicking.rs:425
Dec 06 20:10:00.354 INFO [stderr]    6:     0x55a847f0ef9c - <timely::dataflow::operators::generic::handles::OutputHandle<'a, T, D, P>>::session::h1a1d7c44d680d171
Dec 06 20:10:00.357 INFO [stderr]                                at src/dataflow/operators/generic/handles.rs:212
Dec 06 20:10:00.357 INFO [stderr]    7:     0x55a847e72d8d - timely::dataflow::operators::generic::builder_rc::tests::incorrect_capabilities::{{closure}}::{{closure}}::{{closure}}::h6b5b56e3d5576d5f
Dec 06 20:10:00.359 INFO [stderr]                                at src/dataflow/operators/generic/builder_rc.rs:200
Dec 06 20:10:00.359 INFO [stderr]    8:     0x55a847e06083 - <timely::dataflow::operators::generic::builder_rc::OperatorBuilder<G>>::build::{{closure}}::hb0036b3cc2c7863d
Dec 06 20:10:00.361 INFO [stderr]                                at src/dataflow/operators/generic/builder_rc.rs:136
Dec 06 20:10:00.361 INFO [stderr]    9:     0x55a847e1f380 - <timely::dataflow::operators::generic::builder_raw::OperatorCore<T, PEP, PIP> as timely::progress::operate::Operate<T>>::pull_internal_progress::h92fca6a7f3ed3f84
Dec 06 20:10:00.363 INFO [stderr]                                at src/dataflow/operators/generic/builder_raw.rs:219
Dec 06 20:10:00.363 INFO [stderr]   10:     0x55a847f66fd0 - <timely::progress::nested::subgraph::PerOperatorState<T>>::exchange_progress::hbdc784363975a8fd
Dec 06 20:10:00.365 INFO [stderr]                                at src/progress/nested/subgraph.rs:803
Dec 06 20:10:00.365 INFO [stderr]   11:     0x55a847f6129a - <timely::progress::nested::subgraph::Subgraph<TOuter, TInner> as timely::progress::operate::Operate<TOuter>>::pull_internal_progress::he0f57f991d153e4b
Dec 06 20:10:00.365 INFO [stderr]                                at src/progress/nested/subgraph.rs:536
Dec 06 20:10:00.365 INFO [stderr]   12:     0x55a847f4a55a - timely::worker::Wrapper::step::{{closure}}::he9bd82937eefd77e
Dec 06 20:10:00.368 INFO [stderr]                                at src/worker.rs:197
Dec 06 20:10:00.368 INFO [stderr]   13:     0x55a847e0e944 - <core::option::Option<T>>::map::h4f7993e3b93960f5
Dec 06 20:10:00.368 INFO [stderr]                                at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libcore/option.rs:424
Dec 06 20:10:00.368 INFO [stderr]   14:     0x55a847f4a413 - timely::worker::Wrapper::step::h0d31f75bdf737bb3
Dec 06 20:10:00.368 INFO [stderr]                                at src/worker.rs:197
Dec 06 20:10:00.368 INFO [stderr]   15:     0x55a847f47533 - <timely::worker::Worker<A>>::step::h99b1f2b350e75943
Dec 06 20:10:00.368 INFO [stderr]                                at src/worker.rs:68
Dec 06 20:10:00.368 INFO [stderr]   16:     0x55a847e350de - timely::execute::example::{{closure}}::h2f8b75e151fce13b
Dec 06 20:10:00.370 INFO [stderr]                                at src/execute.rs:59
Dec 06 20:10:00.370 INFO [stderr]   17:     0x55a847df1870 - timely_communication::initialize::initialize_from::{{closure}}::h86b9f323bab32448
Dec 06 20:10:00.370 INFO [stderr]                                at /cargo-home/registry/src/github.com-1ecc6299db9ec823/timely_communication-0.8.0/src/initialize.rs:261
Dec 06 20:10:00.370 INFO [stderr]   18:     0x55a847ed4ba2 - std::sys_common::backtrace::__rust_begin_short_backtrace::h28c8acbb4369a93a
Dec 06 20:10:00.372 INFO [stderr]                                at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/sys_common/backtrace.rs:136
Dec 06 20:10:00.372 INFO [stderr]   19:     0x55a847ef17d4 - std::thread::Builder::spawn_unchecked::{{closure}}::{{closure}}::h53789469ca325f1f
Dec 06 20:10:00.372 INFO [stderr]                                at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/thread/mod.rs:477
Dec 06 20:10:00.373 INFO [stderr]   20:     0x55a847eec2d4 - <std::panic::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::he8e0bb42490ab5bf
Dec 06 20:10:00.373 INFO [stderr]                                at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/panic.rs:319
Dec 06 20:10:00.373 INFO [stderr]   21:     0x55a847ef1f97 - std::panicking::try::do_call::hb9cce92d03d7cdd4
Dec 06 20:10:00.373 INFO [stderr]                                at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/panicking.rs:310
Dec 06 20:10:00.374 INFO [stderr]   22:     0x55a84803c1a9 - __rust_maybe_catch_panic
Dec 06 20:10:00.374 INFO [stderr]                                at src/libpanic_unwind/lib.rs:102
Dec 06 20:10:00.374 INFO [stderr]   23:     0x55a847ef1a98 - std::panicking::try::h2310f67025adef81
Dec 06 20:10:00.374 INFO [stderr]                                at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/panicking.rs:289
Dec 06 20:10:00.374 INFO [stderr]   24:     0x55a847eef854 - std::panic::catch_unwind::h9f2f6ab394b642f1
Dec 06 20:10:00.375 INFO [stderr]                                at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/panic.rs:398
Dec 06 20:10:00.375 INFO [stderr]   25:     0x55a847ef15cd - std::thread::Builder::spawn_unchecked::{{closure}}::hab9cda51f02a76c7
Dec 06 20:10:00.375 INFO [stderr]                                at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/thread/mod.rs:476
Dec 06 20:10:00.376 INFO [stderr]   26:     0x55a847f07ec2 - <F as alloc::boxed::FnBox<A>>::call_box::h0653696d5c16f36d
Dec 06 20:10:00.376 INFO [stderr]                                at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/liballoc/boxed.rs:673
Dec 06 20:10:00.376 INFO [stderr]   27:     0x55a8480300cd - std::sys::unix::thread::Thread::new::thread_start::h0fe9c527147fd5f5
Dec 06 20:10:00.376 INFO [stderr]                                at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/liballoc/boxed.rs:683
Dec 06 20:10:00.376 INFO [stderr]                                at src/libstd/sys_common/thread.rs:24
Dec 06 20:10:00.376 INFO [stderr]                                at src/libstd/sys/unix/thread.rs:90
Dec 06 20:10:00.376 INFO [stderr]   28:     0x7f01a31ca6b9 - start_thread
Dec 06 20:10:00.376 INFO [stderr]   29:     0x7f01a2cea41c - clone
Dec 06 20:10:00.376 INFO [stderr]   30:                0x0 - <unknown>
Dec 06 20:10:00.400 INFO [stdout] test dataflow::operators::generic::builder_rc::tests::incorrect_capabilities ... ok
Dec 06 20:10:01.929 INFO [stdout] test dataflow::operators::capture::event::link::avoid_stack_overflow_in_drop ... ok
Dec 06 20:10:01.929 INFO [stdout] 
Dec 06 20:10:01.929 INFO [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 06 20:10:01.929 INFO [stdout] 
Dec 06 20:10:01.940 INFO [stderr]      Running /target/debug/deps/barrier-bfe3b5abcfe9a757
Dec 06 20:10:01.942 INFO [stdout] 
Dec 06 20:10:01.942 INFO [stdout] running 3 tests
Dec 06 20:10:01.956 INFO [stdout] test barrier_sync_1w ... ok
Dec 06 20:10:01.960 INFO [stdout] test barrier_sync_2w ... ok
Dec 06 20:10:01.972 INFO [stdout] test barrier_sync_3w ... ok
Dec 06 20:10:01.972 INFO [stdout] 
Dec 06 20:10:01.972 INFO [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 06 20:10:01.972 INFO [stdout] 
Dec 06 20:10:01.973 INFO [stderr]      Running /target/debug/deps/skeptic-5f5df1ff58f3a685
Dec 06 20:10:01.975 INFO [stdout] 
Dec 06 20:10:01.975 INFO [stdout] running 0 tests
Dec 06 20:10:01.975 INFO [stdout] 
Dec 06 20:10:01.975 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 06 20:10:01.975 INFO [stdout] 
Dec 06 20:10:01.975 INFO [stderr]    Doc-tests timely
Dec 06 20:10:03.468 INFO [stdout] 
Dec 06 20:10:03.468 INFO [stdout] running 104 tests
Dec 06 20:10:33.884 INFO [stdout] test src/dataflow/operators/branch.rs - dataflow::operators::branch::Branch::branch (line 19) ... ok
Dec 06 20:10:39.036 INFO [stdout] test src/dataflow/mod.rs - dataflow (line 7) ... ok
Dec 06 20:10:41.874 INFO [stdout] test src/dataflow/operators/aggregation/aggregate.rs - dataflow::operators::aggregation::aggregate::Aggregate::aggregate (line 47) ... ok
Dec 06 20:10:44.424 INFO [stdout] test src/dataflow/operators/broadcast.rs - dataflow::operators::broadcast::Broadcast::broadcast (line 22) ... ok
Dec 06 20:10:45.937 INFO [stdout] test src/dataflow/operators/branch.rs - dataflow::operators::branch::BranchWhen::branch_when (line 81) ... ok
Dec 06 20:10:46.548 INFO [stdout] test src/dataflow/operators/aggregation/aggregate.rs - dataflow::operators::aggregation::aggregate::Aggregate::aggregate (line 26) ... ok
Dec 06 20:10:47.513 INFO [stdout] test src/dataflow/operators/capture/capture.rs - dataflow::operators::capture::capture::Capture::capture_into (line 31) ... ok
Dec 06 20:10:48.155 INFO [stdout] test src/dataflow/operators/aggregation/state_machine.rs - dataflow::operators::aggregation::state_machine::StateMachine::state_machine (line 28) ... ok
Dec 06 20:11:11.538 INFO [stdout] test src/dataflow/operators/capture/mod.rs - dataflow::operators::capture (line 48) ... ok
Dec 06 20:11:13.304 INFO [stdout] test src/dataflow/operators/capture/capture.rs - dataflow::operators::capture::capture::Capture::capture_into (line 70) ... ok
Dec 06 20:11:13.344 INFO [stdout] test src/dataflow/operators/capture/mod.rs - dataflow::operators::capture (line 21) ... ok
Dec 06 20:11:13.466 INFO [stdout] test src/dataflow/operators/concat.rs - dataflow::operators::concat::Concat::concat (line 13) ... ok
Dec 06 20:11:17.297 INFO [stdout] test src/dataflow/operators/capture/extract.rs - dataflow::operators::capture::extract::Extract::extract (line 14) ... ok
Dec 06 20:11:17.940 INFO [stdout] test src/dataflow/operators/concat.rs - dataflow::operators::concat::Concatenate::concatenate (line 37) ... ok
Dec 06 20:11:20.895 INFO [stdout] test src/dataflow/operators/count.rs - dataflow::operators::count::Accumulate::accumulate (line 17) ... ok
Dec 06 20:11:22.588 INFO [stdout] test src/dataflow/operators/count.rs - dataflow::operators::count::Accumulate::count (line 35) ... ok
Dec 06 20:11:46.972 INFO [stdout] test src/dataflow/operators/delay.rs - dataflow::operators::delay::Delay::delay (line 25) ... ok
Dec 06 20:11:48.534 INFO [stdout] test src/dataflow/operators/enterleave.rs - dataflow::operators::enterleave (line 8) ... ok
Dec 06 20:11:49.252 INFO [stdout] test src/dataflow/operators/enterleave.rs - dataflow::operators::enterleave::Enter::enter (line 45) ... ok
Dec 06 20:11:50.117 INFO [stdout] test src/dataflow/operators/delay.rs - dataflow::operators::delay::Delay::delay_total (line 52) ... ok
Dec 06 20:11:53.845 INFO [stdout] test src/dataflow/operators/delay.rs - dataflow::operators::delay::Delay::delay_batch (line 80) ... ok
Dec 06 20:11:56.928 INFO [stdout] test src/dataflow/operators/enterleave.rs - dataflow::operators::enterleave::Leave::leave (line 107) ... ok
Dec 06 20:11:57.675 INFO [stdout] test src/dataflow/operators/enterleave.rs - dataflow::operators::enterleave::EnterAt::enter_at (line 66) ... ok
Dec 06 20:11:57.675 INFO [stdout] test src/dataflow/operators/exchange.rs - dataflow::operators::exchange::Exchange::exchange (line 15) ... ok
Dec 06 20:12:30.412 INFO [stdout] test src/dataflow/operators/filter.rs - dataflow::operators::filter::Filter::filter (line 13) ... ok
Dec 06 20:12:30.868 INFO [stdout] test src/dataflow/operators/feedback.rs - dataflow::operators::feedback::ConnectLoop::connect_loop (line 145) ... ok
Dec 06 20:12:35.668 INFO [stdout] test src/dataflow/operators/feedback.rs - dataflow::operators::feedback::Feedback::feedback (line 29) ... ok
Dec 06 20:12:39.592 INFO [stdout] test src/dataflow/operators/generic/handles.rs - dataflow::operators::generic::handles::InputHandle<T, D, P>::for_each (line 63) ... ok
Dec 06 20:12:43.297 INFO [stdout] test src/dataflow/operators/generic/handles.rs - dataflow::operators::generic::handles::FrontieredInputHandle<'a, T, D, P>::for_each (line 110) ... ok
Dec 06 20:12:46.267 INFO [stdout] test src/dataflow/operators/generic/handles.rs - dataflow::operators::generic::handles::OutputHandle<'a, T, D, P>::session (line 194) ... ok
Dec 06 20:12:48.447 INFO [stdout] test src/dataflow/operators/feedback.rs - dataflow::operators::feedback::LoopVariable::loop_variable (line 55) ... ok
Dec 06 20:12:53.876 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 06 20:12:56.271 INFO [stdout] test src/dataflow/operators/flow_controlled.rs - dataflow::operators::flow_controlled::iterator_source (line 36) ... ok
Dec 06 20:13:29.452 INFO [stdout] test src/dataflow/operators/generic/notificator.rs - dataflow::operators::generic::notificator::FrontierNotificator<T>::pending (line 378) ... ok
Dec 06 20:13:30.421 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 06 20:13:30.880 INFO [stdout] test src/dataflow/operators/generic/notificator.rs - dataflow::operators::generic::notificator::FrontierNotificator<T>::notify_at (line 262) ... test src/dataflow/operators/generic/notificator.rs - dataflow::operators::generic::notificator::FrontierNotificator<T>::notify_at (line 262) has been running for over 60 seconds
Dec 06 20:13:33.148 INFO [stdout] test src/dataflow/operators/generic/notificator.rs - dataflow::operators::generic::notificator::Notificator<'a, T>::notify_at (line 52) ... ok
Dec 06 20:13:37.536 INFO [stdout] test src/dataflow/operators/generic/notificator.rs - dataflow::operators::generic::notificator::FrontierNotificator<T>::notify_at (line 262) ... ok
Dec 06 20:13:37.548 INFO [stdout] test src/dataflow/operators/generic/notificator.rs - dataflow::operators::generic::notificator::FrontierNotificator (line 186) ... ok
Dec 06 20:13:41.368 INFO [stdout] test src/dataflow/operators/generic/operator.rs - dataflow::operators::generic::operator::Operator::binary (line 260) ... ok
Dec 06 20:13:45.000 INFO [stdout] test src/dataflow/operators/generic/operator.rs - dataflow::operators::generic::operator::Operator::binary_notify (line 205) ... ok
Dec 06 20:13:46.288 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 06 20:13:51.448 INFO [stdout] test src/dataflow/operators/generic/operator.rs - dataflow::operators::generic::operator::Operator::binary_frontier (line 144) ... ok
Dec 06 20:13:55.691 INFO [stdout] test src/dataflow/operators/generic/operator.rs - dataflow::operators::generic::operator::Operator::sink (line 305) ... ok
Dec 06 20:14:28.112 INFO [stdout] test src/dataflow/operators/generic/operator.rs - dataflow::operators::generic::operator::Operator::unary (line 108) ... ok
Dec 06 20:14:30.636 INFO [stdout] test src/dataflow/operators/generic/operator.rs - dataflow::operators::generic::operator::Operator::unary_frontier (line 25) ... ok
Dec 06 20:14:34.524 INFO [stdout] test src/dataflow/operators/generic/operator.rs - dataflow::operators::generic::operator::Operator::unary_notify (line 72) ... ok
Dec 06 20:14:34.964 INFO [stdout] test src/dataflow/operators/generic/operator.rs - dataflow::operators::generic::operator::source (line 533) ... ok
Dec 06 20:14:37.544 INFO [stdout] test src/dataflow/operators/generic/operator.rs - dataflow::operators::generic::operator::empty (line 592) ... test src/dataflow/operators/generic/operator.rs - dataflow::operators::generic::operator::empty (line 592) has been running for over 60 seconds
Dec 06 20:14:37.680 INFO [stdout] test src/dataflow/operators/generic/operator.rs - dataflow::operators::generic::operator::empty (line 592) ... ok
Dec 06 20:14:38.708 INFO [stdout] test src/dataflow/operators/input.rs - dataflow::operators::input::Handle<T, D>::new (line 168) ... ok
Dec 06 20:14:45.772 INFO [stdout] test src/dataflow/operators/input.rs - dataflow::operators::input::Handle<T, D>::to_stream (line 204) ... ok
Dec 06 20:14:50.607 INFO [stdout] test src/dataflow/operators/input.rs - dataflow::operators::input::Input::input_from (line 67) ... ok
Dec 06 20:15:15.423 INFO [stdout] test src/dataflow/operators/inspect.rs - dataflow::operators::inspect::Inspect::inspect (line 13) ... ok
Dec 06 20:15:18.397 INFO [stdout] test src/dataflow/operators/inspect.rs - dataflow::operators::inspect::Inspect::inspect_batch (line 49) ... ok
Dec 06 20:15:18.730 INFO [stdout] test src/dataflow/operators/input.rs - dataflow::operators::input::Input::new_input (line 36) ... ok
Dec 06 20:15:21.711 INFO [stdout] test src/dataflow/operators/map.rs - dataflow::operators::map::Map::flat_map (line 39) ... ok
Dec 06 20:15:22.244 INFO [stdout] test src/dataflow/operators/inspect.rs - dataflow::operators::inspect::Inspect::inspect_time (line 30) ... ok
Dec 06 20:15:25.360 INFO [stdout] test src/dataflow/operators/map.rs - dataflow::operators::map::Map::map (line 13) ... ok
Dec 06 20:15:28.540 INFO [stdout] test src/dataflow/operators/map.rs - dataflow::operators::map::Map::map_in_place (line 26) ... ok
Dec 06 20:15:40.493 INFO [stdout] test src/dataflow/operators/partition.rs - dataflow::operators::partition::Partition::partition (line 22) ... ok
Dec 06 20:15:44.072 INFO [stdout] test src/dataflow/operators/probe.rs - dataflow::operators::probe::Handle<T>::with_frontier (line 159) ... ok
Dec 06 20:15:59.344 INFO [stdout] test src/dataflow/operators/probe.rs - dataflow::operators::probe::Probe::probe_with (line 52) ... ok
Dec 06 20:15:59.717 INFO [stdout] test src/dataflow/operators/to_stream.rs - dataflow::operators::to_stream::ToStream::to_stream (line 16) ... ok
Dec 06 20:16:03.693 INFO [stdout] test src/dataflow/operators/probe.rs - dataflow::operators::probe::Probe::probe (line 23) ... ok
Dec 06 20:16:11.316 INFO [stdout] test src/dataflow/operators/unordered_input.rs - dataflow::operators::unordered_input::UnorderedInput::new_unordered_input (line 39) ... ok
Dec 06 20:16:11.644 INFO [stdout] test src/dataflow/operators/reclock.rs - dataflow::operators::reclock::Reclock::reclock (line 21) ... ok
Dec 06 20:16:28.553 INFO [stdout] test src/dataflow/scopes/mod.rs - dataflow::scopes::Scope::iterative (line 106) ... test src/dataflow/scopes/mod.rs - dataflow::scopes::Scope::iterative (line 106) has been running for over 60 seconds
Dec 06 20:16:34.536 INFO [stdout] test src/dataflow/scopes/mod.rs - dataflow::scopes::Scope::iterative (line 106) ... ok
Dec 06 20:16:39.632 INFO [stdout] test src/dataflow/scopes/mod.rs - dataflow::scopes::Scope::region (line 136) ... ok
Dec 06 20:16:39.632 INFO [stdout] test src/execute.rs - execute::execute_from_args (line 221) ... ignored
Dec 06 20:16:39.632 INFO [stdout] test src/execute.rs - execute::execute_from_args (line 227) ... ignored
Dec 06 20:16:44.068 INFO [stdout] test src/dataflow/scopes/mod.rs - dataflow::scopes::Scope::scoped (line 78) ... test src/dataflow/scopes/mod.rs - dataflow::scopes::Scope::scoped (line 78) has been running for over 60 seconds
Dec 06 20:16:55.504 INFO [stdout] test src/execute.rs - execute::example (line 24) ... ok
Dec 06 20:16:57.768 INFO [stdout] test src/dataflow/scopes/mod.rs - dataflow::scopes::Scope::scoped (line 78) ... ok
Dec 06 20:16:59.727 INFO [stdout] test src/execute.rs - execute::example (line 38) ... test src/execute.rs - execute::example (line 38) has been running for over 60 seconds
Dec 06 20:17:01.390 INFO [stdout] test src/execute.rs - execute::example (line 38) ... ok
Dec 06 20:17:03.530 INFO [stdout] test src/execute.rs - execute::execute (line 81) ... ok
Dec 06 20:17:10.012 INFO [stdout] test src/execute.rs - execute::execute (line 97) ... ok
Dec 06 20:17:10.032 INFO [stdout] test src/execute.rs - execute::execute_from (line 246) ... ok
Dec 06 20:17:18.892 INFO [stdout] test src/progress/change_batch.rs - progress::change_batch::ChangeBatch<T>::drain (line 142) ... ok
Dec 06 20:17:19.134 INFO [stdout] test src/progress/change_batch.rs - progress::change_batch::ChangeBatch<T>::clear (line 164) ... ok
Dec 06 20:17:23.592 INFO [stdout] test src/progress/change_batch.rs - progress::change_batch::ChangeBatch<T>::extend (line 85) ... ok
Dec 06 20:17:24.816 INFO [stdout] test src/progress/change_batch.rs - progress::change_batch::ChangeBatch<T>::drain_into (line 220) ... ok
Dec 06 20:17:28.279 INFO [stdout] test src/progress/change_batch.rs - progress::change_batch::ChangeBatch<T>::into_inner (line 103) ... ok
Dec 06 20:17:28.352 INFO [stdout] test src/execute.rs - execute::execute_from_args (line 210) ... ok
Dec 06 20:17:29.756 INFO [stdout] test src/progress/change_batch.rs - progress::change_batch::ChangeBatch<T>::is_empty (line 186) ... ok
Dec 06 20:17:33.892 INFO [stdout] test src/lib.rs -  (line 25) ... ok
Dec 06 20:17:37.248 INFO [stdout] test src/progress/change_batch.rs - progress::change_batch::ChangeBatch<T>::new (line 26) ... ok
Dec 06 20:17:37.700 INFO [stdout] test src/progress/change_batch.rs - progress::change_batch::ChangeBatch<T>::iter (line 118) ... ok
Dec 06 20:17:39.592 INFO [stdout] test src/progress/change_batch.rs - progress::change_batch::ChangeBatch<T>::new_from (line 43) ... ok
Dec 06 20:17:41.475 INFO [stdout] test src/progress/change_batch.rs - progress::change_batch::ChangeBatch<T>::update (line 69) ... ok
Dec 06 20:17:43.242 INFO [stdout] test src/progress/frontier.rs - progress::frontier::AntichainRef<'a, T>::is_empty (line 385) ... ok
Dec 06 20:17:44.027 INFO [stdout] test src/progress/frontier.rs - progress::frontier::AntichainRef<'a, T>::iter (line 400) ... ok
Dec 06 20:17:46.340 INFO [stdout] test src/progress/frontier.rs - progress::frontier::AntichainRef<'a, T>::less_equal (line 433) ... ok
Dec 06 20:17:49.060 INFO [stdout] test src/progress/frontier.rs - progress::frontier::AntichainRef<'a, T>::less_than (line 416) ... ok
Dec 06 20:17:50.696 INFO [stdout] test src/progress/frontier.rs - progress::frontier::MutableAntichain<T>::frontier (line 143) ... ok
Dec 06 20:17:50.839 INFO [stdout] test src/progress/frontier.rs - progress::frontier::MutableAntichain<T>::clear (line 118) ... ok
Dec 06 20:17:52.132 INFO [stdout] test src/progress/frontier.rs - progress::frontier::MutableAntichain<T>::is_empty (line 179) ... ok
Dec 06 20:17:53.223 INFO [stdout] test src/progress/frontier.rs - progress::frontier::MutableAntichain<T>::less_equal (line 213) ... ok
Dec 06 20:17:54.420 INFO [stdout] test src/progress/frontier.rs - progress::frontier::MutableAntichain<T>::less_than (line 195) ... ok
Dec 06 20:17:55.703 INFO [stdout] test src/progress/frontier.rs - progress::frontier::MutableAntichain<T>::new (line 98) ... ok
Dec 06 20:17:56.524 INFO [stdout] test src/progress/frontier.rs - progress::frontier::MutableAntichain<T>::new_bottom (line 159) ... ok
Dec 06 20:17:58.160 INFO [stdout] test src/progress/frontier.rs - progress::frontier::MutableAntichain<T>::update_iter (line 245) ... ok
Dec 06 20:17:59.964 INFO [stdout] test src/progress/frontier.rs - progress::frontier::MutableAntichain<T>::update_iter_and (line 268) ... ok
Dec 06 20:18:00.219 INFO [stdout] test src/progress/nested/reachability.rs - progress::nested::reachability (line 10) ... ok
Dec 06 20:18:01.232 INFO [stdout] test src/progress/nested/reachability.rs - progress::nested::reachability::Builder (line 72) ... ok
Dec 06 20:18:01.560 INFO [stdout] test src/progress/nested/reachability.rs - progress::nested::reachability::Tracker (line 299) ... ok
Dec 06 20:18:02.521 INFO [stdout] test src/progress/timestamp.rs - progress::timestamp::PathSummary::followed_by (line 51) ... ok
Dec 06 20:18:02.783 INFO [stdout] test src/progress/nested/reachability_neu.rs - progress::nested::reachability_neu::Builder (line 107) ... ok
Dec 06 20:18:02.912 INFO [stdout] test src/progress/nested/reachability_neu.rs - progress::nested::reachability_neu (line 10) ... ok
Dec 06 20:18:03.069 INFO [stdout] test src/progress/timestamp.rs - progress::timestamp::PathSummary::results_in (line 30) ... ok
Dec 06 20:18:03.069 INFO [stdout] 
Dec 06 20:18:03.070 INFO [stdout] test result: ok. 102 passed; 0 failed; 2 ignored; 0 measured; 0 filtered out
Dec 06 20:18:03.070 INFO [stdout] 
Dec 06 20:18:03.123 INFO [stderr] su: No module specific data is present
Dec 06 20:18:03.382 INFO running `"docker" "inspect" "7da15096d450a6240b7817fe9603fa4222032d5bc71b659909cefc3474c16171"`
Dec 06 20:18:03.452 INFO running `"docker" "rm" "-f" "7da15096d450a6240b7817fe9603fa4222032d5bc71b659909cefc3474c16171"`
Dec 06 20:18:03.523 INFO [stdout] 7da15096d450a6240b7817fe9603fa4222032d5bc71b659909cefc3474c16171