[12:29:34.038] New invocation is queued and will start shortly
[12:29:35.896] Starting the invocation (attempt 1)
[12:29:35.935] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/bd5a1f582b920b38df88be72c8fb1ea2b7e4d65f"
[12:29:35.935] Popped gitiles commit info from properties and tags
[12:29:35.935] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[12:29:35.937] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[12:29:35.937] Buildbucket request:
{
"requestId": "8871536857633261632",
"builder": {
"project": "chromium",
"bucket": "build",
"builder": "win-build-perf-developer"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "bd5a1f582b920b38df88be72c8fb1ea2b7e4d65f",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8871536857633261632"
},
{
"key": "scheduler_job_id",
"value": "chromium/win-build-perf-developer"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[12:29:37.136] Scheduled build:
{
"id": "8706106794835475825",
"builder": {
"project": "chromium",
"bucket": "build",
"builder": "win-build-perf-developer"
},
"number": 4701,
"createdBy": "project:chromium",
"createTime": "2025-08-19T12:29:36.083305681Z",
"updateTime": "2025-08-19T12:29:36.083305681Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "bd5a1f582b920b38df88be72c8fb1ea2b7e4d65f",
"ref": "refs/heads/main"
}
}
}
[12:29:37.136] Task URL: https://cr-buildbucket.appspot.com/build/8706106794835475825
[12:29:37.136] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8871536857633261632:2:0) after 4m49s
[12:29:37.943] Received PubSub notification, asking Buildbucket for the build status
[12:29:37.982] Build status: SCHEDULED
[12:29:42.000] Received PubSub notification, asking Buildbucket for the build status
[12:29:42.026] Build status: STARTED
[12:34:26.250] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8871536857633261632:2:0)
[12:34:26.275] Build status: STARTED
[12:34:26.275] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8871536857633261632:5:0) after 5m41s
[12:40:07.422] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8871536857633261632:5:0)
[12:40:07.490] Build status: STARTED
[12:40:07.490] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8871536857633261632:6:0) after 6m46s
[12:46:53.559] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8871536857633261632:6:0)
[12:46:53.588] Build status: STARTED
[12:46:53.588] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8871536857633261632:7:0) after 8m4s
[12:54:57.712] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8871536857633261632:7:0)
[12:54:57.736] Build status: STARTED
[12:54:57.737] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8871536857633261632:8:0) after 9m42s
[13:04:39.755] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8871536857633261632:8:0)
[13:04:39.799] Build status: STARTED
[13:04:39.799] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8871536857633261632:9:0) after 2m15s
[13:06:54.820] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8871536857633261632:9:0)
[13:06:54.854] Build status: STARTED
[13:06:54.854] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8871536857633261632:10:0) after 2m23s
[13:09:17.943] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8871536857633261632:10:0)
[13:09:17.975] Build status: STARTED
[13:09:17.975] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8871536857633261632:11:0) after 9m45s
[13:19:03.006] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8871536857633261632:11:0)
[13:19:03.057] Build status: STARTED
[13:19:03.057] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8871536857633261632:12:0) after 5m11s
[13:24:14.075] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8871536857633261632:12:0)
[13:24:14.094] Build status: STARTED
[13:24:14.095] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8871536857633261632:13:0) after 5m8s
[13:29:22.635] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8871536857633261632:13:0)
[13:29:22.657] Build status: STARTED
[13:29:22.657] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8871536857633261632:14:0) after 1m11s
[13:30:33.738] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8871536857633261632:14:0)
[13:30:33.794] Build status: STARTED
[13:30:33.794] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8871536857633261632:15:0) after 5m58s
[13:36:31.862] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8871536857633261632:15:0)
[13:36:31.915] Build status: STARTED
[13:36:31.916] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8871536857633261632:16:0) after 8m26s
[13:44:58.072] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8871536857633261632:16:0)
[13:44:58.229] Build status: STARTED
[13:44:58.229] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8871536857633261632:17:0) after 3m29s
[13:48:27.423] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8871536857633261632:17:0)
[13:48:27.442] Build status: STARTED
[13:48:27.442] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8871536857633261632:18:0) after 8m40s
[13:57:07.458] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8871536857633261632:18:0)
[13:57:07.490] Build status: STARTED
[13:57:07.490] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8871536857633261632:19:0) after 3m48s
[14:00:55.598] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8871536857633261632:19:0)
[14:00:55.617] Build status: STARTED
[14:00:55.617] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8871536857633261632:20:0) after 7m5s
[14:08:00.645] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8871536857633261632:20:0)
[14:08:00.673] Build status: STARTED
[14:08:00.674] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8871536857633261632:21:0) after 6m52s
[14:14:52.926] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8871536857633261632:21:0)
[14:14:53.016] Build status: STARTED
[14:14:53.016] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8871536857633261632:22:0) after 2m46s
[14:17:39.128] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8871536857633261632:22:0)
[14:17:39.152] Build status: STARTED
[14:17:39.152] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8871536857633261632:23:0) after 1m39s
[14:19:18.291] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8871536857633261632:23:0)
[14:19:18.319] Build status: STARTED
[14:19:18.319] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8871536857633261632:24:0) after 7m27s
[14:26:45.382] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8871536857633261632:24:0)
[14:26:45.422] Build status: STARTED
[14:26:45.423] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8871536857633261632:25:0) after 9m4s
[14:35:49.640] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8871536857633261632:25:0)
[14:35:49.656] Build status: STARTED
[14:35:49.656] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8871536857633261632:26:0) after 7m8s
[14:42:57.681] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8871536857633261632:26:0)
[14:42:57.697] Build status: STARTED
[14:42:57.697] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8871536857633261632:27:0) after 1m10s
[14:44:07.719] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8871536857633261632:27:0)
[14:44:07.750] Build status: STARTED
[14:44:07.750] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8871536857633261632:28:0) after 2m53s
[14:47:00.782] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8871536857633261632:28:0)
[14:47:00.798] Build status: STARTED
[14:47:00.798] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8871536857633261632:29:0) after 1m24s
[14:48:24.819] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8871536857633261632:29:0)
[14:48:24.845] Build status: STARTED
[14:48:24.845] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8871536857633261632:30:0) after 7m20s
[14:55:44.864] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8871536857633261632:30:0)
[14:55:44.883] Build status: STARTED
[14:55:44.883] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8871536857633261632:31:0) after 2m55s
[14:58:39.906] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8871536857633261632:31:0)
[14:58:39.922] Build status: STARTED
[14:58:39.922] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8871536857633261632:32:0) after 8m58s
[15:07:37.944] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8871536857633261632:32:0)
[15:07:37.974] Build status: STARTED
[15:07:37.974] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8871536857633261632:33:0) after 3m33s
[15:11:11.016] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8871536857633261632:33:0)
[15:11:11.045] Build status: STARTED
[15:11:11.045] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8871536857633261632:34:0) after 1m41s
[15:12:52.073] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8871536857633261632:34:0)
[15:12:52.153] Build status: STARTED
[15:12:52.153] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8871536857633261632:35:0) after 4m19s
[15:17:11.180] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8871536857633261632:35:0)
[15:17:11.201] Build status: STARTED
[15:17:11.201] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8871536857633261632:36:0) after 9m4s
[15:18:13.584] Received PubSub notification, asking Buildbucket for the build status
[15:18:13.619] Build:
{
"id": "8706106794835475825",
"builder": {
"project": "chromium",
"bucket": "build",
"builder": "win-build-perf-developer"
},
"number": 4701,
"createdBy": "project:chromium",
"createTime": "2025-08-19T12:29:36.083305681Z",
"startTime": "2025-08-19T12:29:41.420035083Z",
"endTime": "2025-08-19T15:18:13.169930150Z",
"updateTime": "2025-08-19T15:18:13.169930150Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "bd5a1f582b920b38df88be72c8fb1ea2b7e4d65f",
"ref": "refs/heads/main"
}
}
}
[15:18:13.619] Invocation finished in 2h48m39.588873539s with status SUCCEEDED