Dec 07 14:39:06.317 INFO testing google-appengine1_beta4-1.0.8+20181005 against master#906deae0790bd18681b937fe9a141a3c26cf1855 for pr-56514
Dec 07 14:39:06.317 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 07 14:39:06.776 INFO [stdout] eba74b4e9db8b2cc64a942552d223ccddaa329c2eeefa6e957102eef17b325a5
Dec 07 14:39:06.780 INFO running `"docker" "start" "-a" "eba74b4e9db8b2cc64a942552d223ccddaa329c2eeefa6e957102eef17b325a5"`
Dec 07 14:39:08.124 INFO [stderr] usermod: no changes
Dec 07 14:39:08.330 INFO [stderr]    Compiling google-appengine1_beta4 v1.0.8+20181005 (/source)
Dec 07 14:39:18.140 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 9.98s
Dec 07 14:39:18.152 INFO [stderr] su: No module specific data is present
Dec 07 14:39:18.954 INFO running `"docker" "inspect" "eba74b4e9db8b2cc64a942552d223ccddaa329c2eeefa6e957102eef17b325a5"`
Dec 07 14:39:19.204 INFO running `"docker" "rm" "-f" "eba74b4e9db8b2cc64a942552d223ccddaa329c2eeefa6e957102eef17b325a5"`
Dec 07 14:39:19.470 INFO [stdout] eba74b4e9db8b2cc64a942552d223ccddaa329c2eeefa6e957102eef17b325a5
Dec 07 14:39:19.476 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 07 14:39:19.908 INFO [stdout] 01d857ae8fe6fa3b150110eca923810c3eab36070d988b6fffbf2dd92a0f1bc1
Dec 07 14:39:19.912 INFO running `"docker" "start" "-a" "01d857ae8fe6fa3b150110eca923810c3eab36070d988b6fffbf2dd92a0f1bc1"`
Dec 07 14:39:21.800 INFO [stderr] usermod: no changes
Dec 07 14:39:21.976 INFO [stderr]    Compiling google-appengine1_beta4 v1.0.8+20181005 (/source)
Dec 07 14:39:30.994 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 9.17s
Dec 07 14:39:31.009 INFO [stderr] su: No module specific data is present
Dec 07 14:39:32.148 INFO running `"docker" "inspect" "01d857ae8fe6fa3b150110eca923810c3eab36070d988b6fffbf2dd92a0f1bc1"`
Dec 07 14:39:32.590 INFO running `"docker" "rm" "-f" "01d857ae8fe6fa3b150110eca923810c3eab36070d988b6fffbf2dd92a0f1bc1"`
Dec 07 14:39:32.832 INFO [stdout] 01d857ae8fe6fa3b150110eca923810c3eab36070d988b6fffbf2dd92a0f1bc1
Dec 07 14:39:32.838 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 07 14:39:33.218 INFO [stdout] b5901ac4520d78c41eefffe8dffb2df06d469b14c2b5ac1b577e4faee3db5c26
Dec 07 14:39:33.220 INFO running `"docker" "start" "-a" "b5901ac4520d78c41eefffe8dffb2df06d469b14c2b5ac1b577e4faee3db5c26"`
Dec 07 14:39:35.139 INFO [stderr] usermod: no changes
Dec 07 14:39:35.395 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.23s
Dec 07 14:39:35.399 INFO [stderr]      Running /target/debug/deps/google_appengine1_beta4-211c33623e04104d
Dec 07 14:39:35.401 INFO [stdout] 
Dec 07 14:39:35.403 INFO [stdout] running 0 tests
Dec 07 14:39:35.403 INFO [stdout] 
Dec 07 14:39:35.403 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 14:39:35.403 INFO [stdout] 
Dec 07 14:39:35.403 INFO [stderr]    Doc-tests google-appengine1_beta4
Dec 07 14:39:43.400 INFO [stdout] 
Dec 07 14:39:43.400 INFO [stdout] running 24 tests
Dec 07 14:39:43.469 INFO [stdout] test src/lib.rs -  (line 49) ... ignored
Dec 07 14:40:37.944 INFO [stdout] test src/lib.rs - AppLocationGetCall (line 5220) ... ok
Dec 07 14:40:39.468 INFO [stdout] test src/lib.rs -  (line 88) ... ok
Dec 07 14:40:40.741 INFO [stdout] test src/lib.rs - AppCreateCall (line 3014) ... ok
Dec 07 14:40:41.236 INFO [stdout] test src/lib.rs - AppGetCall (line 4151) ... ok
Dec 07 14:40:42.457 INFO [stdout] test src/lib.rs - AppLocationListCall (line 5479) ... ok
Dec 07 14:40:42.732 INFO [stdout] test src/lib.rs - AppModuleDeleteCall (line 4410) ... ok
Dec 07 14:40:42.824 INFO [stdout] test src/lib.rs - AppModuleGetCall (line 2199) ... ok
Dec 07 14:40:43.432 INFO [stdout] test src/lib.rs - AppMethods (line 1410) ... test src/lib.rs - AppMethods (line 1410) has been running for over 60 seconds
Dec 07 14:40:44.700 INFO [stdout] test src/lib.rs - AppMethods (line 1410) ... ok
Dec 07 14:41:37.945 INFO [stdout] test src/lib.rs - AppModuleListCall (line 2741) ... test src/lib.rs - AppModuleListCall (line 2741) has been running for over 60 seconds
Dec 07 14:41:39.476 INFO [stdout] test src/lib.rs - AppModulePatchCall (line 1885) ... test src/lib.rs - AppModulePatchCall (line 1885) has been running for over 60 seconds
Dec 07 14:41:40.436 INFO [stdout] test src/lib.rs - AppModuleListCall (line 2741) ... ok
Dec 07 14:41:40.752 INFO [stdout] test src/lib.rs - AppModuleVersionCreateCall (line 6634) ... test src/lib.rs - AppModuleVersionCreateCall (line 6634) has been running for over 60 seconds
Dec 07 14:41:41.248 INFO [stdout] test src/lib.rs - AppModuleVersionDeleteCall (line 6363) ... test src/lib.rs - AppModuleVersionDeleteCall (line 6363) has been running for over 60 seconds
Dec 07 14:41:42.476 INFO [stdout] test src/lib.rs - AppModuleVersionGetCall (line 6924) ... test src/lib.rs - AppModuleVersionGetCall (line 6924) has been running for over 60 seconds
Dec 07 14:41:42.768 INFO [stdout] test src/lib.rs - AppModuleVersionInstanceDebugCall (line 3837) ... test src/lib.rs - AppModuleVersionInstanceDebugCall (line 3837) has been running for over 60 seconds
Dec 07 14:41:42.848 INFO [stdout] test src/lib.rs - AppModuleVersionInstanceDeleteCall (line 3554) ... test src/lib.rs - AppModuleVersionInstanceDeleteCall (line 3554) has been running for over 60 seconds
Dec 07 14:41:43.864 INFO [stdout] test src/lib.rs - AppModuleVersionCreateCall (line 6634) ... ok
Dec 07 14:41:44.448 INFO [stdout] test src/lib.rs - AppModuleVersionGetCall (line 6924) ... ok
Dec 07 14:41:44.704 INFO [stdout] test src/lib.rs - AppModuleVersionInstanceGetCall (line 2458) ... test src/lib.rs - AppModuleVersionInstanceGetCall (line 2458) has been running for over 60 seconds
Dec 07 14:41:45.608 INFO [stdout] test src/lib.rs - AppModulePatchCall (line 1885) ... ok
Dec 07 14:41:46.356 INFO [stdout] test src/lib.rs - AppModuleVersionDeleteCall (line 6363) ... ok
Dec 07 14:41:47.352 INFO [stdout] test src/lib.rs - AppModuleVersionInstanceDebugCall (line 3837) ... ok
Dec 07 14:41:47.395 INFO [stdout] test src/lib.rs - AppModuleVersionInstanceGetCall (line 2458) ... ok
Dec 07 14:41:47.480 INFO [stdout] test src/lib.rs - AppModuleVersionInstanceDeleteCall (line 3554) ... ok
Dec 07 14:42:37.781 INFO [stdout] test src/lib.rs - AppModuleVersionListCall (line 7207) ... ok
Dec 07 14:42:38.080 INFO [stdout] test src/lib.rs - AppModuleVersionInstanceListCall (line 3259) ... ok
Dec 07 14:42:38.972 INFO [stdout] test src/lib.rs - AppModuleVersionPatchCall (line 5766) ... ok
Dec 07 14:42:38.987 INFO [stdout] test src/lib.rs - Appengine (line 283) ... ok
Dec 07 14:42:39.276 INFO [stdout] test src/lib.rs - AppOperationListCall (line 6080) ... ok
Dec 07 14:42:39.475 INFO [stdout] test src/lib.rs - AppOperationGetCall (line 4961) ... ok
Dec 07 14:42:39.690 INFO [stdout] test src/lib.rs - AppPatchCall (line 4671) ... ok
Dec 07 14:42:39.690 INFO [stdout] 
Dec 07 14:42:39.690 INFO [stdout] test result: ok. 23 passed; 0 failed; 1 ignored; 0 measured; 0 filtered out
Dec 07 14:42:39.690 INFO [stdout] 
Dec 07 14:42:39.738 INFO [stderr] su: No module specific data is present
Dec 07 14:42:41.208 INFO running `"docker" "inspect" "b5901ac4520d78c41eefffe8dffb2df06d469b14c2b5ac1b577e4faee3db5c26"`
Dec 07 14:42:41.500 INFO running `"docker" "rm" "-f" "b5901ac4520d78c41eefffe8dffb2df06d469b14c2b5ac1b577e4faee3db5c26"`
Dec 07 14:42:41.829 INFO [stdout] b5901ac4520d78c41eefffe8dffb2df06d469b14c2b5ac1b577e4faee3db5c26