[15:08:23.212] New invocation is queued and will start shortly
[15:08:23.212] Triggered by user:fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com
[15:08:24.457] Starting the invocation (attempt 1)
[15:08:24.486] Popped buildset tag "buildset:commit/gitiles/llvm.googlesource.com/llvm-project/+/f40a7901d1762d2715e14074825bd195b3675874"
[15:08:24.486] Reconstructed gitiles commit from tags
[15:08:24.486] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[15:08:24.486] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[15:08:24.487] Buildbucket request:
{
"requestId": "8946631750717895168",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "clang_toolchain.prod.core.x64-debug"
},
"properties": {
"$fuchsia/build": {
"clang_toolchain": {
"source": "isolated",
"version": "6681fb422798326ac08c3c5ae0d39fdd8275ee618f8a67694147c4fe2c1ca61c/652"
}
},
"$fuchsia/checkout": {
"gitiles_commit": {
"host": "fuchsia.googlesource.com",
"id": "eacbb2be0678b67d7cfbb5cdfb115f981165a2d0",
"project": "integration"
}
},
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8946631750717895168",
"job": "fuchsia/clang_toolchain.prod.core.x64-debug",
"triggers": [
{
"buildbucket": {
"properties": {
"$fuchsia/build": {
"clang_toolchain": {
"source": "isolated",
"version": "6681fb422798326ac08c3c5ae0d39fdd8275ee618f8a67694147c4fe2c1ca61c/652"
}
},
"$fuchsia/checkout": {
"gitiles_commit": {
"host": "fuchsia.googlesource.com",
"id": "eacbb2be0678b67d7cfbb5cdfb115f981165a2d0",
"project": "integration"
}
},
"clang_toolchain": {
"git_repository": "https://llvm.googlesource.com/llvm-project",
"git_revision": "f40a7901d1762d2715e14074825bd195b3675874"
}
},
"tags": [
"buildset:commit/gitiles/llvm.googlesource.com/llvm-project/+/f40a7901d1762d2715e14074825bd195b3675874",
"gitiles_ref:refs/heads/main",
"parent_buildername:clang-linux-x64",
"user_agent:recipe"
]
},
"id": "63dbe69d-9ec9-4b93-b3a0-9e288a796483",
"title": "clang-linux-x64/0"
}
]
},
"clang_toolchain": {
"git_repository": "https://llvm.googlesource.com/llvm-project",
"git_revision": "f40a7901d1762d2715e14074825bd195b3675874"
}
},
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "f40a7901d1762d2715e14074825bd195b3675874",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8946631750717895168"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/clang_toolchain.prod.core.x64-debug"
},
{
"key": "user_agent",
"value": "luci-scheduler"
},
{
"key": "parent_buildername",
"value": "clang-linux-x64"
},
{
"key": "user_agent",
"value": "recipe"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[15:08:25.076] Scheduled build:
{
"id": "8781201688622348497",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "clang_toolchain.prod.core.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2023-05-13T15:08:24.585440564Z",
"updateTime": "2023-05-13T15:08:24.585440564Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "f40a7901d1762d2715e14074825bd195b3675874",
"ref": "refs/heads/main"
}
}
}
[15:08:25.076] Task URL: https://cr-buildbucket.appspot.com/build/8781201688622348497
[15:08:25.076] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8946631750717895168:2:0) after 2m6s
[15:08:27.528] Received PubSub notification, asking Buildbucket for the build status
[15:08:27.561] Build status: STARTED
[15:10:31.085] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8946631750717895168:2:0)
[15:10:31.108] Build status: STARTED
[15:10:31.109] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8946631750717895168:4:0) after 9m30s
[15:20:01.189] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8946631750717895168:4:0)
[15:20:01.212] Build status: STARTED
[15:20:01.212] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8946631750717895168:5:0) after 6m19s
[15:26:20.279] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8946631750717895168:5:0)
[15:26:20.299] Build status: STARTED
[15:26:20.299] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8946631750717895168:6:0) after 5m41s
[15:32:01.501] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8946631750717895168:6:0)
[15:32:01.520] Build status: STARTED
[15:32:01.521] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8946631750717895168:7:0) after 7m11s
[15:39:12.650] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8946631750717895168:7:0)
[15:39:12.670] Build status: STARTED
[15:39:12.670] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8946631750717895168:8:0) after 5m20s
[15:44:32.834] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8946631750717895168:8:0)
[15:44:32.853] Build status: STARTED
[15:44:32.853] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8946631750717895168:9:0) after 8m17s
[15:52:49.871] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8946631750717895168:9:0)
[15:52:49.892] Build status: STARTED
[15:52:49.892] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8946631750717895168:10:0) after 2m30s
[15:55:20.013] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8946631750717895168:10:0)
[15:55:20.076] Build status: STARTED
[15:55:20.076] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8946631750717895168:11:0) after 7m7s
[16:02:27.110] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8946631750717895168:11:0)
[16:02:27.132] Build status: STARTED
[16:02:27.132] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8946631750717895168:12:0) after 1m57s
[16:04:24.274] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8946631750717895168:12:0)
[16:04:24.293] Build status: STARTED
[16:04:24.294] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8946631750717895168:13:0) after 3m0s
[16:07:24.283] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8946631750717895168:13:0)
[16:07:24.314] Build status: STARTED
[16:07:24.314] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8946631750717895168:14:0) after 3m20s
[16:10:44.500] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8946631750717895168:14:0)
[16:10:44.529] Build status: STARTED
[16:10:44.529] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8946631750717895168:15:0) after 8m31s
[16:19:15.661] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8946631750717895168:15:0)
[16:19:15.689] Build status: STARTED
[16:19:15.689] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8946631750717895168:16:0) after 3m50s
[16:23:05.705] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8946631750717895168:16:0)
[16:23:05.726] Build status: STARTED
[16:23:05.726] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8946631750717895168:17:0) after 7m44s
[16:30:49.780] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8946631750717895168:17:0)
[16:30:49.814] Build status: STARTED
[16:30:49.814] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8946631750717895168:18:0) after 3m14s
[16:34:03.832] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8946631750717895168:18:0)
[16:34:03.852] Build status: STARTED
[16:34:03.852] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8946631750717895168:19:0) after 1m47s
[16:35:50.879] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8946631750717895168:19:0)
[16:35:50.897] Build status: STARTED
[16:35:50.897] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8946631750717895168:20:0) after 1m56s
[16:37:46.918] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8946631750717895168:20:0)
[16:37:46.938] Build status: STARTED
[16:37:46.938] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8946631750717895168:21:0) after 5m35s
[16:43:21.963] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8946631750717895168:21:0)
[16:43:21.984] Build status: STARTED
[16:43:21.984] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8946631750717895168:22:0) after 7m50s
[16:51:12.079] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8946631750717895168:22:0)
[16:51:12.129] Build status: STARTED
[16:51:12.130] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8946631750717895168:23:0) after 2m39s
[16:53:51.123] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8946631750717895168:23:0)
[16:53:51.143] Build status: STARTED
[16:53:51.144] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8946631750717895168:24:0) after 4m8s
[16:57:59.179] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8946631750717895168:24:0)
[16:57:59.220] Build status: STARTED
[16:57:59.220] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8946631750717895168:25:0) after 4m41s
[17:02:40.267] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8946631750717895168:25:0)
[17:02:40.290] Build status: STARTED
[17:02:40.290] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8946631750717895168:26:0) after 6m43s
[17:04:30.541] Received PubSub notification, asking Buildbucket for the build status
[17:04:30.560] Build:
{
"id": "8781201688622348497",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "clang_toolchain.prod.core.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2023-05-13T15:08:24.585440564Z",
"startTime": "2023-05-13T15:08:26.963294Z",
"endTime": "2023-05-13T17:04:30.082421954Z",
"updateTime": "2023-05-13T17:04:30.082421954Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "f40a7901d1762d2715e14074825bd195b3675874",
"ref": "refs/heads/main"
}
}
}
[17:04:30.560] Invocation finished in 1h56m7.356702864s with status SUCCEEDED