[13:23:26.854] New invocation is queued and will start shortly
[13:23:28.008] Starting the invocation (attempt 1)
[13:23:28.059] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/28db3f40c0c68ea8e9040a012a0361bd6cd6f07f"
[13:23:28.059] Popped gitiles commit info from properties and tags
[13:23:28.059] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[13:23:28.059] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[13:23:28.059] Buildbucket request:
{
"requestId": "8913479863222397632",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Linux CFI"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "28db3f40c0c68ea8e9040a012a0361bd6cd6f07f",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8913479863222397632"
},
{
"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"
}
}
[13:23:28.748] Scheduled build:
{
"id": "8748049801183898225",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Linux CFI"
},
"number": 27600,
"createdBy": "project:chromium",
"createTime": "2024-05-13T13:23:28.176300460Z",
"updateTime": "2024-05-13T13:23:28.176300460Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "28db3f40c0c68ea8e9040a012a0361bd6cd6f07f",
"ref": "refs/heads/main"
}
}
}
[13:23:28.748] Task URL: https://cr-buildbucket.appspot.com/build/8748049801183898225
[13:23:28.748] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8913479863222397632:2:0) after 2m26s
[13:23:29.640] Received PubSub notification, asking Buildbucket for the build status
[13:23:29.681] Build status: SCHEDULED
[13:25:54.776] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8913479863222397632:2:0)
[13:25:54.833] Build status: SCHEDULED
[13:25:54.833] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8913479863222397632:4:0) after 6m24s
[13:30:35.320] Received PubSub notification, asking Buildbucket for the build status
[13:30:35.425] Build status: STARTED
[13:32:18.850] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8913479863222397632:4:0)
[13:32:18.955] Build status: STARTED
[13:32:18.955] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8913479863222397632:6:0) after 6m52s
[13:39:11.251] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8913479863222397632:6:0)
[13:39:11.277] Build status: STARTED
[13:39:11.277] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8913479863222397632:7:0) after 8m28s
[13:47:39.577] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8913479863222397632:7:0)
[13:47:39.598] Build status: STARTED
[13:47:39.598] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8913479863222397632:8:0) after 2m43s
[13:50:22.610] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8913479863222397632:8:0)
[13:50:22.629] Build status: STARTED
[13:50:22.629] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8913479863222397632:9:0) after 6m31s
[13:56:53.616] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8913479863222397632:9:0)
[13:56:53.644] Build status: STARTED
[13:56:53.644] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8913479863222397632:10:0) after 6m1s
[14:02:54.692] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8913479863222397632:10:0)
[14:02:54.739] Build status: STARTED
[14:02:54.739] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8913479863222397632:11:0) after 7m49s
[14:10:43.866] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8913479863222397632:11:0)
[14:10:43.887] Build status: STARTED
[14:10:43.887] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8913479863222397632:12:0) after 5m23s
[14:16:07.108] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8913479863222397632:12:0)
[14:16:07.136] Build status: STARTED
[14:16:07.136] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8913479863222397632:13:0) after 6m45s
[14:22:52.460] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8913479863222397632:13:0)
[14:22:52.517] Build status: STARTED
[14:22:52.517] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8913479863222397632:14:0) after 9m48s
[14:32:40.624] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8913479863222397632:14:0)
[14:32:40.667] Build status: STARTED
[14:32:40.667] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8913479863222397632:15:0) after 1m33s
[14:34:13.700] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8913479863222397632:15:0)
[14:34:13.722] Build status: STARTED
[14:34:13.722] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8913479863222397632:16:0) after 4m14s
[14:38:28.123] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8913479863222397632:16:0)
[14:38:28.179] Build status: STARTED
[14:38:28.179] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8913479863222397632:17:0) after 5m51s
[14:44:19.698] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8913479863222397632:17:0)
[14:44:19.775] Build status: STARTED
[14:44:19.775] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8913479863222397632:18:0) after 7m17s
[14:51:36.922] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8913479863222397632:18:0)
[14:51:36.946] Build status: STARTED
[14:51:36.946] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8913479863222397632:19:0) after 4m44s
[14:56:21.097] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8913479863222397632:19:0)
[14:56:21.117] Build status: STARTED
[14:56:21.117] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8913479863222397632:20:0) after 2m25s
[14:58:46.136] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8913479863222397632:20:0)
[14:58:46.158] Build status: STARTED
[14:58:46.158] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8913479863222397632:21:0) after 6m58s
[15:05:44.193] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8913479863222397632:21:0)
[15:05:44.220] Build status: STARTED
[15:05:44.220] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8913479863222397632:22:0) after 1m14s
[15:06:58.260] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8913479863222397632:22:0)
[15:06:58.306] Build status: STARTED
[15:06:58.306] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8913479863222397632:23:0) after 6m42s
[15:13:40.407] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8913479863222397632:23:0)
[15:13:40.436] Build status: STARTED
[15:13:40.436] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8913479863222397632:24:0) after 4m5s
[15:17:45.560] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8913479863222397632:24:0)
[15:17:45.581] Build status: STARTED
[15:17:45.581] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8913479863222397632:25:0) after 3m20s
[15:21:05.764] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8913479863222397632:25:0)
[15:21:05.782] Build status: STARTED
[15:21:05.782] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8913479863222397632:26:0) after 2m6s
[15:23:11.848] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8913479863222397632:26:0)
[15:23:11.867] Build status: STARTED
[15:23:11.867] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8913479863222397632:27:0) after 7m2s
[15:30:13.894] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8913479863222397632:27:0)
[15:30:13.924] Build status: STARTED
[15:30:13.924] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8913479863222397632:28:0) after 7m18s
[15:37:32.457] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8913479863222397632:28:0)
[15:37:32.482] Build status: STARTED
[15:37:32.482] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8913479863222397632:29:0) after 1m30s
[15:39:02.643] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8913479863222397632:29:0)
[15:39:02.673] Build status: STARTED
[15:39:02.673] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8913479863222397632:30:0) after 4m59s
[15:44:01.706] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8913479863222397632:30:0)
[15:44:01.772] Build status: STARTED
[15:44:01.772] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8913479863222397632:31:0) after 6m46s
[15:50:47.798] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8913479863222397632:31:0)
[15:50:48.036] Build status: STARTED
[15:50:48.036] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8913479863222397632:32:0) after 7m47s
[15:58:35.158] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8913479863222397632:32:0)
[15:58:35.178] Build status: STARTED
[15:58:35.178] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8913479863222397632:33:0) after 6m5s
[16:04:40.208] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8913479863222397632:33:0)
[16:04:40.226] Build status: STARTED
[16:04:40.226] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8913479863222397632:34:0) after 3m8s
[16:07:48.360] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8913479863222397632:34:0)
[16:07:48.379] Build status: STARTED
[16:07:48.379] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8913479863222397632:35:0) after 1m21s
[16:09:09.447] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8913479863222397632:35:0)
[16:09:09.498] Build status: STARTED
[16:09:09.498] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8913479863222397632:36:0) after 5m53s
[16:15:02.570] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8913479863222397632:36:0)
[16:15:02.668] Build status: STARTED
[16:15:02.669] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8913479863222397632:37:0) after 4m53s
[16:19:55.691] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8913479863222397632:37:0)
[16:19:55.719] Build status: STARTED
[16:19:55.719] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8913479863222397632:38:0) after 2m57s
[16:22:52.772] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8913479863222397632:38:0)
[16:22:52.807] Build status: STARTED
[16:22:52.807] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8913479863222397632:39:0) after 2m0s
[16:24:52.872] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8913479863222397632:39:0)
[16:24:52.899] Build status: STARTED
[16:24:52.899] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8913479863222397632:40:0) after 5m39s
[16:30:31.972] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8913479863222397632:40:0)
[16:30:31.996] Build status: STARTED
[16:30:31.996] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8913479863222397632:41:0) after 7m21s
[16:37:53.350] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8913479863222397632:41:0)
[16:37:53.371] Build status: STARTED
[16:37:53.371] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8913479863222397632:42:0) after 2m6s
[16:39:59.671] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8913479863222397632:42:0)
[16:39:59.727] Build status: STARTED
[16:39:59.727] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8913479863222397632:43:0) after 8m52s
[16:48:51.840] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8913479863222397632:43:0)
[16:48:51.857] Build status: STARTED
[16:48:51.857] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8913479863222397632:44:0) after 1m56s
[16:50:47.920] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8913479863222397632:44:0)
[16:50:47.972] Build status: STARTED
[16:50:47.972] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8913479863222397632:45:0) after 9m4s
[16:59:51.997] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8913479863222397632:45:0)
[16:59:52.027] Build status: STARTED
[16:59:52.027] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8913479863222397632:46:0) after 3m32s
[17:03:24.106] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8913479863222397632:46:0)
[17:03:24.162] Build status: STARTED
[17:03:24.162] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8913479863222397632:47:0) after 1m3s
[17:04:27.198] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8913479863222397632:47:0)
[17:04:27.291] Build status: STARTED
[17:04:27.291] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8913479863222397632:48:0) after 5m32s
[17:09:59.352] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8913479863222397632:48:0)
[17:09:59.372] Build status: STARTED
[17:09:59.372] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8913479863222397632:49:0) after 1m47s
[17:11:46.393] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8913479863222397632:49:0)
[17:11:46.453] Build status: STARTED
[17:11:46.453] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8913479863222397632:50:0) after 9m32s
[17:21:18.587] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8913479863222397632:50:0)
[17:21:18.627] Build status: STARTED
[17:21:18.627] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8913479863222397632:51:0) after 2m21s
[17:23:39.660] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8913479863222397632:51:0)
[17:23:39.677] Build status: STARTED
[17:23:39.677] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8913479863222397632:52:0) after 3m58s
[17:27:27.645] Received PubSub notification, asking Buildbucket for the build status
[17:27:27.775] Build:
{
"id": "8748049801183898225",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Linux CFI"
},
"number": 27600,
"createdBy": "project:chromium",
"createTime": "2024-05-13T13:23:28.176300460Z",
"startTime": "2024-05-13T13:30:34.919369493Z",
"endTime": "2024-05-13T17:27:26.876945933Z",
"updateTime": "2024-05-13T17:27:26.876945933Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "28db3f40c0c68ea8e9040a012a0361bd6cd6f07f",
"ref": "refs/heads/main"
}
}
}
[17:27:27.775] Invocation finished in 4h4m0.926963733s with status SUCCEEDED