[04:20:56.064] New invocation is queued and will start shortly
[04:20:56.064] Triggered by user:fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com
[04:20:57.147] Starting the invocation (attempt 1)
[04:20:57.190] Popped buildset tag "buildset:commit/gitiles/llvm.googlesource.com/llvm-project/+/e652f3f04a253d1c6bc3e16f15115ff3e8401dc0"
[04:20:57.190] Reconstructed gitiles commit from tags
[04:20:57.190] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[04:20:57.191] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[04:20:57.191] Buildbucket request:
{
"requestId": "8994688877112394848",
"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/+/1164c4b37583eca98866853ed22149f1a1b55a3d",
"gitiles_ref:refs/heads/main",
"parent_buildername:clang-ci-trigger",
"user_agent:recipe"
]
},
"id": "93d8d357-ed54-468f-95a2-ca0c396891a9",
"title": "clang-ci-trigger/0"
},
{
"buildbucket": {
"tags": [
"buildset:commit/gitiles/llvm.googlesource.com/llvm-project/+/ace1d0ad3dc43e28715cbe2f3e0a5a76578bda9f",
"gitiles_ref:refs/heads/main",
"parent_buildername:clang-ci-trigger",
"user_agent:recipe"
]
},
"id": "853f9da0-b2bd-4f18-9fbc-25da2c64bd62",
"title": "clang-ci-trigger/0"
},
{
"buildbucket": {
"tags": [
"buildset:commit/gitiles/llvm.googlesource.com/llvm-project/+/e652f3f04a253d1c6bc3e16f15115ff3e8401dc0",
"gitiles_ref:refs/heads/main",
"parent_buildername:clang-ci-trigger",
"user_agent:recipe"
]
},
"id": "2d156c26-b1ab-4bdb-ac19-518643c27c45",
"title": "clang-ci-trigger/0"
}
]
}
},
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "e652f3f04a253d1c6bc3e16f15115ff3e8401dc0",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8994688877112394848"
},
{
"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"
}
}
[04:20:57.707] Scheduled build:
{
"id": "8829258815198375665",
"builder": {
"project": "fuchsia",
"bucket": "prod",
"builder": "clang-prod-trigger"
},
"createdBy": "project:fuchsia",
"createTime": "2021-11-29T04:20:57.249313753Z",
"updateTime": "2021-11-29T04:20:57.249313753Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "e652f3f04a253d1c6bc3e16f15115ff3e8401dc0",
"ref": "refs/heads/main"
}
}
}
[04:20:57.707] Task URL: https://cr-buildbucket.appspot.com/build/8829258815198375665
[04:20:57.707] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8994688877112394848:2:0) after 3m31s
[04:21:11.029] Received PubSub notification, asking Buildbucket for the build status
[04:21:11.075] Build status: STARTED
[04:24:28.772] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8994688877112394848:2:0)
[04:24:28.804] Build status: STARTED
[04:24:28.804] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8994688877112394848:4:0) after 3m51s
[04:28:19.822] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8994688877112394848:4:0)
[04:28:19.857] Build status: STARTED
[04:28:19.857] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8994688877112394848:5:0) after 7m41s
[04:36:00.912] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8994688877112394848:5:0)
[04:36:00.945] Build status: STARTED
[04:36:00.945] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8994688877112394848:6:0) after 9m59s
[04:46:00.000] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8994688877112394848:6:0)
[04:46:00.033] Build status: STARTED
[04:46:00.033] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8994688877112394848:7:0) after 6m52s
[04:52:52.078] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8994688877112394848:7:0)
[04:52:52.106] Build status: STARTED
[04:52:52.106] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8994688877112394848:8:0) after 6m56s
[04:59:48.153] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8994688877112394848:8:0)
[04:59:48.203] Build status: STARTED
[04:59:48.203] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8994688877112394848:9:0) after 5m13s
[05:05:01.713] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8994688877112394848:9:0)
[05:05:01.745] Build status: STARTED
[05:05:01.745] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8994688877112394848:10:0) after 7m14s
[05:12:15.936] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8994688877112394848:10:0)
[05:12:15.968] Build status: STARTED
[05:12:15.968] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8994688877112394848:11:0) after 2m8s
[05:14:23.983] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8994688877112394848:11:0)
[05:14:24.013] Build status: STARTED
[05:14:24.013] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8994688877112394848:12:0) after 5m5s
[05:19:29.110] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8994688877112394848:12:0)
[05:19:29.142] Build status: STARTED
[05:19:29.142] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8994688877112394848:13:0) after 2m41s
[05:22:10.235] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8994688877112394848:13:0)
[05:22:10.268] Build status: STARTED
[05:22:10.268] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8994688877112394848:14:0) after 2m5s
[05:24:15.287] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8994688877112394848:14:0)
[05:24:15.328] Build status: STARTED
[05:24:15.328] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8994688877112394848:15:0) after 2m44s
[05:26:59.345] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8994688877112394848:15:0)
[05:26:59.382] Build status: STARTED
[05:26:59.382] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8994688877112394848:16:0) after 4m42s
[05:31:41.572] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8994688877112394848:16:0)
[05:31:41.599] Build status: STARTED
[05:31:41.599] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8994688877112394848:17:0) after 5m26s
[05:37:07.690] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8994688877112394848:17:0)
[05:37:07.721] Build status: STARTED
[05:37:07.721] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8994688877112394848:18:0) after 7m53s
[05:45:00.828] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8994688877112394848:18:0)
[05:45:00.859] Build status: STARTED
[05:45:00.859] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8994688877112394848:19:0) after 9m41s
[05:54:42.220] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8994688877112394848:19:0)
[05:54:42.248] Build status: STARTED
[05:54:42.248] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8994688877112394848:20:0) after 5m33s
[06:00:15.275] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8994688877112394848:20:0)
[06:00:15.312] Build status: STARTED
[06:00:15.312] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8994688877112394848:21:0) after 7m14s
[06:07:29.498] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8994688877112394848:21:0)
[06:07:29.531] Build status: STARTED
[06:07:29.531] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8994688877112394848:22:0) after 2m56s
[06:10:25.585] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8994688877112394848:22:0)
[06:10:25.616] Build status: STARTED
[06:10:25.616] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8994688877112394848:23:0) after 8m26s
[06:18:51.750] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8994688877112394848:23:0)
[06:18:51.782] Build status: STARTED
[06:18:51.782] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8994688877112394848:24:0) after 2m24s
[06:21:15.808] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8994688877112394848:24:0)
[06:21:15.845] Build status: STARTED
[06:21:15.845] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8994688877112394848:25:0) after 8m21s
[06:27:43.417] Received PubSub notification, asking Buildbucket for the build status
[06:27:43.449] Build:
{
"id": "8829258815198375665",
"builder": {
"project": "fuchsia",
"bucket": "prod",
"builder": "clang-prod-trigger"
},
"createdBy": "project:fuchsia",
"createTime": "2021-11-29T04:20:57.249313753Z",
"startTime": "2021-11-29T04:21:10.018955Z",
"endTime": "2021-11-29T06:27:42.988837450Z",
"updateTime": "2021-11-29T06:27:42.988837450Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "e652f3f04a253d1c6bc3e16f15115ff3e8401dc0",
"ref": "refs/heads/main"
}
}
}
[06:27:43.449] Invocation finished in 2h6m47.409048266s with status SUCCEEDED