[INFO] fetching crate nitinol-sqlite-adaptor 0.1.0...
[INFO] testing nitinol-sqlite-adaptor-0.1.0 against try#b83b707f97d809763b7861afa7638871f3339a33 for pr-145838-1
[INFO] extracting crate nitinol-sqlite-adaptor 0.1.0 into /workspace/builds/worker-5-tc2/source
[INFO] started tweaking crates.io crate nitinol-sqlite-adaptor 0.1.0
[INFO] removed 0 missing tests
[INFO] finished tweaking crates.io crate nitinol-sqlite-adaptor 0.1.0
[INFO] tweaked toml for crates.io crate nitinol-sqlite-adaptor 0.1.0 written to /workspace/builds/worker-5-tc2/source/Cargo.toml
[INFO] validating manifest of crates.io crate nitinol-sqlite-adaptor 0.1.0 on toolchain b83b707f97d809763b7861afa7638871f3339a33
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+b83b707f97d809763b7861afa7638871f3339a33" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate crates.io crate nitinol-sqlite-adaptor 0.1.0 already has a lockfile, it will not be regenerated
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+b83b707f97d809763b7861afa7638871f3339a33" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/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:7ad1b28ee6f5f7f699f6cf7015098d6ccdd96d6f2d78dd06228f5b4c9faf309c" "/opt/rustwide/cargo-home/bin/cargo" "+b83b707f97d809763b7861afa7638871f3339a33" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] bc095430898b6aed431b8b235ac28a05fc6f2aeb00142a6ded431b19b2a75763
[INFO] running `Command { std: "docker" "start" "-a" "bc095430898b6aed431b8b235ac28a05fc6f2aeb00142a6ded431b19b2a75763", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "bc095430898b6aed431b8b235ac28a05fc6f2aeb00142a6ded431b19b2a75763", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "bc095430898b6aed431b8b235ac28a05fc6f2aeb00142a6ded431b19b2a75763", kill_on_drop: false }`
[INFO] [stdout] bc095430898b6aed431b8b235ac28a05fc6f2aeb00142a6ded431b19b2a75763
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/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=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-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:7ad1b28ee6f5f7f699f6cf7015098d6ccdd96d6f2d78dd06228f5b4c9faf309c" "/opt/rustwide/cargo-home/bin/cargo" "+b83b707f97d809763b7861afa7638871f3339a33" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 4aa7bfcba10844a316e0fed8d6a95b930cfa1aed48c449c862b039c0e392954a
[INFO] running `Command { std: "docker" "start" "-a" "4aa7bfcba10844a316e0fed8d6a95b930cfa1aed48c449c862b039c0e392954a", kill_on_drop: false }`
[INFO] [stderr]    Compiling unicode-ident v1.0.17
[INFO] [stderr]    Compiling libc v0.2.170
[INFO] [stderr]    Compiling smallvec v1.14.0
[INFO] [stderr]    Compiling serde v1.0.218
[INFO] [stderr]    Compiling crossbeam-utils v0.8.21
[INFO] [stderr]    Compiling foldhash v0.1.4
[INFO] [stderr]    Compiling serde_json v1.0.139
[INFO] [stderr]    Compiling time-core v0.1.2
[INFO] [stderr]    Compiling deranged v0.3.11
[INFO] [stderr]    Compiling futures-util v0.3.31
[INFO] [stderr]    Compiling cc v1.2.15
[INFO] [stderr]    Compiling log v0.4.26
[INFO] [stderr]    Compiling crc-catalog v2.4.0
[INFO] [stderr]    Compiling crypto-common v0.1.6
[INFO] [stderr]    Compiling spin v0.9.8
[INFO] [stderr]    Compiling getrandom v0.3.1
[INFO] [stderr]    Compiling proc-macro2 v1.0.93
[INFO] [stderr]    Compiling hashbrown v0.15.2
[INFO] [stderr]    Compiling time-macros v0.2.19
[INFO] [stderr]    Compiling crc v3.2.1
[INFO] [stderr]    Compiling digest v0.10.7
[INFO] [stderr]    Compiling rustix v0.38.44
[INFO] [stderr]    Compiling flume v0.11.1
[INFO] [stderr]    Compiling atoi v2.0.0
[INFO] [stderr]    Compiling futures-channel v0.3.31
[INFO] [stderr]    Compiling bitflags v2.8.0
[INFO] [stderr]    Compiling hex v0.4.3
[INFO] [stderr]    Compiling sha2 v0.10.8
[INFO] [stderr]    Compiling quote v1.0.38
[INFO] [stderr]    Compiling concurrent-queue v2.5.0
[INFO] [stderr]    Compiling crossbeam-queue v0.3.12
[INFO] [stderr]    Compiling time v0.3.37
[INFO] [stderr]    Compiling event-listener v5.4.0
[INFO] [stderr]    Compiling syn v2.0.98
[INFO] [stderr]    Compiling hashlink v0.10.0
[INFO] [stderr]    Compiling indexmap v2.7.1
[INFO] [stderr]    Compiling socket2 v0.5.8
[INFO] [stderr]    Compiling mio v1.0.3
[INFO] [stderr]    Compiling parking_lot_core v0.9.10
[INFO] [stderr]    Compiling parking_lot v0.12.3
[INFO] [stderr]    Compiling futures-intrusive v0.5.0
[INFO] [stderr]    Compiling libsqlite3-sys v0.30.1
[INFO] [stderr]    Compiling tokio v1.43.0
[INFO] [stderr]    Compiling futures-executor v0.3.31
[INFO] [stderr]    Compiling tempfile v3.17.1
[INFO] [stderr]    Compiling synstructure v0.13.1
[INFO] [stderr]    Compiling zerovec-derive v0.10.3
[INFO] [stderr]    Compiling displaydoc v0.2.5
[INFO] [stderr]    Compiling icu_provider_macros v1.5.0
[INFO] [stderr]    Compiling serde_derive v1.0.218
[INFO] [stderr]    Compiling thiserror-impl v2.0.11
[INFO] [stderr]    Compiling tracing-attributes v0.1.28
[INFO] [stderr]    Compiling async-trait v0.1.86
[INFO] [stderr]    Compiling zerofrom-derive v0.1.5
[INFO] [stderr]    Compiling yoke-derive v0.7.5
[INFO] [stderr]    Compiling tokio-stream v0.1.17
[INFO] [stderr]    Compiling zerofrom v0.1.5
[INFO] [stderr]    Compiling thiserror v2.0.11
[INFO] [stderr]    Compiling yoke v0.7.5
[INFO] [stderr]    Compiling tracing v0.1.41
[INFO] [stderr]    Compiling zerovec v0.10.4
[INFO] [stderr]    Compiling tinystr v0.7.6
[INFO] [stderr]    Compiling icu_collections v1.5.0
[INFO] [stderr]    Compiling icu_locid v1.5.0
[INFO] [stderr]    Compiling icu_provider v1.5.0
[INFO] [stderr]    Compiling icu_locid_transform v1.5.0
[INFO] [stderr]    Compiling icu_properties v1.5.1
[INFO] [stderr]    Compiling either v1.14.0
[INFO] [stderr]    Compiling serde_urlencoded v0.7.1
[INFO] [stderr]    Compiling nitinol-core v1.0.0
[INFO] [stderr]    Compiling icu_normalizer v1.5.0
[INFO] [stderr]    Compiling idna_adapter v1.2.0
[INFO] [stderr]    Compiling idna v1.0.3
[INFO] [stderr]    Compiling url v2.5.4
[INFO] [stderr]    Compiling sqlx-core v0.8.3
[INFO] [stderr]    Compiling sqlx-sqlite v0.8.3
[INFO] [stderr]    Compiling sqlx-macros-core v0.8.3
[INFO] [stderr]    Compiling sqlx-macros v0.8.3
[INFO] [stderr]    Compiling sqlx v0.8.3
[INFO] [stderr]    Compiling nitinol-protocol v0.1.0
[INFO] [stderr]    Compiling nitinol-sqlite-adaptor v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 52.54s
[INFO] running `Command { std: "docker" "inspect" "4aa7bfcba10844a316e0fed8d6a95b930cfa1aed48c449c862b039c0e392954a", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "4aa7bfcba10844a316e0fed8d6a95b930cfa1aed48c449c862b039c0e392954a", kill_on_drop: false }`
[INFO] [stdout] 4aa7bfcba10844a316e0fed8d6a95b930cfa1aed48c449c862b039c0e392954a
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/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=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-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:7ad1b28ee6f5f7f699f6cf7015098d6ccdd96d6f2d78dd06228f5b4c9faf309c" "/opt/rustwide/cargo-home/bin/cargo" "+b83b707f97d809763b7861afa7638871f3339a33" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 2969d4e00f1fef84a2e55704b10b4e63be3a36fc40b34e7fb35b5ba4e11a5486
[INFO] running `Command { std: "docker" "start" "-a" "2969d4e00f1fef84a2e55704b10b4e63be3a36fc40b34e7fb35b5ba4e11a5486", kill_on_drop: false }`
[INFO] [stderr]    Compiling tracing-core v0.1.33
[INFO] [stderr]    Compiling log v0.4.26
[INFO] [stderr]    Compiling tokio-macros v2.5.0
[INFO] [stderr]    Compiling tokio v1.43.0
[INFO] [stderr]    Compiling nitinol v0.4.1
[INFO] [stderr]    Compiling darling_core v0.20.10
[INFO] [stderr]    Compiling thread_local v1.1.8
[INFO] [stderr]    Compiling tracing v0.1.41
[INFO] [stderr]    Compiling tracing-log v0.2.0
[INFO] [stderr]    Compiling tracing-subscriber v0.3.19
[INFO] [stderr]    Compiling darling_macro v0.20.10
[INFO] [stderr]    Compiling darling v0.20.10
[INFO] [stderr]    Compiling nitinol-macro v0.1.0
[INFO] [stderr]    Compiling tokio-stream v0.1.17
[INFO] [stderr]    Compiling sqlx-core v0.8.3
[INFO] [stderr]    Compiling nitinol-process v0.1.1
[INFO] [stderr]    Compiling sqlx-sqlite v0.8.3
[INFO] [stderr]    Compiling sqlx-macros-core v0.8.3
[INFO] [stderr]    Compiling sqlx-macros v0.8.3
[INFO] [stderr]    Compiling sqlx v0.8.3
[INFO] [stderr]    Compiling nitinol-protocol v0.1.0
[INFO] [stderr]    Compiling nitinol-persistence v0.1.1
[INFO] [stderr]    Compiling nitinol-sqlite-adaptor v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 30.14s
[INFO] running `Command { std: "docker" "inspect" "2969d4e00f1fef84a2e55704b10b4e63be3a36fc40b34e7fb35b5ba4e11a5486", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "2969d4e00f1fef84a2e55704b10b4e63be3a36fc40b34e7fb35b5ba4e11a5486", kill_on_drop: false }`
[INFO] [stdout] 2969d4e00f1fef84a2e55704b10b4e63be3a36fc40b34e7fb35b5ba4e11a5486
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/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=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-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:7ad1b28ee6f5f7f699f6cf7015098d6ccdd96d6f2d78dd06228f5b4c9faf309c" "/opt/rustwide/cargo-home/bin/cargo" "+b83b707f97d809763b7861afa7638871f3339a33" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 7a367a105c1902fd2a4f278bde29cd27c5341c8d9e0f76395a9fdbf1362a7296
[INFO] running `Command { std: "docker" "start" "-a" "7a367a105c1902fd2a4f278bde29cd27c5341c8d9e0f76395a9fdbf1362a7296", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.28s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/nitinol_sqlite_adaptor-f99e9353004b04aa)
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/program.rs (/opt/rustwide/target/debug/deps/program-100e6cf9878c96fc)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] 2025-09-01T10:32:04.839463Z DEBUG connect-eventstore: sqlx::query: summary="PRAGMA foreign_keys = ON; …" db.statement="\n\nPRAGMA foreign_keys = ON; \n" rows_affected=0 rows_returned=0 elapsed=96.26µs elapsed_secs=9.626e-5
[INFO] [stdout] 2025-09-01T10:32:04.840359Z DEBUG migrate-eventstore: sqlx::query: summary="CREATE TABLE IF NOT …" db.statement="\n\n\nCREATE TABLE IF NOT EXISTS _sqlx_migrations (\n    version BIGINT PRIMARY KEY,\n    description TEXT NOT NULL,\n    installed_on TIMESTAMP NOT NULL DEFAULT CURRENT_TIMESTAMP,\n    success BOOLEAN NOT NULL,\n    checksum BLOB NOT NULL,\n    execution_time BIGINT NOT NULL\n);\n                \n" rows_affected=0 rows_returned=0 elapsed=411.45µs elapsed_secs=0.00041145
[INFO] [stdout] 2025-09-01T10:32:04.840757Z DEBUG migrate-eventstore: sqlx::query: summary="SELECT version FROM _sqlx_migrations …" db.statement="\n\nSELECT version FROM _sqlx_migrations WHERE success = false ORDER BY version LIMIT 1\n" rows_affected=0 rows_returned=0 elapsed=133.02µs elapsed_secs=0.00013302
[INFO] [stdout] 2025-09-01T10:32:04.841072Z DEBUG migrate-eventstore: sqlx::query: summary="SELECT version, checksum FROM …" db.statement="\n\nSELECT version, checksum FROM _sqlx_migrations ORDER BY version\n" rows_affected=0 rows_returned=0 elapsed=101.91µs elapsed_secs=0.00010191
[INFO] [stdout] 2025-09-01T10:32:04.841592Z DEBUG migrate-eventstore: sqlx::query: summary="CREATE TABLE journal( id …" db.statement="\n\nCREATE TABLE journal(\n    id           TEXT     NOT NULL,\n    sequence_id  INTEGER  NOT NULL,\n    registry_key TEXT     NOT NULL,\n    bytes        BLOB     NOT NULL,\n    created_at   DATETIME NOT NULL DEFAULT CURRENT_TIMESTAMP,\n\n    PRIMARY KEY (id, sequence_id)\n);\n\n" rows_affected=0 rows_returned=0 elapsed=193.72µs elapsed_secs=0.00019372
[INFO] [stdout] 2025-09-01T10:32:04.842021Z DEBUG migrate-eventstore: sqlx::query: summary="INSERT INTO _sqlx_migrations ( …" db.statement="\n\n\n    INSERT INTO _sqlx_migrations ( version, description, success, checksum, execution_time )\n    VALUES ( ?1, ?2, TRUE, ?3, -1 )\n                \n" rows_affected=1 rows_returned=0 elapsed=180.76µs elapsed_secs=0.00018076
[INFO] [stdout] 2025-09-01T10:32:04.842420Z DEBUG migrate-eventstore: sqlx::query: summary="UPDATE _sqlx_migrations SET execution_time …" db.statement="\n\n\n    UPDATE _sqlx_migrations\n    SET execution_time = ?1\n    WHERE version = ?2\n                \n" rows_affected=1 rows_returned=0 elapsed=81.17µs elapsed_secs=8.117e-5
[INFO] [stdout] 2025-09-01T10:32:04.842639Z  INFO nitinol_process::registry: Registered: test-entity-1
[INFO] [stdout] 2025-09-01T10:32:04.842829Z DEBUG handle: program: Accept command. published event: Created
[INFO] [stdout] 2025-09-01T10:32:04.843566Z DEBUG apply:begin-xact-eventstore: sqlx::query: summary="PRAGMA foreign_keys = ON; …" db.statement="\n\nPRAGMA foreign_keys = ON; \n" rows_affected=0 rows_returned=0 elapsed=127.99µs elapsed_secs=0.00012799
[INFO] [stdout] 2025-09-01T10:32:04.844104Z DEBUG apply:write-eventstore: sqlx::query: summary="INSERT INTO journal(id, sequence_id, …" db.statement="\n\n\n            INSERT INTO journal(id, sequence_id, registry_key, bytes, created_at)\n            VALUES ($1, $2, $3, $4, $5)\n        \n" rows_affected=1 rows_returned=0 elapsed=143.52µs elapsed_secs=0.00014352
[INFO] [stdout] 2025-09-01T10:32:04.844419Z DEBUG apply: program: Entity created.
[INFO] [stdout] 2025-09-01T10:32:07.846766Z DEBUG read-eventstore: sqlx::query: summary="SELECT id, sequence_id, registry_key, …" db.statement="\n\n\n            SELECT\n                id,\n                sequence_id,\n                registry_key,\n                bytes,\n                created_at\n            FROM journal\n            WHERE\n                id LIKE $1\n            AND sequence_id BETWEEN $2 AND $3\n        \n" rows_affected=1 rows_returned=1 elapsed=372.72µs elapsed_secs=0.00037272
[INFO] [stdout] 2025-09-01T10:32:07.847071Z DEBUG program: Payload#entity-event {
[INFO] [stdout]     id: "test-entity-1",
[INFO] [stdout]     sequence: 0,
[INFO] [stdout]     bytes: "<9 bytes>",
[INFO] [stdout]     created_at: 2025-09-01 10:32:04.84295598 +00:00:00,
[INFO] [stdout] }
[INFO] [stdout] test main ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 3.02s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests nitinol_sqlite_adaptor
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "7a367a105c1902fd2a4f278bde29cd27c5341c8d9e0f76395a9fdbf1362a7296", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "7a367a105c1902fd2a4f278bde29cd27c5341c8d9e0f76395a9fdbf1362a7296", kill_on_drop: false }`
[INFO] [stdout] 7a367a105c1902fd2a4f278bde29cd27c5341c8d9e0f76395a9fdbf1362a7296