[08:47:03.025] New invocation is queued and will start shortly
[08:47:03.025] Triggered by user:qemu-ci-builder@fuchsia-service-accounts.iam.gserviceaccount.com
[08:47:04.220] Starting the invocation (attempt 1)
[08:47:04.357] Popped buildset tag "buildset:commit/gitiles/qemu.googlesource.com/qemu/+/db7aa99ef894e88fc5eedf02ca2579b8c344b2ec"
[08:47:04.357] Reconstructed gitiles commit from tags
[08:47:04.357] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[08:47:04.357] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[08:47:04.358] Buildbucket request:
{
"requestId": "8888130102034244560",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "qemu.ci.core.x64-debug"
},
"properties": {
"$fuchsia/checkout": {
"gitiles_commit": {
"host": "fuchsia.googlesource.com",
"id": "c86e6ac868dfa19b21abc6a5ed7e1ef6b99057bb",
"project": "integration",
"ref": "refs/heads/releases/canary"
},
"qemu": {
"cas_digest": "15ada488539a69299afcf5202c99bc1bd7af880fd7494a55a387aa010aca5d2b/334",
"cas_instance": "projects/chromium-swarm/instances/default_instance"
}
},
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8888130102034244560",
"job": "fuchsia/qemu.ci.core.x64-debug",
"triggers": [
{
"buildbucket": {
"properties": {
"$fuchsia/checkout": {
"gitiles_commit": {
"host": "fuchsia.googlesource.com",
"id": "c86e6ac868dfa19b21abc6a5ed7e1ef6b99057bb",
"project": "integration",
"ref": "refs/heads/releases/canary"
},
"qemu": {
"cas_digest": "15ada488539a69299afcf5202c99bc1bd7af880fd7494a55a387aa010aca5d2b/334",
"cas_instance": "projects/chromium-swarm/instances/default_instance"
}
},
"qemu": {
"git_repository": "https://qemu.googlesource.com/qemu",
"git_revision": "db7aa99ef894e88fc5eedf02ca2579b8c344b2ec"
}
},
"tags": [
"buildset:commit/gitiles/qemu.googlesource.com/qemu/+/db7aa99ef894e88fc5eedf02ca2579b8c344b2ec",
"gitiles_ref:refs/heads/main",
"parent_buildername:qemu-linux-x64",
"user_agent:recipe"
]
},
"id": "b42d43c9-b81d-4d0f-9017-7fd0260e4579",
"title": "qemu-linux-x64/0"
}
]
},
"qemu": {
"git_repository": "https://qemu.googlesource.com/qemu",
"git_revision": "db7aa99ef894e88fc5eedf02ca2579b8c344b2ec"
}
},
"gitilesCommit": {
"host": "qemu.googlesource.com",
"project": "qemu",
"id": "db7aa99ef894e88fc5eedf02ca2579b8c344b2ec",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8888130102034244560"
},
{
"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"
}
}
[08:47:04.688] Scheduled build:
{
"id": "8722700039924827425",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "qemu.ci.core.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2025-02-17T08:47:04.415689980Z",
"updateTime": "2025-02-17T08:47:04.415689980Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "qemu.googlesource.com",
"project": "qemu",
"id": "db7aa99ef894e88fc5eedf02ca2579b8c344b2ec",
"ref": "refs/heads/main"
}
}
}
[08:47:04.688] Task URL: https://cr-buildbucket.appspot.com/build/8722700039924827425
[08:47:04.688] Scheduling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.x64-debug:8888130102034244560:2:0) after 1m4s
[08:47:05.667] Received PubSub notification, asking Buildbucket for the build status
[08:47:05.692] Build status: SCHEDULED
[08:47:10.768] Received PubSub notification, asking Buildbucket for the build status
[08:47:10.792] Build status: STARTED
[08:48:09.015] Handling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.x64-debug:8888130102034244560:2:0)
[08:48:09.041] Build status: STARTED
[08:48:09.041] Scheduling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.x64-debug:8888130102034244560:5:0) after 8m36s
[08:56:45.163] Handling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.x64-debug:8888130102034244560:5:0)
[08:56:45.186] Build status: STARTED
[08:56:45.186] Scheduling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.x64-debug:8888130102034244560:6:0) after 7m11s
[09:03:56.393] Handling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.x64-debug:8888130102034244560:6:0)
[09:03:56.413] Build status: STARTED
[09:03:56.413] Scheduling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.x64-debug:8888130102034244560:7:0) after 8m24s
[09:12:20.545] Handling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.x64-debug:8888130102034244560:7:0)
[09:12:20.594] Build status: STARTED
[09:12:20.594] Scheduling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.x64-debug:8888130102034244560:8:0) after 2m50s
[09:15:10.637] Handling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.x64-debug:8888130102034244560:8:0)
[09:15:10.717] Build status: STARTED
[09:15:10.717] Scheduling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.x64-debug:8888130102034244560:9:0) after 5m55s
[09:21:05.740] Handling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.x64-debug:8888130102034244560:9:0)
[09:21:05.761] Build status: STARTED
[09:21:05.761] Scheduling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.x64-debug:8888130102034244560:10:0) after 9m31s
[09:30:36.787] Handling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.x64-debug:8888130102034244560:10:0)
[09:30:36.809] Build status: STARTED
[09:30:36.809] Scheduling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.x64-debug:8888130102034244560:11:0) after 6m35s
[09:37:12.271] Handling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.x64-debug:8888130102034244560:11:0)
[09:37:12.285] Build status: STARTED
[09:37:12.285] Scheduling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.x64-debug:8888130102034244560:12:0) after 4m48s
[09:42:00.312] Handling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.x64-debug:8888130102034244560:12:0)
[09:42:00.335] Build status: STARTED
[09:42:00.336] Scheduling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.x64-debug:8888130102034244560:13:0) after 8m23s
[09:50:23.894] Handling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.x64-debug:8888130102034244560:13:0)
[09:50:23.907] Build status: STARTED
[09:50:23.907] Scheduling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.x64-debug:8888130102034244560:14:0) after 5m36s
[09:56:00.223] Handling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.x64-debug:8888130102034244560:14:0)
[09:56:00.242] Build status: STARTED
[09:56:00.242] Scheduling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.x64-debug:8888130102034244560:15:0) after 7m52s
[09:59:50.260] Received PubSub notification, asking Buildbucket for the build status
[09:59:50.439] Build:
{
"id": "8722700039924827425",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "qemu.ci.core.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2025-02-17T08:47:04.415689980Z",
"startTime": "2025-02-17T08:47:10.539214851Z",
"endTime": "2025-02-17T09:59:48.882258640Z",
"updateTime": "2025-02-17T09:59:48.882258640Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "qemu.googlesource.com",
"project": "qemu",
"id": "db7aa99ef894e88fc5eedf02ca2579b8c344b2ec",
"ref": "refs/heads/main"
}
}
}
[09:59:50.439] Invocation finished in 1h12m47.418707588s with status SUCCEEDED