Nov 02 16:27:53.245 INFO testing graphannis-0.9.0 against 1.30.0 for beta-1.31-1
Nov 02 16:27:53.247 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-1/worker-6/1.30.0:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/beta-1.31-1/1.30.0:/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 +1.30.0 build --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Nov 02 16:27:53.905 INFO blam! 847c820b6b5018be38641892a49ac4a62432e35444cb2a0e8fd8553b6c7559b8
Nov 02 16:27:53.907 INFO running `"docker" "start" "-a" "847c820b6b5018be38641892a49ac4a62432e35444cb2a0e8fd8553b6c7559b8"`
Nov 02 16:27:56.114 INFO kablam! usermod: no changes
Nov 02 16:27:56.541 INFO kablam!    Compiling graphannis-malloc_size_of v0.0.1
Nov 02 16:27:56.542 INFO kablam!    Compiling regex-syntax v0.6.2
Nov 02 16:27:56.583 INFO kablam!    Compiling new_debug_unreachable v1.0.1
Nov 02 16:27:56.588 INFO kablam!    Compiling bincode v1.0.1
Nov 02 16:27:56.590 INFO kablam!    Compiling strum_macros v0.10.0
Nov 02 16:27:56.596 INFO kablam!    Compiling graphannis-malloc_size_of_derive v0.0.1
Nov 02 16:27:56.598 INFO kablam!    Compiling csv v1.0.2
Nov 02 16:27:59.103 INFO kablam!    Compiling string_cache v0.7.3
Nov 02 16:28:06.077 INFO kablam!    Compiling lalrpop-snap v0.15.2
Nov 02 16:28:23.075 INFO kablam!    Compiling regex v1.0.5
Nov 02 16:29:15.705 INFO kablam!    Compiling lalrpop v0.15.2
Nov 02 16:30:02.191 INFO kablam!    Compiling graphannis v0.9.0 (/source)
Nov 02 16:30:40.247 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 2m 44s
Nov 02 16:30:40.263 INFO kablam! su: No module specific data is present
Nov 02 16:30:41.246 INFO running `"docker" "rm" "-f" "847c820b6b5018be38641892a49ac4a62432e35444cb2a0e8fd8553b6c7559b8"`
Nov 02 16:30:41.487 INFO blam! 847c820b6b5018be38641892a49ac4a62432e35444cb2a0e8fd8553b6c7559b8
Nov 02 16:30:41.490 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-1/worker-6/1.30.0:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/beta-1.31-1/1.30.0:/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 +1.30.0 test --frozen --no-run" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Nov 02 16:30:41.831 INFO blam! 45a6859cd421cdf5ec646cd2a757e58c6b562b2e9d8478a3fd46f2c39d79e084
Nov 02 16:30:41.833 INFO running `"docker" "start" "-a" "45a6859cd421cdf5ec646cd2a757e58c6b562b2e9d8478a3fd46f2c39d79e084"`
Nov 02 16:30:42.534 INFO kablam! usermod: no changes
Nov 02 16:30:42.798 INFO kablam!    Compiling simplelog v0.5.3
Nov 02 16:30:44.594 INFO kablam!    Compiling graphannis v0.9.0 (/source)
Nov 02 16:31:13.563 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 31.00s
Nov 02 16:31:13.600 INFO kablam! su: No module specific data is present
Nov 02 16:31:14.356 INFO running `"docker" "rm" "-f" "45a6859cd421cdf5ec646cd2a757e58c6b562b2e9d8478a3fd46f2c39d79e084"`
Nov 02 16:31:14.543 INFO blam! 45a6859cd421cdf5ec646cd2a757e58c6b562b2e9d8478a3fd46f2c39d79e084
Nov 02 16:31:14.543 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-1/worker-6/1.30.0:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/beta-1.31-1/1.30.0:/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 +1.30.0 test --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Nov 02 16:31:14.941 INFO blam! ec62b963ad0b3d1aa05ee1ada551c3e0c126cb6d00d891919f99de9a99b91ec2
Nov 02 16:31:14.942 INFO running `"docker" "start" "-a" "ec62b963ad0b3d1aa05ee1ada551c3e0c126cb6d00d891919f99de9a99b91ec2"`
Nov 02 16:31:15.467 INFO kablam! usermod: no changes
Nov 02 16:31:15.668 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 0.17s
Nov 02 16:31:15.691 INFO kablam!      Running /target/debug/deps/graphannis-5e03e4e165998b70
Nov 02 16:31:15.694 INFO blam! 
Nov 02 16:31:15.694 INFO blam! running 10 tests
Nov 02 16:31:15.695 INFO blam! 16:31:15 [INFO] Using cache with size 3393.82 MiB
Nov 02 16:31:15.696 INFO blam! 16:31:15 [INFO] Using cache with size 3393.64 MiB
Nov 02 16:31:15.696 INFO blam! 16:31:15 [TRACE] graphannis::graphdb: [src/graphdb.rs:515] applying updates
Nov 02 16:31:15.697 INFO blam! test annostorage::tests::insert_same_anno ... 16:31:15 [TRACE] graphannis::graphdb: [src/graphdb.rs:321] applying event AddNode { node_name: "test", node_type: "node" }
Nov 02 16:31:15.697 INFO blam! 16:31:15 [TRACE] graphannis::graphdb: [src/graphdb.rs:526] memory updates completed
Nov 02 16:31:15.698 INFO blam! 16:31:15 [TRACE] graphannis::graphdb: [src/graphdb.rs:529] output location for persisting updates is "/tmp/annis_test.IJPc3Bm2Y1J5/testcorpus"
Nov 02 16:31:15.698 INFO blam! 16:31:15 [TRACE] graphannis::graphdb: [src/graphdb.rs:515] applying updates
Nov 02 16:31:15.698 INFO blam! 16:31:15 [TRACE] graphannis::graphdb: [src/graphdb.rs:321] applying event AddNode { node_name: "test", node_type: "node" }
Nov 02 16:31:15.698 INFO blam! 16:31:15 [TRACE] graphannis::graphdb: [src/graphdb.rs:526] memory updates completed
Nov 02 16:31:15.698 INFO blam! 16:31:15 [TRACE] graphannis::graphdb: [src/graphdb.rs:529] output location for persisting updates is "/tmp/annis_test.bXQvzukfn45v/testcorpus"
Nov 02 16:31:15.698 INFO blam! 16:31:15 [TRACE] graphannis::graphdb: [src/graphdb.rs:538] writing WAL update log to "/tmp/annis_test.bXQvzukfn45v/testcorpus/current/update_log.bin"
Nov 02 16:31:15.698 INFO blam! 16:31:15 [TRACE] graphannis::graphdb: [src/graphdb.rs:538] writing WAL update log to "/tmp/annis_test.IJPc3Bm2Y1J5/testcorpus/current/update_log.bin"
Nov 02 16:31:15.699 INFO blam! 16:31:15 [TRACE] graphannis::graphdb: [src/graphdb.rs:543] finished writing WAL update log
Nov 02 16:31:15.699 INFO blam! 16:31:15 [TRACE] graphannis::api::corpusstorage: [src/api/corpusstorage.rs:1387] Waiting for background thread to finish (1 worker(s) left)...
Nov 02 16:31:15.700 INFO blam! 16:31:15 [TRACE] graphannis::dfs: [src/dfs.rs:72] enter node 1
Nov 02 16:31:15.700 INFO blam! 16:31:15 [TRACE] graphannis::dfs: [src/dfs.rs:92] removing from stack
Nov 02 16:31:15.700 INFO blam! 16:31:15 [TRACE] graphannis::dfs: [src/dfs.rs:109] adding 2 to stack with new size 1
Nov 02 16:31:15.700 INFO blam! 16:31:15 [TRACE] graphannis::dfs: [src/dfs.rs:109] adding 3 to stack with new size 2
Nov 02 16:31:15.700 INFO blam! 16:31:15 [TRACE] graphannis::dfs: [src/dfs.rs:113] enter_node finished with result false for node 1
Nov 02 16:31:15.700 INFO blam! 16:31:15 [TRACE] graphannis::dfs: [src/dfs.rs:72] enter node 3
Nov 02 16:31:15.700 INFO blam! 16:31:15 [TRACE] graphannis::dfs: [src/dfs.rs:92] removing from stack
Nov 02 16:31:15.700 INFO blam! 16:31:15 [TRACE] graphannis::dfs: [src/dfs.rs:109] adding 4 to stack with new size 2
Nov 02 16:31:15.700 INFO blam! 16:31:15 [TRACE] graphannis::dfs: [src/dfs.rs:109] adding 5 to stack with new size 3
Nov 02 16:31:15.700 INFO blam! 16:31:15 [TRACE] graphannis::dfs: [src/dfs.rs:113] enter_node finished with result true for node 3
Nov 02 16:31:15.700 INFO blam! 16:31:15 [TRACE] graphannis::dfs: [src/dfs.rs:72] enter node 5
Nov 02 16:31:15.700 INFO blam! 16:31:15 [TRACE] graphannis::dfs: [src/dfs.rs:92] removing from stack
Nov 02 16:31:15.700 INFO blam! 16:31:15 [TRACE] graphannis::dfs: [src/dfs.rs:109] adding 6 to stack with new size 3
Nov 02 16:31:15.700 INFO blam! 16:31:15 [TRACE] graphannis::dfs: [src/dfs.rs:109] adding 7 to stack with new size 4
Nov 02 16:31:15.700 INFO blam! 16:31:15 [TRACE] graphannis::dfs: [src/dfs.rs:113] enter_node finished with result true for node 5
Nov 02 16:31:15.700 INFO blam! 16:31:15 [TRACE] graphannis::dfs: [src/dfs.rs:72] enter node 7
Nov 02 16:31:15.700 INFO blam! 16:31:15 [TRACE] graphannis::dfs: [src/dfs.rs:92] removing from stack
Nov 02 16:31:15.700 INFO blam! 16:31:15 [TRACE] graphannis::dfs: [src/dfs.rs:113] enter_node finished with result true for node 7
Nov 02 16:31:15.700 INFO blam! 16:31:15 [TRACE] graphannis::dfs: [src/dfs.rs:72] enter node 6
Nov 02 16:31:15.700 INFO blam! 16:31:15 [TRACE] graphannis::dfs: [src/dfs.rs:77] truncating 7 from path
Nov 02 16:31:15.700 INFO blam! 16:31:15 [TRACE] graphannis::dfs: [src/dfs.rs:92] removing from stack
Nov 02 16:31:15.700 INFO blam! 16:31:15 [TRACE] graphannis::dfs: [src/dfs.rs:113] enter_node finished with result true for node 6
Nov 02 16:31:15.700 INFO blam! 16:31:15 [TRACE] graphannis::dfs: [src/dfs.rs:72] enter node 4
Nov 02 16:31:15.700 INFO blam! 16:31:15 [TRACE] graphannis::dfs: [src/dfs.rs:77] truncating 5 from path
Nov 02 16:31:15.700 INFO blam! 16:31:15 [TRACE] graphannis::dfs: [src/dfs.rs:77] truncating 6 from path
Nov 02 16:31:15.700 INFO blam! 16:31:15 [TRACE] graphannis::dfs: [src/dfs.rs:92] removing from stack
Nov 02 16:31:15.700 INFO blam! 16:31:15 [TRACE] graphannis::dfs: [src/dfs.rs:113] enter_node finished with result true for node 4
Nov 02 16:31:15.700 INFO blam! 16:31:15 [TRACE] graphannis::dfs: [src/dfs.rs:72] enter node 2
Nov 02 16:31:15.700 INFO blam! 16:31:15 [TRACE] graphannis::dfs: [src/dfs.rs:77] truncating 3 from path
Nov 02 16:31:15.700 INFO blam! 16:31:15 [TRACE] graphannis::dfs: [src/dfs.rs:77] truncating 4 from path
Nov 02 16:31:15.700 INFO blam! 16:31:15 [TRACE] graphannis::dfs: [src/dfs.rs:92] removing from stack
Nov 02 16:31:15.700 INFO blam! 16:31:15 [TRACE] graphannis::dfs: [src/dfs.rs:109] adding 4 to stack with new size 1
Nov 02 16:31:15.700 INFO blam! 16:31:15 [TRACE] graphannis::dfs: [src/dfs.rs:113] enter_node finished with result true for node 2
Nov 02 16:31:15.700 INFO blam! 16:31:15 [TRACE] graphannis::dfs: [src/dfs.rs:72] enter node 4
Nov 02 16:31:15.700 INFO blam! 16:31:15 [TRACE] graphannis::dfs: [src/dfs.rs:92] removing from stack
Nov 02 16:31:15.700 INFO blam! 16:31:15 [TRACE] graphannis::dfs: [src/dfs.rs:113] enter_node finished with result true for node 4
Nov 02 16:31:15.700 INFO blam! 16:31:15 [TRACE] graphannis::dfs: [src/dfs.rs:72] enter node 3
Nov 02 16:31:15.700 INFO blam! 16:31:15 [TRACE] graphannis::dfs: [src/dfs.rs:92] removing from stack
Nov 02 16:31:15.700 INFO blam! 16:31:15 [TRACE] graphannis::dfs: [src/dfs.rs:109] adding 4 to stack with new size 1
Nov 02 16:31:15.700 INFO blam! 16:31:15 [TRACE] graphannis::dfs: [src/dfs.rs:109] adding 5 to stack with new size 2
Nov 02 16:31:15.700 INFO blam! 16:31:15 [TRACE] graphannis::dfs: [src/dfs.rs:113] enter_node finished with result false for node 3
Nov 02 16:31:15.700 INFO blam! 16:31:15 [TRACE] graphannis::dfs: [src/dfs.rs:72] enter node 5
Nov 02 16:31:15.700 INFO blam! 16:31:15 [TRACE] graphannis::dfs: [src/dfs.rs:92] removing from stack
Nov 02 16:31:15.700 INFO blam! 16:31:15 [TRACE] graphannis::dfs: [src/dfs.rs:109] adding 6 to stack with new size 2
Nov 02 16:31:15.700 INFO blam! 16:31:15 [TRACE] graphannis::dfs: [src/dfs.rs:109] adding 7 to stack with new size 3
Nov 02 16:31:15.700 INFO blam! 16:31:15 [TRACE] graphannis::dfs: [src/dfs.rs:113] enter_node finished with result false for node 5
Nov 02 16:31:15.700 INFO blam! 16:31:15 [TRACE] graphannis::dfs: [src/dfs.rs:72] enter node 7
Nov 02 16:31:15.700 INFO blam! 16:31:15 [TRACE] graphannis::dfs: [src/dfs.rs:92] removing from stack
Nov 02 16:31:15.700 INFO blam! 16:31:15 [TRACE] graphannis::dfs: [src/dfs.rs:113] enter_node finished with result true for node 7
Nov 02 16:31:15.700 INFO blam! ok16:31:15 [TRACE] graphannis::graphdb: [src/graphdb.rs:543] finished writing WAL update log
Nov 02 16:31:15.703 INFO blam! 16:31:15 [TRACE] graphannis::api::corpusstorage: [src/api/corpusstorage.rs:1359] Starting background thread to sync WAL updates
Nov 02 16:31:15.703 INFO blam! 16:31:15 [TRACE] graphannis::api::corpusstorage: [src/api/corpusstorage.rs:1359] Starting background thread to sync WAL updates
Nov 02 16:31:15.703 INFO blam! 16:31:15 [TRACE] graphannis::api::corpusstorage: [src/api/corpusstorage.rs:1366] Finished background thread to sync WAL updates
Nov 02 16:31:15.704 INFO blam! 16:31:15 [TRACE] graphannis::api::corpusstorage: [src/api/corpusstorage.rs:1395] Unlocked CorpusStorage lock file
Nov 02 16:31:15.704 INFO blam! 16:31:15 [INFO] Using cache with size 3393.48 MiB
Nov 02 16:31:15.704 INFO blam! 16:31:15 [TRACE] graphannis::graphdb: [src/graphdb.rs:515] applying updates
Nov 02 16:31:15.704 INFO blam! 16:31:15 [TRACE] graphannis::graphdb: [src/graphdb.rs:321] applying event AddNode { node_name: "test", node_type: "node" }
Nov 02 16:31:15.704 INFO blam! 16:31:15 [TRACE] graphannis::graphdb: [src/graphdb.rs:526] memory updates completed
Nov 02 16:31:15.705 INFO blam! 16:31:15 [TRACE] graphannis::graphdb: [src/graphdb.rs:529] output location for persisting updates is "/tmp/annis_test.bXQvzukfn45v/testcorpus"
Nov 02 16:31:15.705 INFO blam! 16:31:15 [TRACE] graphannis::graphdb: [src/graphdb.rs:538] writing WAL update log to "/tmp/annis_test.bXQvzukfn45v/testcorpus/current/update_log.bin"
Nov 02 16:31:15.705 INFO blam! 16:31:15 [TRACE] graphannis::graphdb: [src/graphdb.rs:543] finished writing WAL update log
Nov 02 16:31:15.705 INFO blam! 16:31:15 [TRACE] graphannis::api::corpusstorage: [src/api/corpusstorage.rs:1387] Waiting for background thread to finish (1 worker(s) left)...
Nov 02 16:31:15.705 INFO blam! 16:31:15 [TRACE] graphannis::api::corpusstorage: [src/api/corpusstorage.rs:1359] Starting background thread to sync WAL updates
Nov 02 16:31:15.705 INFO blam! 16:31:15 [TRACE] graphannis::api::corpusstorage: [src/api/corpusstorage.rs:1366] Finished background thread to sync WAL updates
Nov 02 16:31:15.706 INFO blam! 16:31:15 [TRACE] graphannis::api::corpusstorage: [src/api/corpusstorage.rs:1395] Unlocked CorpusStorage lock file
Nov 02 16:31:15.710 INFO blam! 16:31:15 [TRACE] graphannis::api::corpusstorage: [src/api/corpusstorage.rs:1387] Waiting for background thread to finish (1 worker(s) left)...
Nov 02 16:31:15.710 INFO blam! 
Nov 02 16:31:15.710 INFO blam! test annostorage::tests::get_all_for_node ... ok
Nov 02 16:31:15.710 INFO blam! test annostorage::tests::remove ... ok
Nov 02 16:31:15.710 INFO blam! test graphdb::tests::create_writeable_gs ... ok
Nov 02 16:31:15.710 INFO blam! test stringstorage::tests::insert_and_get ... ok
Nov 02 16:31:15.710 INFO blam! test api::corpusstorage::tests::load_cs_twice ... ok
Nov 02 16:31:15.710 INFO blam! 16:31:15 [TRACE] graphannis::dfs: [src/dfs.rs:72] enter node 6
Nov 02 16:31:15.711 INFO blam! test stringstorage::tests::insert_clear_insert_get ... ok
Nov 02 16:31:15.715 INFO blam! test stringstorage::tests::serialization ... ok
Nov 02 16:31:15.831 INFO kablam! 16:31:15 [ERROR] Can't sync changes in background thread: Error(IO(Os { code: 2, kind: NotFound, message: "No such file or directory" }), State { next_error: None, backtrace: InternalBacktrace { backtrace: Some(stack backtrace:
Nov 02 16:31:15.831 INFO kablam!    0:     0x556155975e05 - backtrace::backtrace::libunwind::trace::hba5052cf16a6608e
Nov 02 16:31:15.831 INFO kablam!                         at /cargo-home/registry/src/github.com-1ecc6299db9ec823/backtrace-0.3.9/src/backtrace/libunwind.rs:53
Nov 02 16:31:15.832 INFO kablam!                          - backtrace::backtrace::trace::h7f53757600de709a
Nov 02 16:31:15.832 INFO kablam!                         at /cargo-home/registry/src/github.com-1ecc6299db9ec823/backtrace-0.3.9/src/backtrace/mod.rs:42
Nov 02 16:31:15.833 INFO kablam!    1:     0x55615597059e - backtrace::capture::Backtrace::new_unresolved::hc360bfe5b93d9931
Nov 02 16:31:15.833 INFO kablam!                         at /cargo-home/registry/src/github.com-1ecc6299db9ec823/backtrace-0.3.9/src/capture.rs:88
Nov 02 16:31:15.834 INFO kablam!    2:     0x55615596f1af - error_chain::backtrace::imp::InternalBacktrace::new::h9db116f61a9a2820
Nov 02 16:31:15.835 INFO kablam!                         at /cargo-home/registry/src/github.com-1ecc6299db9ec823/error-chain-0.12.0/src/backtrace.rs:56
Nov 02 16:31:15.835 INFO kablam!    3:     0x55615596d41e - <error_chain::State as core::default::Default>::default::h6bc2e78e6b3ccf57
Nov 02 16:31:15.836 INFO kablam!                         at /cargo-home/registry/src/github.com-1ecc6299db9ec823/error-chain-0.12.0/src/lib.rs:666
Nov 02 16:31:15.836 INFO kablam!    4:     0x55615584d3ab - graphannis::errors::Error::from_kind::he22f52278c582872
Nov 02 16:31:15.836 INFO kablam!                         at /source/<::error_chain::error_chain::impl_error_chain_processed macros>:53
Nov 02 16:31:15.837 INFO kablam!    5:     0x55615584d6cf - <graphannis::errors::Error as core::convert::From<std::io::error::Error>>::from::h4c1bfbf4e90adb09
Nov 02 16:31:15.838 INFO kablam!                         at src/errors.rs:5
Nov 02 16:31:15.838 INFO kablam!    6:     0x55615580f9a4 - graphannis::graphdb::GraphDB::background_sync_wal_updates::hfad05d60de774922
Nov 02 16:31:15.838 INFO kablam!                         at src/graphdb.rs:570
Nov 02 16:31:15.839 INFO kablam!    7:     0x55615557b481 - graphannis::api::corpusstorage::CorpusStorage::apply_update::{{closure}}::hdecf02dfb29051eb
Nov 02 16:31:15.839 INFO kablam!                         at src/api/corpusstorage.rs:1363
Nov 02 16:31:15.840 INFO kablam!    8:     0x556155569dba - std::sys_common::backtrace::__rust_begin_short_backtrace::hbc2d5dd8ad19b537
Nov 02 16:31:15.840 INFO kablam!                         at libstd/sys_common/backtrace.rs:136
Nov 02 16:31:15.841 INFO kablam!    9:     0x5561557d2bac - std::thread::Builder::spawn::{{closure}}::{{closure}}::h85b1529f9d1fff75
Nov 02 16:31:15.841 INFO kablam!                         at libstd/thread/mod.rs:409
Nov 02 16:31:15.842 INFO kablam!   10:     0x5561555e614c - <std::panic::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::hd02de473c192ac71
Nov 02 16:31:15.842 INFO kablam!                         at libstd/panic.rs:313
Nov 02 16:31:15.843 INFO kablam!   11:     0x5561555e723f - std::panicking::try::do_call::h880e5a5ae45f3d5b
Nov 02 16:31:15.843 INFO kablam!                         at libstd/panicking.rs:310
Nov 02 16:31:15.843 INFO kablam!   12:     0x5561559bcae9 - __rust_maybe_catch_panic
Nov 02 16:31:15.843 INFO kablam!                         at libpanic_unwind/lib.rs:103
Nov 02 16:31:15.844 INFO kablam!   13:     0x5561555e7161 - std::panicking::try::h2dced0c668df5f1c
Nov 02 16:31:15.845 INFO kablam!                         at libstd/panicking.rs:289
Nov 02 16:31:15.845 INFO kablam!   14:     0x5561555e70ec - std::panic::catch_unwind::h874fb8a2057c03ea
Nov 02 16:31:15.846 INFO kablam!                         at libstd/panic.rs:392
Nov 02 16:31:15.847 INFO kablam!   15:     0x5561557d29cc - std::thread::Builder::spawn::{{closure}}::hc36795ce52506cc4
Nov 02 16:31:15.848 INFO kablam!                         at libstd/thread/mod.rs:408
Nov 02 16:31:15.848 INFO kablam!   16:     0x5561557e0da6 - <F as alloc::boxed::FnBox<A>>::call_box::h8b3e4c8a3d505c27
Nov 02 16:31:15.848 INFO kablam!                         at liballoc/boxed.rs:646
Nov 02 16:31:15.848 INFO kablam!   17:     0x5561559ae1ca - <alloc::boxed::Box<(dyn alloc::boxed::FnBox<A, Output=R> + 'a)> as core::ops::function::FnOnce<A>>::call_once::hd022a0500f5eee13
Nov 02 16:31:15.848 INFO kablam!                         at liballoc/boxed.rs:656
Nov 02 16:31:15.848 INFO kablam!                          - std::sys_common::thread::start_thread::hb77935aee02382da
Nov 02 16:31:15.848 INFO kablam!                         at libstd/sys_common/thread.rs:24
Nov 02 16:31:15.848 INFO kablam!   18:     0x55615599dc95 - std::sys::unix::thread::Thread::new::thread_start::hd61f7429b3de1d75
Nov 02 16:31:15.848 INFO kablam!                         at libstd/sys/unix/thread.rs:90
Nov 02 16:31:15.848 INFO kablam!   19:     0x7fb08df926b9 - start_thread
Nov 02 16:31:15.848 INFO kablam!   20:     0x7fb08dab241c - clone
Nov 02 16:31:15.848 INFO kablam!   21:                0x0 - <unknown>) } })
Nov 02 16:31:15.848 INFO blam! 16:31:15 [TRACE] graphannis::api::corpusstorage: [src/api/corpusstorage.rs:1395] Unlocked CorpusStorage lock file
Nov 02 16:31:15.848 INFO blam! test api::corpusstorage::tests::delete ... ok
Nov 02 16:31:15.848 INFO blam! 16:31:15 [TRACE] graphannis::dfs: [src/dfs.rs:77] truncating 7 from path
Nov 02 16:31:15.848 INFO blam! 16:31:15 [TRACE] graphannis::dfs: [src/dfs.rs:92] removing from stack
Nov 02 16:31:15.848 INFO blam! 16:31:15 [TRACE] graphannis::dfs: [src/dfs.rs:113] enter_node finished with result true for node 6
Nov 02 16:31:15.849 INFO blam! 16:31:15 [TRACE] graphannis::dfs: [src/dfs.rs:72] enter node 4
Nov 02 16:31:15.849 INFO blam! 16:31:15 [TRACE] graphannis::dfs: [src/dfs.rs:77] truncating 5 from path
Nov 02 16:31:15.849 INFO blam! 16:31:15 [TRACE] graphannis::dfs: [src/dfs.rs:77] truncating 6 from path
Nov 02 16:31:15.849 INFO blam! 16:31:15 [TRACE] graphannis::dfs: [src/dfs.rs:92] removing from stack
Nov 02 16:31:15.849 INFO blam! 16:31:15 [TRACE] graphannis::dfs: [src/dfs.rs:113] enter_node finished with result false for node 4
Nov 02 16:31:15.849 INFO blam! 16:31:15 [TRACE] graphannis::dfs: [src/dfs.rs:72] enter node 1
Nov 02 16:31:15.849 INFO blam! 16:31:15 [TRACE] graphannis::dfs: [src/dfs.rs:92] removing from stack
Nov 02 16:31:15.849 INFO blam! 16:31:15 [TRACE] graphannis::dfs: [src/dfs.rs:109] adding 2 to stack with new size 1
Nov 02 16:31:15.849 INFO blam! 16:31:15 [TRACE] graphannis::dfs: [src/dfs.rs:109] adding 3 to stack with new size 2
Nov 02 16:31:15.849 INFO blam! 16:31:15 [TRACE] graphannis::dfs: [src/dfs.rs:113] enter_node finished with result false for node 1
Nov 02 16:31:15.849 INFO blam! 16:31:15 [TRACE] graphannis::dfs: [src/dfs.rs:72] enter node 3
Nov 02 16:31:15.849 INFO blam! 16:31:15 [TRACE] graphannis::dfs: [src/dfs.rs:92] removing from stack
Nov 02 16:31:15.849 INFO blam! 16:31:15 [TRACE] graphannis::dfs: [src/dfs.rs:109] adding 4 to stack with new size 2
Nov 02 16:31:15.849 INFO blam! 16:31:15 [TRACE] graphannis::dfs: [src/dfs.rs:109] adding 5 to stack with new size 3
Nov 02 16:31:15.849 INFO blam! 16:31:15 [TRACE] graphannis::dfs: [src/dfs.rs:113] enter_node finished with result false for node 3
Nov 02 16:31:15.849 INFO blam! 16:31:15 [TRACE] graphannis::dfs: [src/dfs.rs:72] enter node 5
Nov 02 16:31:15.849 INFO blam! 16:31:15 [TRACE] graphannis::dfs: [src/dfs.rs:92] removing from stack
Nov 02 16:31:15.849 INFO blam! 16:31:15 [TRACE] graphannis::dfs: [src/dfs.rs:109] adding 6 to stack with new size 3
Nov 02 16:31:15.849 INFO blam! 16:31:15 [TRACE] graphannis::dfs: [src/dfs.rs:109] adding 7 to stack with new size 4
Nov 02 16:31:15.849 INFO blam! 16:31:15 [TRACE] graphannis::dfs: [src/dfs.rs:113] enter_node finished with result true for node 5
Nov 02 16:31:15.849 INFO blam! 16:31:15 [TRACE] graphannis::dfs: [src/dfs.rs:72] enter node 7
Nov 02 16:31:15.849 INFO blam! 16:31:15 [TRACE] graphannis::dfs: [src/dfs.rs:92] removing from stack
Nov 02 16:31:15.849 INFO blam! 16:31:15 [TRACE] graphannis::dfs: [src/dfs.rs:113] enter_node finished with result true for node 7
Nov 02 16:31:15.849 INFO blam! 16:31:15 [TRACE] graphannis::dfs: [src/dfs.rs:72] enter node 6
Nov 02 16:31:15.849 INFO blam! 16:31:15 [TRACE] graphannis::dfs: [src/dfs.rs:77] truncating 7 from path
Nov 02 16:31:15.849 INFO blam! 16:31:15 [TRACE] graphannis::dfs: [src/dfs.rs:92] removing from stack
Nov 02 16:31:15.849 INFO blam! 16:31:15 [TRACE] graphannis::dfs: [src/dfs.rs:113] enter_node finished with result true for node 6
Nov 02 16:31:15.849 INFO blam! 16:31:15 [TRACE] graphannis::dfs: [src/dfs.rs:72] enter node 4
Nov 02 16:31:15.849 INFO blam! 16:31:15 [TRACE] graphannis::dfs: [src/dfs.rs:77] truncating 5 from path
Nov 02 16:31:15.849 INFO blam! 16:31:15 [TRACE] graphannis::dfs: [src/dfs.rs:77] truncating 6 from path
Nov 02 16:31:15.849 INFO blam! 16:31:15 [TRACE] graphannis::dfs: [src/dfs.rs:92] removing from stack
Nov 02 16:31:15.849 INFO blam! 16:31:15 [TRACE] graphannis::dfs: [src/dfs.rs:113] enter_node finished with result true for node 4
Nov 02 16:31:15.849 INFO blam! 16:31:15 [TRACE] graphannis::dfs: [src/dfs.rs:72] enter node 2
Nov 02 16:31:15.849 INFO blam! 16:31:15 [TRACE] graphannis::dfs: [src/dfs.rs:77] truncating 3 from path
Nov 02 16:31:15.849 INFO blam! 16:31:15 [TRACE] graphannis::dfs: [src/dfs.rs:77] truncating 4 from path
Nov 02 16:31:15.849 INFO blam! 16:31:15 [TRACE] graphannis::dfs: [src/dfs.rs:92] removing from stack
Nov 02 16:31:15.849 INFO blam! 16:31:15 [TRACE] graphannis::dfs: [src/dfs.rs:109] adding 4 to stack with new size 1
Nov 02 16:31:15.849 INFO blam! 16:31:15 [TRACE] graphannis::dfs: [src/dfs.rs:113] enter_node finished with result false for node 2
Nov 02 16:31:15.849 INFO blam! 16:31:15 [TRACE] graphannis::dfs: [src/dfs.rs:72] enter node 4
Nov 02 16:31:15.849 INFO blam! 16:31:15 [TRACE] graphannis::dfs: [src/dfs.rs:92] removing from stack
Nov 02 16:31:15.849 INFO blam! 16:31:15 [TRACE] graphannis::dfs: [src/dfs.rs:113] enter_node finished with result true for node 4
Nov 02 16:31:15.849 INFO blam! 16:31:15 [TRACE] graphannis::dfs: [src/dfs.rs:72] enter node 7
Nov 02 16:31:15.849 INFO blam! 16:31:15 [TRACE] graphannis::dfs: [src/dfs.rs:92] removing from stack
Nov 02 16:31:15.849 INFO blam! 16:31:15 [TRACE] graphannis::dfs: [src/dfs.rs:113] enter_node finished with result false for node 7
Nov 02 16:31:15.850 INFO blam! test graphstorage::adjacencylist::tests::simple_dag_find_all ... ok
Nov 02 16:31:15.850 INFO blam! 
Nov 02 16:31:15.850 INFO blam! test result: ok. 10 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 02 16:31:15.850 INFO blam! 
Nov 02 16:31:15.854 INFO kablam!      Running /target/debug/deps/loadtest-aec4bffad4452a6e
Nov 02 16:31:15.856 INFO blam! 
Nov 02 16:31:15.856 INFO blam! running 7 tests
Nov 02 16:31:15.858 INFO kablam!      Running /target/debug/deps/searchtest-69e9f253beebd185
Nov 02 16:31:15.858 INFO blam! test count_annos ... ok
Nov 02 16:31:15.858 INFO blam! test index_join ... ok
Nov 02 16:31:15.858 INFO blam! test edges ... ok
Nov 02 16:31:15.858 INFO blam! test node_annos ... ok
Nov 02 16:31:15.858 INFO blam! test nested_loop_join ... ok
Nov 02 16:31:15.858 INFO blam! test parallel_nested_loop_join ... ok
Nov 02 16:31:15.858 INFO blam! test parallel_index_join ... ok
Nov 02 16:31:15.858 INFO blam! 
Nov 02 16:31:15.858 INFO blam! test result: ok. 7 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 02 16:31:15.858 INFO blam! 
Nov 02 16:31:15.864 INFO blam! 
Nov 02 16:31:15.864 INFO blam! running 6 tests
Nov 02 16:31:15.864 INFO blam! test all_from_folder ... ignored
Nov 02 16:31:15.864 INFO blam! test count_gum ... ignored
Nov 02 16:31:15.864 INFO blam! test count_parlament ... ignored
Nov 02 16:31:15.864 INFO blam! test count_pcc2 ... ignored
Nov 02 16:31:15.864 INFO blam! test count_ridges ... ignored
Nov 02 16:31:15.864 INFO blam! test count_tiger ... ignored
Nov 02 16:31:15.865 INFO blam! 
Nov 02 16:31:15.865 INFO blam! test result: ok. 0 passed; 0 failed; 6 ignored; 0 measured; 0 filtered out
Nov 02 16:31:15.865 INFO blam! 
Nov 02 16:31:15.865 INFO kablam!      Running /target/debug/deps/searchtest_parallel-332700d1864a5aec
Nov 02 16:31:15.867 INFO blam! 
Nov 02 16:31:15.867 INFO blam! running 6 tests
Nov 02 16:31:15.867 INFO blam! test all_from_folder ... ignored
Nov 02 16:31:15.867 INFO blam! test count_gum ... ignored
Nov 02 16:31:15.867 INFO blam! test count_parlament ... ignored
Nov 02 16:31:15.867 INFO blam! test count_pcc2 ... ignored
Nov 02 16:31:15.867 INFO blam! test count_ridges ... ignored
Nov 02 16:31:15.867 INFO blam! test count_tiger ... ignored
Nov 02 16:31:15.867 INFO blam! 
Nov 02 16:31:15.867 INFO blam! test result: ok. 0 passed; 0 failed; 6 ignored; 0 measured; 0 filtered out
Nov 02 16:31:15.867 INFO blam! 
Nov 02 16:31:15.867 INFO kablam!    Doc-tests graphannis
Nov 02 16:31:21.784 INFO blam! 
Nov 02 16:31:21.784 INFO blam! running 1 test
Nov 02 16:31:34.007 INFO blam! test src/util/mod.rs - util::extract_node_path (line 75) ... ok
Nov 02 16:31:34.007 INFO blam! 
Nov 02 16:31:34.007 INFO blam! test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 02 16:31:34.008 INFO blam! 
Nov 02 16:31:34.028 INFO kablam! su: No module specific data is present
Nov 02 16:31:34.655 INFO running `"docker" "rm" "-f" "ec62b963ad0b3d1aa05ee1ada551c3e0c126cb6d00d891919f99de9a99b91ec2"`
Nov 02 16:31:34.927 INFO blam! ec62b963ad0b3d1aa05ee1ada551c3e0c126cb6d00d891919f99de9a99b91ec2
