[13:48:59.112] New invocation is queued and will start shortly
[13:49:00.338] Starting the invocation (attempt 1)
[13:49:00.361] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/485c3ac7da1a98c77b1382c0068550bb3361b5a9"
[13:49:00.362] Popped gitiles commit info from properties and tags
[13:49:00.362] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[13:49:00.362] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[13:49:00.362] Buildbucket request:
{
"requestId": "8860026046359290672",
"builder": {
"project": "chromium-m142",
"bucket": "ci",
"builder": "win32-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8860026046359290672",
"job": "chromium-m142/win32-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7444",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "6d5c95b117d61e9ec0d8e0877ae92dc75ff147c7"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7444@6d5c95b117d61e9ec0d8e0877ae92dc75ff147c7",
"title": "6d5c95b117d61e9ec0d8e0877ae92dc75ff147c7",
"url": "https://chromium.googlesource.com/chromium/src/+/6d5c95b117d61e9ec0d8e0877ae92dc75ff147c7"
},
{
"gitiles": {
"ref": "refs/branch-heads/7444",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "c2ed8fd391bcf3a0aeac3156966847c5a6c106bd"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7444@c2ed8fd391bcf3a0aeac3156966847c5a6c106bd",
"title": "c2ed8fd391bcf3a0aeac3156966847c5a6c106bd",
"url": "https://chromium.googlesource.com/chromium/src/+/c2ed8fd391bcf3a0aeac3156966847c5a6c106bd"
},
{
"gitiles": {
"ref": "refs/branch-heads/7444",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "485c3ac7da1a98c77b1382c0068550bb3361b5a9"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7444@485c3ac7da1a98c77b1382c0068550bb3361b5a9",
"title": "485c3ac7da1a98c77b1382c0068550bb3361b5a9",
"url": "https://chromium.googlesource.com/chromium/src/+/485c3ac7da1a98c77b1382c0068550bb3361b5a9"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "485c3ac7da1a98c77b1382c0068550bb3361b5a9",
"ref": "refs/branch-heads/7444"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8860026046359290672"
},
{
"key": "scheduler_job_id",
"value": "chromium-m142/win32-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[13:49:00.849] Scheduled build:
{
"id": "8694595984333910081",
"builder": {
"project": "chromium-m142",
"bucket": "ci",
"builder": "win32-official"
},
"number": 537,
"createdBy": "project:chromium-m142",
"createTime": "2025-12-24T13:49:00.419192306Z",
"updateTime": "2025-12-24T13:49:00.419192306Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "485c3ac7da1a98c77b1382c0068550bb3361b5a9",
"ref": "refs/branch-heads/7444"
}
}
}
[13:49:00.849] Task URL: https://cr-buildbucket.appspot.com/build/8694595984333910081
[13:49:00.849] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8860026046359290672:2:0) after 3m47s
[13:49:01.774] Received PubSub notification, asking Buildbucket for the build status
[13:49:01.788] Build status: SCHEDULED
[13:49:04.887] Received PubSub notification, asking Buildbucket for the build status
[13:49:04.903] Build status: STARTED
[13:52:47.966] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8860026046359290672:2:0)
[13:52:48.021] Build status: STARTED
[13:52:48.021] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8860026046359290672:5:0) after 4m9s
[13:56:57.174] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8860026046359290672:5:0)
[13:56:57.208] Build status: STARTED
[13:56:57.208] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8860026046359290672:6:0) after 1m54s
[13:58:51.224] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8860026046359290672:6:0)
[13:58:51.247] Build status: STARTED
[13:58:51.247] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8860026046359290672:7:0) after 7m45s
[14:06:36.291] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8860026046359290672:7:0)
[14:06:36.346] Build status: STARTED
[14:06:36.346] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8860026046359290672:8:0) after 5m22s
[14:11:58.364] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8860026046359290672:8:0)
[14:11:58.380] Build status: STARTED
[14:11:58.380] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8860026046359290672:9:0) after 9m27s
[14:21:26.183] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8860026046359290672:9:0)
[14:21:26.205] Build status: STARTED
[14:21:26.205] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8860026046359290672:10:0) after 1m30s
[14:22:56.586] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8860026046359290672:10:0)
[14:22:56.614] Build status: STARTED
[14:22:56.615] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8860026046359290672:11:0) after 8m9s
[14:31:05.635] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8860026046359290672:11:0)
[14:31:05.720] Build status: STARTED
[14:31:05.720] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8860026046359290672:12:0) after 6m54s
[14:37:59.733] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8860026046359290672:12:0)
[14:37:59.751] Build status: STARTED
[14:37:59.751] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8860026046359290672:13:0) after 7m44s
[14:45:43.889] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8860026046359290672:13:0)
[14:45:43.921] Build status: STARTED
[14:45:43.921] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8860026046359290672:14:0) after 5m34s
[14:51:17.934] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8860026046359290672:14:0)
[14:51:17.960] Build status: STARTED
[14:51:17.960] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8860026046359290672:15:0) after 5m53s
[14:57:11.064] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8860026046359290672:15:0)
[14:57:11.082] Build status: STARTED
[14:57:11.082] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8860026046359290672:16:0) after 4m33s
[15:01:44.097] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8860026046359290672:16:0)
[15:01:44.111] Build status: STARTED
[15:01:44.111] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8860026046359290672:17:0) after 7m5s
[15:08:49.344] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8860026046359290672:17:0)
[15:08:49.359] Build status: STARTED
[15:08:49.359] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8860026046359290672:18:0) after 2m2s
[15:10:51.373] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8860026046359290672:18:0)
[15:10:51.390] Build status: STARTED
[15:10:51.390] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8860026046359290672:19:0) after 9m44s
[15:20:35.630] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8860026046359290672:19:0)
[15:20:35.652] Build status: STARTED
[15:20:35.652] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8860026046359290672:20:0) after 7m21s
[15:27:56.667] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8860026046359290672:20:0)
[15:27:56.925] Build status: STARTED
[15:27:56.925] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8860026046359290672:21:0) after 2m1s
[15:29:57.949] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8860026046359290672:21:0)
[15:29:58.037] Build status: STARTED
[15:29:58.037] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8860026046359290672:22:0) after 4m53s
[15:34:51.052] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8860026046359290672:22:0)
[15:34:51.157] Build status: STARTED
[15:34:51.157] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8860026046359290672:23:0) after 4m6s
[15:38:57.170] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8860026046359290672:23:0)
[15:38:57.187] Build status: STARTED
[15:38:57.187] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8860026046359290672:24:0) after 5m12s
[15:44:09.200] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8860026046359290672:24:0)
[15:44:09.215] Build status: STARTED
[15:44:09.215] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8860026046359290672:25:0) after 2m10s
[15:46:19.577] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8860026046359290672:25:0)
[15:46:19.607] Build status: STARTED
[15:46:19.607] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8860026046359290672:26:0) after 2m6s
[15:48:25.621] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8860026046359290672:26:0)
[15:48:25.638] Build status: STARTED
[15:48:25.638] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8860026046359290672:27:0) after 4m8s
[15:52:33.723] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8860026046359290672:27:0)
[15:52:33.743] Build status: STARTED
[15:52:33.743] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8860026046359290672:28:0) after 7m49s
[16:00:22.862] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8860026046359290672:28:0)
[16:00:22.877] Build status: STARTED
[16:00:22.877] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8860026046359290672:29:0) after 4m27s
[16:04:49.896] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8860026046359290672:29:0)
[16:04:49.913] Build status: STARTED
[16:04:49.913] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8860026046359290672:30:0) after 5m19s
[16:10:08.927] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8860026046359290672:30:0)
[16:10:08.961] Build status: STARTED
[16:10:08.961] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8860026046359290672:31:0) after 9m58s
[16:20:07.155] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8860026046359290672:31:0)
[16:20:07.172] Build status: STARTED
[16:20:07.172] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8860026046359290672:32:0) after 7m18s
[16:27:25.193] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8860026046359290672:32:0)
[16:27:25.229] Build status: STARTED
[16:27:25.229] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8860026046359290672:33:0) after 8m17s
[16:35:42.318] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8860026046359290672:33:0)
[16:35:42.332] Build status: STARTED
[16:35:42.332] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8860026046359290672:34:0) after 9m8s
[16:44:50.349] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8860026046359290672:34:0)
[16:44:50.383] Build status: STARTED
[16:44:50.383] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8860026046359290672:35:0) after 3m47s
[16:48:37.549] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8860026046359290672:35:0)
[16:48:37.567] Build status: STARTED
[16:48:37.567] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8860026046359290672:36:0) after 3m23s
[16:52:00.645] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8860026046359290672:36:0)
[16:52:00.678] Build status: STARTED
[16:52:00.678] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8860026046359290672:37:0) after 5m5s
[16:57:05.740] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8860026046359290672:37:0)
[16:57:05.753] Build status: STARTED
[16:57:05.753] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8860026046359290672:38:0) after 9m13s
[17:06:18.757] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8860026046359290672:38:0)
[17:06:18.777] Build status: STARTED
[17:06:18.777] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8860026046359290672:39:0) after 9m32s
[17:15:50.794] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8860026046359290672:39:0)
[17:15:50.810] Build status: STARTED
[17:15:50.810] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8860026046359290672:40:0) after 5m50s
[17:21:40.871] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8860026046359290672:40:0)
[17:21:40.913] Build status: STARTED
[17:21:40.913] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8860026046359290672:41:0) after 4m17s
[17:22:33.577] Received PubSub notification, asking Buildbucket for the build status
[17:22:33.612] Build:
{
"id": "8694595984333910081",
"builder": {
"project": "chromium-m142",
"bucket": "ci",
"builder": "win32-official"
},
"number": 537,
"createdBy": "project:chromium-m142",
"createTime": "2025-12-24T13:49:00.419192306Z",
"startTime": "2025-12-24T13:49:04.736458069Z",
"endTime": "2025-12-24T17:22:33.246902477Z",
"updateTime": "2025-12-24T17:22:33.246902477Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "485c3ac7da1a98c77b1382c0068550bb3361b5a9",
"ref": "refs/branch-heads/7444"
}
}
}
[17:22:33.612] Invocation finished in 3h33m34.508830069s with status SUCCEEDED