[04:32:38.380] New invocation is queued and will start shortly
[04:32:39.531] Starting the invocation (attempt 1)
[04:32:39.552] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1a90f9431518fd3752366f6ee0a75ce5bfdd4767"
[04:32:39.552] Popped gitiles commit info from properties and tags
[04:32:39.553] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[04:32:39.553] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[04:32:39.554] Buildbucket request:
{
"requestId": "8883706856832575904",
"builder": {
"project": "chromium-m136",
"bucket": "ci",
"builder": "win32-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8883706856832575904",
"job": "chromium-m136/win32-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "ff7b600fa7776187782571b3f6e61ccc38ebb401"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7103@ff7b600fa7776187782571b3f6e61ccc38ebb401",
"title": "ff7b600fa7776187782571b3f6e61ccc38ebb401",
"url": "https://chromium.googlesource.com/chromium/src/+/ff7b600fa7776187782571b3f6e61ccc38ebb401"
},
{
"gitiles": {
"ref": "refs/branch-heads/7103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "94c77cbc0cbac4cbff6c5b603a9feb494542ae21"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7103@94c77cbc0cbac4cbff6c5b603a9feb494542ae21",
"title": "94c77cbc0cbac4cbff6c5b603a9feb494542ae21",
"url": "https://chromium.googlesource.com/chromium/src/+/94c77cbc0cbac4cbff6c5b603a9feb494542ae21"
},
{
"gitiles": {
"ref": "refs/branch-heads/7103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "fa02d900d8b240f49c79b201ee0360978f783ea6"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7103@fa02d900d8b240f49c79b201ee0360978f783ea6",
"title": "fa02d900d8b240f49c79b201ee0360978f783ea6",
"url": "https://chromium.googlesource.com/chromium/src/+/fa02d900d8b240f49c79b201ee0360978f783ea6"
},
{
"gitiles": {
"ref": "refs/branch-heads/7103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "9cc9805a346bcf57dc9def7566572b290f03cff4"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7103@9cc9805a346bcf57dc9def7566572b290f03cff4",
"title": "9cc9805a346bcf57dc9def7566572b290f03cff4",
"url": "https://chromium.googlesource.com/chromium/src/+/9cc9805a346bcf57dc9def7566572b290f03cff4"
},
{
"gitiles": {
"ref": "refs/branch-heads/7103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "c5fd90c6b96dceb2d78cfbf57f1defb09743c667"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7103@c5fd90c6b96dceb2d78cfbf57f1defb09743c667",
"title": "c5fd90c6b96dceb2d78cfbf57f1defb09743c667",
"url": "https://chromium.googlesource.com/chromium/src/+/c5fd90c6b96dceb2d78cfbf57f1defb09743c667"
},
{
"gitiles": {
"ref": "refs/branch-heads/7103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "541876356db757440443377ed032a9c6bdcfeb2c"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7103@541876356db757440443377ed032a9c6bdcfeb2c",
"title": "541876356db757440443377ed032a9c6bdcfeb2c",
"url": "https://chromium.googlesource.com/chromium/src/+/541876356db757440443377ed032a9c6bdcfeb2c"
},
{
"gitiles": {
"ref": "refs/branch-heads/7103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "68012fef309d6e69d5507007ae5fcff483cc8b8f"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7103@68012fef309d6e69d5507007ae5fcff483cc8b8f",
"title": "68012fef309d6e69d5507007ae5fcff483cc8b8f",
"url": "https://chromium.googlesource.com/chromium/src/+/68012fef309d6e69d5507007ae5fcff483cc8b8f"
},
{
"gitiles": {
"ref": "refs/branch-heads/7103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "1a90f9431518fd3752366f6ee0a75ce5bfdd4767"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7103@1a90f9431518fd3752366f6ee0a75ce5bfdd4767",
"title": "1a90f9431518fd3752366f6ee0a75ce5bfdd4767",
"url": "https://chromium.googlesource.com/chromium/src/+/1a90f9431518fd3752366f6ee0a75ce5bfdd4767"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "1a90f9431518fd3752366f6ee0a75ce5bfdd4767",
"ref": "refs/branch-heads/7103"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8883706856832575904"
},
{
"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"
}
}
[04:32:39.982] Scheduled build:
{
"id": "8718276794883613985",
"builder": {
"project": "chromium-m136",
"bucket": "ci",
"builder": "win32-official"
},
"number": 36,
"createdBy": "project:chromium-m136",
"createTime": "2025-04-07T04:32:39.606292022Z",
"updateTime": "2025-04-07T04:32:39.606292022Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "1a90f9431518fd3752366f6ee0a75ce5bfdd4767",
"ref": "refs/branch-heads/7103"
}
}
}
[04:32:39.982] Task URL: https://cr-buildbucket.appspot.com/build/8718276794883613985
[04:32:39.982] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883706856832575904:2:0) after 6m1s
[04:32:41.311] Received PubSub notification, asking Buildbucket for the build status
[04:32:41.352] Build status: SCHEDULED
[04:33:02.454] Received PubSub notification, asking Buildbucket for the build status
[04:33:02.478] Build status: STARTED
[04:38:40.996] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883706856832575904:2:0)
[04:38:41.013] Build status: STARTED
[04:38:41.013] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883706856832575904:5:0) after 9m11s
[04:47:52.035] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883706856832575904:5:0)
[04:47:52.061] Build status: STARTED
[04:47:52.061] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883706856832575904:6:0) after 7m35s
[04:55:27.062] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883706856832575904:6:0)
[04:55:27.084] Build status: STARTED
[04:55:27.084] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883706856832575904:7:0) after 6m55s
[05:02:22.287] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883706856832575904:7:0)
[05:02:22.306] Build status: STARTED
[05:02:22.306] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883706856832575904:8:0) after 2m4s
[05:04:26.324] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883706856832575904:8:0)
[05:04:26.373] Build status: STARTED
[05:04:26.373] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883706856832575904:9:0) after 3m14s
[05:07:40.440] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883706856832575904:9:0)
[05:07:40.455] Build status: STARTED
[05:07:40.455] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883706856832575904:10:0) after 8m5s
[05:15:45.511] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883706856832575904:10:0)
[05:15:45.527] Build status: STARTED
[05:15:45.527] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883706856832575904:11:0) after 1m4s
[05:16:49.583] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883706856832575904:11:0)
[05:16:49.599] Build status: STARTED
[05:16:49.599] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883706856832575904:12:0) after 2m15s
[05:19:05.026] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883706856832575904:12:0)
[05:19:05.092] Build status: STARTED
[05:19:05.093] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883706856832575904:13:0) after 3m40s
[05:22:45.266] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883706856832575904:13:0)
[05:22:45.287] Build status: STARTED
[05:22:45.287] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883706856832575904:14:0) after 8m14s
[05:30:59.413] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883706856832575904:14:0)
[05:30:59.429] Build status: STARTED
[05:30:59.429] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883706856832575904:15:0) after 9m15s
[05:40:14.552] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883706856832575904:15:0)
[05:40:14.568] Build status: STARTED
[05:40:14.568] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883706856832575904:16:0) after 5m49s
[05:46:03.584] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883706856832575904:16:0)
[05:46:03.607] Build status: STARTED
[05:46:03.607] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883706856832575904:17:0) after 8m6s
[05:54:10.062] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883706856832575904:17:0)
[05:54:10.115] Build status: STARTED
[05:54:10.115] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883706856832575904:18:0) after 6m34s
[06:00:44.289] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883706856832575904:18:0)
[06:00:44.306] Build status: STARTED
[06:00:44.306] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883706856832575904:19:0) after 3m4s
[06:03:48.597] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883706856832575904:19:0)
[06:03:48.614] Build status: STARTED
[06:03:48.614] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883706856832575904:20:0) after 3m1s
[06:06:49.845] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883706856832575904:20:0)
[06:06:49.908] Build status: STARTED
[06:06:49.908] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883706856832575904:21:0) after 6m24s
[06:13:13.993] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883706856832575904:21:0)
[06:13:14.012] Build status: STARTED
[06:13:14.012] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883706856832575904:22:0) after 3m56s
[06:17:10.279] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883706856832575904:22:0)
[06:17:10.301] Build status: STARTED
[06:17:10.301] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883706856832575904:23:0) after 1m39s
[06:18:49.448] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883706856832575904:23:0)
[06:18:49.467] Build status: STARTED
[06:18:49.467] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883706856832575904:24:0) after 3m58s
[06:22:47.741] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883706856832575904:24:0)
[06:22:47.768] Build status: STARTED
[06:22:47.768] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883706856832575904:25:0) after 4m28s
[06:27:16.015] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883706856832575904:25:0)
[06:27:16.046] Build status: STARTED
[06:27:16.046] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883706856832575904:26:0) after 6m7s
[06:33:23.085] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883706856832575904:26:0)
[06:33:23.102] Build status: STARTED
[06:33:23.102] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883706856832575904:27:0) after 2m23s
[06:35:46.104] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883706856832575904:27:0)
[06:35:46.187] Build status: STARTED
[06:35:46.187] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883706856832575904:28:0) after 7m6s
[06:42:52.209] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883706856832575904:28:0)
[06:42:52.235] Build status: STARTED
[06:42:52.235] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883706856832575904:29:0) after 3m10s
[06:46:02.433] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883706856832575904:29:0)
[06:46:02.494] Build status: STARTED
[06:46:02.494] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883706856832575904:30:0) after 7m58s
[06:54:00.577] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883706856832575904:30:0)
[06:54:00.650] Build status: STARTED
[06:54:00.650] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883706856832575904:31:0) after 6m30s
[07:00:30.679] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883706856832575904:31:0)
[07:00:30.736] Build status: STARTED
[07:00:30.736] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883706856832575904:32:0) after 3m59s
[07:04:29.995] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883706856832575904:32:0)
[07:04:30.025] Build status: STARTED
[07:04:30.025] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883706856832575904:33:0) after 4m43s
[07:09:13.243] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883706856832575904:33:0)
[07:09:13.275] Build status: STARTED
[07:09:13.275] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883706856832575904:34:0) after 6m32s
[07:15:45.476] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883706856832575904:34:0)
[07:15:45.493] Build status: STARTED
[07:15:45.493] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883706856832575904:35:0) after 3m33s
[07:19:18.556] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883706856832575904:35:0)
[07:19:18.570] Build status: STARTED
[07:19:18.570] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883706856832575904:36:0) after 8m55s
[07:28:13.645] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883706856832575904:36:0)
[07:28:13.661] Build status: STARTED
[07:28:13.661] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883706856832575904:37:0) after 2m24s
[07:30:37.682] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883706856832575904:37:0)
[07:30:37.709] Build status: STARTED
[07:30:37.709] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883706856832575904:38:0) after 8m44s
[07:39:21.765] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883706856832575904:38:0)
[07:39:21.824] Build status: STARTED
[07:39:21.824] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883706856832575904:39:0) after 3m2s
[07:42:24.011] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883706856832575904:39:0)
[07:42:24.034] Build status: STARTED
[07:42:24.034] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883706856832575904:40:0) after 2m31s
[07:44:55.059] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883706856832575904:40:0)
[07:44:55.124] Build status: STARTED
[07:44:55.124] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883706856832575904:41:0) after 7m28s
[07:52:23.147] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883706856832575904:41:0)
[07:52:23.203] Build status: STARTED
[07:52:23.203] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883706856832575904:42:0) after 8m2s
[08:00:25.225] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883706856832575904:42:0)
[08:00:25.247] Build status: STARTED
[08:00:25.247] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883706856832575904:43:0) after 3m23s
[08:00:41.915] Received PubSub notification, asking Buildbucket for the build status
[08:00:41.934] Build:
{
"id": "8718276794883613985",
"builder": {
"project": "chromium-m136",
"bucket": "ci",
"builder": "win32-official"
},
"number": 36,
"createdBy": "project:chromium-m136",
"createTime": "2025-04-07T04:32:39.606292022Z",
"startTime": "2025-04-07T04:33:02.222846962Z",
"endTime": "2025-04-07T08:00:41.587582591Z",
"updateTime": "2025-04-07T08:00:41.587582591Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "1a90f9431518fd3752366f6ee0a75ce5bfdd4767",
"ref": "refs/branch-heads/7103"
}
}
}
[08:00:41.934] Invocation finished in 3h28m3.569552996s with status SUCCEEDED