[10:14:22.923] New invocation is queued and will start shortly
[10:14:22.923] Triggered by user:fuchsia-ci-builder@fuchsia-infra.iam.gserviceaccount.com
[10:14:24.021] Starting the invocation (attempt 1)
[10:14:24.055] Popped buildset tag "buildset:commit/gitiles/llvm.googlesource.com/llvm-project/+/294eecd4cbd8a1e0dcc0cdbe1238817b92ba7668"
[10:14:24.055] Reconstructed gitiles commit from tags
[10:14:24.055] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[10:14:24.055] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[10:14:24.056] Buildbucket request:
{
"requestId": "8914307130898083632",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "clang_toolchain.ci.core.arm64-debug"
},
"properties": {
"$fuchsia/checkout": {
"clang_toolchain": {
"cas_digest": "7ab7abb543d79055d29671dd1c9c77026e0744328d47d139b1eb4ea20f5620ae/578",
"cas_instance": "projects/chromium-swarm/instances/default_instance"
},
"gitiles_commit": {
"host": "fuchsia.googlesource.com",
"id": "b3ea9f4abadfe34a0b99b1760bf9aaa2eeb09c38",
"project": "integration"
}
},
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8914307130898083632",
"job": "fuchsia/clang_toolchain.ci.core.arm64-debug",
"triggers": [
{
"buildbucket": {
"properties": {
"$fuchsia/checkout": {
"clang_toolchain": {
"cas_digest": "f4a54be3599393a65a64cfc74d39683389cd44f52b4ce301637b3a5b947ad8b9/578",
"cas_instance": "projects/chromium-swarm/instances/default_instance"
},
"gitiles_commit": {
"host": "fuchsia.googlesource.com",
"id": "b3ea9f4abadfe34a0b99b1760bf9aaa2eeb09c38",
"project": "integration"
}
},
"clang_toolchain": {
"git_repository": "https://llvm.googlesource.com/llvm-project",
"git_revision": "1343e68862874f1baf2d8523320e2278fcb9df32"
}
},
"tags": [
"buildset:commit/gitiles/llvm.googlesource.com/llvm-project/+/1343e68862874f1baf2d8523320e2278fcb9df32",
"gitiles_ref:refs/heads/main",
"parent_buildername:clang-linux-x64",
"user_agent:recipe"
]
},
"id": "c15bf42a-4e2f-45ad-9339-cf4f622fee8b",
"title": "clang-linux-x64/0"
},
{
"buildbucket": {
"properties": {
"$fuchsia/checkout": {
"clang_toolchain": {
"cas_digest": "69442a5a4415c5db28010a51ec6c542527de1b78a8d510bc39a922db496eac3f/578",
"cas_instance": "projects/chromium-swarm/instances/default_instance"
},
"gitiles_commit": {
"host": "fuchsia.googlesource.com",
"id": "b3ea9f4abadfe34a0b99b1760bf9aaa2eeb09c38",
"project": "integration"
}
},
"clang_toolchain": {
"git_repository": "https://llvm.googlesource.com/llvm-project",
"git_revision": "554459a02f2a2064d8f3b567670a436cfda11aaf"
}
},
"tags": [
"buildset:commit/gitiles/llvm.googlesource.com/llvm-project/+/554459a02f2a2064d8f3b567670a436cfda11aaf",
"gitiles_ref:refs/heads/main",
"parent_buildername:clang-linux-x64",
"user_agent:recipe"
]
},
"id": "d578ce16-7a3d-462c-a20b-3afdc2c93c6c",
"title": "clang-linux-x64/0"
},
{
"buildbucket": {
"properties": {
"$fuchsia/checkout": {
"clang_toolchain": {
"cas_digest": "7ab7abb543d79055d29671dd1c9c77026e0744328d47d139b1eb4ea20f5620ae/578",
"cas_instance": "projects/chromium-swarm/instances/default_instance"
},
"gitiles_commit": {
"host": "fuchsia.googlesource.com",
"id": "b3ea9f4abadfe34a0b99b1760bf9aaa2eeb09c38",
"project": "integration"
}
},
"clang_toolchain": {
"git_repository": "https://llvm.googlesource.com/llvm-project",
"git_revision": "294eecd4cbd8a1e0dcc0cdbe1238817b92ba7668"
}
},
"tags": [
"buildset:commit/gitiles/llvm.googlesource.com/llvm-project/+/294eecd4cbd8a1e0dcc0cdbe1238817b92ba7668",
"gitiles_ref:refs/heads/main",
"parent_buildername:clang-linux-x64",
"user_agent:recipe"
]
},
"id": "da7e13ab-fb75-40d1-be14-6d021d02d2af",
"title": "clang-linux-x64/0"
}
]
},
"clang_toolchain": {
"git_repository": "https://llvm.googlesource.com/llvm-project",
"git_revision": "294eecd4cbd8a1e0dcc0cdbe1238817b92ba7668"
}
},
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "294eecd4cbd8a1e0dcc0cdbe1238817b92ba7668",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8914307130898083632"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/clang_toolchain.ci.core.arm64-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"
}
}
[10:14:24.601] Scheduled build:
{
"id": "8748877068959494497",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "clang_toolchain.ci.core.arm64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2024-05-04T10:14:24.145386051Z",
"updateTime": "2024-05-04T10:14:24.145386051Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "294eecd4cbd8a1e0dcc0cdbe1238817b92ba7668",
"ref": "refs/heads/main"
}
}
}
[10:14:24.601] Task URL: https://cr-buildbucket.appspot.com/build/8748877068959494497
[10:14:24.601] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914307130898083632:2:0) after 5m51s
[10:14:24.960] Received PubSub notification, asking Buildbucket for the build status
[10:14:24.990] Build status: SCHEDULED
[10:14:32.269] Received PubSub notification, asking Buildbucket for the build status
[10:14:32.303] Build status: STARTED
[10:20:15.876] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914307130898083632:2:0)
[10:20:15.894] Build status: STARTED
[10:20:15.894] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914307130898083632:5:0) after 2m35s
[10:22:50.911] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914307130898083632:5:0)
[10:22:50.954] Build status: STARTED
[10:22:50.954] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914307130898083632:6:0) after 3m18s
[10:26:09.078] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914307130898083632:6:0)
[10:26:09.094] Build status: STARTED
[10:26:09.094] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914307130898083632:7:0) after 2m40s
[10:28:49.124] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914307130898083632:7:0)
[10:28:49.161] Build status: STARTED
[10:28:49.161] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914307130898083632:8:0) after 7m37s
[10:36:26.320] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914307130898083632:8:0)
[10:36:26.339] Build status: STARTED
[10:36:26.339] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914307130898083632:9:0) after 3m53s
[10:40:19.775] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914307130898083632:9:0)
[10:40:19.815] Build status: STARTED
[10:40:19.815] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914307130898083632:10:0) after 3m42s
[10:44:01.832] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914307130898083632:10:0)
[10:44:01.856] Build status: STARTED
[10:44:01.856] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914307130898083632:11:0) after 7m26s
[10:51:27.876] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914307130898083632:11:0)
[10:51:27.976] Build status: STARTED
[10:51:27.976] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914307130898083632:12:0) after 8m15s
[10:59:43.288] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914307130898083632:12:0)
[10:59:43.319] Build status: STARTED
[10:59:43.319] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914307130898083632:13:0) after 3m9s
[11:02:52.342] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914307130898083632:13:0)
[11:02:52.425] Build status: STARTED
[11:02:52.425] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914307130898083632:14:0) after 3m9s
[11:06:01.474] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914307130898083632:14:0)
[11:06:01.575] Build status: STARTED
[11:06:01.575] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914307130898083632:15:0) after 1m13s
[11:07:14.676] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914307130898083632:15:0)
[11:07:14.696] Build status: STARTED
[11:07:14.696] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914307130898083632:16:0) after 5m47s
[11:13:01.714] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914307130898083632:16:0)
[11:13:01.737] Build status: STARTED
[11:13:01.737] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914307130898083632:17:0) after 7m14s
[11:20:15.771] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914307130898083632:17:0)
[11:20:15.833] Build status: STARTED
[11:20:15.833] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914307130898083632:18:0) after 5m58s
[11:26:13.931] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914307130898083632:18:0)
[11:26:13.983] Build status: STARTED
[11:26:13.983] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914307130898083632:19:0) after 9m49s
[11:36:03.035] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914307130898083632:19:0)
[11:36:03.063] Build status: STARTED
[11:36:03.063] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914307130898083632:20:0) after 2m30s
[11:38:33.108] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914307130898083632:20:0)
[11:38:33.126] Build status: STARTED
[11:38:33.126] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914307130898083632:21:0) after 8m42s
[11:47:15.181] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914307130898083632:21:0)
[11:47:15.227] Build status: STARTED
[11:47:15.227] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914307130898083632:22:0) after 3m57s
[11:51:12.255] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914307130898083632:22:0)
[11:51:12.281] Build status: STARTED
[11:51:12.281] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914307130898083632:23:0) after 2m9s
[11:53:21.378] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914307130898083632:23:0)
[11:53:21.406] Build status: STARTED
[11:53:21.406] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914307130898083632:24:0) after 2m56s
[11:56:17.466] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914307130898083632:24:0)
[11:56:17.488] Build status: STARTED
[11:56:17.488] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914307130898083632:25:0) after 6m58s
[12:03:15.590] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914307130898083632:25:0)
[12:03:15.614] Build status: STARTED
[12:03:15.614] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914307130898083632:26:0) after 2m51s
[12:06:06.631] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914307130898083632:26:0)
[12:06:06.657] Build status: STARTED
[12:06:06.657] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914307130898083632:27:0) after 6m46s
[12:12:52.966] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914307130898083632:27:0)
[12:12:52.992] Build status: STARTED
[12:12:52.992] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914307130898083632:28:0) after 3m4s
[12:15:57.027] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914307130898083632:28:0)
[12:15:57.060] Build status: STARTED
[12:15:57.060] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914307130898083632:29:0) after 2m39s
[12:18:36.227] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914307130898083632:29:0)
[12:18:36.254] Build status: STARTED
[12:18:36.255] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914307130898083632:30:0) after 2m21s
[12:20:57.495] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914307130898083632:30:0)
[12:20:57.516] Build status: STARTED
[12:20:57.516] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914307130898083632:31:0) after 5m4s
[12:26:01.549] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914307130898083632:31:0)
[12:26:01.573] Build status: STARTED
[12:26:01.573] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914307130898083632:32:0) after 1m35s
[12:27:36.598] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914307130898083632:32:0)
[12:27:36.621] Build status: STARTED
[12:27:36.621] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914307130898083632:33:0) after 8m5s
[12:35:41.765] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914307130898083632:33:0)
[12:35:41.793] Build status: STARTED
[12:35:41.793] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914307130898083632:34:0) after 6m34s
[12:42:15.873] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914307130898083632:34:0)
[12:42:15.894] Build status: STARTED
[12:42:15.894] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914307130898083632:35:0) after 1m9s
[12:43:24.920] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914307130898083632:35:0)
[12:43:24.981] Build status: STARTED
[12:43:24.981] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914307130898083632:36:0) after 6m21s
[12:49:46.191] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914307130898083632:36:0)
[12:49:46.210] Build status: STARTED
[12:49:46.210] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914307130898083632:37:0) after 7m59s
[12:57:32.843] Received PubSub notification, asking Buildbucket for the build status
[12:57:32.910] Build:
{
"id": "8748877068959494497",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "clang_toolchain.ci.core.arm64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2024-05-04T10:14:24.145386051Z",
"startTime": "2024-05-04T10:14:30.177314436Z",
"endTime": "2024-05-04T12:57:32.429256116Z",
"updateTime": "2024-05-04T12:57:32.429256116Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "294eecd4cbd8a1e0dcc0cdbe1238817b92ba7668",
"ref": "refs/heads/main"
}
}
}
[12:57:32.910] Invocation finished in 2h43m9.997576943s with status SUCCEEDED