[00:54:53.565] New invocation is queued and will start shortly
[00:54:54.694] Starting the invocation (attempt 1)
[00:54:54.719] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/cc108333f1bc56caf986798d43e41b8e3c033ee7"
[00:54:54.719] Popped gitiles commit info from properties and tags
[00:54:54.719] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:54:54.719] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:54:54.719] Buildbucket request:
{
"requestId": "8865691759969154256",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "kythe"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8865691759969154256",
"job": "fuchsia/global.ci-kythe-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "50a5ded343823709778355bc0de94942b6e48898"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@50a5ded343823709778355bc0de94942b6e48898",
"title": "50a5ded343823709778355bc0de94942b6e48898",
"url": "https://fuchsia.googlesource.com/integration/+/50a5ded343823709778355bc0de94942b6e48898"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "a62d2cc8ce708c2e009d3124f14551e12d0859c8"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@a62d2cc8ce708c2e009d3124f14551e12d0859c8",
"title": "a62d2cc8ce708c2e009d3124f14551e12d0859c8",
"url": "https://fuchsia.googlesource.com/integration/+/a62d2cc8ce708c2e009d3124f14551e12d0859c8"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "4432527db9c64fd3245b01b7cdce414d1be81489"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@4432527db9c64fd3245b01b7cdce414d1be81489",
"title": "4432527db9c64fd3245b01b7cdce414d1be81489",
"url": "https://fuchsia.googlesource.com/integration/+/4432527db9c64fd3245b01b7cdce414d1be81489"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "fe8d7fdd989f06e9e562bb6002e5eb749ada74f3"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@fe8d7fdd989f06e9e562bb6002e5eb749ada74f3",
"title": "fe8d7fdd989f06e9e562bb6002e5eb749ada74f3",
"url": "https://fuchsia.googlesource.com/integration/+/fe8d7fdd989f06e9e562bb6002e5eb749ada74f3"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "37ddad279c0a1966ed959abd6ddea09f02888064"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@37ddad279c0a1966ed959abd6ddea09f02888064",
"title": "37ddad279c0a1966ed959abd6ddea09f02888064",
"url": "https://fuchsia.googlesource.com/integration/+/37ddad279c0a1966ed959abd6ddea09f02888064"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "cc108333f1bc56caf986798d43e41b8e3c033ee7"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@cc108333f1bc56caf986798d43e41b8e3c033ee7",
"title": "cc108333f1bc56caf986798d43e41b8e3c033ee7",
"url": "https://fuchsia.googlesource.com/integration/+/cc108333f1bc56caf986798d43e41b8e3c033ee7"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "cc108333f1bc56caf986798d43e41b8e3c033ee7",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8865691759969154256"
},
{
"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"
}
}
[00:54:55.245] Scheduled build:
{
"id": "8700261698038824145",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "kythe"
},
"createdBy": "project:fuchsia",
"createTime": "2025-10-23T00:54:54.775518658Z",
"updateTime": "2025-10-23T00:54:54.775518658Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "cc108333f1bc56caf986798d43e41b8e3c033ee7",
"ref": "refs/heads/main"
}
},
"canary": true
}
[00:54:55.245] Task URL: https://cr-buildbucket.appspot.com/build/8700261698038824145
[00:54:55.245] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8865691759969154256:2:0) after 6m45s
[00:54:55.480] Received PubSub notification, asking Buildbucket for the build status
[00:54:55.502] Build status: SCHEDULED
[00:54:59.156] Received PubSub notification, asking Buildbucket for the build status
[00:54:59.203] Build status: STARTED
[01:01:40.324] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8865691759969154256:2:0)
[01:01:40.340] Build status: STARTED
[01:01:40.340] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8865691759969154256:5:0) after 9m31s
[01:11:11.410] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8865691759969154256:5:0)
[01:11:11.450] Build status: STARTED
[01:11:11.450] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8865691759969154256:6:0) after 6m9s
[01:17:20.465] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8865691759969154256:6:0)
[01:17:20.481] Build status: STARTED
[01:17:20.481] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8865691759969154256:7:0) after 9m40s
[01:27:00.515] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8865691759969154256:7:0)
[01:27:00.569] Build status: STARTED
[01:27:00.569] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8865691759969154256:8:0) after 6m20s
[01:33:20.627] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8865691759969154256:8:0)
[01:33:20.651] Build status: STARTED
[01:33:20.651] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8865691759969154256:9:0) after 5m12s
[01:38:32.653] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8865691759969154256:9:0)
[01:38:32.667] Build status: STARTED
[01:38:32.667] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8865691759969154256:10:0) after 5m34s
[01:44:06.679] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8865691759969154256:10:0)
[01:44:06.703] Build status: STARTED
[01:44:06.703] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8865691759969154256:11:0) after 3m27s
[01:47:33.746] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8865691759969154256:11:0)
[01:47:33.762] Build status: STARTED
[01:47:33.762] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8865691759969154256:12:0) after 1m7s
[01:48:40.777] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8865691759969154256:12:0)
[01:48:40.800] Build status: STARTED
[01:48:40.800] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8865691759969154256:13:0) after 9m44s
[01:58:24.813] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8865691759969154256:13:0)
[01:58:24.825] Build status: STARTED
[01:58:24.826] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8865691759969154256:14:0) after 5m14s
[02:03:38.833] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8865691759969154256:14:0)
[02:03:38.850] Build status: STARTED
[02:03:38.850] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8865691759969154256:15:0) after 5m5s
[02:08:43.912] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8865691759969154256:15:0)
[02:08:43.930] Build status: STARTED
[02:08:43.930] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8865691759969154256:16:0) after 5m19s
[02:14:02.941] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8865691759969154256:16:0)
[02:14:02.959] Build status: STARTED
[02:14:02.959] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8865691759969154256:17:0) after 2m9s
[02:16:11.976] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8865691759969154256:17:0)
[02:16:12.012] Build status: STARTED
[02:16:12.012] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8865691759969154256:18:0) after 7m43s
[02:23:55.026] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8865691759969154256:18:0)
[02:23:55.047] Build status: STARTED
[02:23:55.047] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8865691759969154256:19:0) after 6m41s
[02:30:36.153] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8865691759969154256:19:0)
[02:30:36.176] Build status: STARTED
[02:30:36.177] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8865691759969154256:20:0) after 7m26s
[02:32:19.069] Received PubSub notification, asking Buildbucket for the build status
[02:32:19.083] Build:
{
"id": "8700261698038824145",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "kythe"
},
"createdBy": "project:fuchsia",
"createTime": "2025-10-23T00:54:54.775518658Z",
"startTime": "2025-10-23T00:54:58.944716688Z",
"endTime": "2025-10-23T02:32:12.240765824Z",
"updateTime": "2025-10-23T02:32:12.240765824Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "cc108333f1bc56caf986798d43e41b8e3c033ee7",
"ref": "refs/heads/main"
}
},
"canary": true
}
[02:32:19.083] Invocation finished in 1h37m25.532916818s with status SUCCEEDED