[01:31:36.581] New invocation is queued and will start shortly
[01:31:36.581] Triggered by user:fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com
[01:31:37.653] Starting the invocation (attempt 1)
[01:31:37.679] Popped buildset tag "buildset:commit/gitiles/llvm.googlesource.com/llvm-project/+/9eea7441831328a56bb0f91e7a15b7c575a42930"
[01:31:37.679] Reconstructed gitiles commit from tags
[01:31:37.679] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:31:37.679] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:31:37.680] Buildbucket request:
{
"requestId": "8928020162820294448",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "clang_toolchain.prod.core.x64-debug"
},
"properties": {
"$fuchsia/checkout": {
"clang_toolchain": {
"cas_digest": "81fc54f2e022a5faaf8131573c6cf212856f1d8d239c43714b562accf9f83f81/577"
},
"gitiles_commit": {
"host": "fuchsia.googlesource.com",
"id": "86c29ddb829aadda0a286bd7532fcb6025d82cb0",
"project": "integration"
}
},
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8928020162820294448",
"job": "fuchsia/clang_toolchain.prod.core.x64-debug",
"triggers": [
{
"buildbucket": {
"properties": {
"$fuchsia/checkout": {
"clang_toolchain": {
"cas_digest": "81fc54f2e022a5faaf8131573c6cf212856f1d8d239c43714b562accf9f83f81/577"
},
"gitiles_commit": {
"host": "fuchsia.googlesource.com",
"id": "86c29ddb829aadda0a286bd7532fcb6025d82cb0",
"project": "integration"
}
},
"clang_toolchain": {
"git_repository": "https://llvm.googlesource.com/llvm-project",
"git_revision": "9eea7441831328a56bb0f91e7a15b7c575a42930"
}
},
"tags": [
"buildset:commit/gitiles/llvm.googlesource.com/llvm-project/+/9eea7441831328a56bb0f91e7a15b7c575a42930",
"gitiles_ref:refs/heads/main",
"parent_buildername:clang-linux-x64",
"user_agent:recipe"
]
},
"id": "d575b08b-feae-4ac6-bae4-cae389802d79",
"title": "clang-linux-x64/0"
}
]
},
"clang_toolchain": {
"git_repository": "https://llvm.googlesource.com/llvm-project",
"git_revision": "9eea7441831328a56bb0f91e7a15b7c575a42930"
}
},
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "9eea7441831328a56bb0f91e7a15b7c575a42930",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8928020162820294448"
},
{
"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"
}
}
[01:31:38.141] Scheduled build:
{
"id": "8762590100943987825",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "clang_toolchain.prod.core.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2023-12-05T01:31:37.742687855Z",
"updateTime": "2023-12-05T01:31:37.742687855Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "9eea7441831328a56bb0f91e7a15b7c575a42930",
"ref": "refs/heads/main"
}
},
"canary": true
}
[01:31:38.141] Task URL: https://cr-buildbucket.appspot.com/build/8762590100943987825
[01:31:38.141] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8928020162820294448:2:0) after 5m10s
[01:31:39.124] Received PubSub notification, asking Buildbucket for the build status
[01:31:39.151] Build status: SCHEDULED
[01:33:31.158] Received PubSub notification, asking Buildbucket for the build status
[01:33:31.231] Build status: STARTED
[01:36:48.154] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8928020162820294448:2:0)
[01:36:48.181] Build status: STARTED
[01:36:48.181] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8928020162820294448:5:0) after 7m14s
[01:44:02.212] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8928020162820294448:5:0)
[01:44:02.234] Build status: STARTED
[01:44:02.234] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8928020162820294448:6:0) after 8m54s
[01:52:56.206] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8928020162820294448:6:0)
[01:52:56.284] Build status: STARTED
[01:52:56.284] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8928020162820294448:7:0) after 7m13s
[02:00:09.310] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8928020162820294448:7:0)
[02:00:09.335] Build status: STARTED
[02:00:09.335] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8928020162820294448:8:0) after 6m12s
[02:06:21.451] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8928020162820294448:8:0)
[02:06:21.475] Build status: STARTED
[02:06:21.475] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8928020162820294448:9:0) after 9m11s
[02:15:32.488] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8928020162820294448:9:0)
[02:15:32.529] Build status: STARTED
[02:15:32.529] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8928020162820294448:10:0) after 9m4s
[02:24:36.552] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8928020162820294448:10:0)
[02:24:36.572] Build status: STARTED
[02:24:36.572] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8928020162820294448:11:0) after 6m18s
[02:30:54.587] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8928020162820294448:11:0)
[02:30:54.609] Build status: STARTED
[02:30:54.609] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8928020162820294448:12:0) after 3m29s
[02:34:23.658] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8928020162820294448:12:0)
[02:34:23.709] Build status: STARTED
[02:34:23.709] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8928020162820294448:13:0) after 5m13s
[02:39:36.725] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8928020162820294448:13:0)
[02:39:36.747] Build status: STARTED
[02:39:36.747] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8928020162820294448:14:0) after 3m26s
[02:43:02.798] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8928020162820294448:14:0)
[02:43:02.815] Build status: STARTED
[02:43:02.815] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8928020162820294448:15:0) after 8m12s
[02:51:14.842] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8928020162820294448:15:0)
[02:51:14.950] Build status: STARTED
[02:51:14.950] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8928020162820294448:16:0) after 5m13s
[02:56:27.970] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8928020162820294448:16:0)
[02:56:27.999] Build status: STARTED
[02:56:27.999] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8928020162820294448:17:0) after 8m5s
[03:04:33.013] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8928020162820294448:17:0)
[03:04:33.033] Build status: STARTED
[03:04:33.033] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8928020162820294448:18:0) after 5m16s
[03:09:49.057] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8928020162820294448:18:0)
[03:09:49.090] Build status: STARTED
[03:09:49.091] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8928020162820294448:19:0) after 2m58s
[03:12:47.184] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8928020162820294448:19:0)
[03:12:47.222] Build status: STARTED
[03:12:47.222] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8928020162820294448:20:0) after 8m26s
[03:21:13.236] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8928020162820294448:20:0)
[03:21:13.312] Build status: STARTED
[03:21:13.312] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8928020162820294448:21:0) after 8m8s
[03:29:21.427] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8928020162820294448:21:0)
[03:29:21.527] Build:
{
"id": "8762590100943987825",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "clang_toolchain.prod.core.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2023-12-05T01:31:37.742687855Z",
"startTime": "2023-12-05T01:33:30.504360Z",
"endTime": "2023-12-05T03:29:10.898073Z",
"updateTime": "2023-12-05T03:29:10.898073Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "9eea7441831328a56bb0f91e7a15b7c575a42930",
"ref": "refs/heads/main"
}
},
"canary": true
}
[03:29:21.527] Invocation finished in 1h57m44.957882428s with status SUCCEEDED