[07:15:02.576] New invocation is queued and will start shortly
[07:15:02.576] Triggered by user:fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com
[07:15:03.662] Starting the invocation (attempt 1)
[07:15:03.696] Popped buildset tag "buildset:commit/gitiles/llvm.googlesource.com/llvm-project/+/66b650f3da70e45ca40ffa281df79e044c28fe04"
[07:15:03.696] Reconstructed gitiles commit from tags
[07:15:03.696] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[07:15:03.696] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[07:15:03.697] Buildbucket request:
{
"requestId": "8998211204965673168",
"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/+/edff0070a126d9a27263958dbb22133f4ed6526e",
"gitiles_ref:refs/heads/main",
"parent_buildername:clang-ci-trigger",
"user_agent:recipe"
]
},
"id": "708a91b4-6e76-4429-9b66-faa4dbd5efcd",
"title": "clang-ci-trigger/0"
},
{
"buildbucket": {
"tags": [
"buildset:commit/gitiles/llvm.googlesource.com/llvm-project/+/66b650f3da70e45ca40ffa281df79e044c28fe04",
"gitiles_ref:refs/heads/main",
"parent_buildername:clang-ci-trigger",
"user_agent:recipe"
]
},
"id": "05bfc429-5801-4627-8bac-34bbfa088726",
"title": "clang-ci-trigger/0"
}
]
}
},
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "66b650f3da70e45ca40ffa281df79e044c28fe04",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8998211204965673168"
},
{
"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"
}
}
[07:15:21.468] Scheduled build:
{
"id": "8832781142704108001",
"builder": {
"project": "fuchsia",
"bucket": "prod",
"builder": "clang-prod-trigger"
},
"createdBy": "project:fuchsia",
"createTime": "2021-10-21T07:15:03.973111842Z",
"updateTime": "2021-10-21T07:15:21.182182268Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "66b650f3da70e45ca40ffa281df79e044c28fe04",
"ref": "refs/heads/main"
}
}
}
[07:15:21.468] Task URL: https://cr-buildbucket.appspot.com/build/8832781142704108001
[07:15:21.468] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8998211204965673168:2:0) after 7m54s
[07:16:30.074] Received PubSub notification, asking Buildbucket for the build status
[07:16:30.107] Build status: STARTED
[07:23:15.486] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8998211204965673168:2:0)
[07:23:15.581] Build status: STARTED
[07:23:15.581] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8998211204965673168:4:0) after 2m38s
[07:25:53.886] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8998211204965673168:4:0)
[07:25:53.984] Build status: STARTED
[07:25:53.984] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8998211204965673168:5:0) after 2m15s
[07:28:09.191] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8998211204965673168:5:0)
[07:28:09.219] Build status: STARTED
[07:28:09.219] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8998211204965673168:6:0) after 3m42s
[07:31:51.236] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8998211204965673168:6:0)
[07:31:51.269] Build status: STARTED
[07:31:51.269] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8998211204965673168:7:0) after 2m29s
[07:34:20.301] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8998211204965673168:7:0)
[07:34:20.332] Build status: STARTED
[07:34:20.332] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8998211204965673168:8:0) after 2m42s
[07:37:02.564] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8998211204965673168:8:0)
[07:37:02.594] Build status: STARTED
[07:37:02.594] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8998211204965673168:9:0) after 4m5s
[07:41:07.795] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8998211204965673168:9:0)
[07:41:07.867] Build status: STARTED
[07:41:07.867] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8998211204965673168:10:0) after 4m25s
[07:45:32.888] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8998211204965673168:10:0)
[07:45:32.923] Build status: STARTED
[07:45:32.923] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8998211204965673168:11:0) after 9m12s
[07:54:45.019] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8998211204965673168:11:0)
[07:54:45.053] Build status: STARTED
[07:54:45.053] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8998211204965673168:12:0) after 1m29s
[07:56:14.046] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8998211204965673168:12:0)
[07:56:14.072] Build status: STARTED
[07:56:14.073] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8998211204965673168:13:0) after 4m4s
[08:00:18.215] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8998211204965673168:13:0)
[08:00:18.247] Build status: STARTED
[08:00:18.247] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8998211204965673168:14:0) after 6m0s
[08:06:18.552] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8998211204965673168:14:0)
[08:06:18.666] Build status: STARTED
[08:06:18.666] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8998211204965673168:15:0) after 6m51s
[08:13:09.948] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8998211204965673168:15:0)
[08:13:10.257] Build status: STARTED
[08:13:10.257] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8998211204965673168:16:0) after 5m11s
[08:18:21.381] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8998211204965673168:16:0)
[08:18:21.449] Build status: STARTED
[08:18:21.449] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8998211204965673168:17:0) after 5m45s
[08:24:06.568] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8998211204965673168:17:0)
[08:24:06.606] Build status: STARTED
[08:24:06.606] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8998211204965673168:18:0) after 4m9s
[08:28:15.628] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8998211204965673168:18:0)
[08:28:15.659] Build status: STARTED
[08:28:15.659] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8998211204965673168:19:0) after 6m16s
[08:34:31.766] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8998211204965673168:19:0)
[08:34:31.795] Build status: STARTED
[08:34:31.795] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8998211204965673168:20:0) after 4m35s
[08:39:06.909] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8998211204965673168:20:0)
[08:39:06.974] Build status: STARTED
[08:39:06.974] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8998211204965673168:21:0) after 3m16s
[08:42:22.992] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8998211204965673168:21:0)
[08:42:23.022] Build status: STARTED
[08:42:23.022] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8998211204965673168:22:0) after 2m18s
[08:44:41.041] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8998211204965673168:22:0)
[08:44:41.068] Build status: STARTED
[08:44:41.068] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8998211204965673168:23:0) after 4m54s
[08:49:35.044] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8998211204965673168:23:0)
[08:49:35.078] Build status: STARTED
[08:49:35.078] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8998211204965673168:24:0) after 9m3s
[08:58:38.094] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8998211204965673168:24:0)
[08:58:38.126] Build status: STARTED
[08:58:38.126] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8998211204965673168:25:0) after 5m6s
[09:03:44.157] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8998211204965673168:25:0)
[09:03:44.369] Build status: STARTED
[09:03:44.369] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8998211204965673168:26:0) after 6m25s
[09:10:09.383] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8998211204965673168:26:0)
[09:10:09.417] Build status: STARTED
[09:10:09.417] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8998211204965673168:27:0) after 3m56s
[09:14:05.539] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8998211204965673168:27:0)
[09:14:05.574] Build status: STARTED
[09:14:05.574] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8998211204965673168:28:0) after 7m4s
[09:21:09.591] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8998211204965673168:28:0)
[09:21:09.627] Build status: STARTED
[09:21:09.627] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8998211204965673168:29:0) after 6m23s
[09:27:32.646] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8998211204965673168:29:0)
[09:27:32.680] Build status: STARTED
[09:27:32.680] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8998211204965673168:30:0) after 2m26s
[09:29:59.695] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8998211204965673168:30:0)
[09:29:59.724] Build status: STARTED
[09:29:59.724] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8998211204965673168:31:0) after 5m53s
[09:35:52.742] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8998211204965673168:31:0)
[09:35:52.795] Build status: STARTED
[09:35:52.795] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8998211204965673168:32:0) after 3m9s
[09:39:01.816] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8998211204965673168:32:0)
[09:39:01.847] Build status: STARTED
[09:39:01.847] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8998211204965673168:33:0) after 6m59s
[09:46:00.981] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8998211204965673168:33:0)
[09:46:01.065] Build status: STARTED
[09:46:01.065] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8998211204965673168:34:0) after 4m33s
[09:50:34.148] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8998211204965673168:34:0)
[09:50:34.184] Build status: STARTED
[09:50:34.184] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8998211204965673168:35:0) after 1m11s
[09:51:45.201] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8998211204965673168:35:0)
[09:51:45.232] Build status: STARTED
[09:51:45.232] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8998211204965673168:36:0) after 6m24s
[09:58:09.250] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8998211204965673168:36:0)
[09:58:09.278] Build status: STARTED
[09:58:09.278] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8998211204965673168:37:0) after 8m7s
[10:06:16.300] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8998211204965673168:37:0)
[10:06:16.395] Build status: STARTED
[10:06:16.395] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8998211204965673168:38:0) after 9m51s
[10:16:07.960] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8998211204965673168:38:0)
[10:16:07.998] Build status: STARTED
[10:16:07.998] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8998211204965673168:39:0) after 7m27s
[10:23:35.020] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8998211204965673168:39:0)
[10:23:35.055] Build status: STARTED
[10:23:35.055] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8998211204965673168:40:0) after 5m36s
[10:28:03.209] Received PubSub notification, asking Buildbucket for the build status
[10:28:03.255] Build:
{
"id": "8832781142704108001",
"builder": {
"project": "fuchsia",
"bucket": "prod",
"builder": "clang-prod-trigger"
},
"createdBy": "project:fuchsia",
"createTime": "2021-10-21T07:15:03.973111842Z",
"startTime": "2021-10-21T07:16:20.788950Z",
"endTime": "2021-10-21T10:28:02.643625466Z",
"updateTime": "2021-10-21T10:28:02.643943814Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "66b650f3da70e45ca40ffa281df79e044c28fe04",
"ref": "refs/heads/main"
}
}
}
[10:28:03.255] Invocation finished in 3h13m0.821907054s with status FAILED