Dec 06 20:07:14.888 INFO testing timely-0.8.0 against master#906deae0790bd18681b937fe9a141a3c26cf1855 for pr-56514
Dec 06 20:07:14.889 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-6/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/pr-56514/master#906deae0790bd18681b937fe9a141a3c26cf1855:/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 +906deae0790bd18681b937fe9a141a3c26cf1855-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:15.089 INFO [stdout] 0e888d82b1f386e4f9fad9c00331e8f2ce0e94e7e197275376b87b79b416f662
Dec 06 20:07:15.090 INFO running `"docker" "start" "-a" "0e888d82b1f386e4f9fad9c00331e8f2ce0e94e7e197275376b87b79b416f662"`
Dec 06 20:07:15.540 INFO [stderr] usermod: no changes
Dec 06 20:07:15.636 INFO [stderr]    Compiling timely_bytes v0.7.0
Dec 06 20:07:15.636 INFO [stderr]    Compiling timely_logging v0.7.0
Dec 06 20:07:15.636 INFO [stderr]    Compiling abomonation v0.7.0
Dec 06 20:07:15.669 INFO [stderr]    Compiling abomonation_derive v0.3.0
Dec 06 20:07:19.149 INFO [stderr]    Compiling timely_communication v0.8.0
Dec 06 20:07:21.989 INFO [stderr]    Compiling timely v0.8.0 (/source)
Dec 06 20:07:27.265 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 11.70s
Dec 06 20:07:27.270 INFO [stderr] su: No module specific data is present
Dec 06 20:07:27.514 INFO running `"docker" "inspect" "0e888d82b1f386e4f9fad9c00331e8f2ce0e94e7e197275376b87b79b416f662"`
Dec 06 20:07:27.601 INFO running `"docker" "rm" "-f" "0e888d82b1f386e4f9fad9c00331e8f2ce0e94e7e197275376b87b79b416f662"`
Dec 06 20:07:27.710 INFO [stdout] 0e888d82b1f386e4f9fad9c00331e8f2ce0e94e7e197275376b87b79b416f662
Dec 06 20:07:27.714 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-6/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/pr-56514/master#906deae0790bd18681b937fe9a141a3c26cf1855:/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 +906deae0790bd18681b937fe9a141a3c26cf1855-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:28.044 INFO [stdout] c05299a0cdef836c77f5285fc86426903eee953a981d91dbe639b8ede2c88d1d
Dec 06 20:07:28.044 INFO running `"docker" "start" "-a" "c05299a0cdef836c77f5285fc86426903eee953a981d91dbe639b8ede2c88d1d"`
Dec 06 20:07:28.476 INFO [stderr] usermod: no changes
Dec 06 20:07:28.534 INFO [stderr]    Compiling timely_sort v0.1.6
Dec 06 20:07:29.193 INFO [stderr]    Compiling timely v0.8.0 (/source)
Dec 06 20:09:59.239 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 2m 30s
Dec 06 20:09:59.257 INFO [stderr] su: No module specific data is present
Dec 06 20:09:59.561 INFO running `"docker" "inspect" "c05299a0cdef836c77f5285fc86426903eee953a981d91dbe639b8ede2c88d1d"`
Dec 06 20:09:59.657 INFO running `"docker" "rm" "-f" "c05299a0cdef836c77f5285fc86426903eee953a981d91dbe639b8ede2c88d1d"`
Dec 06 20:09:59.782 INFO [stdout] c05299a0cdef836c77f5285fc86426903eee953a981d91dbe639b8ede2c88d1d
Dec 06 20:09:59.784 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-6/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/pr-56514/master#906deae0790bd18681b937fe9a141a3c26cf1855:/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 +906deae0790bd18681b937fe9a141a3c26cf1855-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:10:00.027 INFO [stdout] 664acd7c9d042e035f41344fdbe271c83fcc2674771ea49dcf44e91c7855adec
Dec 06 20:10:00.028 INFO running `"docker" "start" "-a" "664acd7c9d042e035f41344fdbe271c83fcc2674771ea49dcf44e91c7855adec"`
Dec 06 20:10:00.452 INFO [stderr] usermod: no changes
Dec 06 20:10:00.572 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.09s
Dec 06 20:10:00.580 INFO [stderr]      Running /target/debug/deps/timely-3c22daba38ee9d0f
Dec 06 20:10:00.592 INFO [stdout] 
Dec 06 20:10:00.592 INFO [stdout] running 5 tests
Dec 06 20:10:00.600 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.600 INFO [stderr] stack backtrace:
Dec 06 20:10:00.600 INFO [stdout] test dataflow::operators::generic::notificator::notificator_delivers_notifications_in_topo_order ... ok
Dec 06 20:10:00.600 INFO [stdout] test dataflow::operators::generic::builder_rc::tests::correct_capabilities ... ok
Dec 06 20:10:00.600 INFO [stdout] test dataflow::operators::probe::tests::probe ... ok
Dec 06 20:10:00.656 INFO [stderr]    0:     0x560c5ef95f73 - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::h3c2ada0a2db8c5e7
Dec 06 20:10:00.700 INFO [stderr]                                at src/libstd/sys/unix/backtrace/tracing/gcc_s.rs:49
Dec 06 20:10:00.700 INFO [stderr]    1:     0x560c5ef90058 - std::sys_common::backtrace::_print::h95865c77318e98b7
Dec 06 20:10:00.700 INFO [stderr]                                at src/libstd/sys_common/backtrace.rs:71
Dec 06 20:10:00.700 INFO [stderr]    2:     0x560c5ef94932 - std::panicking::default_hook::{{closure}}::hb3d1f299fcea42e5
Dec 06 20:10:00.700 INFO [stderr]                                at src/libstd/sys_common/backtrace.rs:59
Dec 06 20:10:00.700 INFO [stderr]                                at src/libstd/panicking.rs:211
Dec 06 20:10:00.700 INFO [stderr]    3:     0x560c5ef9469d - std::panicking::default_hook::h4eae6f26f73244c8
Dec 06 20:10:00.700 INFO [stderr]                                at src/libstd/panicking.rs:227
Dec 06 20:10:00.700 INFO [stderr]    4:     0x560c5ef95040 - std::panicking::rust_panic_with_hook::h368d37fd53b59464
Dec 06 20:10:00.700 INFO [stderr]                                at src/libstd/panicking.rs:491
Dec 06 20:10:00.700 INFO [stderr]    5:     0x560c5ef818f7 - std::panicking::begin_panic::hfecd8637e3e6a4b6
Dec 06 20:10:00.700 INFO [stderr]                                at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libstd/panicking.rs:425
Dec 06 20:10:00.700 INFO [stderr]    6:     0x560c5ee752ec - <timely::dataflow::operators::generic::handles::OutputHandle<'a, T, D, P>>::session::hf827521fca6a2d01
Dec 06 20:10:00.700 INFO [stderr]                                at src/dataflow/operators/generic/handles.rs:212
Dec 06 20:10:00.700 INFO [stderr]    7:     0x560c5edd910d - timely::dataflow::operators::generic::builder_rc::tests::incorrect_capabilities::{{closure}}::{{closure}}::{{closure}}::h79d1cac7ef3f3112
Dec 06 20:10:00.700 INFO [stderr]                                at src/dataflow/operators/generic/builder_rc.rs:200
Dec 06 20:10:00.700 INFO [stderr]    8:     0x560c5ed6c3d3 - <timely::dataflow::operators::generic::builder_rc::OperatorBuilder<G>>::build::{{closure}}::hec7ce780c26c2a65
Dec 06 20:10:00.704 INFO [stderr]                                at src/dataflow/operators/generic/builder_rc.rs:136
Dec 06 20:10:00.708 INFO [stderr]    9:     0x560c5ed857a0 - <timely::dataflow::operators::generic::builder_raw::OperatorCore<T, PEP, PIP> as timely::progress::operate::Operate<T>>::pull_internal_progress::hcb3886dce1d2a82c
Dec 06 20:10:00.708 INFO [stderr]                                at src/dataflow/operators/generic/builder_raw.rs:219
Dec 06 20:10:00.708 INFO [stderr]   10:     0x560c5eecb640 - <timely::progress::nested::subgraph::PerOperatorState<T>>::exchange_progress::h3a683bccb940526f
Dec 06 20:10:00.708 INFO [stderr]                                at src/progress/nested/subgraph.rs:803
Dec 06 20:10:00.708 INFO [stderr]   11:     0x560c5eec61aa - <timely::progress::nested::subgraph::Subgraph<TOuter, TInner> as timely::progress::operate::Operate<TOuter>>::pull_internal_progress::h2327208ad564e772
Dec 06 20:10:00.708 INFO [stderr]                                at src/progress/nested/subgraph.rs:536
Dec 06 20:10:00.708 INFO [stderr]   12:     0x560c5eeb08aa - timely::worker::Wrapper::step::{{closure}}::ha486b0def4a75559
Dec 06 20:10:00.711 INFO [stderr]                                at src/worker.rs:197
Dec 06 20:10:00.711 INFO [stderr]   13:     0x560c5ed73fe4 - <core::option::Option<T>>::map::h330f273b15327f85
Dec 06 20:10:00.715 INFO [stderr]                                at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libcore/option.rs:424
Dec 06 20:10:00.715 INFO [stderr]   14:     0x560c5eeb0763 - timely::worker::Wrapper::step::hc8de3b178f1dfd4d
Dec 06 20:10:00.715 INFO [stderr]                                at src/worker.rs:197
Dec 06 20:10:00.715 INFO [stderr]   15:     0x560c5eead883 - <timely::worker::Worker<A>>::step::h79c45ce88005914f
Dec 06 20:10:00.715 INFO [stderr]                                at src/worker.rs:68
Dec 06 20:10:00.715 INFO [stderr]   16:     0x560c5ed9b42e - timely::execute::example::{{closure}}::h2d02a4842a0a03fd
Dec 06 20:10:00.715 INFO [stderr]                                at src/execute.rs:59
Dec 06 20:10:00.715 INFO [stderr]   17:     0x560c5ed57a10 - timely_communication::initialize::initialize_from::{{closure}}::hdc29819f98aa94d8
Dec 06 20:10:00.715 INFO [stderr]                                at /cargo-home/registry/src/github.com-1ecc6299db9ec823/timely_communication-0.8.0/src/initialize.rs:261
Dec 06 20:10:00.715 INFO [stderr]   18:     0x560c5ee3af22 - std::sys_common::backtrace::__rust_begin_short_backtrace::h132834f3d37dc892
Dec 06 20:10:00.715 INFO [stderr]                                at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libstd/sys_common/backtrace.rs:136
Dec 06 20:10:00.715 INFO [stderr]   19:     0x560c5ee57ba4 - std::thread::Builder::spawn_unchecked::{{closure}}::{{closure}}::h38fa9484cf2c5f47
Dec 06 20:10:00.715 INFO [stderr]                                at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libstd/thread/mod.rs:477
Dec 06 20:10:00.715 INFO [stderr]   20:     0x560c5ee52614 - <std::panic::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::h3e29eaf4949d12f1
Dec 06 20:10:00.715 INFO [stderr]                                at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libstd/panic.rs:319
Dec 06 20:10:00.715 INFO [stderr]   21:     0x560c5ee582a7 - std::panicking::try::do_call::h6283dff169cd7fb7
Dec 06 20:10:00.715 INFO [stderr]                                at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libstd/panicking.rs:310
Dec 06 20:10:00.715 INFO [stderr]   22:     0x560c5efa4399 - __rust_maybe_catch_panic
Dec 06 20:10:00.715 INFO [stderr]                                at src/libpanic_unwind/lib.rs:102
Dec 06 20:10:00.715 INFO [stderr]   23:     0x560c5ee57e28 - std::panicking::try::h1aed72a33b50b0e7
Dec 06 20:10:00.715 INFO [stderr]                                at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libstd/panicking.rs:289
Dec 06 20:10:00.715 INFO [stderr]   24:     0x560c5ee55b84 - std::panic::catch_unwind::hb6c793c8183804bc
Dec 06 20:10:00.715 INFO [stderr]                                at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libstd/panic.rs:398
Dec 06 20:10:00.715 INFO [stderr]   25:     0x560c5ee5795d - std::thread::Builder::spawn_unchecked::{{closure}}::hde68b03f70f7672d
Dec 06 20:10:00.715 INFO [stderr]                                at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libstd/thread/mod.rs:476
Dec 06 20:10:00.715 INFO [stderr]   26:     0x560c5ee6e372 - <F as alloc::boxed::FnBox<A>>::call_box::h95ed8cfda71273a0
Dec 06 20:10:00.715 INFO [stderr]                                at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/liballoc/boxed.rs:673
Dec 06 20:10:00.715 INFO [stderr]   27:     0x560c5ef982bd - std::sys::unix::thread::Thread::new::thread_start::hea5f7f9c570dc4cd
Dec 06 20:10:00.715 INFO [stderr]                                at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/liballoc/boxed.rs:683
Dec 06 20:10:00.715 INFO [stderr]                                at src/libstd/sys_common/thread.rs:24
Dec 06 20:10:00.715 INFO [stderr]                                at src/libstd/sys/unix/thread.rs:90
Dec 06 20:10:00.715 INFO [stderr]   28:     0x7f1bcfa9a6b9 - start_thread
Dec 06 20:10:00.716 INFO [stderr]   29:     0x7f1bcf5ba41c - clone
Dec 06 20:10:00.716 INFO [stderr]   30:                0x0 - <unknown>
Dec 06 20:10:00.745 INFO [stdout] test dataflow::operators::generic::builder_rc::tests::incorrect_capabilities ... ok
Dec 06 20:10:02.200 INFO [stdout] test dataflow::operators::capture::event::link::avoid_stack_overflow_in_drop ... ok
Dec 06 20:10:02.200 INFO [stdout] 
Dec 06 20:10:02.200 INFO [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 06 20:10:02.200 INFO [stdout] 
Dec 06 20:10:02.210 INFO [stderr]      Running /target/debug/deps/barrier-9f73e11cec8b77dc
Dec 06 20:10:02.212 INFO [stdout] 
Dec 06 20:10:02.212 INFO [stdout] running 3 tests
Dec 06 20:10:02.229 INFO [stdout] test barrier_sync_1w ... ok
Dec 06 20:10:02.435 INFO [stdout] test barrier_sync_3w ... ok
Dec 06 20:10:02.451 INFO [stdout] test barrier_sync_2w ... ok
Dec 06 20:10:02.451 INFO [stdout] 
Dec 06 20:10:02.451 INFO [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 06 20:10:02.452 INFO [stdout] 
Dec 06 20:10:02.452 INFO [stderr]      Running /target/debug/deps/skeptic-06b5808156c8cd0d
Dec 06 20:10:02.454 INFO [stdout] 
Dec 06 20:10:02.454 INFO [stdout] running 0 tests
Dec 06 20:10:02.454 INFO [stdout] 
Dec 06 20:10:02.454 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 06 20:10:02.454 INFO [stdout] 
Dec 06 20:10:02.454 INFO [stderr]    Doc-tests timely
Dec 06 20:10:03.848 INFO [stdout] 
Dec 06 20:10:03.848 INFO [stdout] running 104 tests
Dec 06 20:10:33.688 INFO [stdout] test src/dataflow/mod.rs - dataflow (line 7) ... ok
Dec 06 20:10:35.812 INFO [stdout] test src/dataflow/operators/branch.rs - dataflow::operators::branch::BranchWhen::branch_when (line 81) ... ok
Dec 06 20:10:39.268 INFO [stdout] test src/dataflow/operators/capture/capture.rs - dataflow::operators::capture::capture::Capture::capture_into (line 31) ... ok
Dec 06 20:10:44.992 INFO [stdout] test src/dataflow/operators/broadcast.rs - dataflow::operators::broadcast::Broadcast::broadcast (line 22) ... ok
Dec 06 20:10:45.848 INFO [stdout] test src/dataflow/operators/branch.rs - dataflow::operators::branch::Branch::branch (line 19) ... ok
Dec 06 20:10:46.441 INFO [stdout] test src/dataflow/operators/aggregation/state_machine.rs - dataflow::operators::aggregation::state_machine::StateMachine::state_machine (line 28) ... ok
Dec 06 20:10:48.153 INFO [stdout] test src/dataflow/operators/aggregation/aggregate.rs - dataflow::operators::aggregation::aggregate::Aggregate::aggregate (line 47) ... ok
Dec 06 20:10:49.444 INFO [stdout] test src/dataflow/operators/aggregation/aggregate.rs - dataflow::operators::aggregation::aggregate::Aggregate::aggregate (line 26) ... ok
Dec 06 20:11:12.296 INFO [stdout] test src/dataflow/operators/capture/mod.rs - dataflow::operators::capture (line 21) ... ok
Dec 06 20:11:13.420 INFO [stdout] test src/dataflow/operators/concat.rs - dataflow::operators::concat::Concat::concat (line 13) ... ok
Dec 06 20:11:15.257 INFO [stdout] test src/dataflow/operators/concat.rs - dataflow::operators::concat::Concatenate::concatenate (line 37) ... ok
Dec 06 20:11:15.847 INFO [stdout] test src/dataflow/operators/capture/capture.rs - dataflow::operators::capture::capture::Capture::capture_into (line 70) ... ok
Dec 06 20:11:18.901 INFO [stdout] test src/dataflow/operators/capture/mod.rs - dataflow::operators::capture (line 48) ... ok
Dec 06 20:11:19.952 INFO [stdout] test src/dataflow/operators/capture/extract.rs - dataflow::operators::capture::extract::Extract::extract (line 14) ... ok
Dec 06 20:11:22.700 INFO [stdout] test src/dataflow/operators/count.rs - dataflow::operators::count::Accumulate::accumulate (line 17) ... ok
Dec 06 20:11:22.960 INFO [stdout] test src/dataflow/operators/count.rs - dataflow::operators::count::Accumulate::count (line 35) ... ok
Dec 06 20:11:43.244 INFO [stdout] test src/dataflow/operators/delay.rs - dataflow::operators::delay::Delay::delay (line 25) ... ok
Dec 06 20:11:46.772 INFO [stdout] test src/dataflow/operators/enterleave.rs - dataflow::operators::enterleave (line 8) ... ok
Dec 06 20:11:49.288 INFO [stdout] test src/dataflow/operators/enterleave.rs - dataflow::operators::enterleave::Enter::enter (line 45) ... ok
Dec 06 20:11:49.432 INFO [stdout] test src/dataflow/operators/delay.rs - dataflow::operators::delay::Delay::delay_batch (line 80) ... ok
Dec 06 20:11:51.093 INFO [stdout] test src/dataflow/operators/delay.rs - dataflow::operators::delay::Delay::delay_total (line 52) ... ok
Dec 06 20:11:57.579 INFO [stdout] test src/dataflow/operators/enterleave.rs - dataflow::operators::enterleave::Leave::leave (line 107) ... ok
Dec 06 20:11:59.297 INFO [stdout] test src/dataflow/operators/exchange.rs - dataflow::operators::exchange::Exchange::exchange (line 15) ... ok
Dec 06 20:11:59.372 INFO [stdout] test src/dataflow/operators/enterleave.rs - dataflow::operators::enterleave::EnterAt::enter_at (line 66) ... ok
Dec 06 20:12:33.688 INFO [stdout] test src/dataflow/operators/feedback.rs - dataflow::operators::feedback::ConnectLoop::connect_loop (line 145) ... ok
Dec 06 20:12:38.478 INFO [stdout] test src/dataflow/operators/filter.rs - dataflow::operators::filter::Filter::filter (line 13) ... ok
Dec 06 20:12:40.708 INFO [stdout] test src/dataflow/operators/feedback.rs - dataflow::operators::feedback::Feedback::feedback (line 29) ... ok
Dec 06 20:12:49.300 INFO [stdout] test src/dataflow/operators/feedback.rs - dataflow::operators::feedback::LoopVariable::loop_variable (line 55) ... test src/dataflow/operators/feedback.rs - dataflow::operators::feedback::LoopVariable::loop_variable (line 55) has been running for over 60 seconds
Dec 06 20:12:50.531 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:51.110 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:54.328 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:57.285 INFO [stdout] test src/dataflow/operators/feedback.rs - dataflow::operators::feedback::LoopVariable::loop_variable (line 55) ... ok
Dec 06 20:12:57.312 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:59.582 INFO [stdout] test src/dataflow/operators/flow_controlled.rs - dataflow::operators::flow_controlled::iterator_source (line 36) ... ok
Dec 06 20:13:33.696 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:38.484 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:38.500 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:40.712 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 06 20:13:41.748 INFO [stdout] test src/dataflow/operators/generic/notificator.rs - dataflow::operators::generic::notificator::FrontierNotificator<T>::pending (line 378) ... ok
Dec 06 20:13:41.984 INFO [stdout] test src/dataflow/operators/generic/notificator.rs - dataflow::operators::generic::notificator::FrontierNotificator<T>::notify_at (line 262) ... ok
Dec 06 20:13:44.352 INFO [stdout] test src/dataflow/operators/generic/notificator.rs - dataflow::operators::generic::notificator::FrontierNotificator (line 186) ... ok
Dec 06 20:13:48.048 INFO [stdout] test src/dataflow/operators/generic/operator.rs - dataflow::operators::generic::operator::Operator::binary (line 260) ... ok
Dec 06 20:13:53.540 INFO [stdout] test src/dataflow/operators/generic/operator.rs - dataflow::operators::generic::operator::Operator::sink (line 305) ... ok
Dec 06 20:13:57.296 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:57.308 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 06 20:13:58.676 INFO [stdout] test src/dataflow/operators/generic/operator.rs - dataflow::operators::generic::operator::Operator::binary_notify (line 205) ... ok
Dec 06 20:14:01.326 INFO [stdout] test src/dataflow/operators/generic/operator.rs - dataflow::operators::generic::operator::Operator::binary_frontier (line 144) ... ok
Dec 06 20:14:35.248 INFO [stdout] test src/dataflow/operators/generic/operator.rs - dataflow::operators::generic::operator::Operator::unary_frontier (line 25) ... ok
Dec 06 20:14:36.372 INFO [stdout] test src/dataflow/operators/generic/operator.rs - dataflow::operators::generic::operator::source (line 533) ... ok
Dec 06 20:14:37.968 INFO [stdout] test src/dataflow/operators/generic/operator.rs - dataflow::operators::generic::operator::Operator::unary_notify (line 72) ... ok
Dec 06 20:14:38.306 INFO [stdout] test src/dataflow/operators/generic/operator.rs - dataflow::operators::generic::operator::Operator::unary (line 108) ... ok
Dec 06 20:14:42.430 INFO [stdout] test src/dataflow/operators/generic/operator.rs - dataflow::operators::generic::operator::empty (line 592) ... ok
Dec 06 20:14:43.332 INFO [stdout] test src/dataflow/operators/input.rs - dataflow::operators::input::Handle<T, D>::new (line 168) ... ok
Dec 06 20:14:43.576 INFO [stdout] test src/dataflow/operators/input.rs - dataflow::operators::input::Handle<T, D>::to_stream (line 204) ... ok
Dec 06 20:14:49.584 INFO [stdout] test src/dataflow/operators/input.rs - dataflow::operators::input::Input::input_from (line 67) ... ok
Dec 06 20:15:15.740 INFO [stdout] test src/dataflow/operators/inspect.rs - dataflow::operators::inspect::Inspect::inspect (line 13) ... ok
Dec 06 20:15:18.656 INFO [stdout] test src/dataflow/operators/input.rs - dataflow::operators::input::Input::new_input (line 36) ... ok
Dec 06 20:15:18.702 INFO [stdout] test src/dataflow/operators/inspect.rs - dataflow::operators::inspect::Inspect::inspect_batch (line 49) ... ok
Dec 06 20:15:23.464 INFO [stdout] test src/dataflow/operators/inspect.rs - dataflow::operators::inspect::Inspect::inspect_time (line 30) ... ok
Dec 06 20:15:24.390 INFO [stdout] test src/dataflow/operators/map.rs - dataflow::operators::map::Map::map (line 13) ... ok
Dec 06 20:15:25.556 INFO [stdout] test src/dataflow/operators/map.rs - dataflow::operators::map::Map::flat_map (line 39) ... ok
Dec 06 20:15:26.337 INFO [stdout] test src/dataflow/operators/map.rs - dataflow::operators::map::Map::map_in_place (line 26) ... ok
Dec 06 20:15:35.060 INFO [stdout] test src/dataflow/operators/partition.rs - dataflow::operators::partition::Partition::partition (line 22) ... ok
Dec 06 20:15:40.748 INFO [stdout] test src/dataflow/operators/probe.rs - dataflow::operators::probe::Handle<T>::with_frontier (line 159) ... ok
Dec 06 20:16:06.336 INFO [stdout] test src/dataflow/operators/probe.rs - dataflow::operators::probe::Probe::probe (line 23) ... ok
Dec 06 20:16:11.040 INFO [stdout] test src/dataflow/operators/probe.rs - dataflow::operators::probe::Probe::probe_with (line 52) ... ok
Dec 06 20:16:11.733 INFO [stdout] test src/dataflow/operators/to_stream.rs - dataflow::operators::to_stream::ToStream::to_stream (line 16) ... ok
Dec 06 20:16:12.637 INFO [stdout] test src/dataflow/operators/unordered_input.rs - dataflow::operators::unordered_input::UnorderedInput::new_unordered_input (line 39) ... ok
Dec 06 20:16:12.648 INFO [stdout] test src/dataflow/operators/reclock.rs - dataflow::operators::reclock::Reclock::reclock (line 21) ... ok
Dec 06 20:16:15.745 INFO [stdout] test src/dataflow/scopes/mod.rs - dataflow::scopes::Scope::iterative (line 106) ... ok
Dec 06 20:16:16.656 INFO [stdout] test src/dataflow/scopes/mod.rs - dataflow::scopes::Scope::region (line 136) ... ok
Dec 06 20:16:16.656 INFO [stdout] test src/execute.rs - execute::execute_from_args (line 221) ... ignored
Dec 06 20:16:16.656 INFO [stdout] test src/execute.rs - execute::execute_from_args (line 227) ... ignored
Dec 06 20:16:36.780 INFO [stdout] test src/dataflow/scopes/mod.rs - dataflow::scopes::Scope::scoped (line 78) ... ok
Dec 06 20:16:54.220 INFO [stdout] test src/execute.rs - execute::example (line 24) ... ok
Dec 06 20:17:01.964 INFO [stdout] test src/execute.rs - execute::execute_from (line 246) ... ok
Dec 06 20:17:02.063 INFO [stdout] test src/execute.rs - execute::execute (line 81) ... ok
Dec 06 20:17:03.570 INFO [stdout] test src/execute.rs - execute::example (line 38) ... ok
Dec 06 20:17:07.268 INFO [stdout] test src/execute.rs - execute::execute (line 97) ... ok
Dec 06 20:17:12.556 INFO [stdout] test src/execute.rs - execute::execute_from_args (line 210) ... ok
Dec 06 20:17:15.634 INFO [stdout] test src/progress/change_batch.rs - progress::change_batch::ChangeBatch<T>::clear (line 164) ... ok
Dec 06 20:17:15.634 INFO [stdout] test src/lib.rs -  (line 25) ... ok
Dec 06 20:17:19.498 INFO [stdout] test src/progress/change_batch.rs - progress::change_batch::ChangeBatch<T>::drain (line 142) ... ok
Dec 06 20:17:20.120 INFO [stdout] test src/progress/change_batch.rs - progress::change_batch::ChangeBatch<T>::into_inner (line 103) ... ok
Dec 06 20:17:21.974 INFO [stdout] test src/progress/change_batch.rs - progress::change_batch::ChangeBatch<T>::extend (line 85) ... ok
Dec 06 20:17:23.160 INFO [stdout] test src/progress/change_batch.rs - progress::change_batch::ChangeBatch<T>::is_empty (line 186) ... ok
Dec 06 20:17:23.752 INFO [stdout] test src/progress/change_batch.rs - progress::change_batch::ChangeBatch<T>::drain_into (line 220) ... ok
Dec 06 20:17:28.681 INFO [stdout] test src/progress/change_batch.rs - progress::change_batch::ChangeBatch<T>::iter (line 118) ... ok
Dec 06 20:17:29.400 INFO [stdout] test src/progress/change_batch.rs - progress::change_batch::ChangeBatch<T>::new_from (line 43) ... ok
Dec 06 20:17:29.832 INFO [stdout] test src/progress/change_batch.rs - progress::change_batch::ChangeBatch<T>::new (line 26) ... ok
Dec 06 20:17:32.789 INFO [stdout] test src/progress/change_batch.rs - progress::change_batch::ChangeBatch<T>::update (line 69) ... ok
Dec 06 20:17:33.456 INFO [stdout] test src/progress/frontier.rs - progress::frontier::AntichainRef<'a, T>::is_empty (line 385) ... ok
Dec 06 20:17:36.712 INFO [stdout] test src/progress/frontier.rs - progress::frontier::AntichainRef<'a, T>::less_equal (line 433) ... ok
Dec 06 20:17:36.984 INFO [stdout] test src/progress/frontier.rs - progress::frontier::AntichainRef<'a, T>::iter (line 400) ... ok
Dec 06 20:17:37.900 INFO [stdout] test src/progress/frontier.rs - progress::frontier::AntichainRef<'a, T>::less_than (line 416) ... ok
Dec 06 20:17:40.416 INFO [stdout] test src/progress/frontier.rs - progress::frontier::MutableAntichain<T>::frontier (line 143) ... ok
Dec 06 20:17:41.073 INFO [stdout] test src/progress/frontier.rs - progress::frontier::MutableAntichain<T>::clear (line 118) ... ok
Dec 06 20:17:43.174 INFO [stdout] test src/progress/frontier.rs - progress::frontier::MutableAntichain<T>::is_empty (line 179) ... ok
Dec 06 20:17:45.712 INFO [stdout] test src/progress/frontier.rs - progress::frontier::MutableAntichain<T>::less_equal (line 213) ... ok
Dec 06 20:17:46.508 INFO [stdout] test src/progress/frontier.rs - progress::frontier::MutableAntichain<T>::less_than (line 195) ... ok
Dec 06 20:17:49.156 INFO [stdout] test src/progress/frontier.rs - progress::frontier::MutableAntichain<T>::new (line 98) ... ok
Dec 06 20:17:50.072 INFO [stdout] test src/progress/frontier.rs - progress::frontier::MutableAntichain<T>::new_bottom (line 159) ... ok
Dec 06 20:17:50.348 INFO [stdout] test src/progress/frontier.rs - progress::frontier::MutableAntichain<T>::update_iter (line 245) ... ok
Dec 06 20:17:53.021 INFO [stdout] test src/progress/frontier.rs - progress::frontier::MutableAntichain<T>::update_iter_and (line 268) ... ok
Dec 06 20:17:54.176 INFO [stdout] test src/progress/nested/reachability.rs - progress::nested::reachability (line 10) ... ok
Dec 06 20:17:56.176 INFO [stdout] test src/progress/nested/reachability.rs - progress::nested::reachability::Builder (line 72) ... ok
Dec 06 20:17:56.533 INFO [stdout] test src/progress/nested/reachability.rs - progress::nested::reachability::Tracker (line 299) ... ok
Dec 06 20:17:56.970 INFO [stdout] test src/progress/nested/reachability_neu.rs - progress::nested::reachability_neu (line 10) ... ok
Dec 06 20:17:57.246 INFO [stdout] test src/progress/timestamp.rs - progress::timestamp::PathSummary::followed_by (line 51) ... ok
Dec 06 20:17:57.271 INFO [stdout] test src/progress/timestamp.rs - progress::timestamp::PathSummary::results_in (line 30) ... ok
Dec 06 20:17:57.404 INFO [stdout] test src/progress/nested/reachability_neu.rs - progress::nested::reachability_neu::Builder (line 107) ... ok
Dec 06 20:17:57.404 INFO [stdout] 
Dec 06 20:17:57.404 INFO [stdout] test result: ok. 102 passed; 0 failed; 2 ignored; 0 measured; 0 filtered out
Dec 06 20:17:57.404 INFO [stdout] 
Dec 06 20:17:57.459 INFO [stderr] su: No module specific data is present
Dec 06 20:17:57.768 INFO running `"docker" "inspect" "664acd7c9d042e035f41344fdbe271c83fcc2674771ea49dcf44e91c7855adec"`
Dec 06 20:17:57.872 INFO running `"docker" "rm" "-f" "664acd7c9d042e035f41344fdbe271c83fcc2674771ea49dcf44e91c7855adec"`
Dec 06 20:17:58.130 INFO [stdout] 664acd7c9d042e035f41344fdbe271c83fcc2674771ea49dcf44e91c7855adec