[14:52:56.937] New invocation is queued and will start shortly
[14:52:57.985] Starting the invocation (attempt 1)
[14:52:58.022] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/955f0102afba77ad5062ba25a9be09ad973d8f3a"
[14:52:58.022] Popped gitiles commit info from properties and tags
[14:52:58.022] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:52:58.023] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:52:58.023] Buildbucket request:
{
"requestId": "8987944938671556304",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "linux-official"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "955f0102afba77ad5062ba25a9be09ad973d8f3a",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8987944938671556304"
},
{
"key": "scheduler_job_id",
"value": "chromium/linux-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[14:52:58.590] Scheduled build:
{
"id": "8822514876790182945",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "linux-official"
},
"number": 4385,
"createdBy": "project:chromium",
"createTime": "2022-02-11T14:52:58.101445609Z",
"updateTime": "2022-02-11T14:52:58.101445609Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "955f0102afba77ad5062ba25a9be09ad973d8f3a",
"ref": "refs/heads/main"
}
}
}
[14:52:58.590] Task URL: https://cr-buildbucket.appspot.com/build/8822514876790182945
[14:52:58.590] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8987944938671556304:2:0) after 9m24s
[14:53:38.280] Received PubSub notification, asking Buildbucket for the build status
[14:53:38.349] Build status: STARTED
[15:02:22.662] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8987944938671556304:2:0)
[15:02:22.694] Build status: STARTED
[15:02:22.696] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8987944938671556304:4:0) after 5m37s
[15:07:59.782] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8987944938671556304:4:0)
[15:07:59.892] Build status: STARTED
[15:07:59.892] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8987944938671556304:5:0) after 9m16s
[15:17:15.895] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8987944938671556304:5:0)
[15:17:15.945] Build status: STARTED
[15:17:15.945] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8987944938671556304:6:0) after 5m2s
[15:22:18.031] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8987944938671556304:6:0)
[15:22:18.049] Build status: STARTED
[15:22:18.049] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8987944938671556304:7:0) after 2m47s
[15:25:05.071] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8987944938671556304:7:0)
[15:25:05.157] Build status: STARTED
[15:25:05.157] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8987944938671556304:8:0) after 8m2s
[15:33:07.390] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8987944938671556304:8:0)
[15:33:07.417] Build status: STARTED
[15:33:07.417] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8987944938671556304:9:0) after 6m48s
[15:39:55.413] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8987944938671556304:9:0)
[15:39:55.440] Build status: STARTED
[15:39:55.440] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8987944938671556304:10:0) after 8m11s
[15:48:06.534] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8987944938671556304:10:0)
[15:48:06.571] Build status: STARTED
[15:48:06.571] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8987944938671556304:11:0) after 3m39s
[15:51:45.699] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8987944938671556304:11:0)
[15:51:45.760] Build status: STARTED
[15:51:45.760] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8987944938671556304:12:0) after 3m4s
[15:54:49.886] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8987944938671556304:12:0)
[15:54:49.918] Build status: STARTED
[15:54:49.918] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8987944938671556304:13:0) after 3m20s
[15:58:10.267] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8987944938671556304:13:0)
[15:58:10.290] Build status: STARTED
[15:58:10.290] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8987944938671556304:14:0) after 9m59s
[16:08:09.312] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8987944938671556304:14:0)
[16:08:09.392] Build status: STARTED
[16:08:09.392] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8987944938671556304:15:0) after 8m53s
[16:17:02.487] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8987944938671556304:15:0)
[16:17:02.514] Build status: STARTED
[16:17:02.514] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8987944938671556304:16:0) after 2m50s
[16:19:52.539] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8987944938671556304:16:0)
[16:19:52.575] Build status: STARTED
[16:19:52.575] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8987944938671556304:17:0) after 2m44s
[16:22:36.600] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8987944938671556304:17:0)
[16:22:36.620] Build status: STARTED
[16:22:36.620] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8987944938671556304:18:0) after 2m59s
[16:25:35.648] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8987944938671556304:18:0)
[16:25:35.668] Build status: STARTED
[16:25:35.668] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8987944938671556304:19:0) after 7m41s
[16:33:16.719] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8987944938671556304:19:0)
[16:33:16.742] Build status: STARTED
[16:33:16.742] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8987944938671556304:20:0) after 7m26s
[16:40:42.961] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8987944938671556304:20:0)
[16:40:42.984] Build status: STARTED
[16:40:42.984] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8987944938671556304:21:0) after 9m10s
[16:49:53.061] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8987944938671556304:21:0)
[16:49:53.081] Build status: STARTED
[16:49:53.081] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8987944938671556304:22:0) after 4m19s
[16:54:12.143] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8987944938671556304:22:0)
[16:54:12.201] Build status: STARTED
[16:54:12.201] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8987944938671556304:23:0) after 2m55s
[16:57:07.239] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8987944938671556304:23:0)
[16:57:07.276] Build status: STARTED
[16:57:07.276] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8987944938671556304:24:0) after 5m37s
[17:02:44.448] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8987944938671556304:24:0)
[17:02:44.556] Build status: STARTED
[17:02:44.556] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8987944938671556304:25:0) after 8m56s
[17:11:40.585] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8987944938671556304:25:0)
[17:11:40.604] Build status: STARTED
[17:11:40.604] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8987944938671556304:26:0) after 1m37s
[17:13:17.613] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8987944938671556304:26:0)
[17:13:17.647] Build status: STARTED
[17:13:17.647] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8987944938671556304:27:0) after 5m35s
[17:18:52.675] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8987944938671556304:27:0)
[17:18:52.698] Build status: STARTED
[17:18:52.698] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8987944938671556304:28:0) after 1m5s
[17:19:57.709] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8987944938671556304:28:0)
[17:19:57.735] Build status: STARTED
[17:19:57.735] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8987944938671556304:29:0) after 5m6s
[17:25:03.912] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8987944938671556304:29:0)
[17:25:03.930] Build status: STARTED
[17:25:03.931] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8987944938671556304:30:0) after 7m18s
[17:32:22.027] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8987944938671556304:30:0)
[17:32:22.047] Build status: STARTED
[17:32:22.047] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8987944938671556304:31:0) after 7m8s
[17:39:30.148] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8987944938671556304:31:0)
[17:39:30.185] Build status: STARTED
[17:39:30.185] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8987944938671556304:32:0) after 4m34s
[17:44:04.490] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8987944938671556304:32:0)
[17:44:04.511] Build status: STARTED
[17:44:04.512] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8987944938671556304:33:0) after 2m42s
[17:46:46.616] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8987944938671556304:33:0)
[17:46:46.634] Build status: STARTED
[17:46:46.634] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8987944938671556304:34:0) after 1m24s
[17:48:10.895] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8987944938671556304:34:0)
[17:48:10.916] Build status: STARTED
[17:48:10.916] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8987944938671556304:35:0) after 6m3s
[17:54:13.992] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8987944938671556304:35:0)
[17:54:14.012] Build status: STARTED
[17:54:14.012] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8987944938671556304:36:0) after 3m59s
[17:58:13.576] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8987944938671556304:36:0)
[17:58:13.613] Build status: STARTED
[17:58:13.613] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8987944938671556304:37:0) after 8m32s
[18:06:45.688] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8987944938671556304:37:0)
[18:06:45.758] Build status: STARTED
[18:06:45.758] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8987944938671556304:38:0) after 9m18s
[18:16:03.911] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8987944938671556304:38:0)
[18:16:03.932] Build status: STARTED
[18:16:03.932] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8987944938671556304:39:0) after 3m26s
[18:19:29.995] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8987944938671556304:39:0)
[18:19:30.051] Build status: STARTED
[18:19:30.051] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8987944938671556304:40:0) after 5m20s
[18:24:50.257] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8987944938671556304:40:0)
[18:24:50.277] Build status: STARTED
[18:24:50.277] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8987944938671556304:41:0) after 8m10s
[18:33:00.329] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8987944938671556304:41:0)
[18:33:00.360] Build status: STARTED
[18:33:00.360] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8987944938671556304:42:0) after 6m8s
[18:39:08.387] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8987944938671556304:42:0)
[18:39:08.424] Build status: STARTED
[18:39:08.424] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8987944938671556304:43:0) after 9m53s
[18:49:01.590] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8987944938671556304:43:0)
[18:49:01.611] Build status: STARTED
[18:49:01.611] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8987944938671556304:44:0) after 9m41s
[18:58:42.723] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8987944938671556304:44:0)
[18:58:42.744] Build status: STARTED
[18:58:42.744] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8987944938671556304:45:0) after 8m55s
[19:07:37.836] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8987944938671556304:45:0)
[19:07:37.869] Build status: STARTED
[19:07:37.869] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8987944938671556304:46:0) after 3m42s
[19:11:20.029] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8987944938671556304:46:0)
[19:11:20.309] Build status: STARTED
[19:11:20.309] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8987944938671556304:47:0) after 3m30s
[19:14:50.452] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8987944938671556304:47:0)
[19:14:50.481] Build status: STARTED
[19:14:50.481] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8987944938671556304:48:0) after 8m6s
[19:15:19.472] Received PubSub notification, asking Buildbucket for the build status
[19:15:19.494] Build:
{
"id": "8822514876790182945",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "linux-official"
},
"number": 4385,
"createdBy": "project:chromium",
"createTime": "2022-02-11T14:52:58.101445609Z",
"startTime": "2022-02-11T14:53:37.588720Z",
"endTime": "2022-02-11T19:15:19.189107690Z",
"updateTime": "2022-02-11T19:15:19.189107690Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "955f0102afba77ad5062ba25a9be09ad973d8f3a",
"ref": "refs/heads/main"
}
}
}
[19:15:19.494] Invocation finished in 4h22m22.571056053s with status SUCCEEDED