[17:48:16.241] New invocation is queued and will start shortly
[17:48:16.241] Triggered by user:tools-ci-builder@fuchsia-infra.iam.gserviceaccount.com
[17:48:17.327] Starting the invocation (attempt 1)
[17:48:17.354] Popped buildset tag "buildset:commit/gitiles/qemu.googlesource.com/qemu/+/786fd793b81410fb2a28914315e2f05d2ff6733b"
[17:48:17.354] Reconstructed gitiles commit from tags
[17:48:17.354] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:48:17.354] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:48:17.354] Buildbucket request:
{
"requestId": "8917087080653974400",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "qemu.ci.core.arm64-debug"
},
"properties": {
"$fuchsia/checkout": {
"gitiles_commit": {
"host": "fuchsia.googlesource.com",
"id": "8074bb03584e3084cc137d93a2993295073b571f",
"project": "integration"
},
"qemu": {
"cas_digest": "c9393390f8439a2fc4a53f8ae1cc9398bc2b4ea806adfd09e19c76c49cbc092f/334",
"cas_instance": "projects/chromium-swarm/instances/default_instance"
}
},
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8917087080653974400",
"job": "fuchsia/qemu.ci.core.arm64-debug",
"triggers": [
{
"buildbucket": {
"properties": {
"$fuchsia/checkout": {
"gitiles_commit": {
"host": "fuchsia.googlesource.com",
"id": "8074bb03584e3084cc137d93a2993295073b571f",
"project": "integration"
},
"qemu": {
"cas_digest": "c9393390f8439a2fc4a53f8ae1cc9398bc2b4ea806adfd09e19c76c49cbc092f/334",
"cas_instance": "projects/chromium-swarm/instances/default_instance"
}
},
"qemu": {
"git_repository": "https://qemu.googlesource.com/qemu",
"git_revision": "786fd793b81410fb2a28914315e2f05d2ff6733b"
}
},
"tags": [
"buildset:commit/gitiles/qemu.googlesource.com/qemu/+/786fd793b81410fb2a28914315e2f05d2ff6733b",
"gitiles_ref:refs/heads/main",
"parent_buildername:qemu-linux-arm64",
"user_agent:recipe"
]
},
"id": "04f7dba1-f842-4793-9571-e251b2fcd6e5",
"title": "qemu-linux-arm64/0"
}
]
},
"qemu": {
"git_repository": "https://qemu.googlesource.com/qemu",
"git_revision": "786fd793b81410fb2a28914315e2f05d2ff6733b"
}
},
"gitilesCommit": {
"host": "qemu.googlesource.com",
"project": "qemu",
"id": "786fd793b81410fb2a28914315e2f05d2ff6733b",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8917087080653974400"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/qemu.ci.core.arm64-debug"
},
{
"key": "user_agent",
"value": "luci-scheduler"
},
{
"key": "parent_buildername",
"value": "qemu-linux-arm64"
},
{
"key": "user_agent",
"value": "recipe"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[17:48:17.774] Scheduled build:
{
"id": "8751657018767395425",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "qemu.ci.core.arm64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2024-04-03T17:48:17.413837977Z",
"updateTime": "2024-04-03T17:48:17.413837977Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "qemu.googlesource.com",
"project": "qemu",
"id": "786fd793b81410fb2a28914315e2f05d2ff6733b",
"ref": "refs/heads/main"
}
}
}
[17:48:17.775] Task URL: https://cr-buildbucket.appspot.com/build/8751657018767395425
[17:48:17.775] Scheduling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.arm64-debug:8917087080653974400:2:0) after 2m57s
[17:48:17.928] Received PubSub notification, asking Buildbucket for the build status
[17:48:17.986] Build status: SCHEDULED
[17:51:14.833] Handling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.arm64-debug:8917087080653974400:2:0)
[17:51:14.851] Build status: SCHEDULED
[17:51:14.851] Scheduling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.arm64-debug:8917087080653974400:4:0) after 2m41s
[17:51:15.957] Received PubSub notification, asking Buildbucket for the build status
[17:51:16.058] Build status: STARTED
[17:53:55.907] Handling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.arm64-debug:8917087080653974400:4:0)
[17:53:55.942] Build status: STARTED
[17:53:55.942] Scheduling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.arm64-debug:8917087080653974400:6:0) after 3m35s
[17:57:31.027] Handling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.arm64-debug:8917087080653974400:6:0)
[17:57:31.068] Build status: STARTED
[17:57:31.068] Scheduling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.arm64-debug:8917087080653974400:7:0) after 2m58s
[18:00:29.218] Handling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.arm64-debug:8917087080653974400:7:0)
[18:00:29.282] Build status: STARTED
[18:00:29.282] Scheduling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.arm64-debug:8917087080653974400:8:0) after 6m41s
[18:07:10.320] Handling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.arm64-debug:8917087080653974400:8:0)
[18:07:10.338] Build status: STARTED
[18:07:10.338] Scheduling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.arm64-debug:8917087080653974400:9:0) after 6m18s
[18:13:28.410] Handling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.arm64-debug:8917087080653974400:9:0)
[18:13:28.447] Build status: STARTED
[18:13:28.447] Scheduling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.arm64-debug:8917087080653974400:10:0) after 5m0s
[18:18:28.472] Handling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.arm64-debug:8917087080653974400:10:0)
[18:18:28.494] Build status: STARTED
[18:18:28.494] Scheduling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.arm64-debug:8917087080653974400:11:0) after 6m26s
[18:24:54.511] Handling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.arm64-debug:8917087080653974400:11:0)
[18:24:54.601] Build status: STARTED
[18:24:54.601] Scheduling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.arm64-debug:8917087080653974400:12:0) after 2m33s
[18:27:27.615] Handling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.arm64-debug:8917087080653974400:12:0)
[18:27:27.635] Build status: STARTED
[18:27:27.635] Scheduling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.arm64-debug:8917087080653974400:13:0) after 6m59s
[18:34:26.652] Handling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.arm64-debug:8917087080653974400:13:0)
[18:34:26.674] Build status: STARTED
[18:34:26.674] Scheduling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.arm64-debug:8917087080653974400:14:0) after 6m58s
[18:41:24.712] Handling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.arm64-debug:8917087080653974400:14:0)
[18:41:24.733] Build status: STARTED
[18:41:24.733] Scheduling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.arm64-debug:8917087080653974400:15:0) after 3m20s
[18:44:44.751] Handling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.arm64-debug:8917087080653974400:15:0)
[18:44:44.774] Build status: STARTED
[18:44:44.774] Scheduling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.arm64-debug:8917087080653974400:16:0) after 3m34s
[18:48:18.841] Handling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.arm64-debug:8917087080653974400:16:0)
[18:48:18.874] Build status: STARTED
[18:48:18.874] Scheduling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.arm64-debug:8917087080653974400:17:0) after 2m7s
[18:50:25.897] Handling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.arm64-debug:8917087080653974400:17:0)
[18:50:25.919] Build status: STARTED
[18:50:25.919] Scheduling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.arm64-debug:8917087080653974400:18:0) after 6m26s
[18:56:51.970] Handling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.arm64-debug:8917087080653974400:18:0)
[18:56:51.992] Build status: STARTED
[18:56:51.992] Scheduling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.arm64-debug:8917087080653974400:19:0) after 1m25s
[18:58:17.086] Handling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.arm64-debug:8917087080653974400:19:0)
[18:58:17.116] Build status: STARTED
[18:58:17.116] Scheduling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.arm64-debug:8917087080653974400:20:0) after 1m52s
[19:00:09.171] Handling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.arm64-debug:8917087080653974400:20:0)
[19:00:09.274] Build status: STARTED
[19:00:09.274] Scheduling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.arm64-debug:8917087080653974400:21:0) after 1m50s
[19:01:59.290] Handling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.arm64-debug:8917087080653974400:21:0)
[19:01:59.310] Build status: STARTED
[19:01:59.310] Scheduling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.arm64-debug:8917087080653974400:22:0) after 7m58s
[19:09:57.383] Handling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.arm64-debug:8917087080653974400:22:0)
[19:09:57.423] Build status: STARTED
[19:09:57.423] Scheduling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.arm64-debug:8917087080653974400:23:0) after 4m52s
[19:14:49.530] Handling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.arm64-debug:8917087080653974400:23:0)
[19:14:49.553] Build status: STARTED
[19:14:49.553] Scheduling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.arm64-debug:8917087080653974400:24:0) after 5m23s
[19:20:12.605] Handling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.arm64-debug:8917087080653974400:24:0)
[19:20:12.625] Build status: STARTED
[19:20:12.625] Scheduling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.arm64-debug:8917087080653974400:25:0) after 4m24s
[19:24:36.668] Handling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.arm64-debug:8917087080653974400:25:0)
[19:24:36.689] Build status: STARTED
[19:24:36.689] Scheduling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.arm64-debug:8917087080653974400:26:0) after 8m10s
[19:32:46.708] Handling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.arm64-debug:8917087080653974400:26:0)
[19:32:46.724] Build status: STARTED
[19:32:46.724] Scheduling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.arm64-debug:8917087080653974400:27:0) after 1m53s
[19:34:39.760] Handling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.arm64-debug:8917087080653974400:27:0)
[19:34:39.781] Build status: STARTED
[19:34:39.781] Scheduling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.arm64-debug:8917087080653974400:28:0) after 4m5s
[19:38:44.798] Handling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.arm64-debug:8917087080653974400:28:0)
[19:38:44.865] Build status: STARTED
[19:38:44.865] Scheduling timer "check-buildbucket-build-status" (fuchsia/qemu.ci.core.arm64-debug:8917087080653974400:29:0) after 4m54s
[19:38:55.680] Received PubSub notification, asking Buildbucket for the build status
[19:38:55.838] Build:
{
"id": "8751657018767395425",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "qemu.ci.core.arm64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2024-04-03T17:48:17.413837977Z",
"startTime": "2024-04-03T17:51:15.476317572Z",
"endTime": "2024-04-03T19:38:55.206341092Z",
"updateTime": "2024-04-03T19:38:55.206341092Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "qemu.googlesource.com",
"project": "qemu",
"id": "786fd793b81410fb2a28914315e2f05d2ff6733b",
"ref": "refs/heads/main"
}
}
}
[19:38:55.838] Invocation finished in 1h50m39.608232998s with status SUCCEEDED