[08:16:00.672] New invocation is queued and will start shortly
[08:16:02.042] Starting the invocation (attempt 1)
[08:16:02.083] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/670a8bf1c080ab47292fbde26f085a6d59c660c1"
[08:16:02.083] Popped gitiles commit info from properties and tags
[08:16:02.083] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[08:16:02.083] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[08:16:02.083] Buildbucket request:
{
"requestId": "8982624690819728096",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "linux-official"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "670a8bf1c080ab47292fbde26f085a6d59c660c1",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8982624690819728096"
},
{
"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"
}
}
[08:16:02.887] Scheduled build:
{
"id": "8817194628599272305",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "linux-official"
},
"number": 4735,
"createdBy": "project:chromium",
"createTime": "2022-04-11T08:16:02.157606249Z",
"updateTime": "2022-04-11T08:16:02.157606249Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "670a8bf1c080ab47292fbde26f085a6d59c660c1",
"ref": "refs/heads/main"
}
}
}
[08:16:02.887] Task URL: https://cr-buildbucket.appspot.com/build/8817194628599272305
[08:16:02.887] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:2:0) after 1m31s
[08:17:33.903] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:2:0)
[08:17:33.922] Build status: SCHEDULED
[08:17:33.922] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:3:0) after 6m8s
[08:20:21.053] Received PubSub notification, asking Buildbucket for the build status
[08:20:21.076] Build status: STARTED
[08:23:41.938] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:3:0)
[08:23:41.963] Build status: STARTED
[08:23:41.963] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:5:0) after 6m49s
[08:30:30.980] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:5:0)
[08:30:31.011] Build status: STARTED
[08:30:31.011] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:6:0) after 7m38s
[08:38:09.068] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:6:0)
[08:38:09.097] Build status: STARTED
[08:38:09.097] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:7:0) after 5m48s
[08:43:57.238] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:7:0)
[08:43:57.283] Build status: STARTED
[08:43:57.283] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:8:0) after 3m14s
[08:47:11.388] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:8:0)
[08:47:11.413] Build status: STARTED
[08:47:11.413] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:9:0) after 5m44s
[08:52:55.555] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:9:0)
[08:52:55.575] Build status: STARTED
[08:52:55.575] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:10:0) after 4m14s
[08:57:09.603] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:10:0)
[08:57:09.626] Build status: STARTED
[08:57:09.626] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:11:0) after 7m34s
[09:04:43.771] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:11:0)
[09:04:43.794] Build status: STARTED
[09:04:43.794] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:12:0) after 5m7s
[09:09:50.936] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:12:0)
[09:09:50.958] Build status: STARTED
[09:09:50.958] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:13:0) after 4m36s
[09:14:27.027] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:13:0)
[09:14:27.057] Build status: STARTED
[09:14:27.057] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:14:0) after 6m39s
[09:21:06.255] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:14:0)
[09:21:06.357] Build status: STARTED
[09:21:06.357] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:15:0) after 4m46s
[09:25:52.367] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:15:0)
[09:25:52.387] Build status: STARTED
[09:25:52.387] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:16:0) after 4m21s
[09:30:13.427] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:16:0)
[09:30:13.459] Build status: STARTED
[09:30:13.459] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:17:0) after 9m26s
[09:39:39.454] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:17:0)
[09:39:39.490] Build status: STARTED
[09:39:39.490] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:18:0) after 1m5s
[09:40:44.764] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:18:0)
[09:40:44.857] Build status: STARTED
[09:40:44.857] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:19:0) after 7m13s
[09:47:57.895] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:19:0)
[09:47:57.923] Build status: STARTED
[09:47:57.923] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:20:0) after 6m32s
[09:54:29.955] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:20:0)
[09:54:29.978] Build status: STARTED
[09:54:29.978] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:21:0) after 8m22s
[10:02:52.102] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:21:0)
[10:02:52.121] Build status: STARTED
[10:02:52.121] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:22:0) after 5m15s
[10:08:07.172] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:22:0)
[10:08:07.195] Build status: STARTED
[10:08:07.195] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:23:0) after 2m2s
[10:10:09.214] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:23:0)
[10:10:09.241] Build status: STARTED
[10:10:09.241] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:24:0) after 5m5s
[10:15:14.454] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:24:0)
[10:15:14.511] Build status: STARTED
[10:15:14.511] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:25:0) after 7m25s
[10:22:39.568] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:25:0)
[10:22:39.589] Build status: STARTED
[10:22:39.589] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:26:0) after 8m1s
[10:30:40.611] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:26:0)
[10:30:40.642] Build status: STARTED
[10:30:40.642] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:27:0) after 4m56s
[10:35:36.808] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:27:0)
[10:35:36.859] Build status: STARTED
[10:35:36.859] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:28:0) after 1m9s
[10:36:45.993] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:28:0)
[10:36:46.029] Build status: STARTED
[10:36:46.029] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:29:0) after 3m43s
[10:40:29.078] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:29:0)
[10:40:29.108] Build status: STARTED
[10:40:29.108] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:30:0) after 9m26s
[10:49:55.148] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:30:0)
[10:49:55.180] Build status: STARTED
[10:49:55.180] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:31:0) after 3m41s
[10:53:36.302] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:31:0)
[10:53:36.326] Build status: STARTED
[10:53:36.326] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:32:0) after 2m37s
[10:56:13.422] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:32:0)
[10:56:13.444] Build status: STARTED
[10:56:13.444] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:33:0) after 1m34s
[10:57:47.467] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:33:0)
[10:57:47.564] Build status: STARTED
[10:57:47.564] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:34:0) after 5m40s
[11:03:27.584] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:34:0)
[11:03:27.609] Build status: STARTED
[11:03:27.609] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:35:0) after 4m38s
[11:08:05.655] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:35:0)
[11:08:05.685] Build status: STARTED
[11:08:05.685] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:36:0) after 3m30s
[11:11:35.725] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:36:0)
[11:11:35.749] Build status: STARTED
[11:11:35.749] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:37:0) after 9m14s
[11:20:49.817] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:37:0)
[11:20:49.849] Build status: STARTED
[11:20:49.849] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:38:0) after 5m47s
[11:26:36.858] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:38:0)
[11:26:36.884] Build status: STARTED
[11:26:36.884] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:39:0) after 5m55s
[11:32:31.982] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:39:0)
[11:32:32.014] Build status: STARTED
[11:32:32.015] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:40:0) after 1m32s
[11:34:04.037] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:40:0)
[11:34:04.069] Build status: STARTED
[11:34:04.069] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:41:0) after 3m28s
[11:37:32.122] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:41:0)
[11:37:32.152] Build status: STARTED
[11:37:32.152] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:42:0) after 6m45s
[11:44:17.188] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:42:0)
[11:44:17.244] Build status: STARTED
[11:44:17.245] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:43:0) after 5m57s
[11:50:14.305] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:43:0)
[11:50:14.324] Build status: STARTED
[11:50:14.324] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:44:0) after 7m19s
[11:57:33.520] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:44:0)
[11:57:33.547] Build status: STARTED
[11:57:33.547] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:45:0) after 7m37s
[12:05:10.629] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:45:0)
[12:05:10.664] Build status: STARTED
[12:05:10.664] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:46:0) after 3m43s
[12:08:53.925] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:46:0)
[12:08:53.968] Build status: STARTED
[12:08:53.968] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:47:0) after 5m8s
[12:14:02.068] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:47:0)
[12:14:02.101] Build status: STARTED
[12:14:02.101] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:48:0) after 7m0s
[12:21:02.124] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:48:0)
[12:21:02.157] Build status: STARTED
[12:21:02.157] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:49:0) after 5m25s
[12:26:27.212] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:49:0)
[12:26:27.240] Build status: STARTED
[12:26:27.240] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:50:0) after 6m46s
[12:33:13.306] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:50:0)
[12:33:13.331] Build status: STARTED
[12:33:13.331] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:51:0) after 5m57s
[12:39:10.353] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:51:0)
[12:39:10.385] Build status: STARTED
[12:39:10.385] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:52:0) after 4m28s
[12:43:38.409] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:52:0)
[12:43:38.443] Build status: STARTED
[12:43:38.443] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:53:0) after 7m20s
[12:50:58.468] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:53:0)
[12:50:58.491] Build status: STARTED
[12:50:58.491] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:54:0) after 2m32s
[12:53:30.628] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:54:0)
[12:53:30.649] Build status: STARTED
[12:53:30.649] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:55:0) after 7m56s
[13:01:26.882] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:55:0)
[13:01:26.930] Build status: STARTED
[13:01:26.930] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:56:0) after 8m55s
[13:10:22.005] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:56:0)
[13:10:22.150] Build status: STARTED
[13:10:22.150] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:57:0) after 2m2s
[13:12:24.304] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:57:0)
[13:12:24.327] Build status: STARTED
[13:12:24.327] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:58:0) after 5m5s
[13:17:29.361] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:58:0)
[13:17:29.385] Build status: STARTED
[13:17:29.385] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:59:0) after 2m32s
[13:20:01.413] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:59:0)
[13:20:01.434] Build status: STARTED
[13:20:01.434] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:60:0) after 5m2s
[13:25:03.729] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:60:0)
[13:25:03.774] Build status: STARTED
[13:25:03.774] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:61:0) after 7m20s
[13:32:23.810] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:61:0)
[13:32:23.846] Build status: STARTED
[13:32:23.846] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:62:0) after 8m5s
[13:40:28.888] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:62:0)
[13:40:28.910] Build status: STARTED
[13:40:28.910] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:63:0) after 3m12s
[13:43:41.368] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:63:0)
[13:43:41.430] Build status: STARTED
[13:43:41.430] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:64:0) after 3m55s
[13:47:36.456] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:64:0)
[13:47:36.491] Build status: STARTED
[13:47:36.491] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:65:0) after 2m46s
[13:50:22.510] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:65:0)
[13:50:22.530] Build status: STARTED
[13:50:22.530] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:66:0) after 5m23s
[13:55:45.595] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:66:0)
[13:55:45.615] Build status: STARTED
[13:55:45.615] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:67:0) after 9m39s
[14:05:24.754] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:67:0)
[14:05:24.806] Build status: STARTED
[14:05:24.806] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:68:0) after 3m16s
[14:08:41.135] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:68:0)
[14:08:41.156] Build status: STARTED
[14:08:41.156] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:69:0) after 1m23s
[14:10:04.279] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:69:0)
[14:10:04.343] Build status: STARTED
[14:10:04.343] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:70:0) after 2m41s
[14:12:45.384] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:70:0)
[14:12:45.416] Build status: STARTED
[14:12:45.416] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:71:0) after 6m14s
[14:18:59.484] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:71:0)
[14:18:59.506] Build status: STARTED
[14:18:59.506] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982624690819728096:72:0) after 4m13s
[14:19:52.869] Received PubSub notification, asking Buildbucket for the build status
[14:19:52.901] Build:
{
"id": "8817194628599272305",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "linux-official"
},
"number": 4735,
"createdBy": "project:chromium",
"createTime": "2022-04-11T08:16:02.157606249Z",
"startTime": "2022-04-11T08:20:20.090131Z",
"endTime": "2022-04-11T14:19:52.431884322Z",
"updateTime": "2022-04-11T14:19:52.431884322Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "670a8bf1c080ab47292fbde26f085a6d59c660c1",
"ref": "refs/heads/main"
}
}
}
[14:19:52.901] Invocation finished in 6h3m52.244825699s with status SUCCEEDED