Dec 05 19:35:12.824 INFO testing johan-bjareholt/aw-server-rust against master#906deae0790bd18681b937fe9a141a3c26cf1855 for pr-56514
Dec 05 19:35:12.824 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-5/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/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 05 19:35:12.996 INFO [stdout] 267da703926e3a8ccdf70d2e65cbdd0cd74584be51646a39566f22d1f9688689
Dec 05 19:35:12.999 INFO running `"docker" "start" "-a" "267da703926e3a8ccdf70d2e65cbdd0cd74584be51646a39566f22d1f9688689"`
Dec 05 19:35:13.284 INFO [stderr] usermod: no changes
Dec 05 19:35:13.626 INFO [stderr]    Compiling proc-macro2 v0.4.19
Dec 05 19:35:13.627 INFO [stderr]    Compiling lalr v0.0.2
Dec 05 19:35:13.627 INFO [stderr]    Compiling mpsc_requests v0.2.0
Dec 05 19:35:13.635 INFO [stderr]    Compiling redfa v0.0.2
Dec 05 19:35:13.636 INFO [stderr]    Compiling hyper v0.10.13
Dec 05 19:35:13.636 INFO [stderr]    Compiling rusqlite v0.14.0
Dec 05 19:35:21.116 INFO [stderr]    Compiling quote v0.6.8
Dec 05 19:35:22.308 INFO [stderr]    Compiling syn v0.14.9
Dec 05 19:35:22.308 INFO [stderr]    Compiling syn v0.15.4
Dec 05 19:35:30.052 INFO [stderr]    Compiling rocket v0.3.16
Dec 05 19:35:31.476 INFO [stderr]    Compiling serde_derive v1.0.79
Dec 05 19:35:44.368 INFO [stderr]    Compiling rocket_contrib v0.3.16
Dec 05 19:35:44.433 INFO [stderr]    Compiling rocket_codegen v0.3.16
Dec 05 19:35:45.444 INFO [stderr]    Compiling plex v0.2.3
Dec 05 19:36:00.727 INFO [stderr]    Compiling aw_server v0.1.0 (/source)
Dec 05 19:36:17.748 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 1m 04s
Dec 05 19:36:17.755 INFO [stderr] su: No module specific data is present
Dec 05 19:36:18.488 INFO running `"docker" "inspect" "267da703926e3a8ccdf70d2e65cbdd0cd74584be51646a39566f22d1f9688689"`
Dec 05 19:36:18.764 INFO running `"docker" "rm" "-f" "267da703926e3a8ccdf70d2e65cbdd0cd74584be51646a39566f22d1f9688689"`
Dec 05 19:36:19.060 INFO [stdout] 267da703926e3a8ccdf70d2e65cbdd0cd74584be51646a39566f22d1f9688689
Dec 05 19:36:19.068 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-5/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/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 05 19:36:19.496 INFO [stdout] 719c5d3b8a7cbba1f2851ee7aa8b5b6f2dfeafaceb0693efa0a554652dca732f
Dec 05 19:36:19.504 INFO running `"docker" "start" "-a" "719c5d3b8a7cbba1f2851ee7aa8b5b6f2dfeafaceb0693efa0a554652dca732f"`
Dec 05 19:36:21.440 INFO [stderr] usermod: no changes
Dec 05 19:36:21.660 INFO [stderr]    Compiling aw_server v0.1.0 (/source)
Dec 05 19:36:50.213 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 28.75s
Dec 05 19:36:50.224 INFO [stderr] su: No module specific data is present
Dec 05 19:36:50.652 INFO running `"docker" "inspect" "719c5d3b8a7cbba1f2851ee7aa8b5b6f2dfeafaceb0693efa0a554652dca732f"`
Dec 05 19:36:50.858 INFO running `"docker" "rm" "-f" "719c5d3b8a7cbba1f2851ee7aa8b5b6f2dfeafaceb0693efa0a554652dca732f"`
Dec 05 19:36:51.052 INFO [stdout] 719c5d3b8a7cbba1f2851ee7aa8b5b6f2dfeafaceb0693efa0a554652dca732f
Dec 05 19:36:51.054 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-5/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/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 05 19:36:51.324 INFO [stdout] 63dbbc2bdbcc2f9a2152c75b73f200bb872c08c90c8eed27fd77197f3f9f1d6f
Dec 05 19:36:51.326 INFO running `"docker" "start" "-a" "63dbbc2bdbcc2f9a2152c75b73f200bb872c08c90c8eed27fd77197f3f9f1d6f"`
Dec 05 19:36:52.158 INFO [stderr] usermod: no changes
Dec 05 19:36:52.380 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.19s
Dec 05 19:36:52.389 INFO [stderr]      Running /target/debug/deps/aw_server-0c9b086259caadfc
Dec 05 19:36:52.395 INFO [stdout] 
Dec 05 19:36:52.395 INFO [stdout] running 0 tests
Dec 05 19:36:52.395 INFO [stdout] 
Dec 05 19:36:52.395 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 05 19:36:52.396 INFO [stdout] 
Dec 05 19:36:52.396 INFO [stderr]      Running /target/debug/deps/aw_server-a460374250f082d7
Dec 05 19:36:52.402 INFO [stdout] 
Dec 05 19:36:52.402 INFO [stdout] running 0 tests
Dec 05 19:36:52.402 INFO [stdout] 
Dec 05 19:36:52.402 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 05 19:36:52.402 INFO [stdout] 
Dec 05 19:36:52.402 INFO [stderr]      Running /target/debug/deps/api-b85ec1890a6e67d0
Dec 05 19:36:52.404 INFO [stdout] 
Dec 05 19:36:52.404 INFO [stdout] running 4 tests
Dec 05 19:36:52.432 INFO [stdout] Bucket { bid: Some(1), id: "id1", _type: "type", client: "client", hostname: "hostname", created: None, events: Some([Event { id: None, timestamp: 2000-01-01T00:00:00Z, duration: Duration { secs: 1, nanos: 0 }, data: Object({}) }]) }
Dec 05 19:36:52.432 INFO [stdout] Commiting DB! Force commit true, 1 uncommited events
Dec 05 19:36:52.432 INFO [stdout] Bucket { bid: Some(1), id: "id", _type: "type", client: "client", hostname: "hostname", created: None, events: None }
Dec 05 19:36:52.432 INFO [stdout] Commiting DB! Force commit true, 0 uncommited events
Dec 05 19:36:52.432 INFO [stdout] Bucket { bid: Some(2), id: "id2", _type: "type", client: "client", hostname: "hostname", created: None, events: Some([Event { id: None, timestamp: 2000-01-01T00:00:00Z, duration: Duration { secs: 1, nanos: 0 }, data: Object({}) }]) }
Dec 05 19:36:52.432 INFO [stdout] Commiting DB! Force commit true, 1 uncommited events
Dec 05 19:36:52.432 INFO [stdout] DB worker quitting
Dec 05 19:36:52.432 INFO [stdout] Commiting DB! Force commit false, 0 uncommited events
Dec 05 19:36:52.432 INFO [stdout] test api_tests::test_import ... ok
Dec 05 19:36:52.432 INFO [stdout] Bucket { bid: Some(1), id: "id", _type: "type", client: "client", hostname: "hostname", created: None, events: None }
Dec 05 19:36:52.432 INFO [stdout] Commiting DB! Force commit true, 0 uncommited events
Dec 05 19:36:52.433 INFO [stdout] Bucket { bid: Some(1), id: "id", _type: "type", client: "client", hostname: "hostname", created: None, events: None }
Dec 05 19:36:52.433 INFO [stdout] Commiting DB! Force commit true, 0 uncommited events
Dec 05 19:36:52.433 INFO [stdout] Commiting DB! Force commit true, 0 uncommited events
Dec 05 19:36:52.434 INFO [stdout] test api_tests::test_query ... ok
Dec 05 19:36:52.434 INFO [stdout] DB worker quitting
Dec 05 19:36:52.434 INFO [stdout] Commiting DB! Force commit false, 0 uncommited events
Dec 05 19:36:52.434 INFO [stdout] test api_tests::test_bucket ... ok
Dec 05 19:36:52.434 INFO [stdout] DB worker quitting
Dec 05 19:36:52.434 INFO [stdout] Commiting DB! Force commit false, 1 uncommited events
Dec 05 19:36:52.448 INFO [stdout] Commiting DB! Force commit true, 2 uncommited events
Dec 05 19:36:52.448 INFO [stderr]      Running /target/debug/deps/datastore-9f142f6c1a77cc4a
Dec 05 19:36:52.448 INFO [stdout] DB worker quitting
Dec 05 19:36:52.448 INFO [stdout] Commiting DB! Force commit false, 0 uncommited events
Dec 05 19:36:52.448 INFO [stdout] test api_tests::test_events ... ok
Dec 05 19:36:52.448 INFO [stdout] 
Dec 05 19:36:52.448 INFO [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 05 19:36:52.448 INFO [stdout] 
Dec 05 19:36:52.450 INFO [stdout] 
Dec 05 19:36:52.450 INFO [stdout] running 1 test
Dec 05 19:36:52.451 INFO [stdout] Bucket { bid: Some(1), id: "testid", _type: "testtype", client: "testclient", hostname: "testhost", created: Some(2018-12-05T19:36:52.450514132Z), events: None }
Dec 05 19:36:52.453 INFO [stdout] Commiting DB! Force commit true, 0 uncommited events
Dec 05 19:36:52.461 INFO [stdout] Commiting DB! Force commit true, 3 uncommited events
Dec 05 19:36:52.461 INFO [stdout] DB worker quitting
Dec 05 19:36:52.461 INFO [stdout] Commiting DB! Force commit false, 0 uncommited events
Dec 05 19:36:52.465 INFO [stdout] test datastore_tests::test_datastore ... ok
Dec 05 19:36:52.465 INFO [stdout] 
Dec 05 19:36:52.465 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 05 19:36:52.465 INFO [stdout] 
Dec 05 19:36:52.466 INFO [stderr]      Running /target/debug/deps/models-38b4c3954b73b55c
Dec 05 19:36:52.469 INFO [stdout] 
Dec 05 19:36:52.469 INFO [stdout] running 3 tests
Dec 05 19:36:52.470 INFO [stdout] test models_tests::test_bucket ... ok
Dec 05 19:36:52.470 INFO [stdout] test models_tests::test_event ... ok
Dec 05 19:36:52.470 INFO [stdout] test models_tests::test_timeinterval ... ok
Dec 05 19:36:52.470 INFO [stdout] 
Dec 05 19:36:52.470 INFO [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 05 19:36:52.470 INFO [stdout] 
Dec 05 19:36:52.470 INFO [stderr]      Running /target/debug/deps/query-c1c5725be7def1e7
Dec 05 19:36:52.474 INFO [stdout] 
Dec 05 19:36:52.474 INFO [stdout] running 9 tests
Dec 05 19:36:52.476 INFO [stdout] test query_tests::test_list ... ok
Dec 05 19:36:52.478 INFO [stdout] test query_tests::test_return ... ok
Dec 05 19:36:52.479 INFO [stdout] test query_tests::test_number ... ok
Dec 05 19:36:52.480 INFO [stdout] test query_tests::test_math ... ok
Dec 05 19:36:52.489 INFO [stdout] test query_tests::test_dict ... ok
Dec 05 19:36:52.492 INFO [stdout] test query_tests::test_function ... ok
Dec 05 19:36:52.492 INFO [stdout] Bucket { bid: Some(1), id: "testid", _type: "testtype", client: "testclient", hostname: "testhost", created: Some(2018-12-05T19:36:52.475079993Z), events: None }
Dec 05 19:36:52.492 INFO [stdout] Commiting DB! Force commit true, 0 uncommited events
Dec 05 19:36:52.492 INFO [stdout] test query_tests::test_string ... ok
Dec 05 19:36:52.492 INFO [stdout] test query_tests::test_comment ... ok
Dec 05 19:36:52.492 INFO [stdout] DB worker quitting
Dec 05 19:36:52.492 INFO [stdout] Commiting DB! Force commit false, 0 uncommited events
Dec 05 19:36:52.493 INFO [stdout] DB worker quitting
Dec 05 19:36:52.493 INFO [stdout] Commiting DB! Force commit false, 0 uncommited events
Dec 05 19:36:52.494 INFO [stdout] DB worker quitting
Dec 05 19:36:52.494 INFO [stdout] Commiting DB! Force commit false, 0 uncommited events
Dec 05 19:36:52.494 INFO [stdout] DB worker quitting
Dec 05 19:36:52.494 INFO [stdout] Commiting DB! Force commit false, 0 uncommited events
Dec 05 19:36:52.495 INFO [stdout] DB worker quitting
Dec 05 19:36:52.495 INFO [stdout] Commiting DB! Force commit false, 0 uncommited events
Dec 05 19:36:52.496 INFO [stdout] DB worker quitting
Dec 05 19:36:52.496 INFO [stdout] Commiting DB! Force commit false, 0 uncommited events
Dec 05 19:36:52.497 INFO [stdout] DB worker quitting
Dec 05 19:36:52.497 INFO [stdout] Commiting DB! Force commit false, 0 uncommited events
Dec 05 19:36:52.498 INFO [stdout] DB worker quitting
Dec 05 19:36:52.498 INFO [stdout] Commiting DB! Force commit false, 0 uncommited events
Dec 05 19:36:52.505 INFO [stdout] test query_tests::test_all_functions ... ok
Dec 05 19:36:52.505 INFO [stdout] 
Dec 05 19:36:52.505 INFO [stdout] test result: ok. 9 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 05 19:36:52.505 INFO [stdout] 
Dec 05 19:36:52.508 INFO [stderr]      Running /target/debug/deps/transform-55a5ca9e3c51e90d
Dec 05 19:36:52.514 INFO [stdout] 
Dec 05 19:36:52.514 INFO [stdout] running 9 tests
Dec 05 19:36:52.515 INFO [stdout] test transform_tests::test_flood ... ok
Dec 05 19:36:52.515 INFO [stdout] test transform_tests::test_filter_period_intersect ... ok
Dec 05 19:36:52.515 INFO [stdout] test transform_tests::test_chunk_events_by_key ... ok
Dec 05 19:36:52.515 INFO [stdout] test transform_tests::test_heartbeat_data ... ok
Dec 05 19:36:52.515 INFO [stdout] test transform_tests::test_filter_keyvals ... ok
Dec 05 19:36:52.515 INFO [stdout] test transform_tests::test_merge_events_by_key ... ok
Dec 05 19:36:52.515 INFO [stdout] test transform_tests::test_sort_by_duration ... ok
Dec 05 19:36:52.515 INFO [stdout] test transform_tests::test_heartbeat_pulsetime ... ok
Dec 05 19:36:52.516 INFO [stdout] test transform_tests::test_sort_by_timestamp ... ok
Dec 05 19:36:52.516 INFO [stdout] 
Dec 05 19:36:52.516 INFO [stdout] test result: ok. 9 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 05 19:36:52.516 INFO [stdout] 
Dec 05 19:36:52.517 INFO [stderr]    Doc-tests aw_server
Dec 05 19:36:56.036 INFO [stdout] 
Dec 05 19:36:56.036 INFO [stdout] running 0 tests
Dec 05 19:36:56.036 INFO [stdout] 
Dec 05 19:36:56.036 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 05 19:36:56.036 INFO [stdout] 
Dec 05 19:36:56.048 INFO [stderr] su: No module specific data is present
Dec 05 19:36:56.362 INFO running `"docker" "inspect" "63dbbc2bdbcc2f9a2152c75b73f200bb872c08c90c8eed27fd77197f3f9f1d6f"`
Dec 05 19:36:56.490 INFO running `"docker" "rm" "-f" "63dbbc2bdbcc2f9a2152c75b73f200bb872c08c90c8eed27fd77197f3f9f1d6f"`
Dec 05 19:36:56.676 INFO [stdout] 63dbbc2bdbcc2f9a2152c75b73f200bb872c08c90c8eed27fd77197f3f9f1d6f