[22:31:28.998] New invocation is queued and will start shortly
[22:31:28.998] Triggered by user:fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com
[22:31:30.061] Starting the invocation (attempt 1)
[22:31:30.098] Popped buildset tag "buildset:commit/gitiles/llvm.googlesource.com/llvm-project/+/3019898e0d1b494b7e7e76790adb3d83eff4aca1"
[22:31:30.098] Reconstructed gitiles commit from tags
[22:31:30.098] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:31:30.098] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:31:30.098] Buildbucket request:
{
"requestId": "8999240710987147904",
"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/+/dda810c332db35b92596d43caba14e1bc3a515bc",
"gitiles_ref:refs/heads/main",
"parent_buildername:clang-ci-trigger",
"user_agent:recipe"
]
},
"id": "bff6c27f-0cfe-41e9-9b2b-147baa2c7dd9",
"title": "clang-ci-trigger/0"
},
{
"buildbucket": {
"tags": [
"buildset:commit/gitiles/llvm.googlesource.com/llvm-project/+/3019898e0d1b494b7e7e76790adb3d83eff4aca1",
"gitiles_ref:refs/heads/main",
"parent_buildername:clang-ci-trigger",
"user_agent:recipe"
]
},
"id": "d2302e3e-e541-4f21-b6f2-fac6845dd3a1",
"title": "clang-ci-trigger/0"
}
]
}
},
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "3019898e0d1b494b7e7e76790adb3d83eff4aca1",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8999240710987147904"
},
{
"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"
}
}
[22:31:30.530] Scheduled build:
{
"id": "8833810649087625777",
"builder": {
"project": "fuchsia",
"bucket": "prod",
"builder": "clang-prod-trigger"
},
"createdBy": "project:fuchsia",
"createTime": "2021-10-09T22:31:30.177725363Z",
"updateTime": "2021-10-09T22:31:30.340378252Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "3019898e0d1b494b7e7e76790adb3d83eff4aca1",
"ref": "refs/heads/main"
}
}
}
[22:31:30.530] Task URL: https://cr-buildbucket.appspot.com/build/8833810649087625777
[22:31:30.530] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8999240710987147904:2:0) after 7m11s
[22:31:45.683] Received PubSub notification, asking Buildbucket for the build status
[22:31:45.708] Build status: STARTED
[22:38:41.549] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8999240710987147904:2:0)
[22:38:41.582] Build status: STARTED
[22:38:41.582] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8999240710987147904:4:0) after 9m39s
[22:48:20.598] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8999240710987147904:4:0)
[22:48:20.628] Build status: STARTED
[22:48:20.629] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8999240710987147904:5:0) after 4m12s
[22:52:32.677] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8999240710987147904:5:0)
[22:52:32.711] Build status: STARTED
[22:52:32.711] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8999240710987147904:6:0) after 4m24s
[22:56:56.727] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8999240710987147904:6:0)
[22:56:56.779] Build status: STARTED
[22:56:56.779] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8999240710987147904:7:0) after 6m11s
[23:03:07.893] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8999240710987147904:7:0)
[23:03:07.932] Build status: STARTED
[23:03:07.932] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8999240710987147904:8:0) after 8m52s
[23:12:00.059] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8999240710987147904:8:0)
[23:12:00.089] Build status: STARTED
[23:12:00.089] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8999240710987147904:9:0) after 9m59s
[23:21:59.106] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8999240710987147904:9:0)
[23:21:59.167] Build status: STARTED
[23:21:59.167] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8999240710987147904:10:0) after 5m41s
[23:27:40.191] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8999240710987147904:10:0)
[23:27:40.226] Build status: STARTED
[23:27:40.226] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8999240710987147904:11:0) after 2m13s
[23:29:53.986] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8999240710987147904:11:0)
[23:29:54.015] Build status: STARTED
[23:29:54.015] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8999240710987147904:12:0) after 9m8s
[23:39:02.461] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8999240710987147904:12:0)
[23:39:02.488] Build status: STARTED
[23:39:02.488] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8999240710987147904:13:0) after 9m17s
[23:48:19.508] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8999240710987147904:13:0)
[23:48:19.540] Build status: STARTED
[23:48:19.540] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8999240710987147904:14:0) after 9m19s
[23:57:38.555] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8999240710987147904:14:0)
[23:57:38.579] Build status: STARTED
[23:57:38.579] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8999240710987147904:15:0) after 7m27s
[00:05:05.727] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8999240710987147904:15:0)
[00:05:05.754] Build status: STARTED
[00:05:05.754] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8999240710987147904:16:0) after 4m43s
[00:09:48.978] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8999240710987147904:16:0)
[00:09:49.025] Build status: STARTED
[00:09:49.025] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8999240710987147904:17:0) after 5m27s
[00:15:16.077] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8999240710987147904:17:0)
[00:15:16.115] Build status: STARTED
[00:15:16.115] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8999240710987147904:18:0) after 7m5s
[00:22:21.134] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8999240710987147904:18:0)
[00:22:21.162] Build status: STARTED
[00:22:21.162] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8999240710987147904:19:0) after 4m53s
[00:27:14.183] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8999240710987147904:19:0)
[00:27:14.215] Build status: STARTED
[00:27:14.215] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8999240710987147904:20:0) after 3m3s
[00:30:17.231] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8999240710987147904:20:0)
[00:30:17.269] Build status: STARTED
[00:30:17.269] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8999240710987147904:21:0) after 2m12s
[00:32:29.391] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8999240710987147904:21:0)
[00:32:29.436] Build status: STARTED
[00:32:29.436] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8999240710987147904:22:0) after 3m58s
[00:36:27.452] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8999240710987147904:22:0)
[00:36:27.488] Build status: STARTED
[00:36:27.488] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8999240710987147904:23:0) after 6m38s
[00:43:05.606] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8999240710987147904:23:0)
[00:43:05.640] Build status: STARTED
[00:43:05.640] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8999240710987147904:24:0) after 3m13s
[00:46:18.658] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8999240710987147904:24:0)
[00:46:18.696] Build status: STARTED
[00:46:18.696] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8999240710987147904:25:0) after 4m49s
[00:51:08.527] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8999240710987147904:25:0)
[00:51:08.555] Build status: STARTED
[00:51:08.555] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8999240710987147904:26:0) after 6m42s
[00:57:50.608] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8999240710987147904:26:0)
[00:57:50.635] Build status: STARTED
[00:57:50.635] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8999240710987147904:27:0) after 4m56s
[01:02:46.667] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8999240710987147904:27:0)
[01:02:46.700] Build status: STARTED
[01:02:46.700] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8999240710987147904:28:0) after 2m11s
[01:04:57.722] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8999240710987147904:28:0)
[01:04:57.774] Build status: STARTED
[01:04:57.774] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8999240710987147904:29:0) after 5m47s
[01:10:45.092] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8999240710987147904:29:0)
[01:10:45.120] Build status: STARTED
[01:10:45.120] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8999240710987147904:30:0) after 3m45s
[01:14:30.143] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8999240710987147904:30:0)
[01:14:30.176] Build status: STARTED
[01:14:30.176] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8999240710987147904:31:0) after 5m43s
[01:20:13.198] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8999240710987147904:31:0)
[01:20:13.228] Build status: STARTED
[01:20:13.228] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8999240710987147904:32:0) after 5m12s
[01:25:25.252] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8999240710987147904:32:0)
[01:25:25.279] Build status: STARTED
[01:25:25.279] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8999240710987147904:33:0) after 4m59s
[01:30:24.374] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8999240710987147904:33:0)
[01:30:24.406] Build status: STARTED
[01:30:24.406] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8999240710987147904:34:0) after 2m47s
[01:33:11.423] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8999240710987147904:34:0)
[01:33:11.458] Build status: STARTED
[01:33:11.458] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8999240710987147904:35:0) after 3m23s
[01:36:34.476] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8999240710987147904:35:0)
[01:36:34.510] Build status: STARTED
[01:36:34.510] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8999240710987147904:36:0) after 9m30s
[01:39:28.084] Received PubSub notification, asking Buildbucket for the build status
[01:39:28.116] Build:
{
"id": "8833810649087625777",
"builder": {
"project": "fuchsia",
"bucket": "prod",
"builder": "clang-prod-trigger"
},
"createdBy": "project:fuchsia",
"createTime": "2021-10-09T22:31:30.177725363Z",
"startTime": "2021-10-09T22:31:44.330923Z",
"endTime": "2021-10-10T01:39:27.796070060Z",
"updateTime": "2021-10-10T01:39:27.796360375Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "3019898e0d1b494b7e7e76790adb3d83eff4aca1",
"ref": "refs/heads/main"
}
}
}
[01:39:28.116] Invocation finished in 3h7m59.13381033s with status SUCCEEDED