Nov 17 20:36:09.465 INFO testing timely-0.7.0 against master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c for pr-55982
Nov 17 20:36:09.465 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-2/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/pr-55982/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/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 +6b9b97bd9b704f85f0184f7a213cc4d62bd9654c-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 17 20:36:09.612 INFO [stdout] 80ccfc8c85dc2d3c61f8ef150b30a897b55ddeb79888927be03b68fe05a33983
Nov 17 20:36:09.614 INFO running `"docker" "start" "-a" "80ccfc8c85dc2d3c61f8ef150b30a897b55ddeb79888927be03b68fe05a33983"`
Nov 17 20:36:10.439 INFO [stderr] usermod: no changes
Nov 17 20:36:10.535 INFO [stderr]    Compiling timely_logging v0.7.0
Nov 17 20:36:10.536 INFO [stderr]    Compiling timely_bytes v0.7.0
Nov 17 20:36:10.538 INFO [stderr]    Compiling abomonation v0.7.0
Nov 17 20:36:13.982 INFO [stderr]    Compiling timely_communication v0.7.2
Nov 17 20:36:17.089 INFO [stderr]    Compiling timely v0.7.0 (/source)
Nov 17 20:36:21.816 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 11.34s
Nov 17 20:36:21.819 INFO [stderr] su: No module specific data is present
Nov 17 20:36:22.072 INFO running `"docker" "inspect" "80ccfc8c85dc2d3c61f8ef150b30a897b55ddeb79888927be03b68fe05a33983"`
Nov 17 20:36:22.152 INFO running `"docker" "rm" "-f" "80ccfc8c85dc2d3c61f8ef150b30a897b55ddeb79888927be03b68fe05a33983"`
Nov 17 20:36:22.241 INFO [stdout] 80ccfc8c85dc2d3c61f8ef150b30a897b55ddeb79888927be03b68fe05a33983
Nov 17 20:36:22.246 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-2/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/pr-55982/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/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 +6b9b97bd9b704f85f0184f7a213cc4d62bd9654c-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 17 20:36:22.443 INFO [stdout] 490887d0c3fc4909806f015492a223136d28956112663a9c7d69e4af3c6b8e3f
Nov 17 20:36:22.445 INFO running `"docker" "start" "-a" "490887d0c3fc4909806f015492a223136d28956112663a9c7d69e4af3c6b8e3f"`
Nov 17 20:36:23.007 INFO [stderr] usermod: no changes
Nov 17 20:36:23.111 INFO [stderr]    Compiling timely_sort v0.1.6
Nov 17 20:36:23.685 INFO [stderr]    Compiling timely v0.7.0 (/source)
Nov 17 20:38:50.203 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 2m 27s
Nov 17 20:38:50.227 INFO [stderr] su: No module specific data is present
Nov 17 20:38:50.687 INFO running `"docker" "inspect" "490887d0c3fc4909806f015492a223136d28956112663a9c7d69e4af3c6b8e3f"`
Nov 17 20:38:50.864 INFO running `"docker" "rm" "-f" "490887d0c3fc4909806f015492a223136d28956112663a9c7d69e4af3c6b8e3f"`
Nov 17 20:38:51.131 INFO [stdout] 490887d0c3fc4909806f015492a223136d28956112663a9c7d69e4af3c6b8e3f
Nov 17 20:38:51.132 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-2/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/pr-55982/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/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 +6b9b97bd9b704f85f0184f7a213cc4d62bd9654c-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 17 20:38:51.447 INFO [stdout] 49705d196c910850c09f4a6aaa079f12703bc8850335e7cd261d719dd4e8dcc9
Nov 17 20:38:51.455 INFO running `"docker" "start" "-a" "49705d196c910850c09f4a6aaa079f12703bc8850335e7cd261d719dd4e8dcc9"`
Nov 17 20:38:52.007 INFO [stderr] usermod: no changes
Nov 17 20:38:52.076 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.05s
Nov 17 20:38:52.091 INFO [stderr]      Running /target/debug/deps/timely-90e2465568d6f2b3
Nov 17 20:38:52.093 INFO [stdout] 
Nov 17 20:38:52.093 INFO [stdout] running 5 tests
Nov 17 20:38:52.094 INFO [stdout] test dataflow::operators::generic::notificator::notificator_delivers_notifications_in_topo_order ... ok
Nov 17 20:38:52.096 INFO [stderr] thread 'worker thread 0' panicked at 'Attempted to open output session with invalid capability', src/dataflow/operators/generic/handles.rs:212:9
Nov 17 20:38:52.097 INFO [stderr] stack backtrace:
Nov 17 20:38:52.097 INFO [stdout] test dataflow::operators::probe::tests::probe ... ok
Nov 17 20:38:52.107 INFO [stdout] test dataflow::operators::generic::builder_rc::tests::correct_capabilities ... ok
Nov 17 20:38:52.134 INFO [stderr]    0:     0x5574357d1703 - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::h1a944b80a560daa8
Nov 17 20:38:52.179 INFO [stderr]                                at libstd/sys/unix/backtrace/tracing/gcc_s.rs:49
Nov 17 20:38:52.179 INFO [stderr]    1:     0x5574357cb878 - std::sys_common::backtrace::_print::h18f735b4c3a49bdb
Nov 17 20:38:52.179 INFO [stderr]                                at libstd/sys_common/backtrace.rs:71
Nov 17 20:38:52.179 INFO [stderr]    2:     0x5574357d00c4 - std::panicking::default_hook::{{closure}}::h97ab3548a215c097
Nov 17 20:38:52.179 INFO [stderr]                                at libstd/sys_common/backtrace.rs:59
Nov 17 20:38:52.179 INFO [stderr]                                at libstd/panicking.rs:211
Nov 17 20:38:52.179 INFO [stderr]    3:     0x5574357cfe2d - std::panicking::default_hook::h98c2a01ef734e4b0
Nov 17 20:38:52.179 INFO [stderr]                                at libstd/panicking.rs:227
Nov 17 20:38:52.179 INFO [stderr]    4:     0x5574357d07d0 - std::panicking::rust_panic_with_hook::hb4b3f28eb79ea752
Nov 17 20:38:52.179 INFO [stderr]                                at libstd/panicking.rs:476
Nov 17 20:38:52.179 INFO [stderr]    5:     0x5574357b6407 - std::panicking::begin_panic::h953de411d567abf3
Nov 17 20:38:52.179 INFO [stderr]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/panicking.rs:410
Nov 17 20:38:52.179 INFO [stderr]    6:     0x5574356c808c - <timely::dataflow::operators::generic::handles::OutputHandle<'a, T, D, P>>::session::habd8c65ebd443ae1
Nov 17 20:38:52.187 INFO [stderr]                                at src/dataflow/operators/generic/handles.rs:212
Nov 17 20:38:52.187 INFO [stderr]    7:     0x5574356976bd - timely::dataflow::operators::generic::builder_rc::tests::incorrect_capabilities::{{closure}}::{{closure}}::{{closure}}::ha23ed00317247bc9
Nov 17 20:38:52.187 INFO [stderr]                                at src/dataflow/operators/generic/builder_rc.rs:200
Nov 17 20:38:52.187 INFO [stderr]    8:     0x5574355b6ea3 - <timely::dataflow::operators::generic::builder_rc::OperatorBuilder<G>>::build::{{closure}}::hf4d5fdbe7f18eb39
Nov 17 20:38:52.194 INFO [stderr]                                at src/dataflow/operators/generic/builder_rc.rs:136
Nov 17 20:38:52.194 INFO [stderr]    9:     0x5574355cddb0 - <timely::dataflow::operators::generic::builder_raw::OperatorCore<T, PEP, PIP> as timely::progress::operate::Operate<T>>::pull_internal_progress::hcf0ba7c0e40b2123
Nov 17 20:38:52.199 INFO [stderr]                                at src/dataflow/operators/generic/builder_raw.rs:219
Nov 17 20:38:52.199 INFO [stderr]   10:     0x5574356e293f - <timely::progress::nested::subgraph::PerOperatorState<T>>::exchange_progress::h07fe4941499bf911
Nov 17 20:38:52.200 INFO [stderr]                                at src/progress/nested/subgraph.rs:805
Nov 17 20:38:52.200 INFO [stderr]   11:     0x5574356de726 - <timely::progress::nested::subgraph::Subgraph<TOuter, TInner> as timely::progress::operate::Operate<TOuter>>::pull_internal_progress::h2efa4376dfaa0ab4
Nov 17 20:38:52.200 INFO [stderr]                                at src/progress/nested/subgraph.rs:540
Nov 17 20:38:52.200 INFO [stderr]   12:     0x55743559ac7a - timely::worker::Wrapper::step::{{closure}}::hcdfaa282476f6a44
Nov 17 20:38:52.207 INFO [stderr]                                at src/worker.rs:184
Nov 17 20:38:52.207 INFO [stderr]   13:     0x5574355ab504 - <core::option::Option<T>>::map::hb191b7056822569a
Nov 17 20:38:52.207 INFO [stderr]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libcore/option.rs:424
Nov 17 20:38:52.207 INFO [stderr]   14:     0x55743559ab33 - timely::worker::Wrapper::step::hf46795ae8308a467
Nov 17 20:38:52.207 INFO [stderr]                                at src/worker.rs:184
Nov 17 20:38:52.207 INFO [stderr]   15:     0x557435597cef - <timely::worker::Worker<A>>::step::h18924770a49bb13c
Nov 17 20:38:52.207 INFO [stderr]                                at src/worker.rs:65
Nov 17 20:38:52.207 INFO [stderr]   16:     0x5574355fc330 - timely::execute::example::{{closure}}::h4e3c05b6b6da0874
Nov 17 20:38:52.207 INFO [stderr]                                at src/execute.rs:59
Nov 17 20:38:52.207 INFO [stderr]   17:     0x55743559f0c0 - timely_communication::initialize::initialize_from::{{closure}}::h4e1945fc01288198
Nov 17 20:38:52.207 INFO [stderr]                                at /cargo-home/registry/src/github.com-1ecc6299db9ec823/timely_communication-0.7.2/src/initialize.rs:244
Nov 17 20:38:52.207 INFO [stderr]   18:     0x5574355ebce4 - std::sys_common::backtrace::__rust_begin_short_backtrace::hac05a5534e85f6a6
Nov 17 20:38:52.207 INFO [stderr]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/sys_common/backtrace.rs:136
Nov 17 20:38:52.207 INFO [stderr]   19:     0x55743558b3c6 - std::thread::Builder::spawn_unchecked::{{closure}}::{{closure}}::h9a117dff1b8b625e
Nov 17 20:38:52.207 INFO [stderr]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/thread/mod.rs:477
Nov 17 20:38:52.207 INFO [stderr]   20:     0x557435582826 - <std::panic::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::h7693ec78be1c7e44
Nov 17 20:38:52.207 INFO [stderr]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/panic.rs:319
Nov 17 20:38:52.207 INFO [stderr]   21:     0x55743558ba99 - std::panicking::try::do_call::hc4609c2d02c649a2
Nov 17 20:38:52.207 INFO [stderr]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/panicking.rs:310
Nov 17 20:38:52.207 INFO [stderr]   22:     0x5574357dfc59 - __rust_maybe_catch_panic
Nov 17 20:38:52.207 INFO [stderr]                                at libpanic_unwind/lib.rs:102
Nov 17 20:38:52.207 INFO [stderr]   23:     0x55743558b8f7 - std::panicking::try::h6ee1c91a272e666f
Nov 17 20:38:52.207 INFO [stderr]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/panicking.rs:289
Nov 17 20:38:52.207 INFO [stderr]   24:     0x557435589386 - std::panic::catch_unwind::hba47e11e1a49928b
Nov 17 20:38:52.207 INFO [stderr]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/panic.rs:398
Nov 17 20:38:52.216 INFO [stderr]   25:     0x55743558a9e4 - std::thread::Builder::spawn_unchecked::{{closure}}::h2fa14a30e404a65d
Nov 17 20:38:52.219 INFO [stderr]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/thread/mod.rs:476
Nov 17 20:38:52.219 INFO [stderr]   26:     0x55743558fdc2 - <F as alloc::boxed::FnBox<A>>::call_box::hbfbe33ae25138bfa
Nov 17 20:38:52.219 INFO [stderr]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/liballoc/boxed.rs:673
Nov 17 20:38:52.219 INFO [stderr]   27:     0x5574357d3a7d - std::sys::unix::thread::Thread::new::thread_start::h5f8d67158a1ca038
Nov 17 20:38:52.219 INFO [stderr]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/liballoc/boxed.rs:683
Nov 17 20:38:52.219 INFO [stderr]                                at libstd/sys_common/thread.rs:24
Nov 17 20:38:52.219 INFO [stderr]                                at libstd/sys/unix/thread.rs:90
Nov 17 20:38:52.219 INFO [stderr]   28:     0x7faeac1ba6b9 - start_thread
Nov 17 20:38:52.219 INFO [stderr]   29:     0x7faeabcda41c - clone
Nov 17 20:38:52.219 INFO [stderr]   30:                0x0 - <unknown>
Nov 17 20:38:52.255 INFO [stdout] test dataflow::operators::generic::builder_rc::tests::incorrect_capabilities ... ok
Nov 17 20:38:53.717 INFO [stdout] test dataflow::operators::capture::event::link::avoid_stack_overflow_in_drop ... ok
Nov 17 20:38:53.717 INFO [stdout] 
Nov 17 20:38:53.717 INFO [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 17 20:38:53.717 INFO [stdout] 
Nov 17 20:38:53.731 INFO [stdout] 
Nov 17 20:38:53.731 INFO [stderr]      Running /target/debug/deps/barrier-b76ea95c758db7e4
Nov 17 20:38:53.731 INFO [stdout] running 3 tests
Nov 17 20:38:53.811 INFO [stdout] test barrier_sync_1w ... ok
Nov 17 20:38:54.160 INFO [stdout] test barrier_sync_3w ... ok
Nov 17 20:38:54.416 INFO [stdout] test barrier_sync_2w ... ok
Nov 17 20:38:54.417 INFO [stdout] 
Nov 17 20:38:54.417 INFO [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 17 20:38:54.417 INFO [stdout] 
Nov 17 20:38:54.423 INFO [stderr]      Running /target/debug/deps/skeptic-0286119ea1b1643d
Nov 17 20:38:54.423 INFO [stderr]    Doc-tests timely
Nov 17 20:38:54.424 INFO [stdout] 
Nov 17 20:38:54.424 INFO [stdout] running 0 tests
Nov 17 20:38:54.424 INFO [stdout] 
Nov 17 20:38:54.424 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 17 20:38:54.425 INFO [stdout] 
Nov 17 20:38:55.708 INFO [stdout] 
Nov 17 20:38:55.709 INFO [stdout] running 100 tests
Nov 17 20:39:08.335 INFO [stdout] test src/dataflow/operators/branch.rs - dataflow::operators::branch::Branch::branch (line 19) ... ok
Nov 17 20:39:26.704 INFO [stdout] test src/dataflow/operators/branch.rs - dataflow::operators::branch::BranchWhen::branch_when (line 81) ... ok
Nov 17 20:39:27.943 INFO [stdout] test src/dataflow/operators/broadcast.rs - dataflow::operators::broadcast::Broadcast::broadcast (line 22) ... ok
Nov 17 20:39:29.338 INFO [stdout] test src/dataflow/operators/aggregation/state_machine.rs - dataflow::operators::aggregation::state_machine::StateMachine::state_machine (line 28) ... ok
Nov 17 20:39:29.920 INFO [stdout] test src/dataflow/operators/capture/capture.rs - dataflow::operators::capture::capture::Capture::capture_into (line 31) ... ok
Nov 17 20:39:30.823 INFO [stdout] test src/dataflow/mod.rs - dataflow (line 7) ... ok
Nov 17 20:39:34.626 INFO [stdout] test src/dataflow/operators/aggregation/aggregate.rs - dataflow::operators::aggregation::aggregate::Aggregate::aggregate (line 26) ... ok
Nov 17 20:39:35.806 INFO [stdout] test src/dataflow/operators/aggregation/aggregate.rs - dataflow::operators::aggregation::aggregate::Aggregate::aggregate (line 47) ... ok
Nov 17 20:39:50.775 INFO [stdout] test src/dataflow/operators/capture/capture.rs - dataflow::operators::capture::capture::Capture::capture_into (line 70) ... ok
Nov 17 20:39:52.304 INFO [stdout] test src/dataflow/operators/capture/mod.rs - dataflow::operators::capture (line 48) ... ok
Nov 17 20:39:53.795 INFO [stdout] test src/dataflow/operators/capture/mod.rs - dataflow::operators::capture (line 21) ... ok
Nov 17 20:39:57.515 INFO [stdout] test src/dataflow/operators/capture/extract.rs - dataflow::operators::capture::extract::Extract::extract (line 14) ... ok
Nov 17 20:40:00.955 INFO [stdout] test src/dataflow/operators/concat.rs - dataflow::operators::concat::Concat::concat (line 13) ... ok
Nov 17 20:40:01.099 INFO [stdout] test src/dataflow/operators/concat.rs - dataflow::operators::concat::Concatenate::concatenate (line 37) ... ok
Nov 17 20:40:01.871 INFO [stdout] test src/dataflow/operators/count.rs - dataflow::operators::count::Accumulate::accumulate (line 17) ... ok
Nov 17 20:40:11.634 INFO [stdout] test src/dataflow/operators/count.rs - dataflow::operators::count::Accumulate::count (line 36) ... ok
Nov 17 20:40:18.065 INFO [stdout] test src/dataflow/operators/delay.rs - dataflow::operators::delay::Delay::delay (line 24) ... ok
Nov 17 20:40:29.079 INFO [stdout] test src/dataflow/operators/delay.rs - dataflow::operators::delay::Delay::delay_batch (line 52) ... ok
Nov 17 20:40:29.437 INFO [stdout] test src/dataflow/operators/enterleave.rs - dataflow::operators::enterleave::Enter::enter (line 44) ... ok
Nov 17 20:40:33.951 INFO [stdout] test src/dataflow/operators/enterleave.rs - dataflow::operators::enterleave (line 8) ... ok
Nov 17 20:40:41.191 INFO [stdout] test src/dataflow/operators/exchange.rs - dataflow::operators::exchange::Exchange::exchange (line 15) ... ok
Nov 17 20:40:45.945 INFO [stdout] test src/dataflow/operators/enterleave.rs - dataflow::operators::enterleave::EnterAt::enter_at (line 63) ... ok
Nov 17 20:40:48.075 INFO [stdout] test src/dataflow/operators/enterleave.rs - dataflow::operators::enterleave::Leave::leave (line 104) ... ok
Nov 17 20:40:49.358 INFO [stdout] test src/dataflow/operators/feedback.rs - dataflow::operators::feedback::ConnectLoop::connect_loop (line 107) ... ok
Nov 17 20:40:51.179 INFO [stdout] test src/dataflow/operators/feedback.rs - dataflow::operators::feedback::LoopVariable::loop_variable (line 33) ... ok
Nov 17 20:41:00.801 INFO [stdout] test src/dataflow/operators/generic/handles.rs - dataflow::operators::generic::handles::FrontieredInputHandle<'a, T, D, P>::for_each (line 110) ... ok
Nov 17 20:41:04.663 INFO [stdout] test src/dataflow/operators/filter.rs - dataflow::operators::filter::Filter::filter (line 13) ... ok
Nov 17 20:41:07.463 INFO [stdout] test src/dataflow/operators/generic/handles.rs - dataflow::operators::generic::handles::InputHandle<T, D, P>::for_each (line 63) ... ok
Nov 17 20:41:13.535 INFO [stdout] test src/dataflow/operators/generic/handles.rs - dataflow::operators::generic::handles::OutputHandle<'a, T, D, P>::session (line 194) ... ok
Nov 17 20:41:17.585 INFO [stdout] test src/dataflow/operators/generic/notificator.rs - dataflow::operators::generic::notificator::FrontierNotificator<T>::notify_at (line 263) ... ok
Nov 17 20:41:17.739 INFO [stdout] test src/dataflow/operators/generic/notificator.rs - dataflow::operators::generic::notificator::FrontierNotificator (line 187) ... ok
Nov 17 20:41:20.861 INFO [stdout] test src/dataflow/operators/flow_controlled.rs - dataflow::operators::flow_controlled::iterator_source (line 36) ... ok
Nov 17 20:41:28.091 INFO [stdout] test src/dataflow/operators/generic/notificator.rs - dataflow::operators::generic::notificator::FrontierNotificator<T>::pending (line 380) ... ok
Nov 17 20:41:36.271 INFO [stdout] test src/dataflow/operators/generic/notificator.rs - dataflow::operators::generic::notificator::Notificator<'a, T>::notify_at (line 52) ... ok
Nov 17 20:41:42.223 INFO [stdout] test src/dataflow/operators/generic/operator.rs - dataflow::operators::generic::operator::Operator::binary_frontier (line 146) ... ok
Nov 17 20:41:43.451 INFO [stdout] test src/dataflow/operators/generic/operator.rs - dataflow::operators::generic::operator::Operator::binary (line 262) ... ok
Nov 17 20:42:03.175 INFO [stdout] test src/dataflow/operators/generic/operator.rs - dataflow::operators::generic::operator::Operator::binary_notify (line 207) ... ok
Nov 17 20:42:03.723 INFO [stdout] test src/dataflow/operators/generic/operator.rs - dataflow::operators::generic::operator::Operator::unary (line 109) ... ok
Nov 17 20:42:07.431 INFO [stdout] test src/dataflow/operators/generic/operator.rs - dataflow::operators::generic::operator::Operator::unary_frontier (line 25) ... ok
Nov 17 20:42:09.527 INFO [stdout] test src/dataflow/operators/generic/operator.rs - dataflow::operators::generic::operator::Operator::sink (line 308) ... ok
Nov 17 20:42:22.427 INFO [stdout] test src/dataflow/operators/generic/operator.rs - dataflow::operators::generic::operator::Operator::unary_notify (line 73) ... ok
Nov 17 20:42:25.007 INFO [stdout] test src/dataflow/operators/generic/operator.rs - dataflow::operators::generic::operator::empty (line 597) ... ok
Nov 17 20:42:39.075 INFO [stdout] test src/dataflow/operators/generic/operator.rs - dataflow::operators::generic::operator::source (line 537) ... ok
Nov 17 20:42:40.523 INFO [stdout] test src/dataflow/operators/input.rs - dataflow::operators::input::Handle<T, D>::new (line 169) ... ok
Nov 17 20:42:53.707 INFO [stdout] test src/dataflow/operators/input.rs - dataflow::operators::input::Input::input_from (line 69) ... ok
Nov 17 20:42:56.281 INFO [stdout] test src/dataflow/operators/input.rs - dataflow::operators::input::Handle<T, D>::to_stream (line 205) ... ok
Nov 17 20:43:07.019 INFO [stdout] test src/dataflow/operators/inspect.rs - dataflow::operators::inspect::Inspect::inspect (line 13) ... ok
Nov 17 20:43:07.027 INFO [stdout] test src/dataflow/operators/input.rs - dataflow::operators::input::Input::new_input (line 38) ... ok
Nov 17 20:43:12.688 INFO [stdout] test src/dataflow/operators/inspect.rs - dataflow::operators::inspect::Inspect::inspect_batch (line 49) ... ok
Nov 17 20:43:12.913 INFO [stdout] test src/dataflow/operators/inspect.rs - dataflow::operators::inspect::Inspect::inspect_time (line 30) ... ok
Nov 17 20:43:24.783 INFO [stdout] test src/dataflow/operators/map.rs - dataflow::operators::map::Map::map (line 13) ... ok
Nov 17 20:43:25.428 INFO [stdout] test src/dataflow/operators/probe.rs - dataflow::operators::probe::Handle<T>::with_frontier (line 161) ... ok
Nov 17 20:43:26.699 INFO [stdout] test src/dataflow/operators/map.rs - dataflow::operators::map::Map::flat_map (line 39) ... ok
Nov 17 20:43:32.878 INFO [stdout] test src/dataflow/operators/map.rs - dataflow::operators::map::Map::map_in_place (line 26) ... ok
Nov 17 20:43:48.779 INFO [stdout] test src/dataflow/operators/partition.rs - dataflow::operators::partition::Partition::partition (line 22) ... ok
Nov 17 20:43:55.003 INFO [stdout] test src/dataflow/operators/probe.rs - dataflow::operators::probe::Probe::probe (line 23) ... ok
Nov 17 20:43:55.471 INFO [stdout] test src/dataflow/operators/probe.rs - dataflow::operators::probe::Probe::probe_with (line 53) ... ok
Nov 17 20:44:00.923 INFO [stdout] test src/dataflow/operators/reclock.rs - dataflow::operators::reclock::Reclock::reclock (line 21) ... ok
Nov 17 20:44:10.755 INFO [stdout] test src/dataflow/operators/to_stream.rs - dataflow::operators::to_stream::ToStream::to_stream (line 16) ... ok
Nov 17 20:44:10.899 INFO [stdout] test src/dataflow/operators/unordered_input.rs - dataflow::operators::unordered_input::UnorderedInput::new_unordered_input (line 39) ... ok
Nov 17 20:44:10.899 INFO [stdout] test src/execute.rs - execute::execute_from_args (line 221) ... ignored
Nov 17 20:44:10.899 INFO [stdout] test src/execute.rs - execute::execute_from_args (line 227) ... ignored
Nov 17 20:44:18.171 INFO [stdout] test src/execute.rs - execute::example (line 24) ... ok
Nov 17 20:44:18.171 INFO [stdout] test src/dataflow/scopes/mod.rs - dataflow::scopes::Scope::scoped (line 78) ... ok
Nov 17 20:44:22.404 INFO [stdout] test src/execute.rs - execute::example (line 38) ... ok
Nov 17 20:44:37.855 INFO [stdout] test src/progress/change_batch.rs - progress::change_batch::ChangeBatch<T>::drain (line 142) ... ok
Nov 17 20:44:38.363 INFO [stdout] test src/execute.rs - execute::execute (line 81) ... ok
Nov 17 20:44:41.079 INFO [stdout] test src/progress/change_batch.rs - progress::change_batch::ChangeBatch<T>::drain_into (line 220) ... ok
Nov 17 20:44:41.697 INFO [stdout] test src/progress/change_batch.rs - progress::change_batch::ChangeBatch<T>::clear (line 164) ... ok
Nov 17 20:44:44.845 INFO [stdout] test src/execute.rs - execute::execute (line 97) ... ok
Nov 17 20:44:46.594 INFO [stdout] test src/execute.rs - execute::execute_from (line 246) ... ok
Nov 17 20:44:49.764 INFO [stdout] test src/progress/change_batch.rs - progress::change_batch::ChangeBatch<T>::extend (line 85) ... ok
Nov 17 20:44:50.008 INFO [stdout] test src/lib.rs -  (line 25) ... ok
Nov 17 20:44:50.778 INFO [stdout] test src/execute.rs - execute::execute_from_args (line 210) ... ok
Nov 17 20:44:51.196 INFO [stdout] test src/progress/change_batch.rs - progress::change_batch::ChangeBatch<T>::into_inner (line 103) ... ok
Nov 17 20:44:51.459 INFO [stdout] test src/progress/change_batch.rs - progress::change_batch::ChangeBatch<T>::iter (line 118) ... ok
Nov 17 20:44:52.195 INFO [stdout] test src/progress/change_batch.rs - progress::change_batch::ChangeBatch<T>::is_empty (line 186) ... ok
Nov 17 20:44:54.491 INFO [stdout] test src/progress/change_batch.rs - progress::change_batch::ChangeBatch<T>::new (line 26) ... ok
Nov 17 20:44:55.035 INFO [stdout] test src/progress/change_batch.rs - progress::change_batch::ChangeBatch<T>::new_from (line 43) ... ok
Nov 17 20:44:58.874 INFO [stdout] test src/progress/frontier.rs - progress::frontier::AntichainRef<'a, T>::iter (line 400) ... ok
Nov 17 20:44:59.223 INFO [stdout] test src/progress/frontier.rs - progress::frontier::AntichainRef<'a, T>::is_empty (line 385) ... ok
Nov 17 20:45:00.189 INFO [stdout] test src/progress/frontier.rs - progress::frontier::MutableAntichain<T>::is_empty (line 179) ... ok
Nov 17 20:45:00.200 INFO [stdout] test src/progress/change_batch.rs - progress::change_batch::ChangeBatch<T>::update (line 69) ... ok
Nov 17 20:45:00.366 INFO [stdout] test src/progress/frontier.rs - progress::frontier::AntichainRef<'a, T>::less_than (line 416) ... ok
Nov 17 20:45:00.919 INFO [stdout] test src/progress/frontier.rs - progress::frontier::MutableAntichain<T>::clear (line 118) ... ok
Nov 17 20:45:01.951 INFO [stdout] test src/progress/frontier.rs - progress::frontier::AntichainRef<'a, T>::less_equal (line 433) ... ok
Nov 17 20:45:05.633 INFO [stdout] test src/progress/frontier.rs - progress::frontier::MutableAntichain<T>::less_than (line 195) ... ok
Nov 17 20:45:05.639 INFO [stdout] test src/progress/frontier.rs - progress::frontier::MutableAntichain<T>::frontier (line 143) ... ok
Nov 17 20:45:07.425 INFO [stdout] test src/progress/frontier.rs - progress::frontier::MutableAntichain<T>::less_equal (line 213) ... ok
Nov 17 20:45:09.391 INFO [stdout] test src/progress/frontier.rs - progress::frontier::MutableAntichain<T>::new_bottom (line 159) ... ok
Nov 17 20:45:09.963 INFO [stdout] test src/progress/frontier.rs - progress::frontier::MutableAntichain<T>::new (line 98) ... ok
Nov 17 20:45:10.671 INFO [stdout] test src/progress/frontier.rs - progress::frontier::MutableAntichain<T>::update_iter (line 245) ... ok
Nov 17 20:45:13.507 INFO [stdout] test src/progress/frontier.rs - progress::frontier::MutableAntichain<T>::update_iter_and (line 268) ... ok
Nov 17 20:45:14.532 INFO [stdout] test src/progress/nested/reachability.rs - progress::nested::reachability (line 10) ... ok
Nov 17 20:45:14.711 INFO [stdout] test src/progress/nested/reachability.rs - progress::nested::reachability::Tracker (line 299) ... ok
Nov 17 20:45:15.419 INFO [stdout] test src/progress/nested/reachability.rs - progress::nested::reachability::Builder (line 72) ... ok
Nov 17 20:45:16.903 INFO [stdout] test src/progress/nested/reachability_neu.rs - progress::nested::reachability_neu (line 10) ... ok
Nov 17 20:45:17.191 INFO [stdout] test src/progress/timestamp.rs - progress::timestamp::PathSummary::followed_by (line 55) ... ok
Nov 17 20:45:17.391 INFO [stdout] test src/progress/timestamp.rs - progress::timestamp::PathSummary::results_in (line 34) ... ok
Nov 17 20:45:17.587 INFO [stdout] test src/progress/nested/reachability_neu.rs - progress::nested::reachability_neu::Builder (line 91) ... ok
Nov 17 20:45:17.587 INFO [stdout] 
Nov 17 20:45:17.587 INFO [stdout] test result: ok. 98 passed; 0 failed; 2 ignored; 0 measured; 0 filtered out
Nov 17 20:45:17.587 INFO [stdout] 
Nov 17 20:45:17.659 INFO [stderr] su: No module specific data is present
Nov 17 20:45:18.465 INFO running `"docker" "inspect" "49705d196c910850c09f4a6aaa079f12703bc8850335e7cd261d719dd4e8dcc9"`
Nov 17 20:45:18.793 INFO running `"docker" "rm" "-f" "49705d196c910850c09f4a6aaa079f12703bc8850335e7cd261d719dd4e8dcc9"`
Nov 17 20:45:19.023 INFO [stdout] 49705d196c910850c09f4a6aaa079f12703bc8850335e7cd261d719dd4e8dcc9