[01:02:12.076] New invocation is queued and will start shortly
[01:02:13.556] Starting the invocation (attempt 1)
[01:02:13.586] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f41beba2e9199dd06fa86e226b4eb2986b6ddf11"
[01:02:13.586] Popped gitiles commit info from properties and tags
[01:02:13.586] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:02:13.587] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:02:13.587] Buildbucket request:
{
"requestId": "8845850564507692480",
"builder": {
"project": "chromium",
"bucket": "build",
"builder": "win-rbe-untrusted-test"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "f41beba2e9199dd06fa86e226b4eb2986b6ddf11",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8845850564507692480"
},
{
"key": "scheduler_job_id",
"value": "chromium/win-rbe-untrusted-test"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[01:02:14.025] Scheduled build:
{
"id": "8680420502202415521",
"builder": {
"project": "chromium",
"bucket": "build",
"builder": "win-rbe-untrusted-test"
},
"number": 4886,
"createdBy": "project:chromium",
"createTime": "2026-05-30T01:02:13.651707489Z",
"updateTime": "2026-05-30T01:02:13.651707489Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "f41beba2e9199dd06fa86e226b4eb2986b6ddf11",
"ref": "refs/heads/main"
}
}
}
[01:02:14.026] Task URL: https://cr-buildbucket.appspot.com/build/8680420502202415521
[01:02:14.026] Scheduling timer "check-buildbucket-build-status" (chromium/win-rbe-untrusted-test:8845850564507692480:2:0) after 8m59s
[01:02:15.014] Received PubSub notification, asking Buildbucket for the build status
[01:02:15.065] Build status: SCHEDULED
[01:02:18.487] Received PubSub notification, asking Buildbucket for the build status
[01:02:18.501] Build status: STARTED
[01:11:13.049] Handling timer "check-buildbucket-build-status" (chromium/win-rbe-untrusted-test:8845850564507692480:2:0)
[01:11:13.118] Build status: STARTED
[01:11:13.118] Scheduling timer "check-buildbucket-build-status" (chromium/win-rbe-untrusted-test:8845850564507692480:5:0) after 9m7s
[01:20:20.265] Handling timer "check-buildbucket-build-status" (chromium/win-rbe-untrusted-test:8845850564507692480:5:0)
[01:20:20.292] Build status: STARTED
[01:20:20.292] Scheduling timer "check-buildbucket-build-status" (chromium/win-rbe-untrusted-test:8845850564507692480:6:0) after 1m52s
[01:22:12.384] Handling timer "check-buildbucket-build-status" (chromium/win-rbe-untrusted-test:8845850564507692480:6:0)
[01:22:12.401] Build status: STARTED
[01:22:12.401] Scheduling timer "check-buildbucket-build-status" (chromium/win-rbe-untrusted-test:8845850564507692480:7:0) after 8m35s
[01:30:47.421] Handling timer "check-buildbucket-build-status" (chromium/win-rbe-untrusted-test:8845850564507692480:7:0)
[01:30:47.443] Build status: STARTED
[01:30:47.443] Scheduling timer "check-buildbucket-build-status" (chromium/win-rbe-untrusted-test:8845850564507692480:8:0) after 2m46s
[01:33:33.512] Handling timer "check-buildbucket-build-status" (chromium/win-rbe-untrusted-test:8845850564507692480:8:0)
[01:33:33.526] Build status: STARTED
[01:33:33.526] Scheduling timer "check-buildbucket-build-status" (chromium/win-rbe-untrusted-test:8845850564507692480:9:0) after 5m1s
[01:38:34.618] Handling timer "check-buildbucket-build-status" (chromium/win-rbe-untrusted-test:8845850564507692480:9:0)
[01:38:34.666] Build status: STARTED
[01:38:34.666] Scheduling timer "check-buildbucket-build-status" (chromium/win-rbe-untrusted-test:8845850564507692480:10:0) after 5m40s
[01:44:14.835] Handling timer "check-buildbucket-build-status" (chromium/win-rbe-untrusted-test:8845850564507692480:10:0)
[01:44:14.859] Build status: STARTED
[01:44:14.859] Scheduling timer "check-buildbucket-build-status" (chromium/win-rbe-untrusted-test:8845850564507692480:11:0) after 2m13s
[01:46:27.882] Handling timer "check-buildbucket-build-status" (chromium/win-rbe-untrusted-test:8845850564507692480:11:0)
[01:46:27.901] Build status: STARTED
[01:46:27.901] Scheduling timer "check-buildbucket-build-status" (chromium/win-rbe-untrusted-test:8845850564507692480:12:0) after 5m55s
[01:52:22.967] Handling timer "check-buildbucket-build-status" (chromium/win-rbe-untrusted-test:8845850564507692480:12:0)
[01:52:23.035] Build status: STARTED
[01:52:23.035] Scheduling timer "check-buildbucket-build-status" (chromium/win-rbe-untrusted-test:8845850564507692480:13:0) after 4m38s
[01:57:01.067] Handling timer "check-buildbucket-build-status" (chromium/win-rbe-untrusted-test:8845850564507692480:13:0)
[01:57:01.103] Build status: STARTED
[01:57:01.103] Scheduling timer "check-buildbucket-build-status" (chromium/win-rbe-untrusted-test:8845850564507692480:14:0) after 3m29s
[02:00:30.178] Handling timer "check-buildbucket-build-status" (chromium/win-rbe-untrusted-test:8845850564507692480:14:0)
[02:00:30.197] Build status: STARTED
[02:00:30.197] Scheduling timer "check-buildbucket-build-status" (chromium/win-rbe-untrusted-test:8845850564507692480:15:0) after 8m18s
[02:08:48.293] Handling timer "check-buildbucket-build-status" (chromium/win-rbe-untrusted-test:8845850564507692480:15:0)
[02:08:48.312] Build status: STARTED
[02:08:48.312] Scheduling timer "check-buildbucket-build-status" (chromium/win-rbe-untrusted-test:8845850564507692480:16:0) after 3m12s
[02:12:00.336] Handling timer "check-buildbucket-build-status" (chromium/win-rbe-untrusted-test:8845850564507692480:16:0)
[02:12:00.359] Build status: STARTED
[02:12:00.359] Scheduling timer "check-buildbucket-build-status" (chromium/win-rbe-untrusted-test:8845850564507692480:17:0) after 2m40s
[02:14:40.485] Handling timer "check-buildbucket-build-status" (chromium/win-rbe-untrusted-test:8845850564507692480:17:0)
[02:14:40.509] Build status: STARTED
[02:14:40.509] Scheduling timer "check-buildbucket-build-status" (chromium/win-rbe-untrusted-test:8845850564507692480:18:0) after 6m5s
[02:20:45.717] Handling timer "check-buildbucket-build-status" (chromium/win-rbe-untrusted-test:8845850564507692480:18:0)
[02:20:45.733] Build status: STARTED
[02:20:45.733] Scheduling timer "check-buildbucket-build-status" (chromium/win-rbe-untrusted-test:8845850564507692480:19:0) after 9m51s
[02:30:36.773] Handling timer "check-buildbucket-build-status" (chromium/win-rbe-untrusted-test:8845850564507692480:19:0)
[02:30:36.789] Build status: STARTED
[02:30:36.789] Scheduling timer "check-buildbucket-build-status" (chromium/win-rbe-untrusted-test:8845850564507692480:20:0) after 5m54s
[02:36:30.788] Handling timer "check-buildbucket-build-status" (chromium/win-rbe-untrusted-test:8845850564507692480:20:0)
[02:36:30.806] Build status: STARTED
[02:36:30.806] Scheduling timer "check-buildbucket-build-status" (chromium/win-rbe-untrusted-test:8845850564507692480:21:0) after 6m24s
[02:42:54.803] Handling timer "check-buildbucket-build-status" (chromium/win-rbe-untrusted-test:8845850564507692480:21:0)
[02:42:54.820] Build status: STARTED
[02:42:54.820] Scheduling timer "check-buildbucket-build-status" (chromium/win-rbe-untrusted-test:8845850564507692480:22:0) after 1m59s
[02:44:53.853] Handling timer "check-buildbucket-build-status" (chromium/win-rbe-untrusted-test:8845850564507692480:22:0)
[02:44:53.871] Build status: STARTED
[02:44:53.871] Scheduling timer "check-buildbucket-build-status" (chromium/win-rbe-untrusted-test:8845850564507692480:23:0) after 4m0s
[02:48:54.006] Handling timer "check-buildbucket-build-status" (chromium/win-rbe-untrusted-test:8845850564507692480:23:0)
[02:48:54.023] Build status: STARTED
[02:48:54.023] Scheduling timer "check-buildbucket-build-status" (chromium/win-rbe-untrusted-test:8845850564507692480:24:0) after 3m1s
[02:51:55.123] Handling timer "check-buildbucket-build-status" (chromium/win-rbe-untrusted-test:8845850564507692480:24:0)
[02:51:55.156] Build status: STARTED
[02:51:55.156] Scheduling timer "check-buildbucket-build-status" (chromium/win-rbe-untrusted-test:8845850564507692480:25:0) after 4m58s
[02:56:53.170] Handling timer "check-buildbucket-build-status" (chromium/win-rbe-untrusted-test:8845850564507692480:25:0)
[02:56:53.189] Build status: STARTED
[02:56:53.189] Scheduling timer "check-buildbucket-build-status" (chromium/win-rbe-untrusted-test:8845850564507692480:26:0) after 6m55s
[03:03:48.387] Handling timer "check-buildbucket-build-status" (chromium/win-rbe-untrusted-test:8845850564507692480:26:0)
[03:03:48.457] Build status: STARTED
[03:03:48.457] Scheduling timer "check-buildbucket-build-status" (chromium/win-rbe-untrusted-test:8845850564507692480:27:0) after 2m18s
[03:06:06.490] Handling timer "check-buildbucket-build-status" (chromium/win-rbe-untrusted-test:8845850564507692480:27:0)
[03:06:06.507] Build status: STARTED
[03:06:06.507] Scheduling timer "check-buildbucket-build-status" (chromium/win-rbe-untrusted-test:8845850564507692480:28:0) after 7m53s
[03:13:59.577] Handling timer "check-buildbucket-build-status" (chromium/win-rbe-untrusted-test:8845850564507692480:28:0)
[03:13:59.602] Build status: STARTED
[03:13:59.602] Scheduling timer "check-buildbucket-build-status" (chromium/win-rbe-untrusted-test:8845850564507692480:29:0) after 2m50s
[03:16:49.626] Handling timer "check-buildbucket-build-status" (chromium/win-rbe-untrusted-test:8845850564507692480:29:0)
[03:16:49.643] Build status: STARTED
[03:16:49.643] Scheduling timer "check-buildbucket-build-status" (chromium/win-rbe-untrusted-test:8845850564507692480:30:0) after 1m43s
[03:18:32.721] Handling timer "check-buildbucket-build-status" (chromium/win-rbe-untrusted-test:8845850564507692480:30:0)
[03:18:32.736] Build status: STARTED
[03:18:32.736] Scheduling timer "check-buildbucket-build-status" (chromium/win-rbe-untrusted-test:8845850564507692480:31:0) after 5m28s
[03:24:00.760] Handling timer "check-buildbucket-build-status" (chromium/win-rbe-untrusted-test:8845850564507692480:31:0)
[03:24:00.776] Build status: STARTED
[03:24:00.776] Scheduling timer "check-buildbucket-build-status" (chromium/win-rbe-untrusted-test:8845850564507692480:32:0) after 1m18s
[03:25:18.861] Handling timer "check-buildbucket-build-status" (chromium/win-rbe-untrusted-test:8845850564507692480:32:0)
[03:25:18.881] Build status: STARTED
[03:25:18.881] Scheduling timer "check-buildbucket-build-status" (chromium/win-rbe-untrusted-test:8845850564507692480:33:0) after 3m41s
[03:28:59.881] Handling timer "check-buildbucket-build-status" (chromium/win-rbe-untrusted-test:8845850564507692480:33:0)
[03:28:59.898] Build status: STARTED
[03:28:59.898] Scheduling timer "check-buildbucket-build-status" (chromium/win-rbe-untrusted-test:8845850564507692480:34:0) after 3m27s
[03:32:26.914] Handling timer "check-buildbucket-build-status" (chromium/win-rbe-untrusted-test:8845850564507692480:34:0)
[03:32:26.931] Build status: STARTED
[03:32:26.931] Scheduling timer "check-buildbucket-build-status" (chromium/win-rbe-untrusted-test:8845850564507692480:35:0) after 5m43s
[03:38:09.947] Handling timer "check-buildbucket-build-status" (chromium/win-rbe-untrusted-test:8845850564507692480:35:0)
[03:38:09.968] Build status: STARTED
[03:38:09.968] Scheduling timer "check-buildbucket-build-status" (chromium/win-rbe-untrusted-test:8845850564507692480:36:0) after 8m14s
[03:46:24.024] Handling timer "check-buildbucket-build-status" (chromium/win-rbe-untrusted-test:8845850564507692480:36:0)
[03:46:24.039] Build status: STARTED
[03:46:24.039] Scheduling timer "check-buildbucket-build-status" (chromium/win-rbe-untrusted-test:8845850564507692480:37:0) after 3m32s
[03:49:56.060] Handling timer "check-buildbucket-build-status" (chromium/win-rbe-untrusted-test:8845850564507692480:37:0)
[03:49:56.090] Build status: STARTED
[03:49:56.090] Scheduling timer "check-buildbucket-build-status" (chromium/win-rbe-untrusted-test:8845850564507692480:38:0) after 4m41s
[03:50:06.627] Received PubSub notification, asking Buildbucket for the build status
[03:50:06.644] Build:
{
"id": "8680420502202415521",
"builder": {
"project": "chromium",
"bucket": "build",
"builder": "win-rbe-untrusted-test"
},
"number": 4886,
"createdBy": "project:chromium",
"createTime": "2026-05-30T01:02:13.651707489Z",
"startTime": "2026-05-30T01:02:18.263790604Z",
"endTime": "2026-05-30T03:50:06.321411125Z",
"updateTime": "2026-05-30T03:50:06.321411125Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "f41beba2e9199dd06fa86e226b4eb2986b6ddf11",
"ref": "refs/heads/main"
}
}
}
[03:50:06.644] Invocation finished in 2h47m54.574584134s with status SUCCEEDED