Dec 14 16:54:42.092 INFO testing timely-0.8.0 against master#bd47d6825bf4090517549d33cfef10d3300b4a75 for pr-56550 Dec 14 16:54:42.092 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-2/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/pr-56550/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/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 +bd47d6825bf4090517549d33cfef10d3300b4a75-alt build --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"` Dec 14 16:54:42.603 INFO [stdout] 47c88af40ee31789efd82ebde48d3702ce1ba4c0969755696eefd7557aac3d40 Dec 14 16:54:42.611 INFO running `"docker" "start" "-a" "47c88af40ee31789efd82ebde48d3702ce1ba4c0969755696eefd7557aac3d40"` Dec 14 16:54:43.364 INFO [stderr] usermod: no changes Dec 14 16:54:43.466 INFO [stderr] Compiling timely_communication v0.8.0 Dec 14 16:54:46.044 INFO [stderr] Compiling timely v0.8.0 (/source) Dec 14 16:54:51.639 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 8.25s Dec 14 16:54:51.647 INFO [stderr] su: No module specific data is present Dec 14 16:54:51.921 INFO running `"docker" "inspect" "47c88af40ee31789efd82ebde48d3702ce1ba4c0969755696eefd7557aac3d40"` Dec 14 16:54:51.997 INFO running `"docker" "rm" "-f" "47c88af40ee31789efd82ebde48d3702ce1ba4c0969755696eefd7557aac3d40"` Dec 14 16:54:52.061 INFO [stdout] 47c88af40ee31789efd82ebde48d3702ce1ba4c0969755696eefd7557aac3d40 Dec 14 16:54:52.061 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-2/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/pr-56550/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/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 +bd47d6825bf4090517549d33cfef10d3300b4a75-alt test --frozen --no-run" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"` Dec 14 16:54:52.246 INFO [stdout] edf1411cce166bed225e26bb8ae788b059e544e0eca3e2d6ebd04501b5afe613 Dec 14 16:54:52.248 INFO running `"docker" "start" "-a" "edf1411cce166bed225e26bb8ae788b059e544e0eca3e2d6ebd04501b5afe613"` Dec 14 16:54:52.869 INFO [stderr] usermod: no changes Dec 14 16:54:52.932 INFO [stderr] Compiling timely v0.8.0 (/source) Dec 14 16:57:25.695 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 2m 32s Dec 14 16:57:25.751 INFO [stderr] su: No module specific data is present Dec 14 16:57:26.839 INFO running `"docker" "inspect" "edf1411cce166bed225e26bb8ae788b059e544e0eca3e2d6ebd04501b5afe613"` Dec 14 16:57:27.073 INFO running `"docker" "rm" "-f" "edf1411cce166bed225e26bb8ae788b059e544e0eca3e2d6ebd04501b5afe613"` Dec 14 16:57:27.359 INFO [stdout] edf1411cce166bed225e26bb8ae788b059e544e0eca3e2d6ebd04501b5afe613 Dec 14 16:57:27.363 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-2/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/pr-56550/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/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 +bd47d6825bf4090517549d33cfef10d3300b4a75-alt test --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"` Dec 14 16:57:27.979 INFO [stdout] 32eb0cc5c2338e516e4dc2cc2830055eee7591f9126342ac123b69d8b3fab828 Dec 14 16:57:27.984 INFO running `"docker" "start" "-a" "32eb0cc5c2338e516e4dc2cc2830055eee7591f9126342ac123b69d8b3fab828"` Dec 14 16:57:29.076 INFO [stderr] usermod: no changes Dec 14 16:57:29.199 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 0.08s Dec 14 16:57:29.211 INFO [stderr] Running /target/debug/deps/timely-8edcf6ac68c44e08 Dec 14 16:57:29.215 INFO [stdout] Dec 14 16:57:29.215 INFO [stdout] running 5 tests Dec 14 16:57:29.220 INFO [stdout] test dataflow::operators::generic::builder_rc::tests::correct_capabilities ... ok Dec 14 16:57:29.220 INFO [stdout] test dataflow::operators::generic::notificator::notificator_delivers_notifications_in_topo_order ... ok Dec 14 16:57:29.222 INFO [stderr] thread 'worker thread 0' panicked at 'Attempted to open output session with invalid capability', src/dataflow/operators/generic/handles.rs:212:9 Dec 14 16:57:29.227 INFO [stderr] stack backtrace: Dec 14 16:57:29.239 INFO [stdout] test dataflow::operators::probe::tests::probe ... ok Dec 14 16:57:29.287 INFO [stderr] 0: 0x560bfbd1c2a3 - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::heb4d4d98bcf46d90 Dec 14 16:57:29.306 INFO [stderr] at src/libstd/sys/unix/backtrace/tracing/gcc_s.rs:49 Dec 14 16:57:29.306 INFO [stderr] 1: 0x560bfbd15d08 - std::sys_common::backtrace::_print::h919420b22c2473e3 Dec 14 16:57:29.306 INFO [stderr] at src/libstd/sys_common/backtrace.rs:71 Dec 14 16:57:29.306 INFO [stderr] 2: 0x560bfbd1a502 - std::panicking::default_hook::{{closure}}::h22d4ac021acb88d5 Dec 14 16:57:29.306 INFO [stderr] at src/libstd/sys_common/backtrace.rs:59 Dec 14 16:57:29.306 INFO [stderr] at src/libstd/panicking.rs:210 Dec 14 16:57:29.306 INFO [stderr] 3: 0x560bfbd1a274 - std::panicking::default_hook::h1134a4d34aab4428 Dec 14 16:57:29.306 INFO [stderr] at src/libstd/panicking.rs:224 Dec 14 16:57:29.306 INFO [stderr] 4: 0x560bfbd1ac10 - std::panicking::rust_panic_with_hook::h9c89557c251f2d42 Dec 14 16:57:29.306 INFO [stderr] at src/libstd/panicking.rs:487 Dec 14 16:57:29.306 INFO [stderr] 5: 0x560bfbd00a97 - std::panicking::begin_panic::hbd6a3d489e00cc84 Dec 14 16:57:29.307 INFO [stderr] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/panicking.rs:421 Dec 14 16:57:29.307 INFO [stderr] 6: 0x560bfbbfd36c - >::session::hfb6982a11191eb5a Dec 14 16:57:29.319 INFO [stderr] at src/dataflow/operators/generic/handles.rs:212 Dec 14 16:57:29.319 INFO [stderr] 7: 0x560bfbb6107d - timely::dataflow::operators::generic::builder_rc::tests::incorrect_capabilities::{{closure}}::{{closure}}::{{closure}}::ha6e38140338ca627 Dec 14 16:57:29.324 INFO [stderr] at src/dataflow/operators/generic/builder_rc.rs:200 Dec 14 16:57:29.324 INFO [stderr] 8: 0x560bfbaf43a3 - >::build::{{closure}}::hf010ee6c20a94a12 Dec 14 16:57:29.327 INFO [stderr] at src/dataflow/operators/generic/builder_rc.rs:136 Dec 14 16:57:29.327 INFO [stderr] 9: 0x560bfbb0d5a0 - as timely::progress::operate::Operate>::pull_internal_progress::h53aedbd001116104 Dec 14 16:57:29.332 INFO [stderr] at src/dataflow/operators/generic/builder_raw.rs:219 Dec 14 16:57:29.332 INFO [stderr] 10: 0x560bfbc553a0 - >::exchange_progress::hb885984b7d4dc757 Dec 14 16:57:29.335 INFO [stderr] at src/progress/nested/subgraph.rs:803 Dec 14 16:57:29.335 INFO [stderr] 11: 0x560bfbc4f66a - as timely::progress::operate::Operate>::pull_internal_progress::hfa967e9b5309c08b Dec 14 16:57:29.335 INFO [stderr] at src/progress/nested/subgraph.rs:536 Dec 14 16:57:29.335 INFO [stderr] 12: 0x560bfbc3892a - timely::worker::Wrapper::step::{{closure}}::hb3cbfe07c7c0ff44 Dec 14 16:57:29.335 INFO [stderr] at src/worker.rs:197 Dec 14 16:57:29.335 INFO [stderr] 13: 0x560bfbafa584 - >::map::h08e5cd1d24e0a8a4 Dec 14 16:57:29.335 INFO [stderr] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libcore/option.rs:424 Dec 14 16:57:29.335 INFO [stderr] 14: 0x560bfbc387e3 - timely::worker::Wrapper::step::hacf6b046309f7096 Dec 14 16:57:29.335 INFO [stderr] at src/worker.rs:197 Dec 14 16:57:29.335 INFO [stderr] 15: 0x560bfbc35903 - >::step::hb6d4e155f1a77406 Dec 14 16:57:29.335 INFO [stderr] at src/worker.rs:68 Dec 14 16:57:29.335 INFO [stderr] 16: 0x560bfbb234ee - timely::execute::example::{{closure}}::h964ea2785e32b369 Dec 14 16:57:29.347 INFO [stderr] at src/execute.rs:59 Dec 14 16:57:29.347 INFO [stderr] 17: 0x560bfbadf640 - timely_communication::initialize::initialize_from::{{closure}}::h1e244329c9a380e4 Dec 14 16:57:29.347 INFO [stderr] at /cargo-home/registry/src/github.com-1ecc6299db9ec823/timely_communication-0.8.0/src/initialize.rs:261 Dec 14 16:57:29.347 INFO [stderr] 18: 0x560bfbbc2f22 - std::sys_common::backtrace::__rust_begin_short_backtrace::hfe4ed0533a65223d Dec 14 16:57:29.347 INFO [stderr] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/sys_common/backtrace.rs:136 Dec 14 16:57:29.347 INFO [stderr] 19: 0x560bfbbdfbc4 - std::thread::Builder::spawn_unchecked::{{closure}}::{{closure}}::h43c4a398099efb68 Dec 14 16:57:29.347 INFO [stderr] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/thread/mod.rs:479 Dec 14 16:57:29.347 INFO [stderr] 20: 0x560bfbbda534 - as core::ops::function::FnOnce<()>>::call_once::h5c0ef0d76e4336ff Dec 14 16:57:29.347 INFO [stderr] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/panic.rs:319 Dec 14 16:57:29.347 INFO [stderr] 21: 0x560bfbbe0237 - std::panicking::try::do_call::h81ad910a9ccf59cb Dec 14 16:57:29.347 INFO [stderr] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/panicking.rs:306 Dec 14 16:57:29.347 INFO [stderr] 22: 0x560bfbd2a6c9 - __rust_maybe_catch_panic Dec 14 16:57:29.347 INFO [stderr] at src/libpanic_unwind/lib.rs:102 Dec 14 16:57:29.347 INFO [stderr] 23: 0x560bfbbe0118 - std::panicking::try::he80943f988ff73fa Dec 14 16:57:29.347 INFO [stderr] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/panicking.rs:285 Dec 14 16:57:29.347 INFO [stderr] 24: 0x560bfbbddae4 - std::panic::catch_unwind::h4e13048a9de976be Dec 14 16:57:29.348 INFO [stderr] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/panic.rs:398 Dec 14 16:57:29.348 INFO [stderr] 25: 0x560bfbbdf24d - std::thread::Builder::spawn_unchecked::{{closure}}::h0847da24dab7b444 Dec 14 16:57:29.348 INFO [stderr] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/thread/mod.rs:478 Dec 14 16:57:29.348 INFO [stderr] 26: 0x560bfbbf6292 - >::call_box::h95e8990fc5ac6dd2 Dec 14 16:57:29.348 INFO [stderr] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/liballoc/boxed.rs:673 Dec 14 16:57:29.348 INFO [stderr] 27: 0x560bfbd1e5ed - std::sys::unix::thread::Thread::new::thread_start::h08aa646226e66c99 Dec 14 16:57:29.348 INFO [stderr] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/liballoc/boxed.rs:683 Dec 14 16:57:29.348 INFO [stderr] at src/libstd/sys_common/thread.rs:24 Dec 14 16:57:29.348 INFO [stderr] at src/libstd/sys/unix/thread.rs:91 Dec 14 16:57:29.348 INFO [stderr] 28: 0x7f855402f6b9 - start_thread Dec 14 16:57:29.348 INFO [stderr] 29: 0x7f8553b4f41c - clone Dec 14 16:57:29.348 INFO [stderr] 30: 0x0 - Dec 14 16:57:29.404 INFO [stdout] test dataflow::operators::generic::builder_rc::tests::incorrect_capabilities ... ok Dec 14 16:57:30.803 INFO [stdout] test dataflow::operators::capture::event::link::avoid_stack_overflow_in_drop ... ok Dec 14 16:57:30.803 INFO [stdout] Dec 14 16:57:30.803 INFO [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out Dec 14 16:57:30.803 INFO [stdout] Dec 14 16:57:30.803 INFO [stdout] Dec 14 16:57:30.803 INFO [stdout] running 3 tests Dec 14 16:57:30.803 INFO [stderr] Running /target/debug/deps/barrier-b2970f64d817bc07 Dec 14 16:57:30.830 INFO [stdout] test barrier_sync_1w ... ok Dec 14 16:57:30.867 INFO [stdout] test barrier_sync_2w ... ok Dec 14 16:57:30.958 INFO [stdout] test barrier_sync_3w ... ok Dec 14 16:57:30.958 INFO [stdout] Dec 14 16:57:30.958 INFO [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out Dec 14 16:57:30.958 INFO [stdout] Dec 14 16:57:30.959 INFO [stderr] Running /target/debug/deps/skeptic-b8026250e8c3c953 Dec 14 16:57:30.960 INFO [stdout] Dec 14 16:57:30.960 INFO [stdout] running 0 tests Dec 14 16:57:30.961 INFO [stdout] Dec 14 16:57:30.961 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out Dec 14 16:57:30.961 INFO [stdout] Dec 14 16:57:30.961 INFO [stderr] Doc-tests timely Dec 14 16:57:32.375 INFO [stdout] Dec 14 16:57:32.375 INFO [stdout] running 104 tests Dec 14 16:58:05.651 INFO [stdout] test src/dataflow/operators/branch.rs - dataflow::operators::branch::Branch::branch (line 19) ... ok Dec 14 16:58:07.199 INFO [stdout] test src/dataflow/operators/broadcast.rs - dataflow::operators::broadcast::Broadcast::broadcast (line 22) ... ok Dec 14 16:58:08.619 INFO [stdout] test src/dataflow/mod.rs - dataflow (line 7) ... ok Dec 14 16:58:12.407 INFO [stdout] test src/dataflow/operators/branch.rs - dataflow::operators::branch::BranchWhen::branch_when (line 81) ... ok Dec 14 16:58:12.536 INFO [stdout] test src/dataflow/operators/capture/capture.rs - dataflow::operators::capture::capture::Capture::capture_into (line 31) ... ok Dec 14 16:58:15.599 INFO [stdout] test src/dataflow/operators/aggregation/aggregate.rs - dataflow::operators::aggregation::aggregate::Aggregate::aggregate (line 47) ... ok Dec 14 16:58:16.586 INFO [stdout] test src/dataflow/operators/aggregation/state_machine.rs - dataflow::operators::aggregation::state_machine::StateMachine::state_machine (line 28) ... ok Dec 14 16:58:16.676 INFO [stdout] test src/dataflow/operators/aggregation/aggregate.rs - dataflow::operators::aggregation::aggregate::Aggregate::aggregate (line 26) ... ok Dec 14 16:58:38.539 INFO [stdout] test src/dataflow/operators/capture/extract.rs - dataflow::operators::capture::extract::Extract::extract (line 14) ... ok Dec 14 16:58:40.911 INFO [stdout] test src/dataflow/operators/capture/mod.rs - dataflow::operators::capture (line 21) ... ok Dec 14 16:58:45.857 INFO [stdout] test src/dataflow/operators/capture/capture.rs - dataflow::operators::capture::capture::Capture::capture_into (line 70) ... ok Dec 14 16:58:45.859 INFO [stdout] test src/dataflow/operators/concat.rs - dataflow::operators::concat::Concatenate::concatenate (line 37) ... ok Dec 14 16:58:45.875 INFO [stdout] test src/dataflow/operators/capture/mod.rs - dataflow::operators::capture (line 48) ... ok Dec 14 16:58:46.807 INFO [stdout] test src/dataflow/operators/concat.rs - dataflow::operators::concat::Concat::concat (line 13) ... ok Dec 14 16:58:50.379 INFO [stdout] test src/dataflow/operators/count.rs - dataflow::operators::count::Accumulate::count (line 35) ... ok Dec 14 16:58:52.203 INFO [stdout] test src/dataflow/operators/count.rs - dataflow::operators::count::Accumulate::accumulate (line 17) ... ok Dec 14 16:59:09.855 INFO [stdout] test src/dataflow/operators/delay.rs - dataflow::operators::delay::Delay::delay (line 25) ... ok Dec 14 16:59:16.655 INFO [stdout] test src/dataflow/operators/delay.rs - dataflow::operators::delay::Delay::delay_batch (line 80) ... ok Dec 14 16:59:19.927 INFO [stdout] test src/dataflow/operators/delay.rs - dataflow::operators::delay::Delay::delay_total (line 52) ... ok Dec 14 16:59:23.459 INFO [stdout] test src/dataflow/operators/enterleave.rs - dataflow::operators::enterleave::Enter::enter (line 45) ... ok Dec 14 16:59:27.335 INFO [stdout] test src/dataflow/operators/enterleave.rs - dataflow::operators::enterleave::Leave::leave (line 107) ... ok Dec 14 16:59:28.311 INFO [stdout] test src/dataflow/operators/exchange.rs - dataflow::operators::exchange::Exchange::exchange (line 15) ... ok Dec 14 16:59:28.861 INFO [stdout] test src/dataflow/operators/enterleave.rs - dataflow::operators::enterleave (line 8) ... ok Dec 14 16:59:29.215 INFO [stdout] test src/dataflow/operators/enterleave.rs - dataflow::operators::enterleave::EnterAt::enter_at (line 66) ... ok Dec 14 16:59:44.263 INFO [stdout] test src/dataflow/operators/feedback.rs - dataflow::operators::feedback::Feedback::feedback (line 29) ... ok Dec 14 16:59:45.459 INFO [stdout] test src/dataflow/operators/feedback.rs - dataflow::operators::feedback::ConnectLoop::connect_loop (line 145) ... ok Dec 14 16:59:49.055 INFO [stdout] test src/dataflow/operators/filter.rs - dataflow::operators::filter::Filter::filter (line 13) ... ok Dec 14 16:59:56.175 INFO [stdout] test src/dataflow/operators/generic/handles.rs - dataflow::operators::generic::handles::InputHandle::for_each (line 63) ... ok Dec 14 16:59:56.693 INFO [stdout] test src/dataflow/operators/generic/handles.rs - dataflow::operators::generic::handles::OutputHandle<'a, T, D, P>::session (line 194) ... ok Dec 14 16:59:56.853 INFO [stdout] test src/dataflow/operators/generic/handles.rs - dataflow::operators::generic::handles::FrontieredInputHandle<'a, T, D, P>::for_each (line 110) ... ok Dec 14 16:59:57.344 INFO [stdout] test src/dataflow/operators/feedback.rs - dataflow::operators::feedback::LoopVariable::loop_variable (line 55) ... ok Dec 14 17:00:06.407 INFO [stdout] test src/dataflow/operators/flow_controlled.rs - dataflow::operators::flow_controlled::iterator_source (line 36) ... ok Dec 14 17:00:30.831 INFO [stdout] test src/dataflow/operators/generic/notificator.rs - dataflow::operators::generic::notificator::FrontierNotificator::notify_at (line 262) ... ok Dec 14 17:00:43.291 INFO [stdout] test src/dataflow/operators/generic/notificator.rs - dataflow::operators::generic::notificator::FrontierNotificator::pending (line 378) ... ok Dec 14 17:00:44.287 INFO [stdout] test src/dataflow/operators/generic/notificator.rs - dataflow::operators::generic::notificator::FrontierNotificator (line 186) ... test src/dataflow/operators/generic/notificator.rs - dataflow::operators::generic::notificator::FrontierNotificator (line 186) has been running for over 60 seconds Dec 14 17:00:46.751 INFO [stdout] test src/dataflow/operators/generic/notificator.rs - dataflow::operators::generic::notificator::FrontierNotificator (line 186) ... ok Dec 14 17:00:54.511 INFO [stdout] test src/dataflow/operators/generic/operator.rs - dataflow::operators::generic::operator::Operator::binary (line 260) ... ok Dec 14 17:00:55.397 INFO [stdout] test src/dataflow/operators/generic/operator.rs - dataflow::operators::generic::operator::Operator::binary_frontier (line 144) ... ok Dec 14 17:00:56.167 INFO [stdout] test src/dataflow/operators/generic/notificator.rs - dataflow::operators::generic::notificator::Notificator<'a, T>::notify_at (line 52) ... test src/dataflow/operators/generic/notificator.rs - dataflow::operators::generic::notificator::Notificator<'a, T>::notify_at (line 52) has been running for over 60 seconds Dec 14 17:00:57.064 INFO [stdout] test src/dataflow/operators/generic/notificator.rs - dataflow::operators::generic::notificator::Notificator<'a, T>::notify_at (line 52) ... ok Dec 14 17:00:57.359 INFO [stdout] test src/dataflow/operators/generic/operator.rs - dataflow::operators::generic::operator::Operator::binary_notify (line 205) ... test src/dataflow/operators/generic/operator.rs - dataflow::operators::generic::operator::Operator::binary_notify (line 205) has been running for over 60 seconds Dec 14 17:00:57.409 INFO [stdout] test src/dataflow/operators/generic/operator.rs - dataflow::operators::generic::operator::Operator::binary_notify (line 205) ... ok Dec 14 17:01:06.419 INFO [stdout] test src/dataflow/operators/generic/operator.rs - dataflow::operators::generic::operator::Operator::sink (line 305) ... test src/dataflow/operators/generic/operator.rs - dataflow::operators::generic::operator::Operator::sink (line 305) has been running for over 60 seconds Dec 14 17:01:11.927 INFO [stdout] test src/dataflow/operators/generic/operator.rs - dataflow::operators::generic::operator::Operator::sink (line 305) ... ok Dec 14 17:01:30.894 INFO [stdout] test src/dataflow/operators/generic/operator.rs - dataflow::operators::generic::operator::Operator::unary (line 108) ... test src/dataflow/operators/generic/operator.rs - dataflow::operators::generic::operator::Operator::unary (line 108) has been running for over 60 seconds Dec 14 17:01:31.955 INFO [stdout] test src/dataflow/operators/generic/operator.rs - dataflow::operators::generic::operator::Operator::unary (line 108) ... ok Dec 14 17:01:35.807 INFO [stdout] test src/dataflow/operators/generic/operator.rs - dataflow::operators::generic::operator::Operator::unary_frontier (line 25) ... ok Dec 14 17:01:46.751 INFO [stdout] test src/dataflow/operators/generic/operator.rs - dataflow::operators::generic::operator::Operator::unary_notify (line 72) ... test src/dataflow/operators/generic/operator.rs - dataflow::operators::generic::operator::Operator::unary_notify (line 72) has been running for over 60 seconds Dec 14 17:01:52.175 INFO [stdout] test src/dataflow/operators/generic/operator.rs - dataflow::operators::generic::operator::source (line 533) ... ok Dec 14 17:01:52.525 INFO [stdout] test src/dataflow/operators/generic/operator.rs - dataflow::operators::generic::operator::Operator::unary_notify (line 72) ... ok Dec 14 17:01:54.507 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 14 17:01:54.524 INFO [stdout] test src/dataflow/operators/input.rs - dataflow::operators::input::Handle::to_stream (line 204) ... ok Dec 14 17:01:54.747 INFO [stdout] test src/dataflow/operators/generic/operator.rs - dataflow::operators::generic::operator::empty (line 592) ... ok Dec 14 17:01:55.533 INFO [stdout] test src/dataflow/operators/input.rs - dataflow::operators::input::Handle::new (line 168) ... ok Dec 14 17:02:11.947 INFO [stdout] test src/dataflow/operators/input.rs - dataflow::operators::input::Input::input_from (line 67) ... test src/dataflow/operators/input.rs - dataflow::operators::input::Input::input_from (line 67) has been running for over 60 seconds Dec 14 17:02:16.842 INFO [stdout] test src/dataflow/operators/input.rs - dataflow::operators::input::Input::input_from (line 67) ... ok Dec 14 17:02:19.447 INFO [stdout] test src/dataflow/operators/input.rs - dataflow::operators::input::Input::new_input (line 36) ... ok Dec 14 17:02:35.823 INFO [stdout] test src/dataflow/operators/inspect.rs - dataflow::operators::inspect::Inspect::inspect (line 13) ... test src/dataflow/operators/inspect.rs - dataflow::operators::inspect::Inspect::inspect (line 13) has been running for over 60 seconds Dec 14 17:02:37.246 INFO [stdout] test src/dataflow/operators/inspect.rs - dataflow::operators::inspect::Inspect::inspect (line 13) ... ok Dec 14 17:02:42.387 INFO [stdout] test src/dataflow/operators/inspect.rs - dataflow::operators::inspect::Inspect::inspect_batch (line 49) ... ok Dec 14 17:02:51.371 INFO [stdout] test src/dataflow/operators/map.rs - dataflow::operators::map::Map::map (line 13) ... ok Dec 14 17:02:51.699 INFO [stdout] test src/dataflow/operators/map.rs - dataflow::operators::map::Map::map_in_place (line 26) ... ok Dec 14 17:02:51.783 INFO [stdout] test src/dataflow/operators/inspect.rs - dataflow::operators::inspect::Inspect::inspect_time (line 30) ... ok Dec 14 17:02:51.943 INFO [stdout] test src/dataflow/operators/map.rs - dataflow::operators::map::Map::flat_map (line 39) ... ok Dec 14 17:02:52.935 INFO [stdout] test src/dataflow/operators/probe.rs - dataflow::operators::probe::Handle::with_frontier (line 159) ... ok Dec 14 17:03:04.943 INFO [stdout] test src/dataflow/operators/partition.rs - dataflow::operators::partition::Partition::partition (line 22) ... ok Dec 14 17:03:16.135 INFO [stdout] test src/dataflow/operators/probe.rs - dataflow::operators::probe::Probe::probe (line 23) ... ok Dec 14 17:03:25.075 INFO [stdout] test src/dataflow/operators/probe.rs - dataflow::operators::probe::Probe::probe_with (line 52) ... ok Dec 14 17:03:31.771 INFO [stdout] test src/dataflow/operators/unordered_input.rs - dataflow::operators::unordered_input::UnorderedInput::new_unordered_input (line 39) ... ok Dec 14 17:03:33.271 INFO [stdout] test src/dataflow/operators/reclock.rs - dataflow::operators::reclock::Reclock::reclock (line 21) ... ok Dec 14 17:03:35.465 INFO [stdout] test src/dataflow/operators/to_stream.rs - dataflow::operators::to_stream::ToStream::to_stream (line 16) ... ok Dec 14 17:03:35.780 INFO [stdout] test src/dataflow/scopes/mod.rs - dataflow::scopes::Scope::region (line 136) ... ok Dec 14 17:03:42.471 INFO [stdout] test src/dataflow/scopes/mod.rs - dataflow::scopes::Scope::iterative (line 106) ... ok Dec 14 17:03:42.471 INFO [stdout] test src/execute.rs - execute::execute_from_args (line 221) ... ignored Dec 14 17:03:42.471 INFO [stdout] test src/execute.rs - execute::execute_from_args (line 227) ... ignored Dec 14 17:03:56.551 INFO [stdout] test src/dataflow/scopes/mod.rs - dataflow::scopes::Scope::scoped (line 78) ... ok Dec 14 17:04:02.287 INFO [stdout] test src/execute.rs - execute::example (line 24) ... ok Dec 14 17:04:10.387 INFO [stdout] test src/execute.rs - execute::example (line 38) ... ok Dec 14 17:04:10.898 INFO [stdout] test src/execute.rs - execute::execute_from (line 246) ... ok Dec 14 17:04:22.863 INFO [stdout] test src/execute.rs - execute::execute (line 81) ... ok Dec 14 17:04:23.019 INFO [stdout] test src/progress/change_batch.rs - progress::change_batch::ChangeBatch::clear (line 164) ... ok Dec 14 17:04:25.779 INFO [stdout] test src/execute.rs - execute::execute (line 97) ... ok Dec 14 17:04:27.905 INFO [stdout] test src/execute.rs - execute::execute_from_args (line 210) ... ok Dec 14 17:04:29.671 INFO [stdout] test src/progress/change_batch.rs - progress::change_batch::ChangeBatch::drain (line 142) ... ok Dec 14 17:04:30.438 INFO [stdout] test src/lib.rs - (line 25) ... ok Dec 14 17:04:36.835 INFO [stdout] test src/progress/change_batch.rs - progress::change_batch::ChangeBatch::drain_into (line 220) ... ok Dec 14 17:04:38.899 INFO [stdout] test src/progress/change_batch.rs - progress::change_batch::ChangeBatch::extend (line 85) ... ok Dec 14 17:04:43.907 INFO [stdout] test src/progress/change_batch.rs - progress::change_batch::ChangeBatch::into_inner (line 103) ... ok Dec 14 17:04:44.271 INFO [stdout] test src/progress/change_batch.rs - progress::change_batch::ChangeBatch::is_empty (line 186) ... ok Dec 14 17:04:46.859 INFO [stdout] test src/progress/change_batch.rs - progress::change_batch::ChangeBatch::new (line 26) ... ok Dec 14 17:04:48.391 INFO [stdout] test src/progress/change_batch.rs - progress::change_batch::ChangeBatch::iter (line 118) ... ok Dec 14 17:04:51.920 INFO [stdout] test src/progress/change_batch.rs - progress::change_batch::ChangeBatch::new_from (line 43) ... ok Dec 14 17:04:52.863 INFO [stdout] test src/progress/change_batch.rs - progress::change_batch::ChangeBatch::update (line 69) ... ok Dec 14 17:04:57.436 INFO [stdout] test src/progress/frontier.rs - progress::frontier::AntichainRef<'a, T>::iter (line 400) ... ok Dec 14 17:04:58.023 INFO [stdout] test src/progress/frontier.rs - progress::frontier::AntichainRef<'a, T>::is_empty (line 385) ... ok Dec 14 17:05:05.493 INFO [stdout] test src/progress/frontier.rs - progress::frontier::AntichainRef<'a, T>::less_equal (line 433) ... ok Dec 14 17:05:06.831 INFO [stdout] test src/progress/frontier.rs - progress::frontier::AntichainRef<'a, T>::less_than (line 416) ... ok Dec 14 17:05:08.883 INFO [stdout] test src/progress/frontier.rs - progress::frontier::MutableAntichain::frontier (line 143) ... ok Dec 14 17:05:09.447 INFO [stdout] test src/progress/frontier.rs - progress::frontier::MutableAntichain::clear (line 118) ... ok Dec 14 17:05:13.400 INFO [stdout] test src/progress/frontier.rs - progress::frontier::MutableAntichain::is_empty (line 179) ... ok Dec 14 17:05:14.835 INFO [stdout] test src/progress/frontier.rs - progress::frontier::MutableAntichain::less_equal (line 213) ... ok Dec 14 17:05:16.627 INFO [stdout] test src/progress/frontier.rs - progress::frontier::MutableAntichain::less_than (line 195) ... ok Dec 14 17:05:18.582 INFO [stdout] test src/progress/frontier.rs - progress::frontier::MutableAntichain::new (line 98) ... ok Dec 14 17:05:24.435 INFO [stdout] test src/progress/frontier.rs - progress::frontier::MutableAntichain::update_iter (line 245) ... ok Dec 14 17:05:27.615 INFO [stdout] test src/progress/frontier.rs - progress::frontier::MutableAntichain::new_bottom (line 159) ... ok Dec 14 17:05:27.955 INFO [stdout] test src/progress/frontier.rs - progress::frontier::MutableAntichain::update_iter_and (line 268) ... ok Dec 14 17:05:31.067 INFO [stdout] test src/progress/nested/reachability.rs - progress::nested::reachability::Builder (line 72) ... ok Dec 14 17:05:32.327 INFO [stdout] test src/progress/nested/reachability.rs - progress::nested::reachability (line 10) ... ok Dec 14 17:05:34.159 INFO [stdout] test src/progress/nested/reachability.rs - progress::nested::reachability::Tracker (line 299) ... ok Dec 14 17:05:35.995 INFO [stdout] test src/progress/nested/reachability_neu.rs - progress::nested::reachability_neu::Builder (line 107) ... ok Dec 14 17:05:38.459 INFO [stdout] test src/progress/nested/reachability_neu.rs - progress::nested::reachability_neu (line 10) ... ok Dec 14 17:05:39.257 INFO [stdout] test src/progress/timestamp.rs - progress::timestamp::PathSummary::followed_by (line 51) ... ok Dec 14 17:05:39.257 INFO [stdout] test src/progress/timestamp.rs - progress::timestamp::PathSummary::results_in (line 30) ... ok Dec 14 17:05:39.257 INFO [stdout] Dec 14 17:05:39.257 INFO [stdout] test result: ok. 102 passed; 0 failed; 2 ignored; 0 measured; 0 filtered out Dec 14 17:05:39.257 INFO [stdout] Dec 14 17:05:39.339 INFO [stderr] su: No module specific data is present Dec 14 17:05:39.999 INFO running `"docker" "inspect" "32eb0cc5c2338e516e4dc2cc2830055eee7591f9126342ac123b69d8b3fab828"` Dec 14 17:05:40.237 INFO running `"docker" "rm" "-f" "32eb0cc5c2338e516e4dc2cc2830055eee7591f9126342ac123b69d8b3fab828"` Dec 14 17:05:40.451 INFO [stdout] 32eb0cc5c2338e516e4dc2cc2830055eee7591f9126342ac123b69d8b3fab828