[05:44:18.996] New invocation is queued and will start shortly
[05:44:20.051] Starting the invocation (attempt 1)
[05:44:20.083] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/642cd6b3cbae65b0b9e8fbd503e19d310144da58"
[05:44:20.083] Popped gitiles commit info from properties and tags
[05:44:20.083] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[05:44:20.083] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[05:44:20.084] Buildbucket request:
{
"requestId": "8866941908521202096",
"builder": {
"project": "chromium-m140",
"bucket": "ci",
"builder": "win-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8866941908521202096",
"job": "chromium-m140/win-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7339",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "5525719cf0aa9577fb55cdf3375d6515210cd4d2"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7339@5525719cf0aa9577fb55cdf3375d6515210cd4d2",
"title": "5525719cf0aa9577fb55cdf3375d6515210cd4d2",
"url": "https://chromium.googlesource.com/chromium/src/+/5525719cf0aa9577fb55cdf3375d6515210cd4d2"
},
{
"gitiles": {
"ref": "refs/branch-heads/7339",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "94810d599ef4bebeaff2df92ac5af6ae72bd4b7d"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7339@94810d599ef4bebeaff2df92ac5af6ae72bd4b7d",
"title": "94810d599ef4bebeaff2df92ac5af6ae72bd4b7d",
"url": "https://chromium.googlesource.com/chromium/src/+/94810d599ef4bebeaff2df92ac5af6ae72bd4b7d"
},
{
"gitiles": {
"ref": "refs/branch-heads/7339",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "3413d6290bf99c133a0f3d9c7186fc8feb12ade5"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7339@3413d6290bf99c133a0f3d9c7186fc8feb12ade5",
"title": "3413d6290bf99c133a0f3d9c7186fc8feb12ade5",
"url": "https://chromium.googlesource.com/chromium/src/+/3413d6290bf99c133a0f3d9c7186fc8feb12ade5"
},
{
"gitiles": {
"ref": "refs/branch-heads/7339",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "df33308b2c407703f7f2da0ce9bc559f640797b7"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7339@df33308b2c407703f7f2da0ce9bc559f640797b7",
"title": "df33308b2c407703f7f2da0ce9bc559f640797b7",
"url": "https://chromium.googlesource.com/chromium/src/+/df33308b2c407703f7f2da0ce9bc559f640797b7"
},
{
"gitiles": {
"ref": "refs/branch-heads/7339",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "095dbb2c29288be65975b1bc872418c024ae3e2a"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7339@095dbb2c29288be65975b1bc872418c024ae3e2a",
"title": "095dbb2c29288be65975b1bc872418c024ae3e2a",
"url": "https://chromium.googlesource.com/chromium/src/+/095dbb2c29288be65975b1bc872418c024ae3e2a"
},
{
"gitiles": {
"ref": "refs/branch-heads/7339",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "6aa41b0af9a2f5573c18775e5405a46805503055"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7339@6aa41b0af9a2f5573c18775e5405a46805503055",
"title": "6aa41b0af9a2f5573c18775e5405a46805503055",
"url": "https://chromium.googlesource.com/chromium/src/+/6aa41b0af9a2f5573c18775e5405a46805503055"
},
{
"gitiles": {
"ref": "refs/branch-heads/7339",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "d61f9179f0433959295b9cd2cb587a30b4fd6265"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7339@d61f9179f0433959295b9cd2cb587a30b4fd6265",
"title": "d61f9179f0433959295b9cd2cb587a30b4fd6265",
"url": "https://chromium.googlesource.com/chromium/src/+/d61f9179f0433959295b9cd2cb587a30b4fd6265"
},
{
"gitiles": {
"ref": "refs/branch-heads/7339",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "03a8767c1c0d0ff3183edbf1cdc72db7bf919dee"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7339@03a8767c1c0d0ff3183edbf1cdc72db7bf919dee",
"title": "03a8767c1c0d0ff3183edbf1cdc72db7bf919dee",
"url": "https://chromium.googlesource.com/chromium/src/+/03a8767c1c0d0ff3183edbf1cdc72db7bf919dee"
},
{
"gitiles": {
"ref": "refs/branch-heads/7339",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "642cd6b3cbae65b0b9e8fbd503e19d310144da58"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7339@642cd6b3cbae65b0b9e8fbd503e19d310144da58",
"title": "642cd6b3cbae65b0b9e8fbd503e19d310144da58",
"url": "https://chromium.googlesource.com/chromium/src/+/642cd6b3cbae65b0b9e8fbd503e19d310144da58"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "642cd6b3cbae65b0b9e8fbd503e19d310144da58",
"ref": "refs/branch-heads/7339"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8866941908521202096"
},
{
"key": "scheduler_job_id",
"value": "chromium-m140/win-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[05:44:20.752] Scheduled build:
{
"id": "8701511846630650097",
"builder": {
"project": "chromium-m140",
"bucket": "ci",
"builder": "win-official"
},
"number": 511,
"createdBy": "project:chromium-m140",
"createTime": "2025-10-09T05:44:20.171489926Z",
"updateTime": "2025-10-09T05:44:20.171489926Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "642cd6b3cbae65b0b9e8fbd503e19d310144da58",
"ref": "refs/branch-heads/7339"
}
}
}
[05:44:20.753] Task URL: https://cr-buildbucket.appspot.com/build/8701511846630650097
[05:44:20.753] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-official:8866941908521202096:2:0) after 8m48s
[05:44:47.428] Received PubSub notification, asking Buildbucket for the build status
[05:44:47.455] Build status: STARTED
[05:45:11.519] Received PubSub notification, asking Buildbucket for the build status
[05:45:11.537] Build status: STARTED
[05:53:08.817] Handling timer "check-buildbucket-build-status" (chromium-m140/win-official:8866941908521202096:2:0)
[05:53:08.831] Build status: STARTED
[05:53:08.831] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-official:8866941908521202096:5:0) after 5m27s
[05:58:36.000] Handling timer "check-buildbucket-build-status" (chromium-m140/win-official:8866941908521202096:5:0)
[05:58:36.023] Build status: STARTED
[05:58:36.023] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-official:8866941908521202096:6:0) after 9m18s
[06:07:54.049] Handling timer "check-buildbucket-build-status" (chromium-m140/win-official:8866941908521202096:6:0)
[06:07:54.065] Build status: STARTED
[06:07:54.065] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-official:8866941908521202096:7:0) after 7m31s
[06:15:25.041] Handling timer "check-buildbucket-build-status" (chromium-m140/win-official:8866941908521202096:7:0)
[06:15:25.065] Build status: STARTED
[06:15:25.065] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-official:8866941908521202096:8:0) after 4m22s
[06:19:47.081] Handling timer "check-buildbucket-build-status" (chromium-m140/win-official:8866941908521202096:8:0)
[06:19:47.099] Build status: STARTED
[06:19:47.099] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-official:8866941908521202096:9:0) after 2m29s
[06:22:16.113] Handling timer "check-buildbucket-build-status" (chromium-m140/win-official:8866941908521202096:9:0)
[06:22:16.128] Build status: STARTED
[06:22:16.128] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-official:8866941908521202096:10:0) after 7m34s
[06:29:50.223] Handling timer "check-buildbucket-build-status" (chromium-m140/win-official:8866941908521202096:10:0)
[06:29:50.242] Build status: STARTED
[06:29:50.242] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-official:8866941908521202096:11:0) after 4m36s
[06:34:26.259] Handling timer "check-buildbucket-build-status" (chromium-m140/win-official:8866941908521202096:11:0)
[06:34:26.282] Build status: STARTED
[06:34:26.282] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-official:8866941908521202096:12:0) after 4m1s
[06:38:27.298] Handling timer "check-buildbucket-build-status" (chromium-m140/win-official:8866941908521202096:12:0)
[06:38:27.315] Build status: STARTED
[06:38:27.315] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-official:8866941908521202096:13:0) after 4m11s
[06:42:38.362] Handling timer "check-buildbucket-build-status" (chromium-m140/win-official:8866941908521202096:13:0)
[06:42:38.425] Build status: STARTED
[06:42:38.425] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-official:8866941908521202096:14:0) after 6m19s
[06:48:57.545] Handling timer "check-buildbucket-build-status" (chromium-m140/win-official:8866941908521202096:14:0)
[06:48:57.560] Build status: STARTED
[06:48:57.560] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-official:8866941908521202096:15:0) after 6m31s
[06:55:28.584] Handling timer "check-buildbucket-build-status" (chromium-m140/win-official:8866941908521202096:15:0)
[06:55:28.601] Build status: STARTED
[06:55:28.601] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-official:8866941908521202096:16:0) after 2m23s
[06:57:51.617] Handling timer "check-buildbucket-build-status" (chromium-m140/win-official:8866941908521202096:16:0)
[06:57:51.632] Build status: STARTED
[06:57:51.632] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-official:8866941908521202096:17:0) after 3m50s
[07:01:41.677] Handling timer "check-buildbucket-build-status" (chromium-m140/win-official:8866941908521202096:17:0)
[07:01:41.762] Build status: STARTED
[07:01:41.762] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-official:8866941908521202096:18:0) after 4m48s
[07:06:29.783] Handling timer "check-buildbucket-build-status" (chromium-m140/win-official:8866941908521202096:18:0)
[07:06:29.804] Build status: STARTED
[07:06:29.804] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-official:8866941908521202096:19:0) after 2m13s
[07:08:42.817] Handling timer "check-buildbucket-build-status" (chromium-m140/win-official:8866941908521202096:19:0)
[07:08:42.874] Build status: STARTED
[07:08:42.874] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-official:8866941908521202096:20:0) after 9m9s
[07:17:51.959] Handling timer "check-buildbucket-build-status" (chromium-m140/win-official:8866941908521202096:20:0)
[07:17:51.975] Build status: STARTED
[07:17:51.975] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-official:8866941908521202096:21:0) after 9m24s
[07:27:16.107] Handling timer "check-buildbucket-build-status" (chromium-m140/win-official:8866941908521202096:21:0)
[07:27:16.127] Build status: STARTED
[07:27:16.127] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-official:8866941908521202096:22:0) after 3m26s
[07:30:42.170] Handling timer "check-buildbucket-build-status" (chromium-m140/win-official:8866941908521202096:22:0)
[07:30:42.189] Build status: STARTED
[07:30:42.189] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-official:8866941908521202096:23:0) after 3m8s
[07:33:50.206] Handling timer "check-buildbucket-build-status" (chromium-m140/win-official:8866941908521202096:23:0)
[07:33:50.227] Build status: STARTED
[07:33:50.227] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-official:8866941908521202096:24:0) after 8m18s
[07:42:08.242] Handling timer "check-buildbucket-build-status" (chromium-m140/win-official:8866941908521202096:24:0)
[07:42:08.257] Build status: STARTED
[07:42:08.257] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-official:8866941908521202096:25:0) after 3m12s
[07:45:20.274] Handling timer "check-buildbucket-build-status" (chromium-m140/win-official:8866941908521202096:25:0)
[07:45:20.290] Build status: STARTED
[07:45:20.290] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-official:8866941908521202096:26:0) after 9m29s
[07:54:49.319] Handling timer "check-buildbucket-build-status" (chromium-m140/win-official:8866941908521202096:26:0)
[07:54:49.451] Build status: STARTED
[07:54:49.451] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-official:8866941908521202096:27:0) after 8m4s
[08:02:53.573] Handling timer "check-buildbucket-build-status" (chromium-m140/win-official:8866941908521202096:27:0)
[08:02:53.593] Build status: STARTED
[08:02:53.593] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-official:8866941908521202096:28:0) after 3m14s
[08:06:07.570] Handling timer "check-buildbucket-build-status" (chromium-m140/win-official:8866941908521202096:28:0)
[08:06:07.707] Build status: STARTED
[08:06:07.707] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-official:8866941908521202096:29:0) after 6m10s
[08:12:17.710] Handling timer "check-buildbucket-build-status" (chromium-m140/win-official:8866941908521202096:29:0)
[08:12:17.747] Build status: STARTED
[08:12:17.747] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-official:8866941908521202096:30:0) after 9m18s
[08:21:35.762] Handling timer "check-buildbucket-build-status" (chromium-m140/win-official:8866941908521202096:30:0)
[08:21:35.822] Build status: STARTED
[08:21:35.823] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-official:8866941908521202096:31:0) after 4m53s
[08:26:28.863] Handling timer "check-buildbucket-build-status" (chromium-m140/win-official:8866941908521202096:31:0)
[08:26:28.882] Build status: STARTED
[08:26:28.882] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-official:8866941908521202096:32:0) after 8m32s
[08:35:00.894] Handling timer "check-buildbucket-build-status" (chromium-m140/win-official:8866941908521202096:32:0)
[08:35:00.920] Build status: STARTED
[08:35:00.920] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-official:8866941908521202096:33:0) after 2m27s
[08:37:27.947] Handling timer "check-buildbucket-build-status" (chromium-m140/win-official:8866941908521202096:33:0)
[08:37:27.973] Build status: STARTED
[08:37:27.973] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-official:8866941908521202096:34:0) after 2m6s
[08:39:34.093] Handling timer "check-buildbucket-build-status" (chromium-m140/win-official:8866941908521202096:34:0)
[08:39:34.128] Build status: STARTED
[08:39:34.128] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-official:8866941908521202096:35:0) after 6m12s
[08:45:46.226] Handling timer "check-buildbucket-build-status" (chromium-m140/win-official:8866941908521202096:35:0)
[08:45:46.253] Build status: STARTED
[08:45:46.253] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-official:8866941908521202096:36:0) after 5m41s
[08:51:27.263] Handling timer "check-buildbucket-build-status" (chromium-m140/win-official:8866941908521202096:36:0)
[08:51:27.288] Build status: STARTED
[08:51:27.288] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-official:8866941908521202096:37:0) after 5m58s
[08:55:58.034] Received PubSub notification, asking Buildbucket for the build status
[08:55:58.069] Build:
{
"id": "8701511846630650097",
"builder": {
"project": "chromium-m140",
"bucket": "ci",
"builder": "win-official"
},
"number": 511,
"createdBy": "project:chromium-m140",
"createTime": "2025-10-09T05:44:20.171489926Z",
"startTime": "2025-10-09T05:44:37.468363947Z",
"endTime": "2025-10-09T08:55:57.665654191Z",
"updateTime": "2025-10-09T08:55:57.665654191Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "642cd6b3cbae65b0b9e8fbd503e19d310144da58",
"ref": "refs/branch-heads/7339"
}
}
}
[08:55:58.069] Invocation finished in 3h11m39.084852832s with status SUCCEEDED