[00:57:34.435] New invocation is queued and will start shortly
[00:57:35.553] Starting the invocation (attempt 1)
[00:57:35.582] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/bd31db4e3289aacb3b44b9163c5462c551443ff8"
[00:57:35.582] Popped gitiles commit info from properties and tags
[00:57:35.582] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:57:35.582] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:57:35.582] Buildbucket request:
{
"requestId": "8916425892470118272",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ChromeOS FYI Release (amd64-generic)"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "bd31db4e3289aacb3b44b9163c5462c551443ff8",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8916425892470118272"
},
{
"key": "scheduler_job_id",
"value": "chromium/ChromeOS FYI Release (amd64-generic)"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[00:57:36.089] Scheduled build:
{
"id": "8750995830546149457",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ChromeOS FYI Release (amd64-generic)"
},
"number": 11372,
"createdBy": "project:chromium",
"createTime": "2024-04-11T00:57:35.637596720Z",
"updateTime": "2024-04-11T00:57:35.637596720Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "bd31db4e3289aacb3b44b9163c5462c551443ff8",
"ref": "refs/heads/main"
}
}
}
[00:57:36.089] Task URL: https://cr-buildbucket.appspot.com/build/8750995830546149457
[00:57:36.089] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8916425892470118272:2:0) after 2m23s
[00:57:39.068] Received PubSub notification, asking Buildbucket for the build status
[00:57:39.090] Build status: SCHEDULED
[00:57:47.204] Received PubSub notification, asking Buildbucket for the build status
[00:57:47.221] Build status: STARTED
[00:59:59.067] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8916425892470118272:2:0)
[00:59:59.093] Build status: STARTED
[00:59:59.093] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8916425892470118272:5:0) after 8m5s
[01:08:04.143] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8916425892470118272:5:0)
[01:08:04.171] Build status: STARTED
[01:08:04.171] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8916425892470118272:6:0) after 1m44s
[01:09:48.215] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8916425892470118272:6:0)
[01:09:48.270] Build status: STARTED
[01:09:48.270] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8916425892470118272:7:0) after 1m22s
[01:11:10.303] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8916425892470118272:7:0)
[01:11:10.328] Build status: STARTED
[01:11:10.328] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8916425892470118272:8:0) after 7m8s
[01:18:18.363] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8916425892470118272:8:0)
[01:18:18.389] Build status: STARTED
[01:18:18.389] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8916425892470118272:9:0) after 2m21s
[01:20:39.426] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8916425892470118272:9:0)
[01:20:39.480] Build status: STARTED
[01:20:39.480] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8916425892470118272:10:0) after 2m21s
[01:23:00.507] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8916425892470118272:10:0)
[01:23:00.534] Build status: STARTED
[01:23:00.534] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8916425892470118272:11:0) after 4m59s
[01:28:00.288] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8916425892470118272:11:0)
[01:28:00.309] Build status: STARTED
[01:28:00.309] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8916425892470118272:12:0) after 4m32s
[01:32:32.396] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8916425892470118272:12:0)
[01:32:32.419] Build status: STARTED
[01:32:32.420] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8916425892470118272:13:0) after 6m48s
[01:39:20.690] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8916425892470118272:13:0)
[01:39:20.709] Build status: STARTED
[01:39:20.709] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8916425892470118272:14:0) after 5m53s
[01:45:13.809] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8916425892470118272:14:0)
[01:45:13.834] Build status: STARTED
[01:45:13.835] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8916425892470118272:15:0) after 6m8s
[01:51:21.892] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8916425892470118272:15:0)
[01:51:21.951] Build status: STARTED
[01:51:21.951] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8916425892470118272:16:0) after 3m41s
[01:55:02.982] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8916425892470118272:16:0)
[01:55:03.004] Build status: STARTED
[01:55:03.004] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8916425892470118272:17:0) after 4m34s
[01:59:37.401] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8916425892470118272:17:0)
[01:59:37.418] Build status: STARTED
[01:59:37.418] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8916425892470118272:18:0) after 5m16s
[02:04:53.543] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8916425892470118272:18:0)
[02:04:53.569] Build status: STARTED
[02:04:53.569] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8916425892470118272:19:0) after 4m8s
[02:09:01.612] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8916425892470118272:19:0)
[02:09:01.640] Build status: STARTED
[02:09:01.641] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8916425892470118272:20:0) after 9m26s
[02:18:27.666] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8916425892470118272:20:0)
[02:18:27.724] Build status: STARTED
[02:18:27.724] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8916425892470118272:21:0) after 3m23s
[02:21:50.764] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8916425892470118272:21:0)
[02:21:50.785] Build status: STARTED
[02:21:50.785] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8916425892470118272:22:0) after 2m23s
[02:24:14.008] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8916425892470118272:22:0)
[02:24:14.033] Build status: STARTED
[02:24:14.033] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8916425892470118272:23:0) after 7m3s
[02:31:17.304] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8916425892470118272:23:0)
[02:31:17.402] Build status: STARTED
[02:31:17.402] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8916425892470118272:24:0) after 1m16s
[02:32:33.389] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8916425892470118272:24:0)
[02:32:33.415] Build status: STARTED
[02:32:33.415] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8916425892470118272:25:0) after 9m12s
[02:41:45.525] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8916425892470118272:25:0)
[02:41:45.543] Build status: STARTED
[02:41:45.543] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8916425892470118272:26:0) after 5m51s
[02:47:36.678] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8916425892470118272:26:0)
[02:47:36.700] Build status: STARTED
[02:47:36.700] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8916425892470118272:27:0) after 4m0s
[02:51:36.926] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8916425892470118272:27:0)
[02:51:36.946] Build status: STARTED
[02:51:36.946] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8916425892470118272:28:0) after 9m31s
[03:01:08.073] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8916425892470118272:28:0)
[03:01:08.157] Build status: STARTED
[03:01:08.157] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8916425892470118272:29:0) after 6m0s
[03:07:08.730] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8916425892470118272:29:0)
[03:07:08.751] Build status: STARTED
[03:07:08.751] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8916425892470118272:30:0) after 1m47s
[03:08:55.772] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8916425892470118272:30:0)
[03:08:55.854] Build status: STARTED
[03:08:55.854] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8916425892470118272:31:0) after 9m28s
[03:18:23.995] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8916425892470118272:31:0)
[03:18:24.018] Build status: STARTED
[03:18:24.018] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8916425892470118272:32:0) after 6m3s
[03:24:27.205] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8916425892470118272:32:0)
[03:24:27.225] Build status: STARTED
[03:24:27.225] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8916425892470118272:33:0) after 4m33s
[03:29:00.244] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8916425892470118272:33:0)
[03:29:00.266] Build status: STARTED
[03:29:00.266] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8916425892470118272:34:0) after 6m32s
[03:35:32.431] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8916425892470118272:34:0)
[03:35:32.477] Build status: STARTED
[03:35:32.477] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8916425892470118272:35:0) after 5m4s
[03:40:36.505] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8916425892470118272:35:0)
[03:40:36.546] Build status: STARTED
[03:40:36.546] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8916425892470118272:36:0) after 2m27s
[03:43:03.633] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8916425892470118272:36:0)
[03:43:03.677] Build status: STARTED
[03:43:03.677] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8916425892470118272:37:0) after 1m8s
[03:44:11.709] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8916425892470118272:37:0)
[03:44:11.729] Build status: STARTED
[03:44:11.730] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8916425892470118272:38:0) after 4m32s
[03:48:43.859] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8916425892470118272:38:0)
[03:48:43.882] Build status: STARTED
[03:48:43.882] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8916425892470118272:39:0) after 4m36s
[03:52:26.330] Received PubSub notification, asking Buildbucket for the build status
[03:52:26.355] Build:
{
"id": "8750995830546149457",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ChromeOS FYI Release (amd64-generic)"
},
"number": 11372,
"createdBy": "project:chromium",
"createTime": "2024-04-11T00:57:35.637596720Z",
"startTime": "2024-04-11T00:57:44.671319862Z",
"endTime": "2024-04-11T03:52:25.967839499Z",
"updateTime": "2024-04-11T03:52:25.967839499Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "bd31db4e3289aacb3b44b9163c5462c551443ff8",
"ref": "refs/heads/main"
}
}
}
[03:52:26.355] Invocation finished in 2h54m51.936185499s with status FAILED