[15:03:24.097] New invocation is queued and will start shortly
[15:03:25.619] Starting the invocation (attempt 1)
[15:03:25.659] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5e88efc93237f510f843af9e8686a03d5e570710"
[15:03:25.659] Popped gitiles commit info from properties and tags
[15:03:25.659] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[15:03:25.659] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[15:03:25.659] Buildbucket request:
{
"requestId": "8935579234459306640",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ChromeOS FYI Release (amd64-generic)"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "5e88efc93237f510f843af9e8686a03d5e570710",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8935579234459306640"
},
{
"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"
}
}
[15:03:26.263] Scheduled build:
{
"id": "8770149172112061585",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ChromeOS FYI Release (amd64-generic)"
},
"number": 9567,
"createdBy": "project:chromium",
"createTime": "2023-09-12T15:03:25.713968978Z",
"updateTime": "2023-09-12T15:03:25.713968978Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "5e88efc93237f510f843af9e8686a03d5e570710",
"ref": "refs/heads/main"
}
}
}
[15:03:26.263] Task URL: https://cr-buildbucket.appspot.com/build/8770149172112061585
[15:03:26.263] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8935579234459306640:2:0) after 2m10s
[15:03:27.315] Received PubSub notification, asking Buildbucket for the build status
[15:03:27.333] Build status: SCHEDULED
[15:05:36.280] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8935579234459306640:2:0)
[15:05:36.296] Build status: SCHEDULED
[15:05:36.296] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8935579234459306640:4:0) after 7m18s
[15:08:08.629] Received PubSub notification, asking Buildbucket for the build status
[15:08:08.660] Build status: STARTED
[15:12:54.313] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8935579234459306640:4:0)
[15:12:54.478] Build status: STARTED
[15:12:54.478] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8935579234459306640:6:0) after 9m27s
[15:22:21.525] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8935579234459306640:6:0)
[15:22:21.551] Build status: STARTED
[15:22:21.551] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8935579234459306640:7:0) after 9m26s
[15:31:47.624] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8935579234459306640:7:0)
[15:31:47.646] Build status: STARTED
[15:31:47.646] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8935579234459306640:8:0) after 1m19s
[15:33:07.315] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8935579234459306640:8:0)
[15:33:07.338] Build status: STARTED
[15:33:07.338] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8935579234459306640:9:0) after 1m42s
[15:34:49.367] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8935579234459306640:9:0)
[15:34:49.386] Build status: STARTED
[15:34:49.386] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8935579234459306640:10:0) after 7m42s
[15:42:31.430] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8935579234459306640:10:0)
[15:42:31.552] Build status: STARTED
[15:42:31.552] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8935579234459306640:11:0) after 6m10s
[15:48:41.569] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8935579234459306640:11:0)
[15:48:41.627] Build status: STARTED
[15:48:41.627] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8935579234459306640:12:0) after 9m22s
[15:58:03.654] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8935579234459306640:12:0)
[15:58:03.672] Build status: STARTED
[15:58:03.672] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8935579234459306640:13:0) after 1m56s
[15:59:59.689] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8935579234459306640:13:0)
[15:59:59.708] Build status: STARTED
[15:59:59.708] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8935579234459306640:14:0) after 1m20s
[16:01:19.876] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8935579234459306640:14:0)
[16:01:19.906] Build status: STARTED
[16:01:19.906] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8935579234459306640:15:0) after 7m19s
[16:08:38.923] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8935579234459306640:15:0)
[16:08:38.977] Build status: STARTED
[16:08:38.977] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8935579234459306640:16:0) after 1m52s
[16:10:31.137] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8935579234459306640:16:0)
[16:10:31.165] Build status: STARTED
[16:10:31.165] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8935579234459306640:17:0) after 1m54s
[16:12:25.240] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8935579234459306640:17:0)
[16:12:25.287] Build status: STARTED
[16:12:25.287] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8935579234459306640:18:0) after 3m16s
[16:15:41.669] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8935579234459306640:18:0)
[16:15:41.699] Build status: STARTED
[16:15:41.699] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8935579234459306640:19:0) after 4m0s
[16:19:41.730] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8935579234459306640:19:0)
[16:19:41.771] Build status: STARTED
[16:19:41.771] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8935579234459306640:20:0) after 9m15s
[16:28:56.978] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8935579234459306640:20:0)
[16:28:56.998] Build status: STARTED
[16:28:56.998] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8935579234459306640:21:0) after 9m13s
[16:38:10.022] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8935579234459306640:21:0)
[16:38:10.081] Build status: STARTED
[16:38:10.081] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8935579234459306640:22:0) after 8m13s
[16:46:23.243] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8935579234459306640:22:0)
[16:46:23.264] Build status: STARTED
[16:46:23.264] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8935579234459306640:23:0) after 7m49s
[16:54:12.280] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8935579234459306640:23:0)
[16:54:12.305] Build status: STARTED
[16:54:12.305] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8935579234459306640:24:0) after 5m52s
[17:00:04.407] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8935579234459306640:24:0)
[17:00:04.438] Build status: STARTED
[17:00:04.438] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8935579234459306640:25:0) after 9m22s
[17:09:26.496] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8935579234459306640:25:0)
[17:09:26.512] Build status: STARTED
[17:09:26.512] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8935579234459306640:26:0) after 3m58s
[17:13:24.516] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8935579234459306640:26:0)
[17:13:24.652] Build status: STARTED
[17:13:24.652] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8935579234459306640:27:0) after 4m45s
[17:18:09.724] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8935579234459306640:27:0)
[17:18:09.748] Build status: STARTED
[17:18:09.748] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8935579234459306640:28:0) after 4m5s
[17:22:14.901] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8935579234459306640:28:0)
[17:22:15.058] Build status: STARTED
[17:22:15.058] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8935579234459306640:29:0) after 4m56s
[17:27:11.106] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8935579234459306640:29:0)
[17:27:11.123] Build status: STARTED
[17:27:11.123] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8935579234459306640:30:0) after 4m47s
[17:31:58.178] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8935579234459306640:30:0)
[17:31:58.219] Build status: STARTED
[17:31:58.219] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8935579234459306640:31:0) after 5m21s
[17:37:19.213] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8935579234459306640:31:0)
[17:37:19.240] Build status: STARTED
[17:37:19.240] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8935579234459306640:32:0) after 7m5s
[17:44:24.261] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8935579234459306640:32:0)
[17:44:24.278] Build status: STARTED
[17:44:24.278] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8935579234459306640:33:0) after 5m3s
[17:49:27.305] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8935579234459306640:33:0)
[17:49:27.332] Build status: STARTED
[17:49:27.332] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8935579234459306640:34:0) after 7m11s
[17:56:38.391] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8935579234459306640:34:0)
[17:56:38.410] Build status: STARTED
[17:56:38.410] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8935579234459306640:35:0) after 7m24s
[18:04:02.437] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8935579234459306640:35:0)
[18:04:02.468] Build status: STARTED
[18:04:02.471] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8935579234459306640:36:0) after 4m12s
[18:08:14.491] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8935579234459306640:36:0)
[18:08:14.512] Build status: STARTED
[18:08:14.512] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8935579234459306640:37:0) after 7m45s
[18:15:59.535] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8935579234459306640:37:0)
[18:15:59.563] Build status: STARTED
[18:15:59.563] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8935579234459306640:38:0) after 5m2s
[18:21:01.800] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8935579234459306640:38:0)
[18:21:01.847] Build status: STARTED
[18:21:01.847] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8935579234459306640:39:0) after 3m24s
[18:24:25.879] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8935579234459306640:39:0)
[18:24:25.902] Build status: STARTED
[18:24:25.903] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8935579234459306640:40:0) after 1m45s
[18:26:10.946] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8935579234459306640:40:0)
[18:26:10.974] Build status: STARTED
[18:26:10.974] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8935579234459306640:41:0) after 4m23s
[18:30:34.030] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8935579234459306640:41:0)
[18:30:34.048] Build status: STARTED
[18:30:34.048] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8935579234459306640:42:0) after 7m2s
[18:37:36.117] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8935579234459306640:42:0)
[18:37:36.146] Build status: STARTED
[18:37:36.146] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8935579234459306640:43:0) after 9m10s
[18:46:46.169] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8935579234459306640:43:0)
[18:46:46.196] Build status: STARTED
[18:46:46.196] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8935579234459306640:44:0) after 5m23s
[18:52:09.226] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8935579234459306640:44:0)
[18:52:09.248] Build status: STARTED
[18:52:09.248] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8935579234459306640:45:0) after 2m7s
[18:54:16.268] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8935579234459306640:45:0)
[18:54:16.288] Build status: STARTED
[18:54:16.288] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8935579234459306640:46:0) after 1m16s
[18:55:32.305] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8935579234459306640:46:0)
[18:55:32.352] Build status: STARTED
[18:55:32.352] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8935579234459306640:47:0) after 2m46s
[18:58:18.523] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8935579234459306640:47:0)
[18:58:18.543] Build status: STARTED
[18:58:18.543] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8935579234459306640:48:0) after 8m0s
[18:59:03.918] Received PubSub notification, asking Buildbucket for the build status
[18:59:03.937] Build:
{
"id": "8770149172112061585",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ChromeOS FYI Release (amd64-generic)"
},
"number": 9567,
"createdBy": "project:chromium",
"createTime": "2023-09-12T15:03:25.713968978Z",
"startTime": "2023-09-12T15:08:08.095195Z",
"endTime": "2023-09-12T18:59:03.535731061Z",
"updateTime": "2023-09-12T18:59:03.535731061Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "5e88efc93237f510f843af9e8686a03d5e570710",
"ref": "refs/heads/main"
}
}
}
[18:59:03.937] Invocation finished in 3h55m39.84615091s with status FAILED