[18:10:47.048] New invocation is queued and will start shortly
[18:10:48.106] Starting the invocation (attempt 1)
[18:10:48.135] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/68d9810e12239f359676aa520ca50d4d04e3f911"
[18:10:48.135] Popped gitiles commit info from properties and tags
[18:10:48.135] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:10:48.135] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:10:48.135] Buildbucket request:
{
"requestId": "8905036267700824656",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Linux CFI"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "68d9810e12239f359676aa520ca50d4d04e3f911",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8905036267700824656"
},
{
"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"
}
}
[18:10:48.727] Scheduled build:
{
"id": "8739606205830270417",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Linux CFI"
},
"number": 28132,
"createdBy": "project:chromium",
"createTime": "2024-08-14T18:10:48.202489228Z",
"updateTime": "2024-08-14T18:10:48.202489228Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "68d9810e12239f359676aa520ca50d4d04e3f911",
"ref": "refs/heads/main"
}
}
}
[18:10:48.727] Task URL: https://cr-buildbucket.appspot.com/build/8739606205830270417
[18:10:48.727] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:2:0) after 1m2s
[18:10:49.669] Received PubSub notification, asking Buildbucket for the build status
[18:10:49.696] Build status: SCHEDULED
[18:10:55.593] Received PubSub notification, asking Buildbucket for the build status
[18:10:55.613] Build status: STARTED
[18:11:50.950] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:2:0)
[18:11:50.976] Build status: STARTED
[18:11:50.976] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:5:0) after 1m45s
[18:13:36.077] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:5:0)
[18:13:36.144] Build status: STARTED
[18:13:36.144] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:6:0) after 4m20s
[18:17:56.179] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:6:0)
[18:17:56.214] Build status: STARTED
[18:17:56.214] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:7:0) after 1m48s
[18:19:44.237] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:7:0)
[18:19:44.255] Build status: STARTED
[18:19:44.255] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:8:0) after 9m15s
[18:28:59.398] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:8:0)
[18:28:59.429] Build status: STARTED
[18:28:59.429] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:9:0) after 1m48s
[18:30:47.489] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:9:0)
[18:30:47.505] Build status: STARTED
[18:30:47.505] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:10:0) after 2m48s
[18:33:35.578] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:10:0)
[18:33:35.600] Build status: STARTED
[18:33:35.600] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:11:0) after 8m6s
[18:41:41.627] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:11:0)
[18:41:41.649] Build status: STARTED
[18:41:41.649] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:12:0) after 6m14s
[18:47:55.678] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:12:0)
[18:47:55.694] Build status: STARTED
[18:47:55.694] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:13:0) after 2m27s
[18:50:22.724] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:13:0)
[18:50:22.819] Build status: STARTED
[18:50:22.819] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:14:0) after 9m41s
[19:00:03.905] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:14:0)
[19:00:03.928] Build status: STARTED
[19:00:03.928] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:15:0) after 2m19s
[19:02:22.899] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:15:0)
[19:02:22.916] Build status: STARTED
[19:02:22.917] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:16:0) after 4m20s
[19:06:43.057] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:16:0)
[19:06:43.073] Build status: STARTED
[19:06:43.073] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:17:0) after 1m31s
[19:08:14.212] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:17:0)
[19:08:14.229] Build status: STARTED
[19:08:14.229] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:18:0) after 5m26s
[19:13:40.420] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:18:0)
[19:13:40.484] Build status: STARTED
[19:13:40.484] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:19:0) after 4m31s
[19:18:11.517] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:19:0)
[19:18:11.538] Build status: STARTED
[19:18:11.538] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:20:0) after 3m0s
[19:21:11.603] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:20:0)
[19:21:11.619] Build status: STARTED
[19:21:11.619] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:21:0) after 2m32s
[19:23:43.818] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:21:0)
[19:23:43.843] Build status: STARTED
[19:23:43.843] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:22:0) after 9m11s
[19:32:54.872] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:22:0)
[19:32:54.894] Build status: STARTED
[19:32:54.894] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:23:0) after 4m8s
[19:37:02.923] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:23:0)
[19:37:02.951] Build status: STARTED
[19:37:02.951] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:24:0) after 5m50s
[19:42:53.110] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:24:0)
[19:42:53.127] Build status: STARTED
[19:42:53.127] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:25:0) after 9m25s
[19:52:18.181] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:25:0)
[19:52:18.213] Build status: STARTED
[19:52:18.213] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:26:0) after 9m38s
[20:01:56.292] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:26:0)
[20:01:56.323] Build status: STARTED
[20:01:56.323] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:27:0) after 1m38s
[20:03:34.494] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:27:0)
[20:03:34.514] Build status: STARTED
[20:03:34.514] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:28:0) after 8m19s
[20:11:53.544] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:28:0)
[20:11:53.616] Build status: STARTED
[20:11:53.617] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:29:0) after 6m46s
[20:18:39.675] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:29:0)
[20:18:39.694] Build status: STARTED
[20:18:39.695] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:30:0) after 7m44s
[20:26:23.717] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:30:0)
[20:26:23.735] Build status: STARTED
[20:26:23.735] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:31:0) after 2m56s
[20:29:19.781] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:31:0)
[20:29:19.810] Build status: STARTED
[20:29:19.810] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:32:0) after 9m46s
[20:39:05.983] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:32:0)
[20:39:06.003] Build status: STARTED
[20:39:06.003] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:33:0) after 2m15s
[20:41:21.023] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:33:0)
[20:41:21.039] Build status: STARTED
[20:41:21.039] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:34:0) after 6m23s
[20:47:44.062] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:34:0)
[20:47:44.082] Build status: STARTED
[20:47:44.082] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:35:0) after 5m17s
[20:53:01.150] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:35:0)
[20:53:01.204] Build status: STARTED
[20:53:01.204] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:36:0) after 4m33s
[20:57:34.397] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:36:0)
[20:57:34.445] Build status: STARTED
[20:57:34.445] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:37:0) after 7m4s
[21:04:38.478] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:37:0)
[21:04:38.557] Build status: STARTED
[21:04:38.557] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:38:0) after 4m32s
[21:09:10.584] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:38:0)
[21:09:10.621] Build status: STARTED
[21:09:10.621] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:39:0) after 8m59s
[21:18:09.666] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:39:0)
[21:18:09.693] Build status: STARTED
[21:18:09.693] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:40:0) after 2m10s
[21:20:19.743] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:40:0)
[21:20:19.768] Build status: STARTED
[21:20:19.768] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:41:0) after 6m57s
[21:27:16.828] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:41:0)
[21:27:16.845] Build status: STARTED
[21:27:16.845] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:42:0) after 6m14s
[21:33:30.866] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:42:0)
[21:33:30.886] Build status: STARTED
[21:33:30.886] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:43:0) after 4m4s
[21:37:34.919] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:43:0)
[21:37:35.032] Build status: STARTED
[21:37:35.032] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:44:0) after 8m46s
[21:46:21.197] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:44:0)
[21:46:21.215] Build status: STARTED
[21:46:21.215] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:45:0) after 1m38s
[21:47:59.279] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:45:0)
[21:47:59.299] Build status: STARTED
[21:47:59.299] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:46:0) after 1m49s
[21:49:48.323] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:46:0)
[21:49:48.339] Build status: STARTED
[21:49:48.339] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:47:0) after 6m29s
[21:56:17.412] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:47:0)
[21:56:17.438] Build status: STARTED
[21:56:17.438] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:48:0) after 6m39s
[22:02:56.494] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:48:0)
[22:02:56.515] Build status: STARTED
[22:02:56.515] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:49:0) after 6m2s
[22:08:58.545] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:49:0)
[22:08:58.561] Build status: STARTED
[22:08:58.561] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:50:0) after 2m40s
[22:11:38.698] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:50:0)
[22:11:38.718] Build status: STARTED
[22:11:38.718] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:51:0) after 2m12s
[22:13:50.749] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:51:0)
[22:13:50.780] Build status: STARTED
[22:13:50.780] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:52:0) after 8m53s
[22:22:43.844] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:52:0)
[22:22:43.863] Build status: STARTED
[22:22:43.863] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:53:0) after 9m30s
[22:32:13.860] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:53:0)
[22:32:13.911] Build status: STARTED
[22:32:13.911] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:54:0) after 9m50s
[22:42:04.043] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:54:0)
[22:42:04.061] Build status: STARTED
[22:42:04.061] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:55:0) after 8m33s
[22:50:37.138] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:55:0)
[22:50:37.155] Build status: STARTED
[22:50:37.155] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:56:0) after 3m30s
[22:54:07.177] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:56:0)
[22:54:07.197] Build status: STARTED
[22:54:07.197] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:57:0) after 2m33s
[22:56:40.282] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:57:0)
[22:56:40.322] Build status: STARTED
[22:56:40.322] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:58:0) after 7m19s
[23:03:59.347] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:58:0)
[23:03:59.364] Build status: STARTED
[23:03:59.364] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:59:0) after 5m46s
[23:09:45.393] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:59:0)
[23:09:45.462] Build status: STARTED
[23:09:45.462] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8905036267700824656:60:0) after 8m19s
[23:10:19.600] Received PubSub notification, asking Buildbucket for the build status
[23:10:19.654] Build:
{
"id": "8739606205830270417",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Linux CFI"
},
"number": 28132,
"createdBy": "project:chromium",
"createTime": "2024-08-14T18:10:48.202489228Z",
"startTime": "2024-08-14T18:10:55.296312690Z",
"endTime": "2024-08-14T23:10:19.118285515Z",
"updateTime": "2024-08-14T23:10:19.118285515Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "68d9810e12239f359676aa520ca50d4d04e3f911",
"ref": "refs/heads/main"
}
}
}
[23:10:19.654] Invocation finished in 4h59m32.619296559s with status SUCCEEDED