[15:47:06.946] New invocation is queued and will start shortly
[15:47:06.946] Triggered by user:tools-ci-builder@fuchsia-infra.iam.gserviceaccount.com
[15:47:09.064] Starting the invocation (attempt 1)
[15:47:09.097] Popped buildset tag "buildset:commit/gitiles/qemu.googlesource.com/qemu/+/da1034094d375afe9e3d8ec8980550ea0f06f7e0"
[15:47:09.097] Reconstructed gitiles commit from tags
[15:47:09.098] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[15:47:09.098] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[15:47:09.099] Buildbucket request:
{
"requestId": "8933673947911050432",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "qemu.ci.core.x64-debug"
},
"properties": {
"$fuchsia/checkout": {
"gitiles_commit": {
"host": "fuchsia.googlesource.com",
"id": "297705b5b7586ccf07a15d678df8f2f49046ce73",
"project": "integration"
},
"qemu": {
"cas_digest": "b7ad1708c0dcd09d24fe211254c8900f5211427461dd9caad3beef694b29bc7b/334"
}
},
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8933673947911050432",
"job": "fuchsia/qemu.ci.core.x64-debug",
"triggers": [
{
"buildbucket": {
"properties": {
"$fuchsia/checkout": {
"gitiles_commit": {
"host": "fuchsia.googlesource.com",
"id": "297705b5b7586ccf07a15d678df8f2f49046ce73",
"project": "integration"
},
"qemu": {
"cas_digest": "b7ad1708c0dcd09d24fe211254c8900f5211427461dd9caad3beef694b29bc7b/334"
}
},
"qemu": {
"git_repository": "https://qemu.googlesource.com/qemu",
"git_revision": "da1034094d375afe9e3d8ec8980550ea0f06f7e0"
}
},
"tags": [
"buildset:commit/gitiles/qemu.googlesource.com/qemu/+/da1034094d375afe9e3d8ec8980550ea0f06f7e0",
"gitiles_ref:refs/heads/main",
"parent_buildername:qemu-linux-x64",
"user_agent:recipe"
]
},
"id": "5943d43e-cc6b-42d0-89b4-92c19b52b11a",
"title": "qemu-linux-x64/0"
}
]
},
"qemu": {
"git_repository": "https://qemu.googlesource.com/qemu",
"git_revision": "da1034094d375afe9e3d8ec8980550ea0f06f7e0"
}
},
"gitilesCommit": {
"host": "qemu.googlesource.com",
"project": "qemu",
"id": "da1034094d375afe9e3d8ec8980550ea0f06f7e0",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8933673947911050432"
},
{
"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"
}
}
[15:47:09.633] Scheduled build:
{
"id": "8768243884921254385",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "qemu.ci.core.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2023-10-03T15:47:09.172388687Z",
"updateTime": "2023-10-03T15:47:09.172388687Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "qemu.googlesource.com",
"project": "qemu",
"id": "da1034094d375afe9e3d8ec8980550ea0f06f7e0",
"ref": "refs/heads/main"
}
}
}
[15:47:09.633] Task URL: https://cr-buildbucket.appspot.com/build/8768243884921254385
[15:47:09.633] Scheduling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.x64-debug:8933673947911050432:2:0) after 6m41s
[15:47:10.533] Received PubSub notification, asking Buildbucket for the build status
[15:47:10.555] Build status: SCHEDULED
[15:47:12.371] Received PubSub notification, asking Buildbucket for the build status
[15:47:12.389] Build status: STARTED
[15:53:50.693] Handling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.x64-debug:8933673947911050432:2:0)
[15:53:50.770] Build status: STARTED
[15:53:50.770] Scheduling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.x64-debug:8933673947911050432:5:0) after 5m44s
[15:59:35.184] Handling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.x64-debug:8933673947911050432:5:0)
[15:59:35.218] Build status: STARTED
[15:59:35.218] Scheduling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.x64-debug:8933673947911050432:6:0) after 7m52s
[16:07:27.757] Handling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.x64-debug:8933673947911050432:6:0)
[16:07:27.786] Build status: STARTED
[16:07:27.787] Scheduling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.x64-debug:8933673947911050432:7:0) after 5m53s
[16:13:20.833] Handling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.x64-debug:8933673947911050432:7:0)
[16:13:20.960] Build status: STARTED
[16:13:20.960] Scheduling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.x64-debug:8933673947911050432:8:0) after 5m46s
[16:19:07.275] Handling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.x64-debug:8933673947911050432:8:0)
[16:19:07.308] Build status: STARTED
[16:19:07.308] Scheduling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.x64-debug:8933673947911050432:9:0) after 1m47s
[16:20:54.339] Handling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.x64-debug:8933673947911050432:9:0)
[16:20:54.374] Build status: STARTED
[16:20:54.374] Scheduling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.x64-debug:8933673947911050432:10:0) after 8m18s
[16:29:12.391] Handling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.x64-debug:8933673947911050432:10:0)
[16:29:12.413] Build status: STARTED
[16:29:12.413] Scheduling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.x64-debug:8933673947911050432:11:0) after 4m55s
[16:34:07.874] Handling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.x64-debug:8933673947911050432:11:0)
[16:34:07.898] Build status: STARTED
[16:34:07.898] Scheduling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.x64-debug:8933673947911050432:12:0) after 5m33s
[16:39:41.015] Handling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.x64-debug:8933673947911050432:12:0)
[16:39:41.036] Build status: STARTED
[16:39:41.036] Scheduling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.x64-debug:8933673947911050432:13:0) after 4m25s
[16:44:06.054] Handling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.x64-debug:8933673947911050432:13:0)
[16:44:06.087] Build status: STARTED
[16:44:06.087] Scheduling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.x64-debug:8933673947911050432:14:0) after 9m9s
[16:53:15.239] Handling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.x64-debug:8933673947911050432:14:0)
[16:53:15.412] Build status: STARTED
[16:53:15.412] Scheduling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.x64-debug:8933673947911050432:15:0) after 8m16s
[17:01:31.546] Handling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.x64-debug:8933673947911050432:15:0)
[17:01:31.563] Build status: STARTED
[17:01:31.563] Scheduling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.x64-debug:8933673947911050432:16:0) after 9m4s
[17:04:06.953] Received PubSub notification, asking Buildbucket for the build status
[17:04:06.988] Build:
{
"id": "8768243884921254385",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "qemu.ci.core.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2023-10-03T15:47:09.172388687Z",
"startTime": "2023-10-03T15:47:11.358593Z",
"endTime": "2023-10-03T17:04:06.030354311Z",
"updateTime": "2023-10-03T17:04:06.030354311Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "qemu.googlesource.com",
"project": "qemu",
"id": "da1034094d375afe9e3d8ec8980550ea0f06f7e0",
"ref": "refs/heads/main"
}
}
}
[17:04:06.988] Invocation finished in 1h17m0.050570441s with status SUCCEEDED