[03:50:01.078] New invocation is queued and will start shortly
[03:50:02.880] Starting the invocation (attempt 1)
[03:50:02.906] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/74957099c2500f73b01686a6b4672750c7d24576"
[03:50:02.906] Popped gitiles commit info from properties and tags
[03:50:02.906] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[03:50:02.907] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[03:50:02.907] Buildbucket request:
{
"requestId": "8868761038898199312",
"builder": {
"project": "chromium-m141",
"bucket": "ci",
"builder": "win-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8868761038898199312",
"job": "chromium-m141/win-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7390",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "8fda3865ab9e817178bc0fbdd8c3eefe3e4c7183"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7390@8fda3865ab9e817178bc0fbdd8c3eefe3e4c7183",
"title": "8fda3865ab9e817178bc0fbdd8c3eefe3e4c7183",
"url": "https://chromium.googlesource.com/chromium/src/+/8fda3865ab9e817178bc0fbdd8c3eefe3e4c7183"
},
{
"gitiles": {
"ref": "refs/branch-heads/7390",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "90650d03428332abfa34486a3c8cb9b4122ebe2f"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7390@90650d03428332abfa34486a3c8cb9b4122ebe2f",
"title": "90650d03428332abfa34486a3c8cb9b4122ebe2f",
"url": "https://chromium.googlesource.com/chromium/src/+/90650d03428332abfa34486a3c8cb9b4122ebe2f"
},
{
"gitiles": {
"ref": "refs/branch-heads/7390",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "dd594d2bae2a4abc462a48faa968fb6a601463d6"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7390@dd594d2bae2a4abc462a48faa968fb6a601463d6",
"title": "dd594d2bae2a4abc462a48faa968fb6a601463d6",
"url": "https://chromium.googlesource.com/chromium/src/+/dd594d2bae2a4abc462a48faa968fb6a601463d6"
},
{
"gitiles": {
"ref": "refs/branch-heads/7390",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "fdd07e9200245663243f8263abc4da9a54ce03fe"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7390@fdd07e9200245663243f8263abc4da9a54ce03fe",
"title": "fdd07e9200245663243f8263abc4da9a54ce03fe",
"url": "https://chromium.googlesource.com/chromium/src/+/fdd07e9200245663243f8263abc4da9a54ce03fe"
},
{
"gitiles": {
"ref": "refs/branch-heads/7390",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "ba4b9cf41972ac909d69b82de838e6ce186ab6b2"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7390@ba4b9cf41972ac909d69b82de838e6ce186ab6b2",
"title": "ba4b9cf41972ac909d69b82de838e6ce186ab6b2",
"url": "https://chromium.googlesource.com/chromium/src/+/ba4b9cf41972ac909d69b82de838e6ce186ab6b2"
},
{
"gitiles": {
"ref": "refs/branch-heads/7390",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "04f877c53f8e01b84eb3d162b001c8bc8c5e8ef4"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7390@04f877c53f8e01b84eb3d162b001c8bc8c5e8ef4",
"title": "04f877c53f8e01b84eb3d162b001c8bc8c5e8ef4",
"url": "https://chromium.googlesource.com/chromium/src/+/04f877c53f8e01b84eb3d162b001c8bc8c5e8ef4"
},
{
"gitiles": {
"ref": "refs/branch-heads/7390",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "a5a7b5835b05276b039cc1473ed24f7974cd0818"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7390@a5a7b5835b05276b039cc1473ed24f7974cd0818",
"title": "a5a7b5835b05276b039cc1473ed24f7974cd0818",
"url": "https://chromium.googlesource.com/chromium/src/+/a5a7b5835b05276b039cc1473ed24f7974cd0818"
},
{
"gitiles": {
"ref": "refs/branch-heads/7390",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "74957099c2500f73b01686a6b4672750c7d24576"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7390@74957099c2500f73b01686a6b4672750c7d24576",
"title": "74957099c2500f73b01686a6b4672750c7d24576",
"url": "https://chromium.googlesource.com/chromium/src/+/74957099c2500f73b01686a6b4672750c7d24576"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "74957099c2500f73b01686a6b4672750c7d24576",
"ref": "refs/branch-heads/7390"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8868761038898199312"
},
{
"key": "scheduler_job_id",
"value": "chromium-m141/win-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[03:50:03.571] Scheduled build:
{
"id": "8703330976221663297",
"builder": {
"project": "chromium-m141",
"bucket": "ci",
"builder": "win-official"
},
"number": 138,
"createdBy": "project:chromium-m141",
"createTime": "2025-09-19T03:50:03.002813276Z",
"updateTime": "2025-09-19T03:50:03.002813276Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "74957099c2500f73b01686a6b4672750c7d24576",
"ref": "refs/branch-heads/7390"
}
}
}
[03:50:03.571] Task URL: https://cr-buildbucket.appspot.com/build/8703330976221663297
[03:50:03.571] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868761038898199312:2:0) after 8m16s
[03:50:24.059] Received PubSub notification, asking Buildbucket for the build status
[03:50:24.157] Build status: STARTED
[03:50:28.318] Received PubSub notification, asking Buildbucket for the build status
[03:50:28.338] Build status: STARTED
[03:58:19.618] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868761038898199312:2:0)
[03:58:19.635] Build status: STARTED
[03:58:19.635] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868761038898199312:5:0) after 1m31s
[03:59:50.700] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868761038898199312:5:0)
[03:59:50.722] Build status: STARTED
[03:59:50.722] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868761038898199312:6:0) after 2m31s
[04:02:21.735] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868761038898199312:6:0)
[04:02:21.809] Build status: STARTED
[04:02:21.810] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868761038898199312:7:0) after 5m39s
[04:08:00.822] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868761038898199312:7:0)
[04:08:00.885] Build status: STARTED
[04:08:00.885] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868761038898199312:8:0) after 2m26s
[04:10:26.986] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868761038898199312:8:0)
[04:10:27.045] Build status: STARTED
[04:10:27.045] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868761038898199312:9:0) after 1m20s
[04:11:47.057] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868761038898199312:9:0)
[04:11:47.279] Build status: STARTED
[04:11:47.279] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868761038898199312:10:0) after 5m2s
[04:16:49.292] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868761038898199312:10:0)
[04:16:49.315] Build status: STARTED
[04:16:49.315] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868761038898199312:11:0) after 3m26s
[04:20:15.334] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868761038898199312:11:0)
[04:20:15.355] Build status: STARTED
[04:20:15.355] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868761038898199312:12:0) after 3m25s
[04:23:40.386] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868761038898199312:12:0)
[04:23:40.418] Build status: STARTED
[04:23:40.418] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868761038898199312:13:0) after 3m10s
[04:26:50.433] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868761038898199312:13:0)
[04:26:50.495] Build status: STARTED
[04:26:50.495] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868761038898199312:14:0) after 4m53s
[04:31:43.546] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868761038898199312:14:0)
[04:31:43.570] Build status: STARTED
[04:31:43.570] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868761038898199312:15:0) after 4m58s
[04:36:41.567] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868761038898199312:15:0)
[04:36:41.585] Build status: STARTED
[04:36:41.585] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868761038898199312:16:0) after 4m39s
[04:41:20.617] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868761038898199312:16:0)
[04:41:20.709] Build status: STARTED
[04:41:20.709] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868761038898199312:17:0) after 7m40s
[04:49:00.728] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868761038898199312:17:0)
[04:49:00.848] Build status: STARTED
[04:49:00.848] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868761038898199312:18:0) after 4m11s
[04:53:12.056] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868761038898199312:18:0)
[04:53:12.268] Build status: STARTED
[04:53:12.268] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868761038898199312:19:0) after 5m32s
[04:58:44.362] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868761038898199312:19:0)
[04:58:44.385] Build status: STARTED
[04:58:44.385] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868761038898199312:20:0) after 2m56s
[05:01:40.401] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868761038898199312:20:0)
[05:01:40.421] Build status: STARTED
[05:01:40.421] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868761038898199312:21:0) after 4m55s
[05:06:35.611] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868761038898199312:21:0)
[05:06:35.634] Build status: STARTED
[05:06:35.634] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868761038898199312:22:0) after 5m58s
[05:12:33.699] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868761038898199312:22:0)
[05:12:33.718] Build status: STARTED
[05:12:33.718] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868761038898199312:23:0) after 8m29s
[05:21:02.763] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868761038898199312:23:0)
[05:21:02.780] Build status: STARTED
[05:21:02.780] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868761038898199312:24:0) after 7m28s
[05:28:30.747] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868761038898199312:24:0)
[05:28:30.770] Build status: STARTED
[05:28:30.770] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868761038898199312:25:0) after 5m31s
[05:34:01.847] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868761038898199312:25:0)
[05:34:01.877] Build status: STARTED
[05:34:01.877] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868761038898199312:26:0) after 1m2s
[05:35:03.945] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868761038898199312:26:0)
[05:35:03.961] Build status: STARTED
[05:35:03.961] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868761038898199312:27:0) after 3m9s
[05:38:12.953] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868761038898199312:27:0)
[05:38:12.969] Build status: STARTED
[05:38:12.969] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868761038898199312:28:0) after 8m50s
[05:47:03.063] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868761038898199312:28:0)
[05:47:03.083] Build status: STARTED
[05:47:03.084] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868761038898199312:29:0) after 3m0s
[05:50:03.311] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868761038898199312:29:0)
[05:50:03.327] Build status: STARTED
[05:50:03.327] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868761038898199312:30:0) after 5m9s
[05:55:12.351] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868761038898199312:30:0)
[05:55:12.370] Build status: STARTED
[05:55:12.370] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868761038898199312:31:0) after 2m47s
[05:57:59.384] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868761038898199312:31:0)
[05:57:59.403] Build status: STARTED
[05:57:59.403] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868761038898199312:32:0) after 7m45s
[06:05:44.418] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868761038898199312:32:0)
[06:05:44.436] Build status: STARTED
[06:05:44.436] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868761038898199312:33:0) after 2m36s
[06:08:20.531] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868761038898199312:33:0)
[06:08:20.552] Build status: STARTED
[06:08:20.552] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868761038898199312:34:0) after 8m3s
[06:16:23.571] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868761038898199312:34:0)
[06:16:23.598] Build status: STARTED
[06:16:23.598] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868761038898199312:35:0) after 1m25s
[06:17:48.572] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868761038898199312:35:0)
[06:17:48.590] Build status: STARTED
[06:17:48.590] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868761038898199312:36:0) after 7m58s
[06:25:46.625] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868761038898199312:36:0)
[06:25:46.640] Build status: STARTED
[06:25:46.640] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868761038898199312:37:0) after 8m49s
[06:31:01.620] Received PubSub notification, asking Buildbucket for the build status
[06:31:01.646] Build:
{
"id": "8703330976221663297",
"builder": {
"project": "chromium-m141",
"bucket": "ci",
"builder": "win-official"
},
"number": 138,
"createdBy": "project:chromium-m141",
"createTime": "2025-09-19T03:50:03.002813276Z",
"startTime": "2025-09-19T03:50:07.114975571Z",
"endTime": "2025-09-19T06:31:00.966388714Z",
"updateTime": "2025-09-19T06:31:00.966388714Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "74957099c2500f73b01686a6b4672750c7d24576",
"ref": "refs/branch-heads/7390"
}
}
}
[06:31:01.646] Invocation finished in 2h41m0.579946764s with status SUCCEEDED