[16:52:02.925] New invocation is queued and will start shortly
[16:52:04.945] Starting the invocation (attempt 1)
[16:52:04.977] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/04cdb282e24353aa3847ad9ba075a55fdb502e07"
[16:52:04.977] Popped gitiles commit info from properties and tags
[16:52:04.977] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:52:04.977] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:52:04.977] Buildbucket request:
{
"requestId": "8849867668756614704",
"builder": {
"project": "chromium-m147",
"bucket": "ci",
"builder": "android-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8849867668756614704",
"job": "chromium-m147/android-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7727",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "7755bf65c4e5d72142b12887791b14fc491d0a34"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7727@7755bf65c4e5d72142b12887791b14fc491d0a34",
"title": "7755bf65c4e5d72142b12887791b14fc491d0a34",
"url": "https://chromium.googlesource.com/chromium/src/+/7755bf65c4e5d72142b12887791b14fc491d0a34"
},
{
"gitiles": {
"ref": "refs/branch-heads/7727",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "30ed64373cc9836b4fa84d302de6b8effa65cf39"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7727@30ed64373cc9836b4fa84d302de6b8effa65cf39",
"title": "30ed64373cc9836b4fa84d302de6b8effa65cf39",
"url": "https://chromium.googlesource.com/chromium/src/+/30ed64373cc9836b4fa84d302de6b8effa65cf39"
},
{
"gitiles": {
"ref": "refs/branch-heads/7727",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "7fad85be6d0653831967a734f5248f16521863e8"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7727@7fad85be6d0653831967a734f5248f16521863e8",
"title": "7fad85be6d0653831967a734f5248f16521863e8",
"url": "https://chromium.googlesource.com/chromium/src/+/7fad85be6d0653831967a734f5248f16521863e8"
},
{
"gitiles": {
"ref": "refs/branch-heads/7727",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "e3939c44e29679e4c29b2a207bc32939bf301187"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7727@e3939c44e29679e4c29b2a207bc32939bf301187",
"title": "e3939c44e29679e4c29b2a207bc32939bf301187",
"url": "https://chromium.googlesource.com/chromium/src/+/e3939c44e29679e4c29b2a207bc32939bf301187"
},
{
"gitiles": {
"ref": "refs/branch-heads/7727",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "5698f67689c240437bbcce8d2d225b404c744fcf"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7727@5698f67689c240437bbcce8d2d225b404c744fcf",
"title": "5698f67689c240437bbcce8d2d225b404c744fcf",
"url": "https://chromium.googlesource.com/chromium/src/+/5698f67689c240437bbcce8d2d225b404c744fcf"
},
{
"gitiles": {
"ref": "refs/branch-heads/7727",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "94a938452811b2f0156a0056540a7ed3760de478"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7727@94a938452811b2f0156a0056540a7ed3760de478",
"title": "94a938452811b2f0156a0056540a7ed3760de478",
"url": "https://chromium.googlesource.com/chromium/src/+/94a938452811b2f0156a0056540a7ed3760de478"
},
{
"gitiles": {
"ref": "refs/branch-heads/7727",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "04cdb282e24353aa3847ad9ba075a55fdb502e07"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7727@04cdb282e24353aa3847ad9ba075a55fdb502e07",
"title": "04cdb282e24353aa3847ad9ba075a55fdb502e07",
"url": "https://chromium.googlesource.com/chromium/src/+/04cdb282e24353aa3847ad9ba075a55fdb502e07"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "04cdb282e24353aa3847ad9ba075a55fdb502e07",
"ref": "refs/branch-heads/7727"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8849867668756614704"
},
{
"key": "scheduler_job_id",
"value": "chromium-m147/android-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[16:52:05.745] Scheduled build:
{
"id": "8684437605881044577",
"builder": {
"project": "chromium-m147",
"bucket": "ci",
"builder": "android-official"
},
"number": 518,
"createdBy": "project:chromium-m147",
"createTime": "2026-04-15T16:52:05.046006304Z",
"updateTime": "2026-04-15T16:52:05.046006304Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "04cdb282e24353aa3847ad9ba075a55fdb502e07",
"ref": "refs/branch-heads/7727"
}
},
"canary": true
}
[16:52:05.745] Task URL: https://cr-buildbucket.appspot.com/build/8684437605881044577
[16:52:05.745] Scheduling timer "check-buildbucket-build-status" (chromium-m147/android-official:8849867668756614704:2:0) after 3m18s
[16:52:06.667] Received PubSub notification, asking Buildbucket for the build status
[16:52:06.683] Build status: SCHEDULED
[16:55:23.807] Handling timer "check-buildbucket-build-status" (chromium-m147/android-official:8849867668756614704:2:0)
[16:55:23.827] Build status: SCHEDULED
[16:55:23.827] Scheduling timer "check-buildbucket-build-status" (chromium-m147/android-official:8849867668756614704:4:0) after 1m6s
[16:56:29.976] Handling timer "check-buildbucket-build-status" (chromium-m147/android-official:8849867668756614704:4:0)
[16:56:29.997] Build status: SCHEDULED
[16:56:29.997] Scheduling timer "check-buildbucket-build-status" (chromium-m147/android-official:8849867668756614704:5:0) after 6m46s
[17:03:16.009] Handling timer "check-buildbucket-build-status" (chromium-m147/android-official:8849867668756614704:5:0)
[17:03:16.039] Build status: SCHEDULED
[17:03:16.039] Scheduling timer "check-buildbucket-build-status" (chromium-m147/android-official:8849867668756614704:6:0) after 6m2s
[17:09:18.054] Handling timer "check-buildbucket-build-status" (chromium-m147/android-official:8849867668756614704:6:0)
[17:09:18.071] Build status: SCHEDULED
[17:09:18.071] Scheduling timer "check-buildbucket-build-status" (chromium-m147/android-official:8849867668756614704:7:0) after 6m18s
[17:15:36.095] Handling timer "check-buildbucket-build-status" (chromium-m147/android-official:8849867668756614704:7:0)
[17:15:36.116] Build status: SCHEDULED
[17:15:36.116] Scheduling timer "check-buildbucket-build-status" (chromium-m147/android-official:8849867668756614704:8:0) after 8m18s
[17:23:54.196] Handling timer "check-buildbucket-build-status" (chromium-m147/android-official:8849867668756614704:8:0)
[17:23:54.221] Build status: SCHEDULED
[17:23:54.221] Scheduling timer "check-buildbucket-build-status" (chromium-m147/android-official:8849867668756614704:9:0) after 4m20s
[17:28:14.237] Handling timer "check-buildbucket-build-status" (chromium-m147/android-official:8849867668756614704:9:0)
[17:28:14.262] Build status: SCHEDULED
[17:28:14.262] Scheduling timer "check-buildbucket-build-status" (chromium-m147/android-official:8849867668756614704:10:0) after 4m55s
[17:33:09.349] Handling timer "check-buildbucket-build-status" (chromium-m147/android-official:8849867668756614704:10:0)
[17:33:09.372] Build status: SCHEDULED
[17:33:09.372] Scheduling timer "check-buildbucket-build-status" (chromium-m147/android-official:8849867668756614704:11:0) after 4m38s
[17:37:47.387] Handling timer "check-buildbucket-build-status" (chromium-m147/android-official:8849867668756614704:11:0)
[17:37:47.460] Build status: SCHEDULED
[17:37:47.460] Scheduling timer "check-buildbucket-build-status" (chromium-m147/android-official:8849867668756614704:12:0) after 9m43s
[17:47:30.650] Handling timer "check-buildbucket-build-status" (chromium-m147/android-official:8849867668756614704:12:0)
[17:47:30.667] Build status: SCHEDULED
[17:47:30.667] Scheduling timer "check-buildbucket-build-status" (chromium-m147/android-official:8849867668756614704:13:0) after 1m54s
[17:49:24.725] Handling timer "check-buildbucket-build-status" (chromium-m147/android-official:8849867668756614704:13:0)
[17:49:24.754] Build status: SCHEDULED
[17:49:24.755] Scheduling timer "check-buildbucket-build-status" (chromium-m147/android-official:8849867668756614704:14:0) after 1m12s
[17:50:36.783] Handling timer "check-buildbucket-build-status" (chromium-m147/android-official:8849867668756614704:14:0)
[17:50:36.800] Build status: SCHEDULED
[17:50:36.800] Scheduling timer "check-buildbucket-build-status" (chromium-m147/android-official:8849867668756614704:15:0) after 6m55s
[17:57:31.788] Handling timer "check-buildbucket-build-status" (chromium-m147/android-official:8849867668756614704:15:0)
[17:57:31.815] Build status: SCHEDULED
[17:57:31.815] Scheduling timer "check-buildbucket-build-status" (chromium-m147/android-official:8849867668756614704:16:0) after 4m41s
[18:02:12.976] Handling timer "check-buildbucket-build-status" (chromium-m147/android-official:8849867668756614704:16:0)
[18:02:12.996] Build status: SCHEDULED
[18:02:12.996] Scheduling timer "check-buildbucket-build-status" (chromium-m147/android-official:8849867668756614704:17:0) after 8m30s
[18:07:41.959] Received PubSub notification, asking Buildbucket for the build status
[18:07:41.981] Build status: STARTED
[18:10:42.996] Handling timer "check-buildbucket-build-status" (chromium-m147/android-official:8849867668756614704:17:0)
[18:10:43.017] Build status: STARTED
[18:10:43.017] Scheduling timer "check-buildbucket-build-status" (chromium-m147/android-official:8849867668756614704:19:0) after 4m6s
[18:14:49.034] Handling timer "check-buildbucket-build-status" (chromium-m147/android-official:8849867668756614704:19:0)
[18:14:49.059] Build status: STARTED
[18:14:49.059] Scheduling timer "check-buildbucket-build-status" (chromium-m147/android-official:8849867668756614704:20:0) after 4m47s
[18:19:36.082] Handling timer "check-buildbucket-build-status" (chromium-m147/android-official:8849867668756614704:20:0)
[18:19:36.106] Build status: STARTED
[18:19:36.106] Scheduling timer "check-buildbucket-build-status" (chromium-m147/android-official:8849867668756614704:21:0) after 4m29s
[18:24:05.221] Handling timer "check-buildbucket-build-status" (chromium-m147/android-official:8849867668756614704:21:0)
[18:24:05.284] Build status: STARTED
[18:24:05.284] Scheduling timer "check-buildbucket-build-status" (chromium-m147/android-official:8849867668756614704:22:0) after 9m2s
[18:33:07.370] Handling timer "check-buildbucket-build-status" (chromium-m147/android-official:8849867668756614704:22:0)
[18:33:07.389] Build status: STARTED
[18:33:07.389] Scheduling timer "check-buildbucket-build-status" (chromium-m147/android-official:8849867668756614704:23:0) after 4m58s
[18:38:05.469] Handling timer "check-buildbucket-build-status" (chromium-m147/android-official:8849867668756614704:23:0)
[18:38:05.531] Build status: STARTED
[18:38:05.531] Scheduling timer "check-buildbucket-build-status" (chromium-m147/android-official:8849867668756614704:24:0) after 8m19s
[18:46:24.549] Handling timer "check-buildbucket-build-status" (chromium-m147/android-official:8849867668756614704:24:0)
[18:46:24.568] Build status: STARTED
[18:46:24.568] Scheduling timer "check-buildbucket-build-status" (chromium-m147/android-official:8849867668756614704:25:0) after 2m35s
[18:48:59.679] Handling timer "check-buildbucket-build-status" (chromium-m147/android-official:8849867668756614704:25:0)
[18:48:59.701] Build status: STARTED
[18:48:59.701] Scheduling timer "check-buildbucket-build-status" (chromium-m147/android-official:8849867668756614704:26:0) after 5m50s
[18:54:49.987] Handling timer "check-buildbucket-build-status" (chromium-m147/android-official:8849867668756614704:26:0)
[18:54:50.010] Build status: STARTED
[18:54:50.010] Scheduling timer "check-buildbucket-build-status" (chromium-m147/android-official:8849867668756614704:27:0) after 8m2s
[19:02:52.037] Handling timer "check-buildbucket-build-status" (chromium-m147/android-official:8849867668756614704:27:0)
[19:02:52.105] Build status: STARTED
[19:02:52.106] Scheduling timer "check-buildbucket-build-status" (chromium-m147/android-official:8849867668756614704:28:0) after 7m15s
[19:10:07.208] Handling timer "check-buildbucket-build-status" (chromium-m147/android-official:8849867668756614704:28:0)
[19:10:07.318] Build status: STARTED
[19:10:07.318] Scheduling timer "check-buildbucket-build-status" (chromium-m147/android-official:8849867668756614704:29:0) after 5m9s
[19:15:16.337] Handling timer "check-buildbucket-build-status" (chromium-m147/android-official:8849867668756614704:29:0)
[19:15:16.368] Build status: STARTED
[19:15:16.368] Scheduling timer "check-buildbucket-build-status" (chromium-m147/android-official:8849867668756614704:30:0) after 6m32s
[19:21:48.389] Handling timer "check-buildbucket-build-status" (chromium-m147/android-official:8849867668756614704:30:0)
[19:21:48.410] Build status: STARTED
[19:21:48.411] Scheduling timer "check-buildbucket-build-status" (chromium-m147/android-official:8849867668756614704:31:0) after 3m47s
[19:25:35.475] Handling timer "check-buildbucket-build-status" (chromium-m147/android-official:8849867668756614704:31:0)
[19:25:35.494] Build status: STARTED
[19:25:35.494] Scheduling timer "check-buildbucket-build-status" (chromium-m147/android-official:8849867668756614704:32:0) after 2m24s
[19:27:59.524] Handling timer "check-buildbucket-build-status" (chromium-m147/android-official:8849867668756614704:32:0)
[19:27:59.547] Build status: STARTED
[19:27:59.547] Scheduling timer "check-buildbucket-build-status" (chromium-m147/android-official:8849867668756614704:33:0) after 2m35s
[19:30:34.564] Handling timer "check-buildbucket-build-status" (chromium-m147/android-official:8849867668756614704:33:0)
[19:30:34.585] Build status: STARTED
[19:30:34.585] Scheduling timer "check-buildbucket-build-status" (chromium-m147/android-official:8849867668756614704:34:0) after 4m46s
[19:35:20.639] Handling timer "check-buildbucket-build-status" (chromium-m147/android-official:8849867668756614704:34:0)
[19:35:20.660] Build status: STARTED
[19:35:20.660] Scheduling timer "check-buildbucket-build-status" (chromium-m147/android-official:8849867668756614704:35:0) after 4m46s
[19:40:06.725] Handling timer "check-buildbucket-build-status" (chromium-m147/android-official:8849867668756614704:35:0)
[19:40:06.788] Build status: STARTED
[19:40:06.788] Scheduling timer "check-buildbucket-build-status" (chromium-m147/android-official:8849867668756614704:36:0) after 8m21s
[19:48:27.903] Handling timer "check-buildbucket-build-status" (chromium-m147/android-official:8849867668756614704:36:0)
[19:48:27.919] Build status: STARTED
[19:48:27.919] Scheduling timer "check-buildbucket-build-status" (chromium-m147/android-official:8849867668756614704:37:0) after 3m47s
[19:52:15.032] Handling timer "check-buildbucket-build-status" (chromium-m147/android-official:8849867668756614704:37:0)
[19:52:15.055] Build status: STARTED
[19:52:15.055] Scheduling timer "check-buildbucket-build-status" (chromium-m147/android-official:8849867668756614704:38:0) after 9m23s
[20:01:38.038] Handling timer "check-buildbucket-build-status" (chromium-m147/android-official:8849867668756614704:38:0)
[20:01:38.070] Build status: STARTED
[20:01:38.070] Scheduling timer "check-buildbucket-build-status" (chromium-m147/android-official:8849867668756614704:39:0) after 9m59s
[20:11:37.155] Handling timer "check-buildbucket-build-status" (chromium-m147/android-official:8849867668756614704:39:0)
[20:11:37.237] Build status: STARTED
[20:11:37.238] Scheduling timer "check-buildbucket-build-status" (chromium-m147/android-official:8849867668756614704:40:0) after 5m54s
[20:17:31.302] Handling timer "check-buildbucket-build-status" (chromium-m147/android-official:8849867668756614704:40:0)
[20:17:31.380] Build status: STARTED
[20:17:31.380] Scheduling timer "check-buildbucket-build-status" (chromium-m147/android-official:8849867668756614704:41:0) after 6m31s
[20:24:02.359] Handling timer "check-buildbucket-build-status" (chromium-m147/android-official:8849867668756614704:41:0)
[20:24:02.386] Build status: STARTED
[20:24:02.387] Scheduling timer "check-buildbucket-build-status" (chromium-m147/android-official:8849867668756614704:42:0) after 5m48s
[20:28:09.456] Received PubSub notification, asking Buildbucket for the build status
[20:28:09.474] Build:
{
"id": "8684437605881044577",
"builder": {
"project": "chromium-m147",
"bucket": "ci",
"builder": "android-official"
},
"number": 518,
"createdBy": "project:chromium-m147",
"createTime": "2026-04-15T16:52:05.046006304Z",
"startTime": "2026-04-15T18:07:41.768458230Z",
"endTime": "2026-04-15T20:28:09.147300889Z",
"updateTime": "2026-04-15T20:28:09.147300889Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "04cdb282e24353aa3847ad9ba075a55fdb502e07",
"ref": "refs/branch-heads/7727"
}
},
"canary": true
}
[20:28:09.474] Invocation finished in 3h36m6.553518004s with status SUCCEEDED