[13:26:10.222] New invocation is queued and will start shortly
[13:26:10.222] Triggered by user:fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com
[13:26:11.309] Starting the invocation (attempt 1)
[13:26:11.353] Popped buildset tag "buildset:commit/gitiles/llvm.googlesource.com/llvm-project/+/6c48f6aafe69017065ab424dde11909d9cd5cb44"
[13:26:11.353] Reconstructed gitiles commit from tags
[13:26:11.353] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[13:26:11.353] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[13:26:11.353] Buildbucket request:
{
"requestId": "8995832334386091888",
"builder": {
"project": "fuchsia",
"bucket": "prod",
"builder": "clang-prod-trigger"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"triggers": [
{
"buildbucket": {
"tags": [
"buildset:commit/gitiles/llvm.googlesource.com/llvm-project/+/11462a82c5fbef879ae75add682274b652ae9aa9",
"gitiles_ref:refs/heads/main",
"parent_buildername:clang-ci-trigger",
"user_agent:recipe"
]
},
"id": "c5a23183-87a0-45fa-900c-ed81d9d797e7",
"title": "clang-ci-trigger/0"
},
{
"buildbucket": {
"tags": [
"buildset:commit/gitiles/llvm.googlesource.com/llvm-project/+/6c48f6aafe69017065ab424dde11909d9cd5cb44",
"gitiles_ref:refs/heads/main",
"parent_buildername:clang-ci-trigger",
"user_agent:recipe"
]
},
"id": "bf4703aa-2713-410d-8243-71f96f667efe",
"title": "clang-ci-trigger/0"
}
]
}
},
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "6c48f6aafe69017065ab424dde11909d9cd5cb44",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8995832334386091888"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/clang-prod-trigger"
},
{
"key": "user_agent",
"value": "luci-scheduler"
},
{
"key": "parent_buildername",
"value": "clang-ci-trigger"
},
{
"key": "user_agent",
"value": "recipe"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[13:26:11.779] Scheduled build:
{
"id": "8830402272418643905",
"builder": {
"project": "fuchsia",
"bucket": "prod",
"builder": "clang-prod-trigger"
},
"createdBy": "project:fuchsia",
"createTime": "2021-11-16T13:26:11.466357423Z",
"updateTime": "2021-11-16T13:26:11.466357423Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "6c48f6aafe69017065ab424dde11909d9cd5cb44",
"ref": "refs/heads/main"
}
}
}
[13:26:11.779] Task URL: https://cr-buildbucket.appspot.com/build/8830402272418643905
[13:26:11.779] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8995832334386091888:2:0) after 2m26s
[13:26:22.954] Received PubSub notification, asking Buildbucket for the build status
[13:26:23.026] Build status: STARTED
[13:28:37.838] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8995832334386091888:2:0)
[13:28:37.872] Build status: STARTED
[13:28:37.872] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8995832334386091888:4:0) after 9m17s
[13:37:54.996] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8995832334386091888:4:0)
[13:37:55.028] Build status: STARTED
[13:37:55.028] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8995832334386091888:5:0) after 3m4s
[13:40:59.046] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8995832334386091888:5:0)
[13:40:59.076] Build status: STARTED
[13:40:59.076] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8995832334386091888:6:0) after 1m6s
[13:42:05.112] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8995832334386091888:6:0)
[13:42:05.146] Build status: STARTED
[13:42:05.146] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8995832334386091888:7:0) after 6m34s
[13:48:39.185] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8995832334386091888:7:0)
[13:48:39.214] Build status: STARTED
[13:48:39.214] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8995832334386091888:8:0) after 4m30s
[13:53:09.237] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8995832334386091888:8:0)
[13:53:09.268] Build status: STARTED
[13:53:09.268] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8995832334386091888:9:0) after 3m1s
[13:56:10.285] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8995832334386091888:9:0)
[13:56:10.317] Build status: STARTED
[13:56:10.317] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8995832334386091888:10:0) after 7m38s
[14:03:48.335] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8995832334386091888:10:0)
[14:03:48.377] Build status: STARTED
[14:03:48.377] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8995832334386091888:11:0) after 4m22s
[14:08:10.400] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8995832334386091888:11:0)
[14:08:10.466] Build status: STARTED
[14:08:10.466] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8995832334386091888:12:0) after 5m41s
[14:13:51.626] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8995832334386091888:12:0)
[14:13:51.656] Build status: STARTED
[14:13:51.656] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8995832334386091888:13:0) after 3m5s
[14:16:56.849] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8995832334386091888:13:0)
[14:16:56.878] Build status: STARTED
[14:16:56.878] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8995832334386091888:14:0) after 1m33s
[14:18:29.899] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8995832334386091888:14:0)
[14:18:29.933] Build status: STARTED
[14:18:29.934] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8995832334386091888:15:0) after 8m22s
[14:26:51.953] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8995832334386091888:15:0)
[14:26:51.982] Build status: STARTED
[14:26:51.982] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8995832334386091888:16:0) after 1m28s
[14:28:20.003] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8995832334386091888:16:0)
[14:28:20.032] Build status: STARTED
[14:28:20.032] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8995832334386091888:17:0) after 4m14s
[14:32:34.162] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8995832334386091888:17:0)
[14:32:34.216] Build status: STARTED
[14:32:34.216] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8995832334386091888:18:0) after 3m51s
[14:36:25.267] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8995832334386091888:18:0)
[14:36:25.363] Build status: STARTED
[14:36:25.363] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8995832334386091888:19:0) after 3m35s
[14:40:00.747] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8995832334386091888:19:0)
[14:40:00.844] Build status: STARTED
[14:40:00.844] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8995832334386091888:20:0) after 8m45s
[14:48:45.974] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8995832334386091888:20:0)
[14:48:46.006] Build status: STARTED
[14:48:46.006] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8995832334386091888:21:0) after 4m18s
[14:53:04.026] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8995832334386091888:21:0)
[14:53:04.060] Build status: STARTED
[14:53:04.060] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8995832334386091888:22:0) after 7m45s
[15:00:49.145] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8995832334386091888:22:0)
[15:00:49.176] Build status: STARTED
[15:00:49.176] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8995832334386091888:23:0) after 8m11s
[15:09:00.195] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8995832334386091888:23:0)
[15:09:00.231] Build status: STARTED
[15:09:00.231] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8995832334386091888:24:0) after 3m23s
[15:12:23.250] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8995832334386091888:24:0)
[15:12:23.304] Build status: STARTED
[15:12:23.304] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8995832334386091888:25:0) after 9m16s
[15:21:39.419] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8995832334386091888:25:0)
[15:21:39.461] Build status: STARTED
[15:21:39.461] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8995832334386091888:26:0) after 8m1s
[15:29:40.566] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8995832334386091888:26:0)
[15:29:40.595] Build status: STARTED
[15:29:40.595] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8995832334386091888:27:0) after 9m49s
[15:39:29.614] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8995832334386091888:27:0)
[15:39:29.642] Build status: STARTED
[15:39:29.642] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8995832334386091888:28:0) after 1m49s
[15:41:18.674] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8995832334386091888:28:0)
[15:41:18.709] Build status: STARTED
[15:41:18.709] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8995832334386091888:29:0) after 3m24s
[15:44:42.775] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8995832334386091888:29:0)
[15:44:42.813] Build status: STARTED
[15:44:42.813] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8995832334386091888:30:0) after 8m13s
[15:52:56.191] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8995832334386091888:30:0)
[15:52:56.277] Build status: STARTED
[15:52:56.277] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8995832334386091888:31:0) after 9m59s
[16:02:55.957] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8995832334386091888:31:0)
[16:02:56.085] Build status: STARTED
[16:02:56.085] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8995832334386091888:32:0) after 4m54s
[16:07:50.166] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8995832334386091888:32:0)
[16:07:50.198] Build status: STARTED
[16:07:50.198] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8995832334386091888:33:0) after 1m55s
[16:09:45.275] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8995832334386091888:33:0)
[16:09:45.313] Build status: STARTED
[16:09:45.313] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8995832334386091888:34:0) after 1m25s
[16:11:10.325] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8995832334386091888:34:0)
[16:11:10.377] Build status: STARTED
[16:11:10.377] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8995832334386091888:35:0) after 8m40s
[16:19:50.479] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8995832334386091888:35:0)
[16:19:50.509] Build status: STARTED
[16:19:50.509] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8995832334386091888:36:0) after 3m4s
[16:22:54.527] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8995832334386091888:36:0)
[16:22:54.561] Build status: STARTED
[16:22:54.561] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8995832334386091888:37:0) after 2m1s
[16:24:55.645] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8995832334386091888:37:0)
[16:24:55.692] Build status: STARTED
[16:24:55.692] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8995832334386091888:38:0) after 4m45s
[16:29:40.929] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8995832334386091888:38:0)
[16:29:40.962] Build status: STARTED
[16:29:40.962] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8995832334386091888:39:0) after 3m21s
[16:33:01.991] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8995832334386091888:39:0)
[16:33:02.027] Build status: STARTED
[16:33:02.027] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8995832334386091888:40:0) after 4m55s
[16:37:57.054] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8995832334386091888:40:0)
[16:37:57.086] Build status: STARTED
[16:37:57.086] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8995832334386091888:41:0) after 7m1s
[16:44:58.108] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8995832334386091888:41:0)
[16:44:58.139] Build:
{
"id": "8830402272418643905",
"builder": {
"project": "fuchsia",
"bucket": "prod",
"builder": "clang-prod-trigger"
},
"createdBy": "project:fuchsia",
"createTime": "2021-11-16T13:26:11.466357423Z",
"startTime": "2021-11-16T13:26:22.277739Z",
"endTime": "2021-11-16T16:38:00.766398199Z",
"updateTime": "2021-11-16T16:38:00.766398199Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "6c48f6aafe69017065ab424dde11909d9cd5cb44",
"ref": "refs/heads/main"
}
}
}
[16:44:58.139] Invocation finished in 3h18m47.931995919s with status SUCCEEDED