[14:58:19.247] New invocation is queued and will start shortly
[14:58:20.359] Starting the invocation (attempt 1)
[14:58:20.386] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a7b58f7f5082385bc8fb929be54b435301015656"
[14:58:20.386] Popped gitiles commit info from properties and tags
[14:58:20.386] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:58:20.386] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:58:20.387] Buildbucket request:
{
"requestId": "8882580328775412448",
"builder": {
"project": "chromium-m136",
"bucket": "ci",
"builder": "win32-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8882580328775412448",
"job": "chromium-m136/win32-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "53abf9f639e415580420eb2e6228946550832999"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7103@53abf9f639e415580420eb2e6228946550832999",
"title": "53abf9f639e415580420eb2e6228946550832999",
"url": "https://chromium.googlesource.com/chromium/src/+/53abf9f639e415580420eb2e6228946550832999"
},
{
"gitiles": {
"ref": "refs/branch-heads/7103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "02bac55eacb6ad399436cb66c59726145b1042e2"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7103@02bac55eacb6ad399436cb66c59726145b1042e2",
"title": "02bac55eacb6ad399436cb66c59726145b1042e2",
"url": "https://chromium.googlesource.com/chromium/src/+/02bac55eacb6ad399436cb66c59726145b1042e2"
},
{
"gitiles": {
"ref": "refs/branch-heads/7103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "f78fd2b9c633aa5929850c0311837fd2a162509e"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7103@f78fd2b9c633aa5929850c0311837fd2a162509e",
"title": "f78fd2b9c633aa5929850c0311837fd2a162509e",
"url": "https://chromium.googlesource.com/chromium/src/+/f78fd2b9c633aa5929850c0311837fd2a162509e"
},
{
"gitiles": {
"ref": "refs/branch-heads/7103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "bff5647c1fce40a85b7fba503e3b86ddb8ecd3db"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7103@bff5647c1fce40a85b7fba503e3b86ddb8ecd3db",
"title": "bff5647c1fce40a85b7fba503e3b86ddb8ecd3db",
"url": "https://chromium.googlesource.com/chromium/src/+/bff5647c1fce40a85b7fba503e3b86ddb8ecd3db"
},
{
"gitiles": {
"ref": "refs/branch-heads/7103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "f203129b9d1f7d0ec0755f3d018718d7f134c7bb"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7103@f203129b9d1f7d0ec0755f3d018718d7f134c7bb",
"title": "f203129b9d1f7d0ec0755f3d018718d7f134c7bb",
"url": "https://chromium.googlesource.com/chromium/src/+/f203129b9d1f7d0ec0755f3d018718d7f134c7bb"
},
{
"gitiles": {
"ref": "refs/branch-heads/7103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "67fc838b7f3d30adabd53e699ba5bd482133d70d"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7103@67fc838b7f3d30adabd53e699ba5bd482133d70d",
"title": "67fc838b7f3d30adabd53e699ba5bd482133d70d",
"url": "https://chromium.googlesource.com/chromium/src/+/67fc838b7f3d30adabd53e699ba5bd482133d70d"
},
{
"gitiles": {
"ref": "refs/branch-heads/7103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "85628021390084fafa7e7900335910b0d5919c1a"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7103@85628021390084fafa7e7900335910b0d5919c1a",
"title": "85628021390084fafa7e7900335910b0d5919c1a",
"url": "https://chromium.googlesource.com/chromium/src/+/85628021390084fafa7e7900335910b0d5919c1a"
},
{
"gitiles": {
"ref": "refs/branch-heads/7103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "a7b58f7f5082385bc8fb929be54b435301015656"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7103@a7b58f7f5082385bc8fb929be54b435301015656",
"title": "a7b58f7f5082385bc8fb929be54b435301015656",
"url": "https://chromium.googlesource.com/chromium/src/+/a7b58f7f5082385bc8fb929be54b435301015656"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "a7b58f7f5082385bc8fb929be54b435301015656",
"ref": "refs/branch-heads/7103"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8882580328775412448"
},
{
"key": "scheduler_job_id",
"value": "chromium-m136/win32-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[14:58:21.036] Scheduled build:
{
"id": "8717150266870606161",
"builder": {
"project": "chromium-m136",
"bucket": "ci",
"builder": "win32-official"
},
"number": 118,
"createdBy": "project:chromium-m136",
"createTime": "2025-04-19T14:58:20.439141131Z",
"updateTime": "2025-04-19T14:58:20.439141131Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "a7b58f7f5082385bc8fb929be54b435301015656",
"ref": "refs/branch-heads/7103"
}
}
}
[14:58:21.036] Task URL: https://cr-buildbucket.appspot.com/build/8717150266870606161
[14:58:21.036] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882580328775412448:2:0) after 7m33s
[14:58:21.846] Received PubSub notification, asking Buildbucket for the build status
[14:58:21.868] Build status: SCHEDULED
[14:59:47.138] Received PubSub notification, asking Buildbucket for the build status
[14:59:47.164] Build status: STARTED
[15:05:54.081] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882580328775412448:2:0)
[15:05:54.103] Build status: STARTED
[15:05:54.103] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882580328775412448:5:0) after 6m12s
[15:12:06.221] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882580328775412448:5:0)
[15:12:06.246] Build status: STARTED
[15:12:06.246] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882580328775412448:6:0) after 8m38s
[15:20:44.559] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882580328775412448:6:0)
[15:20:44.586] Build status: STARTED
[15:20:44.586] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882580328775412448:7:0) after 5m31s
[15:26:15.745] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882580328775412448:7:0)
[15:26:15.768] Build status: STARTED
[15:26:15.768] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882580328775412448:8:0) after 2m33s
[15:28:48.772] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882580328775412448:8:0)
[15:28:48.794] Build status: STARTED
[15:28:48.794] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882580328775412448:9:0) after 6m37s
[15:35:25.848] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882580328775412448:9:0)
[15:35:25.903] Build status: STARTED
[15:35:25.903] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882580328775412448:10:0) after 1m45s
[15:37:10.955] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882580328775412448:10:0)
[15:37:10.979] Build status: STARTED
[15:37:10.979] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882580328775412448:11:0) after 9m57s
[15:47:07.998] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882580328775412448:11:0)
[15:47:08.025] Build status: STARTED
[15:47:08.025] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882580328775412448:12:0) after 9m41s
[15:56:49.094] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882580328775412448:12:0)
[15:56:49.314] Build status: STARTED
[15:56:49.314] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882580328775412448:13:0) after 5m48s
[16:02:37.331] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882580328775412448:13:0)
[16:02:37.357] Build status: STARTED
[16:02:37.357] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882580328775412448:14:0) after 2m27s
[16:05:04.467] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882580328775412448:14:0)
[16:05:04.493] Build status: STARTED
[16:05:04.493] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882580328775412448:15:0) after 3m16s
[16:08:20.828] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882580328775412448:15:0)
[16:08:20.849] Build status: STARTED
[16:08:20.849] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882580328775412448:16:0) after 7m59s
[16:16:19.885] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882580328775412448:16:0)
[16:16:19.906] Build status: STARTED
[16:16:19.906] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882580328775412448:17:0) after 8m26s
[16:24:45.930] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882580328775412448:17:0)
[16:24:45.955] Build status: STARTED
[16:24:45.955] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882580328775412448:18:0) after 1m9s
[16:25:54.939] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882580328775412448:18:0)
[16:25:54.967] Build status: STARTED
[16:25:54.967] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882580328775412448:19:0) after 6m14s
[16:32:08.983] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882580328775412448:19:0)
[16:32:09.071] Build status: STARTED
[16:32:09.071] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882580328775412448:20:0) after 6m58s
[16:39:07.453] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882580328775412448:20:0)
[16:39:07.479] Build status: STARTED
[16:39:07.479] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882580328775412448:21:0) after 5m38s
[16:44:45.526] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882580328775412448:21:0)
[16:44:45.547] Build status: STARTED
[16:44:45.547] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882580328775412448:22:0) after 8m45s
[16:53:30.747] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882580328775412448:22:0)
[16:53:30.769] Build status: STARTED
[16:53:30.769] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882580328775412448:23:0) after 9m36s
[17:03:07.054] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882580328775412448:23:0)
[17:03:07.082] Build status: STARTED
[17:03:07.082] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882580328775412448:24:0) after 9m4s
[17:12:11.098] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882580328775412448:24:0)
[17:12:11.187] Build status: STARTED
[17:12:11.187] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882580328775412448:25:0) after 2m46s
[17:14:57.749] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882580328775412448:25:0)
[17:14:57.779] Build status: STARTED
[17:14:57.779] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882580328775412448:26:0) after 8m48s
[17:23:46.002] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882580328775412448:26:0)
[17:23:46.022] Build status: STARTED
[17:23:46.022] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882580328775412448:27:0) after 5m56s
[17:29:42.040] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882580328775412448:27:0)
[17:29:42.058] Build status: STARTED
[17:29:42.058] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882580328775412448:28:0) after 7m17s
[17:36:59.081] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882580328775412448:28:0)
[17:36:59.108] Build status: STARTED
[17:36:59.108] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882580328775412448:29:0) after 3m0s
[17:39:59.139] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882580328775412448:29:0)
[17:39:59.156] Build status: STARTED
[17:39:59.156] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882580328775412448:30:0) after 8m37s
[17:48:36.174] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882580328775412448:30:0)
[17:48:36.200] Build status: STARTED
[17:48:36.200] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882580328775412448:31:0) after 3m2s
[17:51:38.417] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882580328775412448:31:0)
[17:51:38.439] Build status: STARTED
[17:51:38.439] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882580328775412448:32:0) after 7m52s
[17:59:30.606] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882580328775412448:32:0)
[17:59:30.681] Build status: STARTED
[17:59:30.681] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882580328775412448:33:0) after 6m54s
[18:06:24.702] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882580328775412448:33:0)
[18:06:24.733] Build status: STARTED
[18:06:24.733] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882580328775412448:34:0) after 2m30s
[18:08:55.054] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882580328775412448:34:0)
[18:08:55.085] Build status: STARTED
[18:08:55.085] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882580328775412448:35:0) after 1m56s
[18:10:51.235] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882580328775412448:35:0)
[18:10:51.259] Build status: STARTED
[18:10:51.260] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882580328775412448:36:0) after 1m42s
[18:12:33.562] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882580328775412448:36:0)
[18:12:33.591] Build status: STARTED
[18:12:33.591] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882580328775412448:37:0) after 7m29s
[18:15:32.862] Received PubSub notification, asking Buildbucket for the build status
[18:15:32.887] Build:
{
"id": "8717150266870606161",
"builder": {
"project": "chromium-m136",
"bucket": "ci",
"builder": "win32-official"
},
"number": 118,
"createdBy": "project:chromium-m136",
"createTime": "2025-04-19T14:58:20.439141131Z",
"startTime": "2025-04-19T14:59:46.574412800Z",
"endTime": "2025-04-19T18:15:30.183959959Z",
"updateTime": "2025-04-19T18:15:30.183959959Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "a7b58f7f5082385bc8fb929be54b435301015656",
"ref": "refs/branch-heads/7103"
}
}
}
[18:15:32.887] Invocation finished in 3h17m13.648445734s with status SUCCEEDED