[05:57:12.338] New invocation is queued and will start shortly
[05:57:13.377] Starting the invocation (attempt 1)
[05:57:13.421] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/089f71a000b70459c7c032590291031acf1e5f7c"
[05:57:13.421] Popped gitiles commit info from properties and tags
[05:57:13.421] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[05:57:13.421] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[05:57:13.421] Buildbucket request:
{
"requestId": "8975657457299039008",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "win-official"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "089f71a000b70459c7c032590291031acf1e5f7c",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8975657457299039008"
},
{
"key": "scheduler_job_id",
"value": "chromium/win-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[05:57:14.033] Scheduled build:
{
"id": "8810227395394569473",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "win-official"
},
"number": 4364,
"createdBy": "project:chromium",
"createTime": "2022-06-27T05:57:13.521299553Z",
"updateTime": "2022-06-27T05:57:13.521299553Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "089f71a000b70459c7c032590291031acf1e5f7c",
"ref": "refs/heads/main"
}
}
}
[05:57:14.033] Task URL: https://cr-buildbucket.appspot.com/build/8810227395394569473
[05:57:14.033] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:2:0) after 8m9s
[05:57:21.719] Received PubSub notification, asking Buildbucket for the build status
[05:57:21.741] Build status: STARTED
[06:05:23.249] Handling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:2:0)
[06:05:23.281] Build status: STARTED
[06:05:23.282] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:4:0) after 6m28s
[06:11:51.344] Handling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:4:0)
[06:11:51.443] Build status: STARTED
[06:11:51.443] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:5:0) after 5m50s
[06:17:41.462] Handling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:5:0)
[06:17:41.503] Build status: STARTED
[06:17:41.503] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:6:0) after 4m26s
[06:22:07.523] Handling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:6:0)
[06:22:07.548] Build status: STARTED
[06:22:07.548] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:7:0) after 2m31s
[06:24:38.644] Handling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:7:0)
[06:24:38.666] Build status: STARTED
[06:24:38.666] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:8:0) after 9m26s
[06:34:04.858] Handling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:8:0)
[06:34:05.544] Build status: STARTED
[06:34:05.544] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:9:0) after 4m16s
[06:38:21.653] Handling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:9:0)
[06:38:21.698] Build status: STARTED
[06:38:21.698] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:10:0) after 6m46s
[06:45:07.738] Handling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:10:0)
[06:45:07.759] Build status: STARTED
[06:45:07.759] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:11:0) after 9m4s
[06:54:11.781] Handling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:11:0)
[06:54:11.813] Build status: STARTED
[06:54:11.813] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:12:0) after 9m8s
[07:03:19.807] Handling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:12:0)
[07:03:19.825] Build status: STARTED
[07:03:19.825] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:13:0) after 2m56s
[07:06:15.805] Handling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:13:0)
[07:06:15.829] Build status: STARTED
[07:06:15.830] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:14:0) after 1m56s
[07:08:12.281] Handling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:14:0)
[07:08:12.307] Build status: STARTED
[07:08:12.307] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:15:0) after 9m36s
[07:17:48.560] Handling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:15:0)
[07:17:48.581] Build status: STARTED
[07:17:48.581] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:16:0) after 6m27s
[07:24:15.602] Handling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:16:0)
[07:24:15.633] Build status: STARTED
[07:24:15.633] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:17:0) after 9m13s
[07:33:28.615] Handling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:17:0)
[07:33:28.633] Build status: STARTED
[07:33:28.633] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:18:0) after 1m20s
[07:34:48.660] Handling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:18:0)
[07:34:48.683] Build status: STARTED
[07:34:48.683] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:19:0) after 9m4s
[07:43:52.795] Handling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:19:0)
[07:43:52.820] Build status: STARTED
[07:43:52.820] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:20:0) after 6m15s
[07:50:07.857] Handling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:20:0)
[07:50:07.877] Build status: STARTED
[07:50:07.877] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:21:0) after 8m17s
[07:58:24.895] Handling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:21:0)
[07:58:24.914] Build status: STARTED
[07:58:24.915] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:22:0) after 8m43s
[08:07:08.286] Handling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:22:0)
[08:07:08.326] Build status: STARTED
[08:07:08.326] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:23:0) after 3m4s
[08:10:12.482] Handling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:23:0)
[08:10:12.505] Build status: STARTED
[08:10:12.505] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:24:0) after 7m5s
[08:17:17.579] Handling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:24:0)
[08:17:17.625] Build status: STARTED
[08:17:17.625] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:25:0) after 2m22s
[08:19:39.688] Handling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:25:0)
[08:19:39.725] Build status: STARTED
[08:19:39.725] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:26:0) after 4m43s
[08:24:22.744] Handling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:26:0)
[08:24:22.792] Build status: STARTED
[08:24:22.792] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:27:0) after 9m3s
[08:33:25.845] Handling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:27:0)
[08:33:25.867] Build status: STARTED
[08:33:25.867] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:28:0) after 1m26s
[08:34:51.892] Handling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:28:0)
[08:34:51.940] Build status: STARTED
[08:34:51.940] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:29:0) after 3m59s
[08:38:50.994] Handling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:29:0)
[08:38:51.076] Build status: STARTED
[08:38:51.076] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:30:0) after 1m1s
[08:39:52.298] Handling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:30:0)
[08:39:52.315] Build status: STARTED
[08:39:52.315] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:31:0) after 8m3s
[08:47:55.331] Handling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:31:0)
[08:47:55.355] Build status: STARTED
[08:47:55.355] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:32:0) after 4m10s
[08:52:05.374] Handling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:32:0)
[08:52:05.395] Build status: STARTED
[08:52:05.395] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:33:0) after 2m50s
[08:54:55.418] Handling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:33:0)
[08:54:55.437] Build status: STARTED
[08:54:55.437] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:34:0) after 2m59s
[08:57:54.768] Handling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:34:0)
[08:57:54.803] Build status: STARTED
[08:57:54.803] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:35:0) after 4m13s
[09:02:07.845] Handling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:35:0)
[09:02:07.863] Build status: STARTED
[09:02:07.864] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:36:0) after 4m7s
[09:06:14.883] Handling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:36:0)
[09:06:14.942] Build status: STARTED
[09:06:14.942] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:37:0) after 8m53s
[09:15:08.277] Handling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:37:0)
[09:15:08.311] Build status: STARTED
[09:15:08.311] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:38:0) after 8m43s
[09:23:51.389] Handling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:38:0)
[09:23:51.454] Build status: STARTED
[09:23:51.454] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:39:0) after 3m53s
[09:27:44.589] Handling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:39:0)
[09:27:44.618] Build status: STARTED
[09:27:44.618] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:40:0) after 2m7s
[09:29:51.636] Handling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:40:0)
[09:29:51.851] Build status: STARTED
[09:29:51.851] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:41:0) after 8m14s
[09:38:05.869] Handling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:41:0)
[09:38:05.891] Build status: STARTED
[09:38:05.891] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:42:0) after 4m20s
[09:42:25.910] Handling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:42:0)
[09:42:25.931] Build status: STARTED
[09:42:25.931] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:43:0) after 5m40s
[09:48:05.978] Handling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:43:0)
[09:48:06.009] Build status: STARTED
[09:48:06.010] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:44:0) after 8m35s
[09:56:41.137] Handling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:44:0)
[09:56:41.157] Build status: STARTED
[09:56:41.157] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:45:0) after 3m40s
[10:00:21.282] Handling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:45:0)
[10:00:21.360] Build status: STARTED
[10:00:21.360] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:46:0) after 9m26s
[10:09:47.381] Handling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:46:0)
[10:09:47.407] Build status: STARTED
[10:09:47.407] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:47:0) after 9m12s
[10:18:59.429] Handling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:47:0)
[10:18:59.455] Build status: STARTED
[10:18:59.455] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:48:0) after 4m45s
[10:23:44.566] Handling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:48:0)
[10:23:44.602] Build status: STARTED
[10:23:44.602] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:49:0) after 8m33s
[10:32:17.822] Handling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:49:0)
[10:32:17.846] Build status: STARTED
[10:32:17.846] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:50:0) after 8m59s
[10:41:17.030] Handling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:50:0)
[10:41:17.063] Build status: STARTED
[10:41:17.063] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:51:0) after 8m25s
[10:49:42.205] Handling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:51:0)
[10:49:42.252] Build status: STARTED
[10:49:42.252] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:52:0) after 4m30s
[10:54:12.361] Handling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:52:0)
[10:54:12.398] Build status: STARTED
[10:54:12.398] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:53:0) after 5m22s
[10:59:34.651] Handling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:53:0)
[10:59:34.712] Build status: STARTED
[10:59:34.712] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:54:0) after 4m50s
[11:04:24.848] Handling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:54:0)
[11:04:24.885] Build status: STARTED
[11:04:24.885] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:55:0) after 2m9s
[11:06:33.905] Handling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:55:0)
[11:06:33.926] Build status: STARTED
[11:06:33.926] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8975657457299039008:56:0) after 9m34s
[11:12:07.588] Received PubSub notification, asking Buildbucket for the build status
[11:12:07.609] Build:
{
"id": "8810227395394569473",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "win-official"
},
"number": 4364,
"createdBy": "project:chromium",
"createTime": "2022-06-27T05:57:13.521299553Z",
"startTime": "2022-06-27T05:57:21.209235Z",
"endTime": "2022-06-27T11:12:07.372934670Z",
"updateTime": "2022-06-27T11:12:07.372934670Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "089f71a000b70459c7c032590291031acf1e5f7c",
"ref": "refs/heads/main"
}
}
}
[11:12:07.609] Invocation finished in 5h14m55.28790949s with status SUCCEEDED