[13:43:10.692] New invocation is queued and will start shortly
[13:43:10.692] Triggered by user:fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com
[13:43:11.753] Starting the invocation (attempt 1)
[13:43:11.791] Popped buildset tag "buildset:commit/gitiles/llvm.googlesource.com/llvm-project/+/5ec23863320ca12bfabb6dcff1d0425cb614b7a5"
[13:43:11.791] Reconstructed gitiles commit from tags
[13:43:11.791] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[13:43:11.791] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[13:43:11.791] Buildbucket request:
{
"requestId": "8996465443108572352",
"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/+/6706a4720ffc229eb0b606728b26e988a98c6262",
"gitiles_ref:refs/heads/main",
"parent_buildername:clang-ci-trigger",
"user_agent:recipe"
]
},
"id": "f09eed84-e6f1-47e2-a88f-48e9d7ca3d50",
"title": "clang-ci-trigger/0"
},
{
"buildbucket": {
"tags": [
"buildset:commit/gitiles/llvm.googlesource.com/llvm-project/+/5ec23863320ca12bfabb6dcff1d0425cb614b7a5",
"gitiles_ref:refs/heads/main",
"parent_buildername:clang-ci-trigger",
"user_agent:recipe"
]
},
"id": "dda002b5-e289-4073-97da-0053d63dcdec",
"title": "clang-ci-trigger/0"
}
]
}
},
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "5ec23863320ca12bfabb6dcff1d0425cb614b7a5",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8996465443108572352"
},
{
"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:43:12.197] Scheduled build:
{
"id": "8831035381236795121",
"builder": {
"project": "fuchsia",
"bucket": "prod",
"builder": "clang-prod-trigger"
},
"createdBy": "project:fuchsia",
"createTime": "2021-11-09T13:43:11.847754015Z",
"updateTime": "2021-11-09T13:43:11.847754015Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "5ec23863320ca12bfabb6dcff1d0425cb614b7a5",
"ref": "refs/heads/main"
}
}
}
[13:43:12.197] Task URL: https://cr-buildbucket.appspot.com/build/8831035381236795121
[13:43:12.197] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8996465443108572352:2:0) after 3m16s
[13:43:27.622] Received PubSub notification, asking Buildbucket for the build status
[13:43:27.660] Build status: STARTED
[13:46:28.244] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8996465443108572352:2:0)
[13:46:28.291] Build status: STARTED
[13:46:28.291] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8996465443108572352:4:0) after 7m6s
[13:53:34.316] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8996465443108572352:4:0)
[13:53:34.361] Build status: STARTED
[13:53:34.361] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8996465443108572352:5:0) after 6m53s
[14:00:27.573] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8996465443108572352:5:0)
[14:00:27.617] Build status: STARTED
[14:00:27.617] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8996465443108572352:6:0) after 6m37s
[14:07:04.955] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8996465443108572352:6:0)
[14:07:04.988] Build status: STARTED
[14:07:04.988] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8996465443108572352:7:0) after 2m12s
[14:09:17.114] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8996465443108572352:7:0)
[14:09:17.150] Build status: STARTED
[14:09:17.150] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8996465443108572352:8:0) after 1m18s
[14:10:35.364] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8996465443108572352:8:0)
[14:10:35.414] Build status: STARTED
[14:10:35.414] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8996465443108572352:9:0) after 4m30s
[14:15:05.452] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8996465443108572352:9:0)
[14:15:05.489] Build status: STARTED
[14:15:05.489] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8996465443108572352:10:0) after 4m0s
[14:19:05.673] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8996465443108572352:10:0)
[14:19:05.703] Build status: STARTED
[14:19:05.703] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8996465443108572352:11:0) after 3m45s
[14:22:50.673] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8996465443108572352:11:0)
[14:22:50.764] Build status: STARTED
[14:22:50.764] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8996465443108572352:12:0) after 4m12s
[14:27:02.799] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8996465443108572352:12:0)
[14:27:02.848] Build status: STARTED
[14:27:02.848] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8996465443108572352:13:0) after 9m2s
[14:36:04.870] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8996465443108572352:13:0)
[14:36:04.901] Build status: STARTED
[14:36:04.901] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8996465443108572352:14:0) after 4m51s
[14:40:55.906] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8996465443108572352:14:0)
[14:40:55.937] Build status: STARTED
[14:40:55.937] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8996465443108572352:15:0) after 9m56s
[14:50:51.974] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8996465443108572352:15:0)
[14:50:52.004] Build status: STARTED
[14:50:52.004] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8996465443108572352:16:0) after 7m39s
[14:58:31.086] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8996465443108572352:16:0)
[14:58:31.117] Build status: STARTED
[14:58:31.117] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8996465443108572352:17:0) after 2m2s
[15:00:33.374] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8996465443108572352:17:0)
[15:00:33.457] Build status: STARTED
[15:00:33.457] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8996465443108572352:18:0) after 8m18s
[15:08:51.423] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8996465443108572352:18:0)
[15:08:51.453] Build status: STARTED
[15:08:51.453] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8996465443108572352:19:0) after 2m27s
[15:11:18.783] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8996465443108572352:19:0)
[15:11:18.822] Build status: STARTED
[15:11:18.822] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8996465443108572352:20:0) after 8m56s
[15:20:14.847] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8996465443108572352:20:0)
[15:20:14.897] Build status: STARTED
[15:20:14.897] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8996465443108572352:21:0) after 2m43s
[15:22:57.916] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8996465443108572352:21:0)
[15:22:57.974] Build status: STARTED
[15:22:57.974] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8996465443108572352:22:0) after 5m6s
[15:28:04.313] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8996465443108572352:22:0)
[15:28:04.344] Build status: STARTED
[15:28:04.344] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8996465443108572352:23:0) after 7m50s
[15:35:54.369] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8996465443108572352:23:0)
[15:35:54.400] Build status: STARTED
[15:35:54.400] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8996465443108572352:24:0) after 2m18s
[15:38:12.519] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8996465443108572352:24:0)
[15:38:12.568] Build status: STARTED
[15:38:12.568] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8996465443108572352:25:0) after 4m38s
[15:42:50.798] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8996465443108572352:25:0)
[15:42:50.829] Build status: STARTED
[15:42:50.829] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8996465443108572352:26:0) after 2m54s
[15:45:44.985] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8996465443108572352:26:0)
[15:45:45.025] Build status: STARTED
[15:45:45.025] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8996465443108572352:27:0) after 6m58s
[15:52:43.136] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8996465443108572352:27:0)
[15:52:43.164] Build status: STARTED
[15:52:43.164] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8996465443108572352:28:0) after 1m18s
[15:54:01.365] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8996465443108572352:28:0)
[15:54:01.405] Build status: STARTED
[15:54:01.405] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8996465443108572352:29:0) after 1m27s
[15:55:28.585] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8996465443108572352:29:0)
[15:55:28.626] Build status: STARTED
[15:55:28.627] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8996465443108572352:30:0) after 2m32s
[15:58:00.644] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8996465443108572352:30:0)
[15:58:00.675] Build status: STARTED
[15:58:00.675] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8996465443108572352:31:0) after 6m14s
[16:04:14.867] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8996465443108572352:31:0)
[16:04:14.936] Build status: STARTED
[16:04:14.936] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8996465443108572352:32:0) after 8m32s
[16:12:46.957] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8996465443108572352:32:0)
[16:12:46.997] Build status: STARTED
[16:12:46.997] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8996465443108572352:33:0) after 6m58s
[16:19:45.021] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8996465443108572352:33:0)
[16:19:45.049] Build status: STARTED
[16:19:45.049] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8996465443108572352:34:0) after 4m19s
[16:24:04.074] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8996465443108572352:34:0)
[16:24:04.102] Build status: STARTED
[16:24:04.102] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8996465443108572352:35:0) after 2m59s
[16:27:03.169] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8996465443108572352:35:0)
[16:27:03.201] Build status: STARTED
[16:27:03.201] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8996465443108572352:36:0) after 4m45s
[16:31:48.225] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8996465443108572352:36:0)
[16:31:48.759] Build status: STARTED
[16:31:48.759] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8996465443108572352:37:0) after 1m19s
[16:33:07.931] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8996465443108572352:37:0)
[16:33:08.009] Build status: STARTED
[16:33:08.009] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8996465443108572352:38:0) after 6m55s
[16:40:03.040] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8996465443108572352:38:0)
[16:40:03.072] Build status: STARTED
[16:40:03.072] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8996465443108572352:39:0) after 7m26s
[16:47:29.264] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8996465443108572352:39:0)
[16:47:29.299] Build status: STARTED
[16:47:29.299] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8996465443108572352:40:0) after 3m55s
[16:50:12.566] Received PubSub notification, asking Buildbucket for the build status
[16:50:12.603] Build:
{
"id": "8831035381236795121",
"builder": {
"project": "fuchsia",
"bucket": "prod",
"builder": "clang-prod-trigger"
},
"createdBy": "project:fuchsia",
"createTime": "2021-11-09T13:43:11.847754015Z",
"startTime": "2021-11-09T13:43:22.292649Z",
"endTime": "2021-11-09T16:50:12.176233235Z",
"updateTime": "2021-11-09T16:50:12.176233235Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "5ec23863320ca12bfabb6dcff1d0425cb614b7a5",
"ref": "refs/heads/main"
}
}
}
[16:50:12.603] Invocation finished in 3h7m1.925430174s with status SUCCEEDED