[21:00:50.387] New invocation is queued and will start shortly
[21:00:51.471] Starting the invocation (attempt 1)
[21:00:51.504] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/fc224902bde09296885c99c9b6da8effc9bdd3f9"
[21:00:51.504] Popped gitiles commit info from properties and tags
[21:00:51.504] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:00:51.504] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:00:51.504] Buildbucket request:
{
"requestId": "8880745581725943536",
"builder": {
"project": "chromium",
"bucket": "build",
"builder": "win-build-perf-ci-siso"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "fc224902bde09296885c99c9b6da8effc9bdd3f9",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8880745581725943536"
},
{
"key": "scheduler_job_id",
"value": "chromium/win-build-perf-ci-siso"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[21:00:51.930] Scheduled build:
{
"id": "8715315519823862481",
"builder": {
"project": "chromium",
"bucket": "build",
"builder": "win-build-perf-ci-siso"
},
"number": 993,
"createdBy": "project:chromium",
"createTime": "2025-05-09T21:00:51.575102542Z",
"updateTime": "2025-05-09T21:00:51.575102542Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "fc224902bde09296885c99c9b6da8effc9bdd3f9",
"ref": "refs/heads/main"
}
}
}
[21:00:51.930] Task URL: https://cr-buildbucket.appspot.com/build/8715315519823862481
[21:00:51.930] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:2:0) after 8m26s
[21:00:52.979] Received PubSub notification, asking Buildbucket for the build status
[21:00:53.001] Build status: SCHEDULED
[21:00:56.057] Received PubSub notification, asking Buildbucket for the build status
[21:00:56.090] Build status: STARTED
[21:09:18.015] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:2:0)
[21:09:18.086] Build status: STARTED
[21:09:18.086] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:5:0) after 2m54s
[21:12:12.115] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:5:0)
[21:12:12.141] Build status: STARTED
[21:12:12.141] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:6:0) after 6m0s
[21:18:12.201] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:6:0)
[21:18:12.237] Build status: STARTED
[21:18:12.237] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:7:0) after 7m32s
[21:25:44.280] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:7:0)
[21:25:44.313] Build status: STARTED
[21:25:44.313] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:8:0) after 7m9s
[21:32:53.421] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:8:0)
[21:32:53.455] Build status: STARTED
[21:32:53.455] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:9:0) after 2m57s
[21:35:50.525] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:9:0)
[21:35:50.563] Build status: STARTED
[21:35:50.563] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:10:0) after 7m17s
[21:43:07.794] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:10:0)
[21:43:07.822] Build status: STARTED
[21:43:07.822] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:11:0) after 9m11s
[21:52:18.812] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:11:0)
[21:52:18.837] Build status: STARTED
[21:52:18.837] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:12:0) after 3m2s
[21:55:20.850] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:12:0)
[21:55:20.904] Build status: STARTED
[21:55:20.904] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:13:0) after 2m7s
[21:57:27.940] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:13:0)
[21:57:27.961] Build status: STARTED
[21:57:27.961] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:14:0) after 4m30s
[22:01:57.987] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:14:0)
[22:01:58.017] Build status: STARTED
[22:01:58.018] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:15:0) after 8m35s
[22:10:33.144] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:15:0)
[22:10:33.169] Build status: STARTED
[22:10:33.169] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:16:0) after 1m29s
[22:12:02.248] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:16:0)
[22:12:02.273] Build status: STARTED
[22:12:02.273] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:17:0) after 8m34s
[22:20:36.315] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:17:0)
[22:20:36.406] Build status: STARTED
[22:20:36.406] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:18:0) after 1m55s
[22:22:31.436] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:18:0)
[22:22:31.467] Build status: STARTED
[22:22:31.467] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:19:0) after 3m46s
[22:26:17.496] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:19:0)
[22:26:17.515] Build status: STARTED
[22:26:17.515] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:20:0) after 4m36s
[22:30:53.588] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:20:0)
[22:30:53.605] Build status: STARTED
[22:30:53.605] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:21:0) after 5m22s
[22:36:15.773] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:21:0)
[22:36:15.787] Build status: STARTED
[22:36:15.787] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:22:0) after 7m4s
[22:43:19.817] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:22:0)
[22:43:19.838] Build status: STARTED
[22:43:19.838] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:23:0) after 1m23s
[22:44:42.899] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:23:0)
[22:44:42.941] Build status: STARTED
[22:44:42.941] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:24:0) after 4m51s
[22:49:33.952] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:24:0)
[22:49:33.985] Build status: STARTED
[22:49:33.985] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:25:0) after 2m15s
[22:51:49.048] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:25:0)
[22:51:49.080] Build status: STARTED
[22:51:49.080] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:26:0) after 6m22s
[22:58:11.109] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:26:0)
[22:58:11.129] Build status: STARTED
[22:58:11.130] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:27:0) after 5m23s
[23:03:34.215] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:27:0)
[23:03:34.235] Build status: STARTED
[23:03:34.235] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:28:0) after 1m28s
[23:05:02.261] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:28:0)
[23:05:02.277] Build status: STARTED
[23:05:02.277] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:29:0) after 4m55s
[23:09:57.319] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:29:0)
[23:09:57.347] Build status: STARTED
[23:09:57.347] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:30:0) after 1m51s
[23:11:48.424] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:30:0)
[23:11:48.443] Build status: STARTED
[23:11:48.443] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:31:0) after 4m47s
[23:16:35.449] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:31:0)
[23:16:35.471] Build status: STARTED
[23:16:35.471] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:32:0) after 1m0s
[23:17:35.515] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:32:0)
[23:17:35.542] Build status: STARTED
[23:17:35.542] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:33:0) after 5m39s
[23:23:14.669] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:33:0)
[23:23:14.722] Build status: STARTED
[23:23:14.722] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:34:0) after 8m52s
[23:32:06.803] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:34:0)
[23:32:06.818] Build status: STARTED
[23:32:06.818] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:35:0) after 4m36s
[23:36:42.957] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:35:0)
[23:36:43.010] Build status: STARTED
[23:36:43.010] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:36:0) after 3m38s
[23:40:21.063] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:36:0)
[23:40:21.093] Build status: STARTED
[23:40:21.094] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:37:0) after 8m39s
[23:49:00.176] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:37:0)
[23:49:00.194] Build status: STARTED
[23:49:00.194] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:38:0) after 8m40s
[23:57:40.274] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:38:0)
[23:57:40.289] Build status: STARTED
[23:57:40.289] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:39:0) after 9m42s
[00:07:22.327] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:39:0)
[00:07:22.351] Build status: STARTED
[00:07:22.351] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:40:0) after 4m9s
[00:11:31.379] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:40:0)
[00:11:31.404] Build status: STARTED
[00:11:31.404] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:41:0) after 2m35s
[00:14:06.438] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:41:0)
[00:14:06.458] Build status: STARTED
[00:14:06.458] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:42:0) after 2m13s
[00:16:19.487] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:42:0)
[00:16:19.575] Build status: STARTED
[00:16:19.575] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:43:0) after 2m54s
[00:19:13.630] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:43:0)
[00:19:13.653] Build status: STARTED
[00:19:13.653] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:44:0) after 6m53s
[00:26:06.765] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:44:0)
[00:26:06.797] Build status: STARTED
[00:26:06.797] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:45:0) after 6m5s
[00:32:11.847] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:45:0)
[00:32:12.014] Build status: STARTED
[00:32:12.014] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:46:0) after 7m11s
[00:39:23.044] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:46:0)
[00:39:23.062] Build status: STARTED
[00:39:23.062] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:47:0) after 7m14s
[00:46:37.076] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:47:0)
[00:46:37.098] Build status: STARTED
[00:46:37.099] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:48:0) after 4m57s
[00:51:34.162] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:48:0)
[00:51:34.181] Build status: STARTED
[00:51:34.181] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:49:0) after 5m36s
[00:57:10.207] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:49:0)
[00:57:10.224] Build status: STARTED
[00:57:10.224] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:50:0) after 6m36s
[01:03:46.208] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:50:0)
[01:03:46.225] Build status: STARTED
[01:03:46.225] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:51:0) after 7m16s
[01:11:02.521] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:51:0)
[01:11:02.587] Build status: STARTED
[01:11:02.587] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:52:0) after 6m50s
[01:17:52.638] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:52:0)
[01:17:52.657] Build status: STARTED
[01:17:52.657] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:53:0) after 1m57s
[01:19:49.685] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:53:0)
[01:19:49.703] Build status: STARTED
[01:19:49.703] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:54:0) after 2m48s
[01:22:37.769] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:54:0)
[01:22:37.788] Build status: STARTED
[01:22:37.788] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:55:0) after 5m13s
[01:27:50.845] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:55:0)
[01:27:50.882] Build status: STARTED
[01:27:50.882] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:56:0) after 7m30s
[01:35:20.909] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:56:0)
[01:35:20.996] Build status: STARTED
[01:35:20.996] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:57:0) after 6m54s
[01:42:15.111] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:57:0)
[01:42:15.127] Build status: STARTED
[01:42:15.127] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:58:0) after 1m47s
[01:44:02.118] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:58:0)
[01:44:02.136] Build status: STARTED
[01:44:02.136] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:59:0) after 3m36s
[01:47:38.164] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:59:0)
[01:47:38.182] Build status: STARTED
[01:47:38.182] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:60:0) after 1m40s
[01:49:18.205] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:60:0)
[01:49:18.221] Build status: STARTED
[01:49:18.221] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:61:0) after 6m28s
[01:55:46.311] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:61:0)
[01:55:46.359] Build status: STARTED
[01:55:46.359] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:62:0) after 2m50s
[01:58:36.379] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:62:0)
[01:58:36.403] Build status: STARTED
[01:58:36.403] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:63:0) after 8m11s
[02:06:47.386] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:63:0)
[02:06:47.474] Build status: STARTED
[02:06:47.474] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:64:0) after 2m15s
[02:09:02.502] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:64:0)
[02:09:02.527] Build status: STARTED
[02:09:02.527] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-ci-siso:8880745581725943536:65:0) after 4m42s
[02:12:20.643] Received PubSub notification, asking Buildbucket for the build status
[02:12:20.663] Build:
{
"id": "8715315519823862481",
"builder": {
"project": "chromium",
"bucket": "build",
"builder": "win-build-perf-ci-siso"
},
"number": 993,
"createdBy": "project:chromium",
"createTime": "2025-05-09T21:00:51.575102542Z",
"startTime": "2025-05-09T21:00:55.779849651Z",
"endTime": "2025-05-10T02:12:20.227863442Z",
"updateTime": "2025-05-10T02:12:20.227863442Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "fc224902bde09296885c99c9b6da8effc9bdd3f9",
"ref": "refs/heads/main"
}
}
}
[02:12:20.663] Invocation finished in 5h11m30.285958314s with status SUCCEEDED