[22:58:28.655] New invocation is queued and will start shortly
[22:58:28.655] Triggered by user:fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com
[22:58:29.798] Starting the invocation (attempt 1)
[22:58:29.830] Popped buildset tag "buildset:commit/gitiles/llvm.googlesource.com/llvm-project/+/2f9f9afa4e1281b4ac7c8ad36860a4e35e6f5070"
[22:58:29.830] Reconstructed gitiles commit from tags
[22:58:29.831] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:58:29.831] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:58:29.831] Buildbucket request:
{
"requestId": "8989816928146480400",
"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/+/2f9f9afa4e1281b4ac7c8ad36860a4e35e6f5070",
"gitiles_ref:refs/heads/main",
"parent_buildername:clang-ci-trigger",
"user_agent:recipe"
]
},
"id": "38884081-b980-4755-8c7b-3a2df0bb3a94",
"title": "clang-ci-trigger/0"
}
]
}
},
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "2f9f9afa4e1281b4ac7c8ad36860a4e35e6f5070",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8989816928146480400"
},
{
"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:58:30.261] Scheduled build:
{
"id": "8824386866184195585",
"builder": {
"project": "fuchsia",
"bucket": "prod",
"builder": "clang-prod-trigger"
},
"createdBy": "project:fuchsia",
"createTime": "2022-01-21T22:58:29.895889469Z",
"updateTime": "2022-01-21T22:58:29.895889469Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "2f9f9afa4e1281b4ac7c8ad36860a4e35e6f5070",
"ref": "refs/heads/main"
}
}
}
[22:58:30.261] Task URL: https://cr-buildbucket.appspot.com/build/8824386866184195585
[22:58:30.261] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8989816928146480400:2:0) after 4m28s
[22:58:45.894] Received PubSub notification, asking Buildbucket for the build status
[22:58:45.921] Build status: STARTED
[23:02:58.277] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8989816928146480400:2:0)
[23:02:58.308] Build status: STARTED
[23:02:58.308] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8989816928146480400:4:0) after 5m30s
[23:08:28.313] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8989816928146480400:4:0)
[23:08:28.343] Build status: STARTED
[23:08:28.343] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8989816928146480400:5:0) after 6m36s
[23:15:04.522] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8989816928146480400:5:0)
[23:15:04.557] Build status: STARTED
[23:15:04.557] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8989816928146480400:6:0) after 9m34s
[23:24:38.602] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8989816928146480400:6:0)
[23:24:38.634] Build status: STARTED
[23:24:38.634] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8989816928146480400:7:0) after 4m51s
[23:29:29.870] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8989816928146480400:7:0)
[23:29:29.898] Build status: STARTED
[23:29:29.898] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8989816928146480400:8:0) after 7m17s
[23:36:47.228] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8989816928146480400:8:0)
[23:36:47.271] Build status: STARTED
[23:36:47.271] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8989816928146480400:9:0) after 8m33s
[23:45:20.518] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8989816928146480400:9:0)
[23:45:20.552] Build status: STARTED
[23:45:20.552] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8989816928146480400:10:0) after 2m34s
[23:47:54.569] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8989816928146480400:10:0)
[23:47:54.791] Build status: STARTED
[23:47:54.791] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8989816928146480400:11:0) after 2m12s
[23:50:06.825] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8989816928146480400:11:0)
[23:50:06.850] Build status: STARTED
[23:50:06.850] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8989816928146480400:12:0) after 7m0s
[23:57:07.122] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8989816928146480400:12:0)
[23:57:07.155] Build status: STARTED
[23:57:07.155] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8989816928146480400:13:0) after 6m40s
[00:03:47.175] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8989816928146480400:13:0)
[00:03:47.216] Build status: STARTED
[00:03:47.216] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8989816928146480400:14:0) after 9m44s
[00:13:31.641] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8989816928146480400:14:0)
[00:13:31.677] Build status: STARTED
[00:13:31.677] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8989816928146480400:15:0) after 3m44s
[00:17:16.180] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8989816928146480400:15:0)
[00:17:16.213] Build status: STARTED
[00:17:16.213] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8989816928146480400:16:0) after 5m58s
[00:23:14.233] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8989816928146480400:16:0)
[00:23:14.265] Build status: STARTED
[00:23:14.265] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8989816928146480400:17:0) after 5m24s
[00:28:38.327] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8989816928146480400:17:0)
[00:28:38.356] Build status: STARTED
[00:28:38.356] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8989816928146480400:18:0) after 8m9s
[00:36:47.389] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8989816928146480400:18:0)
[00:36:47.656] Build status: STARTED
[00:36:47.656] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8989816928146480400:19:0) after 9m30s
[00:46:17.756] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8989816928146480400:19:0)
[00:46:17.795] Build status: STARTED
[00:46:17.795] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8989816928146480400:20:0) after 7m31s
[00:53:49.022] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8989816928146480400:20:0)
[00:53:49.051] Build status: STARTED
[00:53:49.051] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8989816928146480400:21:0) after 2m52s
[00:56:41.066] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8989816928146480400:21:0)
[00:56:41.138] Build status: STARTED
[00:56:41.138] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8989816928146480400:22:0) after 2m21s
[00:59:02.667] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8989816928146480400:22:0)
[00:59:02.707] Build status: STARTED
[00:59:02.707] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8989816928146480400:23:0) after 7m13s
[01:06:15.793] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8989816928146480400:23:0)
[01:06:15.857] Build status: STARTED
[01:06:15.857] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8989816928146480400:24:0) after 9m55s
[01:16:10.959] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8989816928146480400:24:0)
[01:16:10.993] Build status: STARTED
[01:16:10.993] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8989816928146480400:25:0) after 2m16s
[01:18:27.289] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8989816928146480400:25:0)
[01:18:27.552] Build status: STARTED
[01:18:27.552] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8989816928146480400:26:0) after 1m24s
[01:19:51.816] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8989816928146480400:26:0)
[01:19:51.848] Build status: STARTED
[01:19:51.848] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8989816928146480400:27:0) after 9m49s
[01:29:40.869] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8989816928146480400:27:0)
[01:29:40.900] Build status: STARTED
[01:29:40.900] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-trigger:8989816928146480400:28:0) after 3m58s
[01:31:25.166] Received PubSub notification, asking Buildbucket for the build status
[01:31:25.206] Build:
{
"id": "8824386866184195585",
"builder": {
"project": "fuchsia",
"bucket": "prod",
"builder": "clang-prod-trigger"
},
"createdBy": "project:fuchsia",
"createTime": "2022-01-21T22:58:29.895889469Z",
"startTime": "2022-01-21T22:58:45.093609Z",
"endTime": "2022-01-22T01:31:24.771872255Z",
"updateTime": "2022-01-22T01:31:24.771872255Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "2f9f9afa4e1281b4ac7c8ad36860a4e35e6f5070",
"ref": "refs/heads/main"
}
}
}
[01:31:25.206] Invocation finished in 2h32m56.565756772s with status SUCCEEDED