[08:06:21.428] New invocation is queued and will start shortly
[08:06:22.587] Starting the invocation (attempt 1)
[08:06:22.622] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e72820b991f4445cde13b9803f73546b61ccf213"
[08:06:22.622] Popped gitiles commit info from properties and tags
[08:06:22.622] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[08:06:22.622] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[08:06:22.623] Buildbucket request:
{
"requestId": "8959885459637436672",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Linux CFI"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "e72820b991f4445cde13b9803f73546b61ccf213",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8959885459637436672"
},
{
"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"
}
}
[08:06:23.071] Scheduled build:
{
"id": "8794455397645273681",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Linux CFI"
},
"number": 24162,
"createdBy": "project:chromium",
"createTime": "2022-12-18T08:06:22.693712150Z",
"updateTime": "2022-12-18T08:06:22.693712150Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "e72820b991f4445cde13b9803f73546b61ccf213",
"ref": "refs/heads/main"
}
}
}
[08:06:23.071] Task URL: https://cr-buildbucket.appspot.com/build/8794455397645273681
[08:06:23.071] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8959885459637436672:2:0) after 1m41s
[08:08:04.091] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8959885459637436672:2:0)
[08:08:04.121] Build status: SCHEDULED
[08:08:04.121] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8959885459637436672:3:0) after 7m22s
[08:09:09.663] Received PubSub notification, asking Buildbucket for the build status
[08:09:09.701] Build status: STARTED
[08:15:26.132] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8959885459637436672:3:0)
[08:15:26.174] Build status: STARTED
[08:15:26.174] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8959885459637436672:5:0) after 6m52s
[08:22:18.197] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8959885459637436672:5:0)
[08:22:18.218] Build status: STARTED
[08:22:18.218] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8959885459637436672:6:0) after 6m16s
[08:28:34.235] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8959885459637436672:6:0)
[08:28:34.254] Build status: STARTED
[08:28:34.254] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8959885459637436672:7:0) after 1m46s
[08:30:20.279] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8959885459637436672:7:0)
[08:30:20.303] Build status: STARTED
[08:30:20.303] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8959885459637436672:8:0) after 3m16s
[08:33:36.329] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8959885459637436672:8:0)
[08:33:36.355] Build status: STARTED
[08:33:36.355] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8959885459637436672:9:0) after 4m22s
[08:37:58.373] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8959885459637436672:9:0)
[08:37:58.395] Build status: STARTED
[08:37:58.395] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8959885459637436672:10:0) after 4m1s
[08:41:59.682] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8959885459637436672:10:0)
[08:41:59.715] Build status: STARTED
[08:41:59.715] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8959885459637436672:11:0) after 2m26s
[08:44:25.799] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8959885459637436672:11:0)
[08:44:25.824] Build status: STARTED
[08:44:25.824] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8959885459637436672:12:0) after 3m32s
[08:47:58.159] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8959885459637436672:12:0)
[08:47:58.199] Build status: STARTED
[08:47:58.199] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8959885459637436672:13:0) after 3m44s
[08:51:42.218] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8959885459637436672:13:0)
[08:51:42.242] Build status: STARTED
[08:51:42.242] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8959885459637436672:14:0) after 4m19s
[08:56:01.263] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8959885459637436672:14:0)
[08:56:01.298] Build status: STARTED
[08:56:01.298] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8959885459637436672:15:0) after 9m40s
[09:05:41.489] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8959885459637436672:15:0)
[09:05:41.524] Build status: STARTED
[09:05:41.524] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8959885459637436672:16:0) after 9m30s
[09:15:11.577] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8959885459637436672:16:0)
[09:15:11.601] Build status: STARTED
[09:15:11.601] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8959885459637436672:17:0) after 8m56s
[09:24:07.719] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8959885459637436672:17:0)
[09:24:07.740] Build status: STARTED
[09:24:07.740] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8959885459637436672:18:0) after 9m42s
[09:33:49.892] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8959885459637436672:18:0)
[09:33:49.915] Build status: STARTED
[09:33:49.915] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8959885459637436672:19:0) after 1m2s
[09:34:51.936] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8959885459637436672:19:0)
[09:34:51.969] Build status: STARTED
[09:34:51.969] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8959885459637436672:20:0) after 8m18s
[09:43:10.244] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8959885459637436672:20:0)
[09:43:10.264] Build status: STARTED
[09:43:10.264] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8959885459637436672:21:0) after 2m28s
[09:45:38.243] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8959885459637436672:21:0)
[09:45:38.276] Build status: STARTED
[09:45:38.277] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8959885459637436672:22:0) after 7m11s
[09:52:49.303] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8959885459637436672:22:0)
[09:52:49.329] Build status: STARTED
[09:52:49.329] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8959885459637436672:23:0) after 2m44s
[09:55:33.369] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8959885459637436672:23:0)
[09:55:33.386] Build status: STARTED
[09:55:33.386] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8959885459637436672:24:0) after 5m33s
[10:01:06.441] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8959885459637436672:24:0)
[10:01:06.467] Build status: STARTED
[10:01:06.467] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8959885459637436672:25:0) after 1m51s
[10:02:57.490] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8959885459637436672:25:0)
[10:02:57.524] Build status: STARTED
[10:02:57.524] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8959885459637436672:26:0) after 8m36s
[10:11:33.552] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8959885459637436672:26:0)
[10:11:33.573] Build status: STARTED
[10:11:33.573] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8959885459637436672:27:0) after 5m57s
[10:17:30.593] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8959885459637436672:27:0)
[10:17:30.619] Build status: STARTED
[10:17:30.619] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8959885459637436672:28:0) after 4m25s
[10:21:55.610] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8959885459637436672:28:0)
[10:21:55.635] Build status: STARTED
[10:21:55.635] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8959885459637436672:29:0) after 1m42s
[10:23:37.701] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8959885459637436672:29:0)
[10:23:37.722] Build status: STARTED
[10:23:37.722] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8959885459637436672:30:0) after 1m3s
[10:24:40.811] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8959885459637436672:30:0)
[10:24:40.836] Build status: STARTED
[10:24:40.836] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8959885459637436672:31:0) after 3m20s
[10:28:00.955] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8959885459637436672:31:0)
[10:28:00.979] Build status: STARTED
[10:28:00.979] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8959885459637436672:32:0) after 8m37s
[10:36:38.255] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8959885459637436672:32:0)
[10:36:38.284] Build status: STARTED
[10:36:38.285] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8959885459637436672:33:0) after 9m6s
[10:45:44.654] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8959885459637436672:33:0)
[10:45:44.676] Build status: STARTED
[10:45:44.676] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8959885459637436672:34:0) after 1m45s
[10:47:29.697] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8959885459637436672:34:0)
[10:47:29.736] Build status: STARTED
[10:47:29.736] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8959885459637436672:35:0) after 6m3s
[10:53:32.788] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8959885459637436672:35:0)
[10:53:32.830] Build status: STARTED
[10:53:32.830] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8959885459637436672:36:0) after 2m38s
[10:56:10.859] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8959885459637436672:36:0)
[10:56:10.893] Build status: STARTED
[10:56:10.894] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8959885459637436672:37:0) after 2m37s
[10:58:48.107] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8959885459637436672:37:0)
[10:58:48.143] Build status: STARTED
[10:58:48.143] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8959885459637436672:38:0) after 7m22s
[11:01:09.055] Received PubSub notification, asking Buildbucket for the build status
[11:01:09.117] Build:
{
"id": "8794455397645273681",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Linux CFI"
},
"number": 24162,
"createdBy": "project:chromium",
"createTime": "2022-12-18T08:06:22.693712150Z",
"startTime": "2022-12-18T08:09:08.683959Z",
"endTime": "2022-12-18T11:01:08.324577451Z",
"updateTime": "2022-12-18T11:01:08.324577451Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "e72820b991f4445cde13b9803f73546b61ccf213",
"ref": "refs/heads/main"
}
}
}
[11:01:09.117] Invocation finished in 2h54m47.708307599s with status SUCCEEDED