[01:32:09.085] New invocation is queued and will start shortly
[01:32:10.269] Starting the invocation (attempt 1)
[01:32:10.296] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/b26dca05199c49f253467fa63dbd3b02f5d4dad8"
[01:32:10.296] Popped gitiles commit info from properties and tags
[01:32:10.296] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:32:10.296] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:32:10.297] Buildbucket request:
{
"requestId": "8851556289117684080",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "kythe"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8851556289117684080",
"job": "fuchsia/global.ci-kythe-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "d0174ddddf2ee3b1e1cb09f6689fa299ee2300cf"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@d0174ddddf2ee3b1e1cb09f6689fa299ee2300cf",
"title": "d0174ddddf2ee3b1e1cb09f6689fa299ee2300cf",
"url": "https://fuchsia.googlesource.com/integration/+/d0174ddddf2ee3b1e1cb09f6689fa299ee2300cf"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "1e1007be5c3307213c47713aacec263656a75b48"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@1e1007be5c3307213c47713aacec263656a75b48",
"title": "1e1007be5c3307213c47713aacec263656a75b48",
"url": "https://fuchsia.googlesource.com/integration/+/1e1007be5c3307213c47713aacec263656a75b48"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "a38c92b0c3d1b798adf45f914d205834b7666dfb"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@a38c92b0c3d1b798adf45f914d205834b7666dfb",
"title": "a38c92b0c3d1b798adf45f914d205834b7666dfb",
"url": "https://fuchsia.googlesource.com/integration/+/a38c92b0c3d1b798adf45f914d205834b7666dfb"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "5277e226ebc6906fcec850ac87db12e6b9c7de7f"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@5277e226ebc6906fcec850ac87db12e6b9c7de7f",
"title": "5277e226ebc6906fcec850ac87db12e6b9c7de7f",
"url": "https://fuchsia.googlesource.com/integration/+/5277e226ebc6906fcec850ac87db12e6b9c7de7f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "695e4a3e5c1274c575f5ba93c3f2905901899f07"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@695e4a3e5c1274c575f5ba93c3f2905901899f07",
"title": "695e4a3e5c1274c575f5ba93c3f2905901899f07",
"url": "https://fuchsia.googlesource.com/integration/+/695e4a3e5c1274c575f5ba93c3f2905901899f07"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "c1dbe3a8deb143a9f1a7f44be39910a58dc3a9ed"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@c1dbe3a8deb143a9f1a7f44be39910a58dc3a9ed",
"title": "c1dbe3a8deb143a9f1a7f44be39910a58dc3a9ed",
"url": "https://fuchsia.googlesource.com/integration/+/c1dbe3a8deb143a9f1a7f44be39910a58dc3a9ed"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "e9506b51a32b331d937efb18130c45efdff80ba3"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@e9506b51a32b331d937efb18130c45efdff80ba3",
"title": "e9506b51a32b331d937efb18130c45efdff80ba3",
"url": "https://fuchsia.googlesource.com/integration/+/e9506b51a32b331d937efb18130c45efdff80ba3"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "db9a0d58f1166958958a36c09f0644e53ed33809"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@db9a0d58f1166958958a36c09f0644e53ed33809",
"title": "db9a0d58f1166958958a36c09f0644e53ed33809",
"url": "https://fuchsia.googlesource.com/integration/+/db9a0d58f1166958958a36c09f0644e53ed33809"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "221b0b3e3ff5cbba102fa4f36a6672bc21a1679f"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@221b0b3e3ff5cbba102fa4f36a6672bc21a1679f",
"title": "221b0b3e3ff5cbba102fa4f36a6672bc21a1679f",
"url": "https://fuchsia.googlesource.com/integration/+/221b0b3e3ff5cbba102fa4f36a6672bc21a1679f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "1920e37804386d3023a11fb5fa20254d00b28eb7"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@1920e37804386d3023a11fb5fa20254d00b28eb7",
"title": "1920e37804386d3023a11fb5fa20254d00b28eb7",
"url": "https://fuchsia.googlesource.com/integration/+/1920e37804386d3023a11fb5fa20254d00b28eb7"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "24a66d608f1e268360d4fd61ce67498a72535750"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@24a66d608f1e268360d4fd61ce67498a72535750",
"title": "24a66d608f1e268360d4fd61ce67498a72535750",
"url": "https://fuchsia.googlesource.com/integration/+/24a66d608f1e268360d4fd61ce67498a72535750"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "01c4cdaeb8008232a6491603f892fa676c720a38"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@01c4cdaeb8008232a6491603f892fa676c720a38",
"title": "01c4cdaeb8008232a6491603f892fa676c720a38",
"url": "https://fuchsia.googlesource.com/integration/+/01c4cdaeb8008232a6491603f892fa676c720a38"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "fe2ba0fb16ade777bc2d08e75a3c71d6f35fae87"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@fe2ba0fb16ade777bc2d08e75a3c71d6f35fae87",
"title": "fe2ba0fb16ade777bc2d08e75a3c71d6f35fae87",
"url": "https://fuchsia.googlesource.com/integration/+/fe2ba0fb16ade777bc2d08e75a3c71d6f35fae87"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "c172f1bd0318433db615ad84966072ea015f4c06"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@c172f1bd0318433db615ad84966072ea015f4c06",
"title": "c172f1bd0318433db615ad84966072ea015f4c06",
"url": "https://fuchsia.googlesource.com/integration/+/c172f1bd0318433db615ad84966072ea015f4c06"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "7118332548e53fd1a081b0d8e6deae473df1b653"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@7118332548e53fd1a081b0d8e6deae473df1b653",
"title": "7118332548e53fd1a081b0d8e6deae473df1b653",
"url": "https://fuchsia.googlesource.com/integration/+/7118332548e53fd1a081b0d8e6deae473df1b653"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "27b24bcb613ed8ab45d49e525858856f99bebac9"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@27b24bcb613ed8ab45d49e525858856f99bebac9",
"title": "27b24bcb613ed8ab45d49e525858856f99bebac9",
"url": "https://fuchsia.googlesource.com/integration/+/27b24bcb613ed8ab45d49e525858856f99bebac9"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "b26dca05199c49f253467fa63dbd3b02f5d4dad8"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@b26dca05199c49f253467fa63dbd3b02f5d4dad8",
"title": "b26dca05199c49f253467fa63dbd3b02f5d4dad8",
"url": "https://fuchsia.googlesource.com/integration/+/b26dca05199c49f253467fa63dbd3b02f5d4dad8"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "b26dca05199c49f253467fa63dbd3b02f5d4dad8",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8851556289117684080"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/global.ci-kythe-e5a0ec7f"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[01:32:10.765] Scheduled build:
{
"id": "8686126227102429281",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "kythe"
},
"createdBy": "project:fuchsia",
"createTime": "2026-03-28T01:32:10.357066904Z",
"updateTime": "2026-03-28T01:32:10.357066904Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "b26dca05199c49f253467fa63dbd3b02f5d4dad8",
"ref": "refs/heads/main"
}
}
}
[01:32:10.765] Task URL: https://cr-buildbucket.appspot.com/build/8686126227102429281
[01:32:10.765] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8851556289117684080:2:0) after 3m2s
[01:32:11.624] Received PubSub notification, asking Buildbucket for the build status
[01:32:11.706] Build status: SCHEDULED
[01:32:14.466] Received PubSub notification, asking Buildbucket for the build status
[01:32:14.555] Build status: STARTED
[01:35:12.857] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8851556289117684080:2:0)
[01:35:12.873] Build status: STARTED
[01:35:12.873] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8851556289117684080:5:0) after 1m18s
[01:36:30.957] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8851556289117684080:5:0)
[01:36:30.986] Build status: STARTED
[01:36:30.986] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8851556289117684080:6:0) after 3m15s
[01:39:46.117] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8851556289117684080:6:0)
[01:39:46.145] Build status: STARTED
[01:39:46.146] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8851556289117684080:7:0) after 2m51s
[01:42:37.162] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8851556289117684080:7:0)
[01:42:37.196] Build status: STARTED
[01:42:37.196] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8851556289117684080:8:0) after 2m0s
[01:44:37.258] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8851556289117684080:8:0)
[01:44:37.288] Build status: STARTED
[01:44:37.288] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8851556289117684080:9:0) after 6m28s
[01:51:05.357] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8851556289117684080:9:0)
[01:51:05.432] Build status: STARTED
[01:51:05.432] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8851556289117684080:10:0) after 7m37s
[01:58:42.499] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8851556289117684080:10:0)
[01:58:42.517] Build status: STARTED
[01:58:42.517] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8851556289117684080:11:0) after 8m26s
[02:07:08.565] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8851556289117684080:11:0)
[02:07:08.587] Build status: STARTED
[02:07:08.587] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8851556289117684080:12:0) after 9m5s
[02:16:13.602] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8851556289117684080:12:0)
[02:16:13.633] Build status: STARTED
[02:16:13.633] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8851556289117684080:13:0) after 3m53s
[02:20:06.725] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8851556289117684080:13:0)
[02:20:06.742] Build status: STARTED
[02:20:06.742] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8851556289117684080:14:0) after 3m24s
[02:23:30.757] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8851556289117684080:14:0)
[02:23:30.774] Build status: STARTED
[02:23:30.774] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8851556289117684080:15:0) after 7m32s
[02:31:02.914] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8851556289117684080:15:0)
[02:31:02.934] Build status: STARTED
[02:31:02.934] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8851556289117684080:16:0) after 2m38s
[02:33:40.951] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8851556289117684080:16:0)
[02:33:41.034] Build status: STARTED
[02:33:41.034] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8851556289117684080:17:0) after 9m41s
[02:43:22.147] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8851556289117684080:17:0)
[02:43:22.169] Build status: STARTED
[02:43:22.170] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8851556289117684080:18:0) after 3m11s
[02:46:33.153] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8851556289117684080:18:0)
[02:46:33.172] Build status: STARTED
[02:46:33.172] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8851556289117684080:19:0) after 5m43s
[02:52:16.190] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8851556289117684080:19:0)
[02:52:16.282] Build status: STARTED
[02:52:16.282] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8851556289117684080:20:0) after 6m2s
[02:58:18.387] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8851556289117684080:20:0)
[02:58:18.426] Build status: STARTED
[02:58:18.426] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8851556289117684080:21:0) after 6m56s
[03:05:14.419] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8851556289117684080:21:0)
[03:05:14.439] Build status: STARTED
[03:05:14.439] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8851556289117684080:22:0) after 1m41s
[03:06:55.455] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8851556289117684080:22:0)
[03:06:55.473] Build status: STARTED
[03:06:55.473] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8851556289117684080:23:0) after 9m50s
[03:16:45.504] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8851556289117684080:23:0)
[03:16:45.535] Build status: STARTED
[03:16:45.535] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8851556289117684080:24:0) after 4m47s
[03:21:32.584] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8851556289117684080:24:0)
[03:21:32.638] Build status: STARTED
[03:21:32.638] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8851556289117684080:25:0) after 1m2s
[03:22:34.656] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8851556289117684080:25:0)
[03:22:34.691] Build status: STARTED
[03:22:34.692] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8851556289117684080:26:0) after 7m58s
[03:25:19.311] Received PubSub notification, asking Buildbucket for the build status
[03:25:19.337] Build:
{
"id": "8686126227102429281",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "kythe"
},
"createdBy": "project:fuchsia",
"createTime": "2026-03-28T01:32:10.357066904Z",
"startTime": "2026-03-28T01:32:14.255221932Z",
"endTime": "2026-03-28T03:25:18.786853223Z",
"updateTime": "2026-03-28T03:25:18.786853223Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "b26dca05199c49f253467fa63dbd3b02f5d4dad8",
"ref": "refs/heads/main"
}
}
}
[03:25:19.337] Invocation finished in 1h53m10.284997382s with status SUCCEEDED