[06:57:36.216] New invocation is queued and will start shortly
[06:57:37.453] Starting the invocation (attempt 1)
[06:57:37.495] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b157b980d024b4dc97bf7e7f945f4fec9d0a6212"
[06:57:37.495] Popped gitiles commit info from properties and tags
[06:57:37.495] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[06:57:37.495] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[06:57:37.495] Buildbucket request:
{
"requestId": "9000930211013155760",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Linux CFI"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "b157b980d024b4dc97bf7e7f945f4fec9d0a6212",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "9000930211013155760"
},
{
"key": "scheduler_job_id",
"value": "chromium/Linux CFI"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[06:57:38.008] Scheduled build:
{
"id": "8835500148918554577",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Linux CFI"
},
"number": 21070,
"createdBy": "project:chromium",
"createTime": "2021-09-21T06:57:37.580415260Z",
"updateTime": "2021-09-21T06:57:37.823630386Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "b157b980d024b4dc97bf7e7f945f4fec9d0a6212",
"ref": "refs/heads/main"
}
}
}
[06:57:38.008] Task URL: https://cr-buildbucket.appspot.com/build/8835500148918554577
[06:57:38.008] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:2:0) after 2m43s
[07:00:21.099] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:2:0)
[07:00:21.303] Build status: SCHEDULED
[07:00:21.303] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:3:0) after 9m52s
[07:10:13.503] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:3:0)
[07:10:13.600] Build status: SCHEDULED
[07:10:13.600] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:4:0) after 5m13s
[07:15:26.630] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:4:0)
[07:15:26.789] Build status: SCHEDULED
[07:15:26.789] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:5:0) after 9m13s
[07:24:39.812] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:5:0)
[07:24:39.849] Build status: SCHEDULED
[07:24:39.850] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:6:0) after 4m37s
[07:25:04.736] Received PubSub notification, asking Buildbucket for the build status
[07:25:04.807] Build status: STARTED
[07:29:16.990] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:6:0)
[07:29:17.032] Build status: STARTED
[07:29:17.032] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:8:0) after 3m43s
[07:33:00.172] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:8:0)
[07:33:00.210] Build status: STARTED
[07:33:00.210] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:9:0) after 1m6s
[07:34:06.265] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:9:0)
[07:34:06.300] Build status: STARTED
[07:34:06.300] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:10:0) after 2m0s
[07:36:06.327] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:10:0)
[07:36:06.361] Build status: STARTED
[07:36:06.361] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:11:0) after 3m45s
[07:39:51.443] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:11:0)
[07:39:51.482] Build status: STARTED
[07:39:51.482] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:12:0) after 1m26s
[07:41:17.505] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:12:0)
[07:41:17.548] Build status: STARTED
[07:41:17.548] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:13:0) after 2m6s
[07:43:23.582] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:13:0)
[07:43:23.616] Build status: STARTED
[07:43:23.616] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:14:0) after 4m39s
[07:48:02.712] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:14:0)
[07:48:02.756] Build status: STARTED
[07:48:02.756] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:15:0) after 5m31s
[07:53:33.789] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:15:0)
[07:53:33.828] Build status: STARTED
[07:53:33.828] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:16:0) after 7m10s
[08:00:43.967] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:16:0)
[08:00:44.033] Build status: STARTED
[08:00:44.033] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:17:0) after 9m35s
[08:10:19.060] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:17:0)
[08:10:19.092] Build status: STARTED
[08:10:19.092] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:18:0) after 4m49s
[08:15:08.235] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:18:0)
[08:15:08.279] Build status: STARTED
[08:15:08.279] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:19:0) after 7m55s
[08:23:03.396] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:19:0)
[08:23:03.431] Build status: STARTED
[08:23:03.431] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:20:0) after 7m55s
[08:30:58.455] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:20:0)
[08:30:58.497] Build status: STARTED
[08:30:58.497] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:21:0) after 5m19s
[08:36:17.737] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:21:0)
[08:36:17.765] Build status: STARTED
[08:36:17.765] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:22:0) after 2m10s
[08:38:27.792] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:22:0)
[08:38:27.847] Build status: STARTED
[08:38:27.847] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:23:0) after 3m16s
[08:41:43.849] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:23:0)
[08:41:43.888] Build status: STARTED
[08:41:43.888] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:24:0) after 8m14s
[08:49:57.938] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:24:0)
[08:49:58.012] Build status: STARTED
[08:49:58.012] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:25:0) after 1m45s
[08:51:43.099] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:25:0)
[08:51:43.146] Build status: STARTED
[08:51:43.146] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:26:0) after 7m38s
[08:59:21.379] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:26:0)
[08:59:21.424] Build status: STARTED
[08:59:21.424] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:27:0) after 9m24s
[09:08:45.448] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:27:0)
[09:08:45.479] Build status: STARTED
[09:08:45.479] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:28:0) after 3m32s
[09:12:17.569] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:28:0)
[09:12:17.620] Build status: STARTED
[09:12:17.620] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:29:0) after 4m28s
[09:16:45.832] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:29:0)
[09:16:45.864] Build status: STARTED
[09:16:45.864] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:30:0) after 4m59s
[09:21:44.887] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:30:0)
[09:21:44.918] Build status: STARTED
[09:21:44.918] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:31:0) after 7m42s
[09:29:26.949] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:31:0)
[09:29:26.997] Build status: STARTED
[09:29:26.997] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:32:0) after 1m33s
[09:31:00.136] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:32:0)
[09:31:00.169] Build status: STARTED
[09:31:00.169] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:33:0) after 6m7s
[09:37:07.269] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:33:0)
[09:37:07.313] Build status: STARTED
[09:37:07.313] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:34:0) after 8m33s
[09:45:40.605] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:34:0)
[09:45:40.641] Build status: STARTED
[09:45:40.641] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:35:0) after 8m0s
[09:53:40.678] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:35:0)
[09:53:40.734] Build status: STARTED
[09:53:40.734] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:36:0) after 1m8s
[09:54:48.755] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:36:0)
[09:54:48.787] Build status: STARTED
[09:54:48.787] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:37:0) after 5m25s
[10:00:13.856] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:37:0)
[10:00:13.891] Build status: STARTED
[10:00:13.891] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:38:0) after 8m11s
[10:08:24.934] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:38:0)
[10:08:24.977] Build status: STARTED
[10:08:24.977] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:39:0) after 5m37s
[10:14:02.029] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:39:0)
[10:14:02.071] Build status: STARTED
[10:14:02.071] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:40:0) after 5m21s
[10:19:23.094] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:40:0)
[10:19:23.174] Build status: STARTED
[10:19:23.174] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:41:0) after 3m17s
[10:22:40.292] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:41:0)
[10:22:40.324] Build status: STARTED
[10:22:40.324] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:42:0) after 3m45s
[10:26:25.464] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:42:0)
[10:26:25.499] Build status: STARTED
[10:26:25.499] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:43:0) after 5m33s
[10:31:58.619] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:43:0)
[10:31:58.652] Build status: STARTED
[10:31:58.652] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:44:0) after 4m15s
[10:36:13.760] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:44:0)
[10:36:13.814] Build status: STARTED
[10:36:13.814] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:45:0) after 3m1s
[10:39:14.834] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:45:0)
[10:39:14.879] Build status: STARTED
[10:39:14.879] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:46:0) after 6m53s
[10:46:07.901] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:46:0)
[10:46:07.981] Build status: STARTED
[10:46:07.981] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:47:0) after 3m35s
[10:49:43.315] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:47:0)
[10:49:43.348] Build status: STARTED
[10:49:43.348] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:48:0) after 3m46s
[10:53:29.365] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:48:0)
[10:53:29.395] Build status: STARTED
[10:53:29.395] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:49:0) after 5m21s
[10:58:50.430] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:49:0)
[10:58:50.456] Build status: STARTED
[10:58:50.456] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:50:0) after 9m35s
[11:08:25.607] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:50:0)
[11:08:25.641] Build status: STARTED
[11:08:25.641] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:51:0) after 7m14s
[11:15:39.667] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:51:0)
[11:15:39.706] Build status: STARTED
[11:15:39.706] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:52:0) after 2m41s
[11:18:20.830] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:52:0)
[11:18:20.862] Build status: STARTED
[11:18:20.862] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:53:0) after 6m10s
[11:24:30.884] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:53:0)
[11:24:30.938] Build status: STARTED
[11:24:30.938] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:54:0) after 1m1s
[11:25:31.957] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:54:0)
[11:25:31.997] Build status: STARTED
[11:25:31.997] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:55:0) after 8m3s
[11:33:35.157] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:55:0)
[11:33:35.194] Build status: STARTED
[11:33:35.194] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:56:0) after 5m3s
[11:38:38.224] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:56:0)
[11:38:38.306] Build status: STARTED
[11:38:38.306] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:57:0) after 3m40s
[11:42:18.424] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:57:0)
[11:42:18.492] Build status: STARTED
[11:42:18.492] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:58:0) after 6m0s
[11:48:18.835] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:58:0)
[11:48:18.898] Build status: STARTED
[11:48:18.898] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9000930211013155760:59:0) after 8m13s
[11:55:58.978] Received PubSub notification, asking Buildbucket for the build status
[11:55:59.013] Build:
{
"id": "8835500148918554577",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Linux CFI"
},
"number": 21070,
"createdBy": "project:chromium",
"createTime": "2021-09-21T06:57:37.580415260Z",
"startTime": "2021-09-21T07:25:03.755438Z",
"endTime": "2021-09-21T11:55:58.491518353Z",
"updateTime": "2021-09-21T11:55:58.491884762Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "b157b980d024b4dc97bf7e7f945f4fec9d0a6212",
"ref": "refs/heads/main"
}
}
}
[11:55:59.013] Invocation finished in 4h58m22.814472706s with status SUCCEEDED