[17:26:09.183] New invocation is queued and will start shortly
[17:26:10.415] Starting the invocation (attempt 1)
[17:26:10.441] Popped buildset tag "buildset:commit/gitiles/llvm.googlesource.com/llvm-project/+/ec4d5a6658782b4a88c634d5b332c56b754c5949"
[17:26:10.441] Popped gitiles commit info from properties and tags
[17:26:10.441] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:26:10.441] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:26:10.441] Buildbucket request:
{
"requestId": "8903951912047872016",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-mac-x64"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8903951912047872016",
"job": "fuchsia/toolchain.ci-clang-mac-x64-40be1bb5",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "e04d124a96311d3714522125bf703950863e0540"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@e04d124a96311d3714522125bf703950863e0540",
"title": "e04d124a96311d3714522125bf703950863e0540",
"url": "https://llvm.googlesource.com/llvm-project/+/e04d124a96311d3714522125bf703950863e0540"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "121ed07975c087291ac7faf681042d51211b9f97"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@121ed07975c087291ac7faf681042d51211b9f97",
"title": "121ed07975c087291ac7faf681042d51211b9f97",
"url": "https://llvm.googlesource.com/llvm-project/+/121ed07975c087291ac7faf681042d51211b9f97"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "04ebd1907c0561831e4fcf2658e1f3614f8cdd77"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@04ebd1907c0561831e4fcf2658e1f3614f8cdd77",
"title": "04ebd1907c0561831e4fcf2658e1f3614f8cdd77",
"url": "https://llvm.googlesource.com/llvm-project/+/04ebd1907c0561831e4fcf2658e1f3614f8cdd77"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "ee09f7d1fc173f2b495838c925f2cf39a2b55369"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@ee09f7d1fc173f2b495838c925f2cf39a2b55369",
"title": "ee09f7d1fc173f2b495838c925f2cf39a2b55369",
"url": "https://llvm.googlesource.com/llvm-project/+/ee09f7d1fc173f2b495838c925f2cf39a2b55369"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "a79cf0228e4ade1f69e8196fdcf9c0184b4ba1b7"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@a79cf0228e4ade1f69e8196fdcf9c0184b4ba1b7",
"title": "a79cf0228e4ade1f69e8196fdcf9c0184b4ba1b7",
"url": "https://llvm.googlesource.com/llvm-project/+/a79cf0228e4ade1f69e8196fdcf9c0184b4ba1b7"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "c1b3ebba7909e9e3e99a4ac45bef38d7f590cc3b"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@c1b3ebba7909e9e3e99a4ac45bef38d7f590cc3b",
"title": "c1b3ebba7909e9e3e99a4ac45bef38d7f590cc3b",
"url": "https://llvm.googlesource.com/llvm-project/+/c1b3ebba7909e9e3e99a4ac45bef38d7f590cc3b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "625e929d4305987a85c86ad4c67f1e15b36f89e0"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@625e929d4305987a85c86ad4c67f1e15b36f89e0",
"title": "625e929d4305987a85c86ad4c67f1e15b36f89e0",
"url": "https://llvm.googlesource.com/llvm-project/+/625e929d4305987a85c86ad4c67f1e15b36f89e0"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "2e426fe8ff314c2565073e73e27fdbdf36c140a3"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@2e426fe8ff314c2565073e73e27fdbdf36c140a3",
"title": "2e426fe8ff314c2565073e73e27fdbdf36c140a3",
"url": "https://llvm.googlesource.com/llvm-project/+/2e426fe8ff314c2565073e73e27fdbdf36c140a3"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "710664341d72071729401d2eb86356056a3d7f46"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@710664341d72071729401d2eb86356056a3d7f46",
"title": "710664341d72071729401d2eb86356056a3d7f46",
"url": "https://llvm.googlesource.com/llvm-project/+/710664341d72071729401d2eb86356056a3d7f46"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "7af61d5cf464f1d716c82bc77907fa3fe4ebc841"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@7af61d5cf464f1d716c82bc77907fa3fe4ebc841",
"title": "7af61d5cf464f1d716c82bc77907fa3fe4ebc841",
"url": "https://llvm.googlesource.com/llvm-project/+/7af61d5cf464f1d716c82bc77907fa3fe4ebc841"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "bbf2781bc49aee4d7ee8ec40dcf7316db360c454"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@bbf2781bc49aee4d7ee8ec40dcf7316db360c454",
"title": "bbf2781bc49aee4d7ee8ec40dcf7316db360c454",
"url": "https://llvm.googlesource.com/llvm-project/+/bbf2781bc49aee4d7ee8ec40dcf7316db360c454"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "c073821142d1bda68682f8ff640d143cbb03ae7b"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@c073821142d1bda68682f8ff640d143cbb03ae7b",
"title": "c073821142d1bda68682f8ff640d143cbb03ae7b",
"url": "https://llvm.googlesource.com/llvm-project/+/c073821142d1bda68682f8ff640d143cbb03ae7b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "2ef3dcf1fd4b3a2fc849b113644533f3e6df8b1e"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@2ef3dcf1fd4b3a2fc849b113644533f3e6df8b1e",
"title": "2ef3dcf1fd4b3a2fc849b113644533f3e6df8b1e",
"url": "https://llvm.googlesource.com/llvm-project/+/2ef3dcf1fd4b3a2fc849b113644533f3e6df8b1e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "ec4d5a6658782b4a88c634d5b332c56b754c5949"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@ec4d5a6658782b4a88c634d5b332c56b754c5949",
"title": "ec4d5a6658782b4a88c634d5b332c56b754c5949",
"url": "https://llvm.googlesource.com/llvm-project/+/ec4d5a6658782b4a88c634d5b332c56b754c5949"
}
]
}
},
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "ec4d5a6658782b4a88c634d5b332c56b754c5949",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8903951912047872016"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/toolchain.ci-clang-mac-x64-40be1bb5"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[17:26:10.990] Scheduled build:
{
"id": "8738521849944176785",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-mac-x64"
},
"createdBy": "project:fuchsia",
"createTime": "2024-08-26T17:26:10.561958452Z",
"updateTime": "2024-08-26T17:26:10.561958452Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "ec4d5a6658782b4a88c634d5b332c56b754c5949",
"ref": "refs/heads/main"
}
}
}
[17:26:10.990] Task URL: https://cr-buildbucket.appspot.com/build/8738521849944176785
[17:26:10.990] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8903951912047872016:2:0) after 2m24s
[17:26:11.912] Received PubSub notification, asking Buildbucket for the build status
[17:26:11.930] Build status: SCHEDULED
[17:26:15.315] Received PubSub notification, asking Buildbucket for the build status
[17:26:15.422] Build status: STARTED
[17:28:35.122] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8903951912047872016:2:0)
[17:28:35.146] Build status: STARTED
[17:28:35.146] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8903951912047872016:5:0) after 1m52s
[17:30:27.181] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8903951912047872016:5:0)
[17:30:27.213] Build status: STARTED
[17:30:27.213] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8903951912047872016:6:0) after 5m16s
[17:35:43.283] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8903951912047872016:6:0)
[17:35:43.322] Build status: STARTED
[17:35:43.322] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8903951912047872016:7:0) after 8m19s
[17:44:02.355] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8903951912047872016:7:0)
[17:44:02.435] Build status: STARTED
[17:44:02.435] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8903951912047872016:8:0) after 7m57s
[17:51:59.457] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8903951912047872016:8:0)
[17:51:59.476] Build status: STARTED
[17:51:59.476] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8903951912047872016:9:0) after 3m34s
[17:55:33.509] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8903951912047872016:9:0)
[17:55:33.550] Build status: STARTED
[17:55:33.551] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8903951912047872016:10:0) after 8m15s
[18:03:48.679] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8903951912047872016:10:0)
[18:03:48.697] Build status: STARTED
[18:03:48.697] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8903951912047872016:11:0) after 6m40s
[18:10:28.750] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8903951912047872016:11:0)
[18:10:28.850] Build status: STARTED
[18:10:28.872] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8903951912047872016:12:0) after 2m31s
[18:12:59.875] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8903951912047872016:12:0)
[18:12:59.993] Build status: STARTED
[18:12:59.993] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8903951912047872016:13:0) after 1m23s
[18:14:23.082] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8903951912047872016:13:0)
[18:14:23.152] Build status: STARTED
[18:14:23.152] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8903951912047872016:14:0) after 3m23s
[18:17:46.235] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8903951912047872016:14:0)
[18:17:46.263] Build status: STARTED
[18:17:46.263] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8903951912047872016:15:0) after 8m28s
[18:26:14.328] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8903951912047872016:15:0)
[18:26:14.347] Build status: STARTED
[18:26:14.347] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8903951912047872016:16:0) after 2m3s
[18:28:17.339] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8903951912047872016:16:0)
[18:28:17.405] Build status: STARTED
[18:28:17.405] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8903951912047872016:17:0) after 6m12s
[18:34:29.424] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8903951912047872016:17:0)
[18:34:29.445] Build status: STARTED
[18:34:29.445] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8903951912047872016:18:0) after 5m50s
[18:40:19.467] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8903951912047872016:18:0)
[18:40:19.487] Build status: STARTED
[18:40:19.487] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8903951912047872016:19:0) after 2m19s
[18:42:38.532] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8903951912047872016:19:0)
[18:42:38.593] Build status: STARTED
[18:42:38.593] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8903951912047872016:20:0) after 2m20s
[18:44:58.580] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8903951912047872016:20:0)
[18:44:58.602] Build status: STARTED
[18:44:58.602] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8903951912047872016:21:0) after 7m37s
[18:52:35.588] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8903951912047872016:21:0)
[18:52:35.616] Build status: STARTED
[18:52:35.617] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8903951912047872016:22:0) after 7m24s
[18:54:52.718] Received PubSub notification, asking Buildbucket for the build status
[18:54:52.762] Build:
{
"id": "8738521849944176785",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-mac-x64"
},
"createdBy": "project:fuchsia",
"createTime": "2024-08-26T17:26:10.561958452Z",
"startTime": "2024-08-26T17:26:14.926996685Z",
"endTime": "2024-08-26T18:54:52.250736432Z",
"updateTime": "2024-08-26T18:54:52.250736432Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "ec4d5a6658782b4a88c634d5b332c56b754c5949",
"ref": "refs/heads/main"
}
}
}
[18:54:52.762] Invocation finished in 1h28m43.590222637s with status SUCCEEDED