Dec 05 19:35:12.754 INFO testing johan-bjareholt/aw-server-rust against try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468 for pr-56514
Dec 05 19:35:12.754 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-6/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/pr-56514/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/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 +6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468-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.892 INFO [stdout] cbf351147b4a0aa70f1615cbff35a568d8fb799ed23cd7ea5a3d22fd8192a3cd
Dec 05 19:35:12.894 INFO running `"docker" "start" "-a" "cbf351147b4a0aa70f1615cbff35a568d8fb799ed23cd7ea5a3d22fd8192a3cd"`
Dec 05 19:35:13.206 INFO [stderr] usermod: no changes
Dec 05 19:35:13.393 INFO [stderr]    Compiling proc-macro2 v0.4.19
Dec 05 19:35:13.394 INFO [stderr]    Compiling lalr v0.0.2
Dec 05 19:35:13.395 INFO [stderr]    Compiling mpsc_requests v0.2.0
Dec 05 19:35:13.399 INFO [stderr]    Compiling redfa v0.0.2
Dec 05 19:35:13.399 INFO [stderr]    Compiling toml v0.4.6
Dec 05 19:35:13.400 INFO [stderr]    Compiling serde_json v1.0.28
Dec 05 19:35:13.400 INFO [stderr]    Compiling chrono v0.4.6
Dec 05 19:35:20.672 INFO [stderr]    Compiling quote v0.6.8
Dec 05 19:35:23.132 INFO [stderr]    Compiling rocket v0.3.16
Dec 05 19:35:23.360 INFO [stderr]    Compiling syn v0.15.4
Dec 05 19:35:23.399 INFO [stderr]    Compiling syn v0.14.9
Dec 05 19:35:23.728 INFO [stderr]    Compiling rusqlite v0.14.0
Dec 05 19:35:33.353 INFO [stderr]    Compiling serde_derive v1.0.79
Dec 05 19:35:37.096 INFO [stderr]    Compiling rocket_contrib v0.3.16
Dec 05 19:35:37.096 INFO [stderr]    Compiling rocket_codegen v0.3.16
Dec 05 19:35:47.009 INFO [stderr]    Compiling plex v0.2.3
Dec 05 19:36:00.651 INFO [stderr]    Compiling aw_server v0.1.0 (/source)
Dec 05 19:36:17.057 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 1m 03s
Dec 05 19:36:17.073 INFO [stderr] su: No module specific data is present
Dec 05 19:36:18.244 INFO running `"docker" "inspect" "cbf351147b4a0aa70f1615cbff35a568d8fb799ed23cd7ea5a3d22fd8192a3cd"`
Dec 05 19:36:18.600 INFO running `"docker" "rm" "-f" "cbf351147b4a0aa70f1615cbff35a568d8fb799ed23cd7ea5a3d22fd8192a3cd"`
Dec 05 19:36:18.928 INFO [stdout] cbf351147b4a0aa70f1615cbff35a568d8fb799ed23cd7ea5a3d22fd8192a3cd
Dec 05 19:36:18.934 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-6/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/pr-56514/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/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 +6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468-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.305 INFO [stdout] 42b8705da208191ec0eb619562c7f44d26f0d4a6bb36524cb83445f05bffcb31
Dec 05 19:36:19.310 INFO running `"docker" "start" "-a" "42b8705da208191ec0eb619562c7f44d26f0d4a6bb36524cb83445f05bffcb31"`
Dec 05 19:36:20.774 INFO [stderr] usermod: no changes
Dec 05 19:36:21.088 INFO [stderr]    Compiling aw_server v0.1.0 (/source)
Dec 05 19:36:49.600 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 28.79s
Dec 05 19:36:49.614 INFO [stderr] su: No module specific data is present
Dec 05 19:36:49.952 INFO running `"docker" "inspect" "42b8705da208191ec0eb619562c7f44d26f0d4a6bb36524cb83445f05bffcb31"`
Dec 05 19:36:50.038 INFO running `"docker" "rm" "-f" "42b8705da208191ec0eb619562c7f44d26f0d4a6bb36524cb83445f05bffcb31"`
Dec 05 19:36:50.092 INFO [stdout] 42b8705da208191ec0eb619562c7f44d26f0d4a6bb36524cb83445f05bffcb31
Dec 05 19:36:50.093 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-6/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/pr-56514/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/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 +6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468-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:50.275 INFO [stdout] 216017bd28170bfd2e1409a7df84463677036bc57734f05cea06bf21d64d4220
Dec 05 19:36:50.277 INFO running `"docker" "start" "-a" "216017bd28170bfd2e1409a7df84463677036bc57734f05cea06bf21d64d4220"`
Dec 05 19:36:50.965 INFO [stderr] usermod: no changes
Dec 05 19:36:51.174 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.19s
Dec 05 19:36:51.181 INFO [stderr]      Running /target/debug/deps/aw_server-98d968a8b7d9baab
Dec 05 19:36:51.193 INFO [stdout] 
Dec 05 19:36:51.193 INFO [stdout] running 0 tests
Dec 05 19:36:51.193 INFO [stdout] 
Dec 05 19:36:51.193 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 05 19:36:51.193 INFO [stdout] 
Dec 05 19:36:51.197 INFO [stderr]      Running /target/debug/deps/aw_server-39bba6784977e823
Dec 05 19:36:51.206 INFO [stdout] 
Dec 05 19:36:51.206 INFO [stdout] running 0 tests
Dec 05 19:36:51.206 INFO [stdout] 
Dec 05 19:36:51.206 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 05 19:36:51.206 INFO [stdout] 
Dec 05 19:36:51.208 INFO [stderr]      Running /target/debug/deps/api-f0f6c4d4f0892cd5
Dec 05 19:36:51.210 INFO [stdout] 
Dec 05 19:36:51.210 INFO [stdout] running 4 tests
Dec 05 19:36:51.234 INFO [stdout] Bucket { bid: Some(1), id: "id", _type: "type", client: "client", hostname: "hostname", created: None, events: None }
Dec 05 19:36:51.234 INFO [stdout] Commiting DB! Force commit true, 0 uncommited events
Dec 05 19:36:51.237 INFO [stdout] Bucket { bid: Some(1), id: "id", _type: "type", client: "client", hostname: "hostname", created: None, events: None }
Dec 05 19:36:51.238 INFO [stdout] Commiting DB! Force commit true, 0 uncommited events
Dec 05 19:36:51.238 INFO [stdout] test api_tests::test_query ... ok
Dec 05 19:36:51.238 INFO [stdout] DB worker quitting
Dec 05 19:36:51.238 INFO [stdout] Commiting DB! Force commit false, 1 uncommited events
Dec 05 19:36:51.240 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:51.240 INFO [stdout] Commiting DB! Force commit true, 1 uncommited events
Dec 05 19:36:51.241 INFO [stdout] Commiting DB! Force commit true, 0 uncommited events
Dec 05 19:36:51.241 INFO [stdout] Bucket { bid: Some(1), id: "id", _type: "type", client: "client", hostname: "hostname", created: None, events: None }
Dec 05 19:36:51.241 INFO [stdout] Commiting DB! Force commit true, 0 uncommited events
Dec 05 19:36:51.241 INFO [stdout] test api_tests::test_bucket ... ok
Dec 05 19:36:51.241 INFO [stdout] DB worker quitting
Dec 05 19:36:51.241 INFO [stdout] Commiting DB! Force commit false, 0 uncommited events
Dec 05 19:36:51.241 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:51.241 INFO [stdout] Commiting DB! Force commit true, 1 uncommited events
Dec 05 19:36:51.242 INFO [stdout] DB worker quitting
Dec 05 19:36:51.242 INFO [stdout] Commiting DB! Force commit false, 0 uncommited events
Dec 05 19:36:51.242 INFO [stdout] test api_tests::test_import ... ok
Dec 05 19:36:51.246 INFO [stdout] Commiting DB! Force commit true, 2 uncommited events
Dec 05 19:36:51.248 INFO [stdout] DB worker quitting
Dec 05 19:36:51.248 INFO [stdout] Commiting DB! Force commit false, 0 uncommited events
Dec 05 19:36:51.248 INFO [stdout] test api_tests::test_events ... ok
Dec 05 19:36:51.248 INFO [stdout] 
Dec 05 19:36:51.248 INFO [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 05 19:36:51.248 INFO [stdout] 
Dec 05 19:36:51.248 INFO [stderr]      Running /target/debug/deps/datastore-19db99cc57c4cae4
Dec 05 19:36:51.249 INFO [stdout] 
Dec 05 19:36:51.249 INFO [stdout] running 1 test
Dec 05 19:36:51.250 INFO [stdout] Bucket { bid: Some(1), id: "testid", _type: "testtype", client: "testclient", hostname: "testhost", created: Some(2018-12-05T19:36:51.249429918Z), events: None }
Dec 05 19:36:51.250 INFO [stdout] Commiting DB! Force commit true, 0 uncommited events
Dec 05 19:36:51.251 INFO [stdout] Commiting DB! Force commit true, 3 uncommited events
Dec 05 19:36:51.251 INFO [stdout] DB worker quitting
Dec 05 19:36:51.251 INFO [stdout] Commiting DB! Force commit false, 0 uncommited events
Dec 05 19:36:51.251 INFO [stdout] test datastore_tests::test_datastore ... ok
Dec 05 19:36:51.251 INFO [stdout] 
Dec 05 19:36:51.251 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 05 19:36:51.251 INFO [stdout] 
Dec 05 19:36:51.252 INFO [stderr]      Running /target/debug/deps/models-550782ee69e3fe7b
Dec 05 19:36:51.258 INFO [stdout] 
Dec 05 19:36:51.258 INFO [stdout] running 3 tests
Dec 05 19:36:51.259 INFO [stdout] test models_tests::test_bucket ... ok
Dec 05 19:36:51.259 INFO [stdout] test models_tests::test_event ... ok
Dec 05 19:36:51.259 INFO [stdout] test models_tests::test_timeinterval ... ok
Dec 05 19:36:51.259 INFO [stdout] 
Dec 05 19:36:51.259 INFO [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 05 19:36:51.259 INFO [stdout] 
Dec 05 19:36:51.260 INFO [stderr]      Running /target/debug/deps/query-f8621f3f0c68476e
Dec 05 19:36:51.266 INFO [stdout] 
Dec 05 19:36:51.268 INFO [stdout] running 9 tests
Dec 05 19:36:51.268 INFO [stdout] test query_tests::test_function ... ok
Dec 05 19:36:51.276 INFO [stdout] test query_tests::test_comment ... ok
Dec 05 19:36:51.276 INFO [stdout] test query_tests::test_return ... ok
Dec 05 19:36:51.277 INFO [stdout] test query_tests::test_math ... ok
Dec 05 19:36:51.284 INFO [stdout] DB worker quitting
Dec 05 19:36:51.284 INFO [stdout] Commiting DB! Force commit false, 0 uncommited events
Dec 05 19:36:51.284 INFO [stdout] Bucket { bid: Some(1), id: "testid", _type: "testtype", client: "testclient", hostname: "testhost", created: Some(2018-12-05T19:36:51.267084138Z), events: None }
Dec 05 19:36:51.284 INFO [stdout] Commiting DB! Force commit true, 0 uncommited events
Dec 05 19:36:51.284 INFO [stdout] test query_tests::test_dict ... ok
Dec 05 19:36:51.284 INFO [stdout] DB worker quitting
Dec 05 19:36:51.284 INFO [stdout] Commiting DB! Force commit false, 0 uncommited events
Dec 05 19:36:51.288 INFO [stdout] test query_tests::test_list ... ok
Dec 05 19:36:51.288 INFO [stdout] test query_tests::test_number ... ok
Dec 05 19:36:51.288 INFO [stdout] DB worker quitting
Dec 05 19:36:51.288 INFO [stdout] Commiting DB! Force commit false, 2 uncommited events
Dec 05 19:36:51.288 INFO [stdout] DB worker quitting
Dec 05 19:36:51.288 INFO [stdout] Commiting DB! Force commit false, 0 uncommited events
Dec 05 19:36:51.292 INFO [stdout] DB worker quitting
Dec 05 19:36:51.292 INFO [stdout] Commiting DB! Force commit false, 0 uncommited events
Dec 05 19:36:51.292 INFO [stdout] DB worker quitting
Dec 05 19:36:51.292 INFO [stdout] Commiting DB! Force commit false, 0 uncommited events
Dec 05 19:36:51.292 INFO [stdout] test query_tests::test_all_functions ... ok
Dec 05 19:36:51.292 INFO [stdout] DB worker quitting
Dec 05 19:36:51.292 INFO [stdout] Commiting DB! Force commit false, 0 uncommited events
Dec 05 19:36:51.293 INFO [stdout] DB worker quitting
Dec 05 19:36:51.293 INFO [stdout] Commiting DB! Force commit false, 0 uncommited events
Dec 05 19:36:51.296 INFO [stdout] test query_tests::test_string ... ok
Dec 05 19:36:51.296 INFO [stdout] 
Dec 05 19:36:51.296 INFO [stdout] test result: ok. 9 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 05 19:36:51.296 INFO [stdout] 
Dec 05 19:36:51.297 INFO [stderr]      Running /target/debug/deps/transform-8da32d003b76196e
Dec 05 19:36:51.303 INFO [stdout] 
Dec 05 19:36:51.305 INFO [stdout] running 9 tests
Dec 05 19:36:51.305 INFO [stdout] test transform_tests::test_chunk_events_by_key ... ok
Dec 05 19:36:51.305 INFO [stdout] test transform_tests::test_filter_period_intersect ... ok
Dec 05 19:36:51.305 INFO [stdout] test transform_tests::test_sort_by_timestamp ... ok
Dec 05 19:36:51.305 INFO [stdout] test transform_tests::test_heartbeat_pulsetime ... ok
Dec 05 19:36:51.305 INFO [stdout] test transform_tests::test_merge_events_by_key ... ok
Dec 05 19:36:51.305 INFO [stdout] test transform_tests::test_flood ... ok
Dec 05 19:36:51.305 INFO [stdout] test transform_tests::test_heartbeat_data ... ok
Dec 05 19:36:51.305 INFO [stdout] test transform_tests::test_filter_keyvals ... ok
Dec 05 19:36:51.305 INFO [stdout] test transform_tests::test_sort_by_duration ... ok
Dec 05 19:36:51.305 INFO [stdout] 
Dec 05 19:36:51.305 INFO [stdout] test result: ok. 9 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 05 19:36:51.305 INFO [stdout] 
Dec 05 19:36:51.305 INFO [stderr]    Doc-tests aw_server
Dec 05 19:36:55.052 INFO [stdout] 
Dec 05 19:36:55.052 INFO [stdout] running 0 tests
Dec 05 19:36:55.052 INFO [stdout] 
Dec 05 19:36:55.052 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 05 19:36:55.052 INFO [stdout] 
Dec 05 19:36:55.061 INFO [stderr] su: No module specific data is present
Dec 05 19:36:55.409 INFO running `"docker" "inspect" "216017bd28170bfd2e1409a7df84463677036bc57734f05cea06bf21d64d4220"`
Dec 05 19:36:55.579 INFO running `"docker" "rm" "-f" "216017bd28170bfd2e1409a7df84463677036bc57734f05cea06bf21d64d4220"`
Dec 05 19:36:55.799 INFO [stdout] 216017bd28170bfd2e1409a7df84463677036bc57734f05cea06bf21d64d4220