[00:05:04.080] New invocation is queued and will start shortly
[00:05:05.166] Starting the invocation (attempt 1)
[00:05:05.191] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b2a06ed04ed1cbe546615b2a6f1e5d231acbe420"
[00:05:05.191] Popped gitiles commit info from properties and tags
[00:05:05.191] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:05:05.191] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:05:05.191] Buildbucket request:
{
"requestId": "8904017411455385264",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "win32-official"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "b2a06ed04ed1cbe546615b2a6f1e5d231acbe420",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8904017411455385264"
},
{
"key": "scheduler_job_id",
"value": "chromium/win32-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[00:05:05.736] Scheduled build:
{
"id": "8738587349571027569",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "win32-official"
},
"number": 8147,
"createdBy": "project:chromium",
"createTime": "2024-08-26T00:05:05.249301354Z",
"updateTime": "2024-08-26T00:05:05.249301354Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "b2a06ed04ed1cbe546615b2a6f1e5d231acbe420",
"ref": "refs/heads/main"
}
}
}
[00:05:05.736] Task URL: https://cr-buildbucket.appspot.com/build/8738587349571027569
[00:05:05.736] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8904017411455385264:2:0) after 6m32s
[00:05:05.787] Received PubSub notification, asking Buildbucket for the build status
[00:05:05.804] Build status: SCHEDULED
[00:05:12.143] Received PubSub notification, asking Buildbucket for the build status
[00:05:12.168] Build status: STARTED
[00:11:37.763] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8904017411455385264:2:0)
[00:11:37.792] Build status: STARTED
[00:11:37.792] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8904017411455385264:5:0) after 6m28s
[00:18:05.817] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8904017411455385264:5:0)
[00:18:05.833] Build status: STARTED
[00:18:05.833] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8904017411455385264:6:0) after 7m21s
[00:25:26.854] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8904017411455385264:6:0)
[00:25:26.918] Build status: STARTED
[00:25:26.918] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8904017411455385264:7:0) after 5m48s
[00:31:14.932] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8904017411455385264:7:0)
[00:31:14.965] Build status: STARTED
[00:31:14.965] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8904017411455385264:8:0) after 6m57s
[00:38:11.979] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8904017411455385264:8:0)
[00:38:12.016] Build status: STARTED
[00:38:12.016] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8904017411455385264:9:0) after 9m22s
[00:47:34.033] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8904017411455385264:9:0)
[00:47:34.086] Build status: STARTED
[00:47:34.087] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8904017411455385264:10:0) after 4m42s
[00:52:16.242] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8904017411455385264:10:0)
[00:52:16.260] Build status: STARTED
[00:52:16.260] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8904017411455385264:11:0) after 2m13s
[00:54:29.277] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8904017411455385264:11:0)
[00:54:29.293] Build status: STARTED
[00:54:29.293] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8904017411455385264:12:0) after 6m58s
[01:01:27.454] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8904017411455385264:12:0)
[01:01:27.477] Build status: STARTED
[01:01:27.478] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8904017411455385264:13:0) after 7m43s
[01:09:10.713] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8904017411455385264:13:0)
[01:09:10.743] Build status: STARTED
[01:09:10.743] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8904017411455385264:14:0) after 2m32s
[01:11:42.860] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8904017411455385264:14:0)
[01:11:42.885] Build status: STARTED
[01:11:42.885] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8904017411455385264:15:0) after 5m32s
[01:17:15.018] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8904017411455385264:15:0)
[01:17:15.033] Build status: STARTED
[01:17:15.033] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8904017411455385264:16:0) after 6m35s
[01:23:50.518] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8904017411455385264:16:0)
[01:23:50.579] Build status: STARTED
[01:23:50.579] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8904017411455385264:17:0) after 4m46s
[01:28:36.584] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8904017411455385264:17:0)
[01:28:36.615] Build status: STARTED
[01:28:36.615] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8904017411455385264:18:0) after 7m54s
[01:36:30.651] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8904017411455385264:18:0)
[01:36:30.676] Build status: STARTED
[01:36:30.676] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8904017411455385264:19:0) after 7m47s
[01:44:17.733] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8904017411455385264:19:0)
[01:44:17.749] Build status: STARTED
[01:44:17.749] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8904017411455385264:20:0) after 8m3s
[01:52:21.062] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8904017411455385264:20:0)
[01:52:21.081] Build status: STARTED
[01:52:21.081] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8904017411455385264:21:0) after 9m12s
[02:01:33.096] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8904017411455385264:21:0)
[02:01:33.113] Build status: STARTED
[02:01:33.113] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8904017411455385264:22:0) after 4m18s
[02:05:51.112] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8904017411455385264:22:0)
[02:05:51.137] Build status: STARTED
[02:05:51.137] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8904017411455385264:23:0) after 1m18s
[02:07:09.307] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8904017411455385264:23:0)
[02:07:09.329] Build status: STARTED
[02:07:09.329] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8904017411455385264:24:0) after 2m42s
[02:09:51.450] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8904017411455385264:24:0)
[02:09:51.472] Build status: STARTED
[02:09:51.472] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8904017411455385264:25:0) after 3m31s
[02:13:22.487] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8904017411455385264:25:0)
[02:13:22.504] Build status: STARTED
[02:13:22.504] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8904017411455385264:26:0) after 3m2s
[02:16:24.709] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8904017411455385264:26:0)
[02:16:24.728] Build status: STARTED
[02:16:24.728] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8904017411455385264:27:0) after 6m5s
[02:22:29.756] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8904017411455385264:27:0)
[02:22:29.772] Build status: STARTED
[02:22:29.772] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8904017411455385264:28:0) after 7m2s
[02:29:31.743] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8904017411455385264:28:0)
[02:29:31.757] Build status: STARTED
[02:29:31.757] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8904017411455385264:29:0) after 2m55s
[02:32:26.772] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8904017411455385264:29:0)
[02:32:26.795] Build status: STARTED
[02:32:26.795] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8904017411455385264:30:0) after 1m22s
[02:33:48.812] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8904017411455385264:30:0)
[02:33:48.828] Build status: STARTED
[02:33:48.828] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8904017411455385264:31:0) after 2m48s
[02:36:36.921] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8904017411455385264:31:0)
[02:36:36.956] Build status: STARTED
[02:36:36.956] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8904017411455385264:32:0) after 1m43s
[02:38:20.010] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8904017411455385264:32:0)
[02:38:20.033] Build status: STARTED
[02:38:20.033] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8904017411455385264:33:0) after 6m15s
[02:44:35.050] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8904017411455385264:33:0)
[02:44:35.130] Build status: STARTED
[02:44:35.130] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8904017411455385264:34:0) after 6m8s
[02:50:43.175] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8904017411455385264:34:0)
[02:50:43.197] Build status: STARTED
[02:50:43.197] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8904017411455385264:35:0) after 5m20s
[02:56:03.192] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8904017411455385264:35:0)
[02:56:03.269] Build status: STARTED
[02:56:03.269] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8904017411455385264:36:0) after 5m1s
[03:01:04.285] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8904017411455385264:36:0)
[03:01:04.307] Build status: STARTED
[03:01:04.307] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8904017411455385264:37:0) after 4m31s
[03:05:35.323] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8904017411455385264:37:0)
[03:05:35.397] Build status: STARTED
[03:05:35.397] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8904017411455385264:38:0) after 2m10s
[03:07:45.483] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8904017411455385264:38:0)
[03:07:45.507] Build status: STARTED
[03:07:45.507] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8904017411455385264:39:0) after 9m56s
[03:17:41.633] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8904017411455385264:39:0)
[03:17:41.703] Build status: STARTED
[03:17:41.703] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8904017411455385264:40:0) after 1m34s
[03:19:16.146] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8904017411455385264:40:0)
[03:19:16.209] Build status: STARTED
[03:19:16.209] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8904017411455385264:41:0) after 9m11s
[03:28:27.289] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8904017411455385264:41:0)
[03:28:27.314] Build status: STARTED
[03:28:27.314] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8904017411455385264:42:0) after 2m0s
[03:30:27.528] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8904017411455385264:42:0)
[03:30:27.545] Build status: STARTED
[03:30:27.545] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8904017411455385264:43:0) after 5m33s
[03:36:00.563] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8904017411455385264:43:0)
[03:36:00.581] Build status: STARTED
[03:36:00.581] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8904017411455385264:44:0) after 8m35s
[03:44:35.600] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8904017411455385264:44:0)
[03:44:35.616] Build status: STARTED
[03:44:35.616] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8904017411455385264:45:0) after 2m0s
[03:46:35.645] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8904017411455385264:45:0)
[03:46:35.660] Build status: STARTED
[03:46:35.660] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8904017411455385264:46:0) after 2m28s
[03:49:03.979] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8904017411455385264:46:0)
[03:49:03.996] Build status: STARTED
[03:49:03.996] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8904017411455385264:47:0) after 3m40s
[03:52:44.016] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8904017411455385264:47:0)
[03:52:44.036] Build status: STARTED
[03:52:44.036] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8904017411455385264:48:0) after 3m21s
[03:56:05.146] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8904017411455385264:48:0)
[03:56:05.238] Build status: STARTED
[03:56:05.238] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8904017411455385264:49:0) after 5m34s
[04:01:39.297] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8904017411455385264:49:0)
[04:01:39.315] Build status: STARTED
[04:01:39.315] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8904017411455385264:50:0) after 7m23s
[04:09:02.453] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8904017411455385264:50:0)
[04:09:02.527] Build status: STARTED
[04:09:02.527] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8904017411455385264:51:0) after 3m32s
[04:12:34.924] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8904017411455385264:51:0)
[04:12:34.944] Build status: STARTED
[04:12:34.944] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8904017411455385264:52:0) after 8m26s
[04:19:01.518] Received PubSub notification, asking Buildbucket for the build status
[04:19:01.537] Build:
{
"id": "8738587349571027569",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "win32-official"
},
"number": 8147,
"createdBy": "project:chromium",
"createTime": "2024-08-26T00:05:05.249301354Z",
"startTime": "2024-08-26T00:05:11.903245132Z",
"endTime": "2024-08-26T04:19:01.095114429Z",
"updateTime": "2024-08-26T04:19:01.095114429Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "b2a06ed04ed1cbe546615b2a6f1e5d231acbe420",
"ref": "refs/heads/main"
}
}
}
[04:19:01.537] Invocation finished in 4h13m57.468949035s with status SUCCEEDED