[INFO] fetching crate sel4-sys 0.0.28...
[INFO] testing sel4-sys-0.0.28 against beta-2022-02-22 for beta-1.60-1
[INFO] extracting crate sel4-sys 0.0.28 into /workspace/builds/worker-28/source
[INFO] validating manifest of crates.io crate sel4-sys 0.0.28 on toolchain beta-2022-02-22
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+beta-2022-02-22" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] started tweaking crates.io crate sel4-sys 0.0.28
[INFO] finished tweaking crates.io crate sel4-sys 0.0.28
[INFO] tweaked toml for crates.io crate sel4-sys 0.0.28 written to /workspace/builds/worker-28/source/Cargo.toml
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+beta-2022-02-22" "generate-lockfile" "--manifest-path" "Cargo.toml" "-Zno-index-update", kill_on_drop: false }`
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+beta-2022-02-22" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-28/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-28/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:0cd99ca24d8e8c98e67c542213511d985b8778b5bdcbb160e038429496686047" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2022-02-22" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] d42249bea24cf3a78d23b957b850bb0e2e9d6b3d52285bf4ffaff7224ddfe0ed
[INFO] running `Command { std: "docker" "start" "-a" "d42249bea24cf3a78d23b957b850bb0e2e9d6b3d52285bf4ffaff7224ddfe0ed", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "d42249bea24cf3a78d23b957b850bb0e2e9d6b3d52285bf4ffaff7224ddfe0ed", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "d42249bea24cf3a78d23b957b850bb0e2e9d6b3d52285bf4ffaff7224ddfe0ed", kill_on_drop: false }`
[INFO] [stdout] d42249bea24cf3a78d23b957b850bb0e2e9d6b3d52285bf4ffaff7224ddfe0ed
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-28/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-28/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:0cd99ca24d8e8c98e67c542213511d985b8778b5bdcbb160e038429496686047" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2022-02-22" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 1b73a419974e0a08b7d53efa60b742ca9dee46345e579efcca21e8263ae6c36d
[INFO] running `Command { std: "docker" "start" "-a" "1b73a419974e0a08b7d53efa60b742ca9dee46345e579efcca21e8263ae6c36d", kill_on_drop: false }`
[INFO] [stderr]    Compiling sel4-sys v0.0.28 (/opt/rustwide/workdir)
[INFO] [stderr]    Compiling rlibc v1.0.0
[INFO] [stderr]    Compiling bitflags v0.7.0
[INFO] [stdout] warning: unused variable: `word_size`
[INFO] [stdout]   --> build.rs:35:26
[INFO] [stdout]    |
[INFO] [stdout] 35 |     for &(arch, archdir, word_size) in &arches {
[INFO] [stdout]    |                          ^^^^^^^^^ help: if this is intentional, prefix it with an underscore: `_word_size`
[INFO] [stdout]    |
[INFO] [stdout]    = note: `#[warn(unused_variables)]` on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: 1 warning emitted
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr] error: failed to run custom build command for `sel4-sys v0.0.28 (/opt/rustwide/workdir)`
[INFO] [stderr] 
[INFO] [stderr] Caused by:
[INFO] [stderr]   process didn't exit successfully: `/opt/rustwide/target/debug/build/sel4-sys-14024bb589b6c24a/build-script-build` (exit status: 101)
[INFO] [stderr]   --- stdout
[INFO] [stderr]   Running: "/usr/bin/env" "python" "tools/syscall_stub_gen.py" "-a" "ia32" "-w" "32" "--buffer" "-o" "/opt/rustwide/target/debug/build/sel4-sys-ac5d8dbefcd92313/out/ia32_syscall_stub.rs" "seL4/libsel4/include/interfaces/sel4.xml" "seL4/libsel4/arch_include/x86/interfaces/sel4arch.xml" "seL4/libsel4/sel4_arch_include/ia32/interfaces/sel4arch.xml"
[INFO] [stderr]   Running: "/usr/bin/env" "python" "tools/syscall_stub_gen.py" "-a" "x86_64" "-w" "64" "--buffer" "-o" "/opt/rustwide/target/debug/build/sel4-sys-ac5d8dbefcd92313/out/x86_64_syscall_stub.rs" "seL4/libsel4/include/interfaces/sel4.xml" "seL4/libsel4/arch_include/x86/interfaces/sel4arch.xml" "seL4/libsel4/sel4_arch_include/x86_64/interfaces/sel4arch.xml"
[INFO] [stderr]   Running: "/usr/bin/env" "python" "tools/syscall_stub_gen.py" "-a" "aarch32" "-w" "32" "--buffer" "-o" "/opt/rustwide/target/debug/build/sel4-sys-ac5d8dbefcd92313/out/aarch32_syscall_stub.rs" "seL4/libsel4/include/interfaces/sel4.xml" "seL4/libsel4/arch_include/arm/interfaces/sel4arch.xml" "seL4/libsel4/sel4_arch_include/aarch32/interfaces/sel4arch.xml"
[INFO] [stderr]   Running "/usr/bin/env" "python" "tools/invocation_header_gen.py" "--dest" "/opt/rustwide/target/debug/build/sel4-sys-ac5d8dbefcd92313/out/ia32_invocation.rs" "seL4/libsel4/include/interfaces/sel4.xml" "seL4/libsel4/arch_include/x86/interfaces/sel4arch.xml" "seL4/libsel4/sel4_arch_include/ia32/interfaces/sel4arch.xml"
[INFO] [stderr] 
[INFO] [stderr]   --- stderr
[INFO] [stderr]   Traceback (most recent call last):
[INFO] [stderr]     File "tools/invocation_header_gen.py", line 20, in <module>
[INFO] [stderr]       import tempita
[INFO] [stderr]   ImportError: No module named tempita
[INFO] [stderr]   thread 'main' panicked at 'assertion failed: cmd.status().unwrap().success()', build.rs:45:9
[INFO] [stderr]   stack backtrace:
[INFO] [stderr]      0:     0x5645a939c5fc - std::backtrace_rs::backtrace::libunwind::trace::h02555d7ca8aa068a
[INFO] [stderr]                                  at /rustc/0a4f984a87c7ba6c74ec3e78442fec955a419e32/library/std/src/../../backtrace/src/backtrace/libunwind.rs:93:5
[INFO] [stderr]      1:     0x5645a939c5fc - std::backtrace_rs::backtrace::trace_unsynchronized::h8555a46024147da8
[INFO] [stderr]                                  at /rustc/0a4f984a87c7ba6c74ec3e78442fec955a419e32/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5
[INFO] [stderr]      2:     0x5645a939c5fc - std::sys_common::backtrace::_print_fmt::h4c7f50b4f105da1e
[INFO] [stderr]                                  at /rustc/0a4f984a87c7ba6c74ec3e78442fec955a419e32/library/std/src/sys_common/backtrace.rs:66:5
[INFO] [stderr]      3:     0x5645a939c5fc - <std::sys_common::backtrace::_print::DisplayBacktrace as core::fmt::Display>::fmt::ha305a40dbc030444
[INFO] [stderr]                                  at /rustc/0a4f984a87c7ba6c74ec3e78442fec955a419e32/library/std/src/sys_common/backtrace.rs:45:22
[INFO] [stderr]      4:     0x5645a93bcc5c - core::fmt::write::hadd15acf4d6ce273
[INFO] [stderr]                                  at /rustc/0a4f984a87c7ba6c74ec3e78442fec955a419e32/library/core/src/fmt/mod.rs:1190:17
[INFO] [stderr]      5:     0x5645a93993a8 - std::io::Write::write_fmt::h0f87f6c0c21ec48a
[INFO] [stderr]                                  at /rustc/0a4f984a87c7ba6c74ec3e78442fec955a419e32/library/std/src/io/mod.rs:1657:15
[INFO] [stderr]      6:     0x5645a939e287 - std::sys_common::backtrace::_print::h0dd5c0810675bd8c
[INFO] [stderr]                                  at /rustc/0a4f984a87c7ba6c74ec3e78442fec955a419e32/library/std/src/sys_common/backtrace.rs:48:5
[INFO] [stderr]      7:     0x5645a939e287 - std::sys_common::backtrace::print::h20a2155fa0a22ca6
[INFO] [stderr]                                  at /rustc/0a4f984a87c7ba6c74ec3e78442fec955a419e32/library/std/src/sys_common/backtrace.rs:35:9
[INFO] [stderr]      8:     0x5645a939e287 - std::panicking::default_hook::{{closure}}::habb3b6f3ccdfe00a
[INFO] [stderr]                                  at /rustc/0a4f984a87c7ba6c74ec3e78442fec955a419e32/library/std/src/panicking.rs:295:22
[INFO] [stderr]      9:     0x5645a939df4f - std::panicking::default_hook::h1c5cf4d5063a38e4
[INFO] [stderr]                                  at /rustc/0a4f984a87c7ba6c74ec3e78442fec955a419e32/library/std/src/panicking.rs:314:9
[INFO] [stderr]     10:     0x5645a939e9da - std::panicking::rust_panic_with_hook::hb5de7a3072d2cf59
[INFO] [stderr]                                  at /rustc/0a4f984a87c7ba6c74ec3e78442fec955a419e32/library/std/src/panicking.rs:698:17
[INFO] [stderr]     11:     0x5645a939e689 - std::panicking::begin_panic_handler::{{closure}}::h5106de2f55f1a411
[INFO] [stderr]                                  at /rustc/0a4f984a87c7ba6c74ec3e78442fec955a419e32/library/std/src/panicking.rs:586:13
[INFO] [stderr]     12:     0x5645a939cac4 - std::sys_common::backtrace::__rust_end_short_backtrace::h5ad5c2a8a003ca72
[INFO] [stderr]                                  at /rustc/0a4f984a87c7ba6c74ec3e78442fec955a419e32/library/std/src/sys_common/backtrace.rs:138:18
[INFO] [stderr]     13:     0x5645a939e3d9 - rust_begin_unwind
[INFO] [stderr]                                  at /rustc/0a4f984a87c7ba6c74ec3e78442fec955a419e32/library/std/src/panicking.rs:584:5
[INFO] [stderr]     14:     0x5645a9379413 - core::panicking::panic_fmt::hfb4b8460cd493e74
[INFO] [stderr]                                  at /rustc/0a4f984a87c7ba6c74ec3e78442fec955a419e32/library/core/src/panicking.rs:143:14
[INFO] [stderr]     15:     0x5645a93792dd - core::panicking::panic::h33838847634b5d2d
[INFO] [stderr]                                  at /rustc/0a4f984a87c7ba6c74ec3e78442fec955a419e32/library/core/src/panicking.rs:48:5
[INFO] [stderr]     16:     0x5645a937f33d - build_script_build::main::h60ed5290fa483d98
[INFO] [stderr]                                  at /opt/rustwide/workdir/build.rs:45:9
[INFO] [stderr]     17:     0x5645a937d24b - core::ops::function::FnOnce::call_once::hd2275f41f0f7a0e4
[INFO] [stderr]                                  at /rustc/0a4f984a87c7ba6c74ec3e78442fec955a419e32/library/core/src/ops/function.rs:227:5
[INFO] [stderr]     18:     0x5645a9382b6e - std::sys_common::backtrace::__rust_begin_short_backtrace::h313b87f8188ecf5a
[INFO] [stderr]                                  at /rustc/0a4f984a87c7ba6c74ec3e78442fec955a419e32/library/std/src/sys_common/backtrace.rs:122:18
[INFO] [stderr]     19:     0x5645a937c571 - std::rt::lang_start::{{closure}}::he0b390dabdc8ca8d
[INFO] [stderr]                                  at /rustc/0a4f984a87c7ba6c74ec3e78442fec955a419e32/library/std/src/rt.rs:145:18
[INFO] [stderr]     20:     0x5645a939bce1 - core::ops::function::impls::<impl core::ops::function::FnOnce<A> for &F>::call_once::h9e134bb161d17658
[INFO] [stderr]                                  at /rustc/0a4f984a87c7ba6c74ec3e78442fec955a419e32/library/core/src/ops/function.rs:259:13
[INFO] [stderr]     21:     0x5645a939bce1 - std::panicking::try::do_call::h7f361d911648db1a
[INFO] [stderr]                                  at /rustc/0a4f984a87c7ba6c74ec3e78442fec955a419e32/library/std/src/panicking.rs:492:40
[INFO] [stderr]     22:     0x5645a939bce1 - std::panicking::try::hbe441b12f8ff00e7
[INFO] [stderr]                                  at /rustc/0a4f984a87c7ba6c74ec3e78442fec955a419e32/library/std/src/panicking.rs:456:19
[INFO] [stderr]     23:     0x5645a939bce1 - std::panic::catch_unwind::h12066b7ab56d7459
[INFO] [stderr]                                  at /rustc/0a4f984a87c7ba6c74ec3e78442fec955a419e32/library/std/src/panic.rs:137:14
[INFO] [stderr]     24:     0x5645a939bce1 - std::rt::lang_start_internal::{{closure}}::h901c86e61467dcde
[INFO] [stderr]                                  at /rustc/0a4f984a87c7ba6c74ec3e78442fec955a419e32/library/std/src/rt.rs:128:48
[INFO] [stderr]     25:     0x5645a939bce1 - std::panicking::try::do_call::h1817b5f028d90a8c
[INFO] [stderr]                                  at /rustc/0a4f984a87c7ba6c74ec3e78442fec955a419e32/library/std/src/panicking.rs:492:40
[INFO] [stderr]     26:     0x5645a939bce1 - std::panicking::try::hd5afeaf2f0454960
[INFO] [stderr]                                  at /rustc/0a4f984a87c7ba6c74ec3e78442fec955a419e32/library/std/src/panicking.rs:456:19
[INFO] [stderr]     27:     0x5645a939bce1 - std::panic::catch_unwind::h13348562ae6426ca
[INFO] [stderr]                                  at /rustc/0a4f984a87c7ba6c74ec3e78442fec955a419e32/library/std/src/panic.rs:137:14
[INFO] [stderr]     28:     0x5645a939bce1 - std::rt::lang_start_internal::hf194c7db73fedcb2
[INFO] [stderr]                                  at /rustc/0a4f984a87c7ba6c74ec3e78442fec955a419e32/library/std/src/rt.rs:128:20
[INFO] [stderr]     29:     0x5645a937c540 - std::rt::lang_start::h41184c75c890b021
[INFO] [stderr]                                  at /rustc/0a4f984a87c7ba6c74ec3e78442fec955a419e32/library/std/src/rt.rs:144:17
[INFO] [stderr]     30:     0x5645a9380d0c - main
[INFO] [stderr]     31:     0x7f95f4a180b3 - __libc_start_main
[INFO] [stderr]     32:     0x5645a9379b9e - _start
[INFO] [stderr]     33:                0x0 - <unknown>
[INFO] running `Command { std: "docker" "inspect" "1b73a419974e0a08b7d53efa60b742ca9dee46345e579efcca21e8263ae6c36d", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "1b73a419974e0a08b7d53efa60b742ca9dee46345e579efcca21e8263ae6c36d", kill_on_drop: false }`
[INFO] [stdout] 1b73a419974e0a08b7d53efa60b742ca9dee46345e579efcca21e8263ae6c36d