Dec 13 08:16:01.331 INFO testing ooooak/sql-split against try#aa49d8ef14939ddec0e34b346b60174a5673d48f for pr-56550
Dec 13 08:16:01.331 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-3/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/pr-56550/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +aa49d8ef14939ddec0e34b346b60174a5673d48f-alt build --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 13 08:16:01.874 INFO [stdout] c21017f11888030cfcb20f80ff51237e648bfa6a006979d320fb85de76ab0a3c
Dec 13 08:16:01.876 INFO running `"docker" "start" "-a" "c21017f11888030cfcb20f80ff51237e648bfa6a006979d320fb85de76ab0a3c"`
Dec 13 08:16:03.075 INFO [stderr] usermod: no changes
Dec 13 08:16:03.247 INFO [stderr]    Compiling clap v2.32.0
Dec 13 08:16:17.452 INFO [stderr]    Compiling sql-split v0.1.0 (/source)
Dec 13 08:16:19.387 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 16.21s
Dec 13 08:16:19.387 INFO [stderr] su: No module specific data is present
Dec 13 08:16:19.955 INFO running `"docker" "inspect" "c21017f11888030cfcb20f80ff51237e648bfa6a006979d320fb85de76ab0a3c"`
Dec 13 08:16:20.327 INFO running `"docker" "rm" "-f" "c21017f11888030cfcb20f80ff51237e648bfa6a006979d320fb85de76ab0a3c"`
Dec 13 08:16:20.555 INFO [stdout] c21017f11888030cfcb20f80ff51237e648bfa6a006979d320fb85de76ab0a3c
Dec 13 08:16:20.559 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-3/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/pr-56550/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +aa49d8ef14939ddec0e34b346b60174a5673d48f-alt test --frozen --no-run" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 13 08:16:20.989 INFO [stdout] e088a637ee166d72b62a97ea653cf91c98c39a534e3528c8b3e55c4d8062291e
Dec 13 08:16:20.991 INFO running `"docker" "start" "-a" "e088a637ee166d72b62a97ea653cf91c98c39a534e3528c8b3e55c4d8062291e"`
Dec 13 08:16:22.197 INFO [stderr] usermod: no changes
Dec 13 08:16:22.275 INFO [stderr]    Compiling sql-split v0.1.0 (/source)
Dec 13 08:16:24.098 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 1.88s
Dec 13 08:16:24.103 INFO [stderr] su: No module specific data is present
Dec 13 08:16:24.604 INFO running `"docker" "inspect" "e088a637ee166d72b62a97ea653cf91c98c39a534e3528c8b3e55c4d8062291e"`
Dec 13 08:16:24.871 INFO running `"docker" "rm" "-f" "e088a637ee166d72b62a97ea653cf91c98c39a534e3528c8b3e55c4d8062291e"`
Dec 13 08:16:25.175 INFO [stdout] e088a637ee166d72b62a97ea653cf91c98c39a534e3528c8b3e55c4d8062291e
Dec 13 08:16:25.181 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-3/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/pr-56550/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +aa49d8ef14939ddec0e34b346b60174a5673d48f-alt test --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 13 08:16:25.603 INFO [stdout] f271d48d348af70d5923e90384e166372703f34c6d0c66619f4d6c1c9d0adc5e
Dec 13 08:16:25.611 INFO running `"docker" "start" "-a" "f271d48d348af70d5923e90384e166372703f34c6d0c66619f4d6c1c9d0adc5e"`
Dec 13 08:16:27.226 INFO [stderr] usermod: no changes
Dec 13 08:16:27.352 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.09s
Dec 13 08:16:27.352 INFO [stderr]      Running /target/debug/deps/sql_split-640748e20145bd8a
Dec 13 08:16:27.359 INFO [stdout] 
Dec 13 08:16:27.359 INFO [stdout] running 5 tests
Dec 13 08:16:27.359 INFO [stdout] test reader::reader_test::peek_next ... ok
Dec 13 08:16:27.359 INFO [stdout] test reader::reader_test::peek ... ok
Dec 13 08:16:27.359 INFO [stdout] test reader::reader_test::get ... ok
Dec 13 08:16:27.367 INFO [stdout] test reader::reader_test::empty_file ... ok
Dec 13 08:16:27.432 INFO [stdout] test parser::reader_test::tokenizer ... FAILED
Dec 13 08:16:27.432 INFO [stdout] 
Dec 13 08:16:27.432 INFO [stdout] failures:
Dec 13 08:16:27.432 INFO [stdout] 
Dec 13 08:16:27.432 INFO [stdout] ---- parser::reader_test::tokenizer stdout ----
Dec 13 08:16:27.432 INFO [stdout] thread 'parser::reader_test::tokenizer' panicked at 'white space', src/parser.rs:277:9
Dec 13 08:16:27.432 INFO [stdout] stack backtrace:
Dec 13 08:16:27.432 INFO [stdout]    0:     0x557d4f7d4ae3 - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::h2c40bdfb30f25bdd
Dec 13 08:16:27.432 INFO [stdout]                                at src/libstd/sys/unix/backtrace/tracing/gcc_s.rs:49
Dec 13 08:16:27.432 INFO [stdout]    1:     0x557d4f7cf278 - std::sys_common::backtrace::_print::he11ceebc70626ac9
Dec 13 08:16:27.432 INFO [stdout]                                at src/libstd/sys_common/backtrace.rs:71
Dec 13 08:16:27.432 INFO [stdout]    2:     0x557d4f7d3a52 - std::panicking::default_hook::{{closure}}::h4fde3ffb19c01413
Dec 13 08:16:27.432 INFO [stdout]                                at src/libstd/sys_common/backtrace.rs:59
Dec 13 08:16:27.432 INFO [stdout]                                at src/libstd/panicking.rs:210
Dec 13 08:16:27.432 INFO [stdout]    3:     0x557d4f7d3747 - std::panicking::default_hook::hf0c15a583008ed0d
Dec 13 08:16:27.432 INFO [stdout]                                at src/libstd/panicking.rs:218
Dec 13 08:16:27.432 INFO [stdout]    4:     0x557d4f7d4160 - std::panicking::rust_panic_with_hook::hc1663f6bc7813185
Dec 13 08:16:27.432 INFO [stdout]                                at src/libstd/panicking.rs:487
Dec 13 08:16:27.432 INFO [stdout]    5:     0x557d4f7c1e37 - std::panicking::begin_panic::ha6232b8af42f365e
Dec 13 08:16:27.432 INFO [stdout]                                at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libstd/panicking.rs:421
Dec 13 08:16:27.432 INFO [stdout]    6:     0x557d4f77e857 - sql_split::parser::reader_test::tokenizer::h03ec863d88a01871
Dec 13 08:16:27.432 INFO [stdout]                                at src/parser.rs:277
Dec 13 08:16:27.432 INFO [stdout]    7:     0x557d4f77e639 - sql_split::parser::reader_test::tokenizer::{{closure}}::h4b5c0b12392186f7
Dec 13 08:16:27.432 INFO [stdout]                                at src/parser.rs:264
Dec 13 08:16:27.432 INFO [stdout]    8:     0x557d4f77a18d - core::ops::function::FnOnce::call_once::hd78a8f6edf3ab241
Dec 13 08:16:27.432 INFO [stdout]                                at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libcore/ops/function.rs:238
Dec 13 08:16:27.433 INFO [stdout]    9:     0x557d4f7898fe - <F as alloc::boxed::FnBox<A>>::call_box::hac337ba1af72dc2b
Dec 13 08:16:27.433 INFO [stdout]                                at src/libtest/lib.rs:1483
Dec 13 08:16:27.433 INFO [stdout]                                at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libcore/ops/function.rs:238
Dec 13 08:16:27.433 INFO [stdout]                                at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/liballoc/boxed.rs:673
Dec 13 08:16:27.433 INFO [stdout]   10:     0x557d4f7e2729 - __rust_maybe_catch_panic
Dec 13 08:16:27.433 INFO [stdout]                                at src/libpanic_unwind/lib.rs:102
Dec 13 08:16:27.433 INFO [stdout]   11:     0x557d4f7a7107 - test::run_test::run_test_inner::{{closure}}::hfbfe4a58c0ebb01b
Dec 13 08:16:27.433 INFO [stdout]                                at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libstd/panicking.rs:285
Dec 13 08:16:27.433 INFO [stdout]                                at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libstd/panic.rs:398
Dec 13 08:16:27.433 INFO [stdout]                                at src/libtest/lib.rs:1438
Dec 13 08:16:27.433 INFO [stdout]   12:     0x557d4f782014 - std::sys_common::backtrace::__rust_begin_short_backtrace::hd1f0cf202092f8d7
Dec 13 08:16:27.433 INFO [stdout]                                at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libstd/sys_common/backtrace.rs:136
Dec 13 08:16:27.433 INFO [stdout]   13:     0x557d4f782604 - std::panicking::try::do_call::h5e99f92fbf448cf6
Dec 13 08:16:27.433 INFO [stdout]                                at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libstd/thread/mod.rs:479
Dec 13 08:16:27.433 INFO [stdout]                                at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libstd/panic.rs:319
Dec 13 08:16:27.433 INFO [stdout]                                at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libstd/panicking.rs:306
Dec 13 08:16:27.433 INFO [stdout]   14:     0x557d4f7e2729 - __rust_maybe_catch_panic
Dec 13 08:16:27.433 INFO [stdout]                                at src/libpanic_unwind/lib.rs:102
Dec 13 08:16:27.433 INFO [stdout]   15:     0x557d4f7897dc - <F as alloc::boxed::FnBox<A>>::call_box::h8ae063aff93ccdb6
Dec 13 08:16:27.433 INFO [stdout]                                at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libstd/panicking.rs:285
Dec 13 08:16:27.433 INFO [stdout]                                at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libstd/panic.rs:398
Dec 13 08:16:27.433 INFO [stdout]                                at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libstd/thread/mod.rs:478
Dec 13 08:16:27.433 INFO [stdout]                                at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/liballoc/boxed.rs:673
Dec 13 08:16:27.433 INFO [stdout]   16:     0x557d4f7d66ed - std::sys::unix::thread::Thread::new::thread_start::h42c32b292fdd228f
Dec 13 08:16:27.433 INFO [stdout]                                at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/liballoc/boxed.rs:683
Dec 13 08:16:27.433 INFO [stdout]                                at src/libstd/sys_common/thread.rs:24
Dec 13 08:16:27.433 INFO [stdout]                                at src/libstd/sys/unix/thread.rs:91
Dec 13 08:16:27.433 INFO [stdout]   17:     0x7fab6784b6b9 - start_thread
Dec 13 08:16:27.433 INFO [stdout]   18:     0x7fab6736b41c - clone
Dec 13 08:16:27.433 INFO [stdout]   19:                0x0 - <unknown>
Dec 13 08:16:27.433 INFO [stdout] 
Dec 13 08:16:27.433 INFO [stdout] 
Dec 13 08:16:27.433 INFO [stdout] failures:
Dec 13 08:16:27.433 INFO [stdout]     parser::reader_test::tokenizer
Dec 13 08:16:27.433 INFO [stdout] 
Dec 13 08:16:27.433 INFO [stdout] test result: FAILED. 4 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 08:16:27.433 INFO [stdout] 
Dec 13 08:16:27.446 INFO [stderr] error: test failed, to rerun pass '--bin sql-split'
Dec 13 08:16:27.448 INFO [stderr] su: No module specific data is present
Dec 13 08:16:28.572 INFO running `"docker" "inspect" "f271d48d348af70d5923e90384e166372703f34c6d0c66619f4d6c1c9d0adc5e"`
Dec 13 08:16:28.913 INFO running `"docker" "rm" "-f" "f271d48d348af70d5923e90384e166372703f34c6d0c66619f4d6c1c9d0adc5e"`
Dec 13 08:16:29.159 INFO [stdout] f271d48d348af70d5923e90384e166372703f34c6d0c66619f4d6c1c9d0adc5e