[12:45:45.584] New invocation is queued and will start shortly
[12:45:45.584] Triggered by user:tools-ci-builder@fuchsia-infra.iam.gserviceaccount.com
[12:45:46.720] Starting the invocation (attempt 1)
[12:45:46.745] Popped buildset tag "buildset:commit/gitiles/qemu.googlesource.com/qemu/+/096ae430a7b5a704af4cd94dca7200d6cb069991"
[12:45:46.745] Reconstructed gitiles commit from tags
[12:45:46.745] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[12:45:46.745] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[12:45:46.745] Buildbucket request:
{
"requestId": "8917830888730690512",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "qemu.ci.core.x64-debug"
},
"properties": {
"$fuchsia/checkout": {
"gitiles_commit": {
"host": "fuchsia.googlesource.com",
"id": "367b710c1676cba2c207b8679cf0d6e1554f9d1b",
"project": "integration"
},
"qemu": {
"cas_digest": "f7e267a457e5410bb8b8f003fff4611a511679c6efeb2a3e255af108d2d1f65b/334",
"cas_instance": "projects/chromium-swarm/instances/default_instance"
}
},
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8917830888730690512",
"job": "fuchsia/qemu.ci.core.x64-debug",
"triggers": [
{
"buildbucket": {
"properties": {
"$fuchsia/checkout": {
"gitiles_commit": {
"host": "fuchsia.googlesource.com",
"id": "367b710c1676cba2c207b8679cf0d6e1554f9d1b",
"project": "integration"
},
"qemu": {
"cas_digest": "f7e267a457e5410bb8b8f003fff4611a511679c6efeb2a3e255af108d2d1f65b/334",
"cas_instance": "projects/chromium-swarm/instances/default_instance"
}
},
"qemu": {
"git_repository": "https://qemu.googlesource.com/qemu",
"git_revision": "096ae430a7b5a704af4cd94dca7200d6cb069991"
}
},
"tags": [
"buildset:commit/gitiles/qemu.googlesource.com/qemu/+/096ae430a7b5a704af4cd94dca7200d6cb069991",
"gitiles_ref:refs/heads/main",
"parent_buildername:qemu-linux-x64",
"user_agent:recipe"
]
},
"id": "be5bb1ba-7227-4951-ab5d-407e74f85911",
"title": "qemu-linux-x64/0"
}
]
},
"qemu": {
"git_repository": "https://qemu.googlesource.com/qemu",
"git_revision": "096ae430a7b5a704af4cd94dca7200d6cb069991"
}
},
"gitilesCommit": {
"host": "qemu.googlesource.com",
"project": "qemu",
"id": "096ae430a7b5a704af4cd94dca7200d6cb069991",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8917830888730690512"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/qemu.ci.core.x64-debug"
},
{
"key": "user_agent",
"value": "luci-scheduler"
},
{
"key": "parent_buildername",
"value": "qemu-linux-x64"
},
{
"key": "user_agent",
"value": "recipe"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[12:45:47.257] Scheduled build:
{
"id": "8752400826782194769",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "qemu.ci.core.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2024-03-26T12:45:46.812589227Z",
"updateTime": "2024-03-26T12:45:46.812589227Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "qemu.googlesource.com",
"project": "qemu",
"id": "096ae430a7b5a704af4cd94dca7200d6cb069991",
"ref": "refs/heads/main"
}
},
"canary": true
}
[12:45:47.257] Task URL: https://cr-buildbucket.appspot.com/build/8752400826782194769
[12:45:47.257] Scheduling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.x64-debug:8917830888730690512:2:0) after 5m18s
[12:45:48.207] Received PubSub notification, asking Buildbucket for the build status
[12:45:48.244] Build status: SCHEDULED
[12:45:49.193] Received PubSub notification, asking Buildbucket for the build status
[12:45:49.246] Build status: STARTED
[12:51:05.397] Handling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.x64-debug:8917830888730690512:2:0)
[12:51:05.464] Build status: STARTED
[12:51:05.464] Scheduling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.x64-debug:8917830888730690512:5:0) after 9m50s
[13:00:55.742] Handling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.x64-debug:8917830888730690512:5:0)
[13:00:55.767] Build status: STARTED
[13:00:55.768] Scheduling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.x64-debug:8917830888730690512:6:0) after 9m41s
[13:10:36.785] Handling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.x64-debug:8917830888730690512:6:0)
[13:10:36.840] Build status: STARTED
[13:10:36.840] Scheduling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.x64-debug:8917830888730690512:7:0) after 1m49s
[13:12:25.900] Handling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.x64-debug:8917830888730690512:7:0)
[13:12:25.923] Build status: STARTED
[13:12:25.923] Scheduling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.x64-debug:8917830888730690512:8:0) after 2m32s
[13:14:58.040] Handling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.x64-debug:8917830888730690512:8:0)
[13:14:58.063] Build status: STARTED
[13:14:58.063] Scheduling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.x64-debug:8917830888730690512:9:0) after 2m40s
[13:17:38.079] Handling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.x64-debug:8917830888730690512:9:0)
[13:17:38.102] Build status: STARTED
[13:17:38.102] Scheduling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.x64-debug:8917830888730690512:10:0) after 5m20s
[13:22:58.117] Handling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.x64-debug:8917830888730690512:10:0)
[13:22:58.137] Build status: STARTED
[13:22:58.137] Scheduling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.x64-debug:8917830888730690512:11:0) after 6m21s
[13:29:19.506] Handling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.x64-debug:8917830888730690512:11:0)
[13:29:19.545] Build status: STARTED
[13:29:19.545] Scheduling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.x64-debug:8917830888730690512:12:0) after 5m5s
[13:34:24.548] Handling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.x64-debug:8917830888730690512:12:0)
[13:34:24.579] Build status: STARTED
[13:34:24.579] Scheduling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.x64-debug:8917830888730690512:13:0) after 2m36s
[13:37:00.694] Handling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.x64-debug:8917830888730690512:13:0)
[13:37:00.731] Build status: STARTED
[13:37:00.731] Scheduling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.x64-debug:8917830888730690512:14:0) after 5m8s
[13:42:08.782] Handling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.x64-debug:8917830888730690512:14:0)
[13:42:08.894] Build status: STARTED
[13:42:08.894] Scheduling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.x64-debug:8917830888730690512:15:0) after 9m36s
[13:51:45.307] Handling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.x64-debug:8917830888730690512:15:0)
[13:51:45.339] Build status: STARTED
[13:51:45.339] Scheduling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.x64-debug:8917830888730690512:16:0) after 5m55s
[13:57:40.359] Handling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.x64-debug:8917830888730690512:16:0)
[13:57:40.382] Build status: STARTED
[13:57:40.382] Scheduling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.x64-debug:8917830888730690512:17:0) after 6m54s
[14:03:37.398] Received PubSub notification, asking Buildbucket for the build status
[14:03:37.434] Build:
{
"id": "8752400826782194769",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "qemu.ci.core.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2024-03-26T12:45:46.812589227Z",
"startTime": "2024-03-26T12:45:47.852863Z",
"endTime": "2024-03-26T14:03:36.633163Z",
"updateTime": "2024-03-26T14:03:36.633163Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "qemu.googlesource.com",
"project": "qemu",
"id": "096ae430a7b5a704af4cd94dca7200d6cb069991",
"ref": "refs/heads/main"
}
},
"canary": true
}
[14:03:37.434] Invocation finished in 1h17m51.862835952s with status SUCCEEDED