[16:40:02.441] New invocation is queued and will start shortly
[16:40:03.615] Starting the invocation (attempt 1)
[16:40:03.680] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2a263d9ce496e493c0b3a9c0dbfcd48e23414504"
[16:40:03.680] Popped gitiles commit info from properties and tags
[16:40:03.680] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:40:03.680] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:40:03.680] Buildbucket request:
{
"requestId": "9017201021189139968",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Linux CFI"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "2a263d9ce496e493c0b3a9c0dbfcd48e23414504",
"ref": "refs/heads/master"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "9017201021189139968"
},
{
"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"
}
}
[16:40:06.213] Scheduled build:
{
"id": "8851770958942319392",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Linux CFI"
},
"number": 20001,
"createdBy": "project:chromium",
"createTime": "2021-03-25T16:40:03.953204Z",
"updateTime": "2021-03-25T16:40:05.970625Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "2a263d9ce496e493c0b3a9c0dbfcd48e23414504",
"ref": "refs/heads/master"
}
}
}
[16:40:06.213] Task URL: https://cr-buildbucket.appspot.com/build/8851770958942319392
[16:40:06.213] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9017201021189139968:2:0) after 7m21s
[16:45:39.690] Received PubSub notification, asking Buildbucket for the build status
[16:45:39.716] Build status: STARTED
[16:47:27.421] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9017201021189139968:2:0)
[16:47:27.462] Build status: STARTED
[16:47:27.462] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9017201021189139968:4:0) after 5m22s
[16:52:49.557] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9017201021189139968:4:0)
[16:52:49.598] Build status: STARTED
[16:52:49.598] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9017201021189139968:5:0) after 5m20s
[16:58:09.625] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9017201021189139968:5:0)
[16:58:09.703] Build status: STARTED
[16:58:09.703] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9017201021189139968:6:0) after 8m49s
[17:06:58.793] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9017201021189139968:6:0)
[17:06:58.836] Build status: STARTED
[17:06:58.836] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9017201021189139968:7:0) after 7m49s
[17:14:47.850] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9017201021189139968:7:0)
[17:14:47.882] Build status: STARTED
[17:14:47.882] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9017201021189139968:8:0) after 6m42s
[17:21:29.885] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9017201021189139968:8:0)
[17:21:29.916] Build status: STARTED
[17:21:29.916] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9017201021189139968:9:0) after 1m24s
[17:22:53.972] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9017201021189139968:9:0)
[17:22:54.008] Build status: STARTED
[17:22:54.008] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9017201021189139968:10:0) after 3m10s
[17:26:04.163] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9017201021189139968:10:0)
[17:26:04.194] Build status: STARTED
[17:26:04.194] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9017201021189139968:11:0) after 8m47s
[17:34:51.224] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9017201021189139968:11:0)
[17:34:51.248] Build status: STARTED
[17:34:51.249] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9017201021189139968:12:0) after 6m12s
[17:41:03.448] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9017201021189139968:12:0)
[17:41:03.477] Build status: STARTED
[17:41:03.477] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9017201021189139968:13:0) after 2m25s
[17:43:28.593] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9017201021189139968:13:0)
[17:43:28.620] Build status: STARTED
[17:43:28.620] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9017201021189139968:14:0) after 5m19s
[17:48:47.643] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9017201021189139968:14:0)
[17:48:47.673] Build status: STARTED
[17:48:47.673] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9017201021189139968:15:0) after 9m35s
[17:58:22.741] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9017201021189139968:15:0)
[17:58:22.822] Build status: STARTED
[17:58:22.822] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9017201021189139968:16:0) after 8m36s
[18:06:58.854] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9017201021189139968:16:0)
[18:06:58.879] Build status: STARTED
[18:06:58.879] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9017201021189139968:17:0) after 6m52s
[18:13:51.007] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9017201021189139968:17:0)
[18:13:51.032] Build status: STARTED
[18:13:51.032] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9017201021189139968:18:0) after 8m11s
[18:22:02.125] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9017201021189139968:18:0)
[18:22:02.151] Build status: STARTED
[18:22:02.151] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9017201021189139968:19:0) after 2m32s
[18:24:34.342] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9017201021189139968:19:0)
[18:24:34.371] Build status: STARTED
[18:24:34.371] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9017201021189139968:20:0) after 5m29s
[18:30:03.484] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9017201021189139968:20:0)
[18:30:03.514] Build status: STARTED
[18:30:03.514] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9017201021189139968:21:0) after 3m53s
[18:33:56.620] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9017201021189139968:21:0)
[18:33:56.653] Build status: STARTED
[18:33:56.653] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9017201021189139968:22:0) after 1m28s
[18:35:24.724] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9017201021189139968:22:0)
[18:35:24.755] Build status: STARTED
[18:35:24.755] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9017201021189139968:23:0) after 5m29s
[18:40:53.793] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9017201021189139968:23:0)
[18:40:53.822] Build status: STARTED
[18:40:53.822] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9017201021189139968:24:0) after 6m6s
[18:47:00.171] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9017201021189139968:24:0)
[18:47:00.218] Build status: STARTED
[18:47:00.218] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9017201021189139968:25:0) after 7m31s
[18:54:31.276] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9017201021189139968:25:0)
[18:54:31.307] Build status: STARTED
[18:54:31.307] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9017201021189139968:26:0) after 1m10s
[18:55:41.489] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9017201021189139968:26:0)
[18:55:41.548] Build status: STARTED
[18:55:41.548] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9017201021189139968:27:0) after 4m50s
[19:00:31.638] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9017201021189139968:27:0)
[19:00:31.668] Build status: STARTED
[19:00:31.668] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9017201021189139968:28:0) after 3m21s
[19:03:52.745] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9017201021189139968:28:0)
[19:03:52.882] Build status: STARTED
[19:03:52.882] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9017201021189139968:29:0) after 8m36s
[19:12:29.062] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9017201021189139968:29:0)
[19:12:29.090] Build status: STARTED
[19:12:29.090] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9017201021189139968:30:0) after 4m40s
[19:17:09.116] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9017201021189139968:30:0)
[19:17:09.146] Build status: STARTED
[19:17:09.146] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9017201021189139968:31:0) after 6m17s
[19:23:26.174] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9017201021189139968:31:0)
[19:23:26.206] Build status: STARTED
[19:23:26.206] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9017201021189139968:32:0) after 9m26s
[19:32:52.236] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9017201021189139968:32:0)
[19:32:52.265] Build status: STARTED
[19:32:52.265] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9017201021189139968:33:0) after 7m51s
[19:40:43.350] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9017201021189139968:33:0)
[19:40:43.434] Build status: STARTED
[19:40:43.434] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9017201021189139968:34:0) after 3m29s
[19:44:12.561] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9017201021189139968:34:0)
[19:44:12.637] Build status: STARTED
[19:44:12.637] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9017201021189139968:35:0) after 6m41s
[19:50:53.670] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9017201021189139968:35:0)
[19:50:53.712] Build status: STARTED
[19:50:53.712] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9017201021189139968:36:0) after 3m56s
[19:54:49.742] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9017201021189139968:36:0)
[19:54:49.773] Build status: STARTED
[19:54:49.773] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9017201021189139968:37:0) after 3m38s
[19:58:27.795] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9017201021189139968:37:0)
[19:58:27.825] Build status: STARTED
[19:58:27.825] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9017201021189139968:38:0) after 5m45s
[20:04:12.843] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9017201021189139968:38:0)
[20:04:12.874] Build status: STARTED
[20:04:12.874] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9017201021189139968:39:0) after 5m33s
[20:09:45.924] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9017201021189139968:39:0)
[20:09:45.951] Build status: STARTED
[20:09:45.952] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9017201021189139968:40:0) after 4m44s
[20:14:30.082] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9017201021189139968:40:0)
[20:14:30.109] Build status: STARTED
[20:14:30.109] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9017201021189139968:41:0) after 6m36s
[20:21:06.248] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9017201021189139968:41:0)
[20:21:06.279] Build status: STARTED
[20:21:06.279] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9017201021189139968:42:0) after 6m2s
[20:27:08.598] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9017201021189139968:42:0)
[20:27:08.631] Build status: STARTED
[20:27:08.631] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9017201021189139968:43:0) after 7m11s
[20:34:20.090] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9017201021189139968:43:0)
[20:34:20.114] Build status: STARTED
[20:34:20.114] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9017201021189139968:44:0) after 5m18s
[20:39:38.347] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9017201021189139968:44:0)
[20:39:38.373] Build status: STARTED
[20:39:38.373] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9017201021189139968:45:0) after 5m11s
[20:44:49.402] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9017201021189139968:45:0)
[20:44:49.428] Build status: STARTED
[20:44:49.428] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9017201021189139968:46:0) after 4m44s
[20:48:13.391] Received PubSub notification, asking Buildbucket for the build status
[20:48:13.420] Build:
{
"id": "8851770958942319392",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Linux CFI"
},
"number": 20001,
"createdBy": "project:chromium",
"createTime": "2021-03-25T16:40:03.953204Z",
"startTime": "2021-03-25T16:45:36.200892Z",
"endTime": "2021-03-25T20:48:11.809356Z",
"updateTime": "2021-03-25T20:48:12.224758Z",
"status": "INFRA_FAILURE",
"statusDetails": {
"timeout": {
}
},
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "2a263d9ce496e493c0b3a9c0dbfcd48e23414504",
"ref": "refs/heads/master"
}
}
}
[20:48:13.420] Invocation finished in 4h8m10.993120412s with status FAILED