[13:21:00.887] New invocation is queued and will start shortly
[13:21:02.624] Starting the invocation (attempt 1)
[13:21:02.654] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/51ef4744f1cecabf72fdae9842ccf6450509af97"
[13:21:02.654] Popped gitiles commit info from properties and tags
[13:21:02.654] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[13:21:02.654] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[13:21:02.654] Buildbucket request:
{
"requestId": "8888565850519657840",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTWindowsCoverage"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "51ef4744f1cecabf72fdae9842ccf6450509af97",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8888565850519657840"
},
{
"key": "scheduler_job_id",
"value": "chromium/ToTWindowsCoverage"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[13:21:03.290] Scheduled build:
{
"id": "8723135787945102625",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTWindowsCoverage"
},
"number": 42039,
"createdBy": "project:chromium",
"createTime": "2025-02-12T13:21:02.720983336Z",
"updateTime": "2025-02-12T13:21:02.720983336Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "51ef4744f1cecabf72fdae9842ccf6450509af97",
"ref": "refs/heads/main"
}
}
}
[13:21:03.290] Task URL: https://cr-buildbucket.appspot.com/build/8723135787945102625
[13:21:03.290] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8888565850519657840:2:0) after 7m55s
[13:21:04.140] Received PubSub notification, asking Buildbucket for the build status
[13:21:04.165] Build status: SCHEDULED
[13:28:58.492] Handling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8888565850519657840:2:0)
[13:28:58.570] Build status: SCHEDULED
[13:28:58.570] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8888565850519657840:4:0) after 7m24s
[13:36:22.652] Handling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8888565850519657840:4:0)
[13:36:22.671] Build status: SCHEDULED
[13:36:22.671] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8888565850519657840:5:0) after 8m1s
[13:44:23.770] Handling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8888565850519657840:5:0)
[13:44:23.787] Build status: SCHEDULED
[13:44:23.787] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8888565850519657840:6:0) after 3m26s
[13:47:49.822] Handling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8888565850519657840:6:0)
[13:47:49.850] Build status: SCHEDULED
[13:47:49.850] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8888565850519657840:7:0) after 8m37s
[13:56:27.023] Handling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8888565850519657840:7:0)
[13:56:27.038] Build status: SCHEDULED
[13:56:27.038] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8888565850519657840:8:0) after 5m27s
[14:01:54.525] Handling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8888565850519657840:8:0)
[14:01:54.550] Build status: SCHEDULED
[14:01:54.550] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8888565850519657840:9:0) after 5m31s
[14:07:25.856] Handling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8888565850519657840:9:0)
[14:07:25.874] Build status: SCHEDULED
[14:07:25.875] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8888565850519657840:10:0) after 4m21s
[14:11:46.897] Handling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8888565850519657840:10:0)
[14:11:46.950] Build status: SCHEDULED
[14:11:46.950] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8888565850519657840:11:0) after 1m5s
[14:12:52.059] Handling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8888565850519657840:11:0)
[14:12:52.087] Build status: SCHEDULED
[14:12:52.087] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8888565850519657840:12:0) after 5m21s
[14:18:14.060] Handling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8888565850519657840:12:0)
[14:18:14.082] Build status: SCHEDULED
[14:18:14.082] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8888565850519657840:13:0) after 3m9s
[14:21:23.301] Handling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8888565850519657840:13:0)
[14:21:23.319] Build status: SCHEDULED
[14:21:23.319] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8888565850519657840:14:0) after 2m12s
[14:23:35.582] Handling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8888565850519657840:14:0)
[14:23:35.630] Build status: SCHEDULED
[14:23:35.630] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8888565850519657840:15:0) after 9m36s
[14:33:11.664] Handling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8888565850519657840:15:0)
[14:33:11.693] Build status: SCHEDULED
[14:33:11.693] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8888565850519657840:16:0) after 2m49s
[14:36:01.047] Handling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8888565850519657840:16:0)
[14:36:01.072] Build status: SCHEDULED
[14:36:01.072] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8888565850519657840:17:0) after 8m50s
[14:44:51.096] Handling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8888565850519657840:17:0)
[14:44:51.157] Build status: SCHEDULED
[14:44:51.157] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8888565850519657840:18:0) after 4m15s
[14:49:06.172] Handling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8888565850519657840:18:0)
[14:49:06.188] Build status: SCHEDULED
[14:49:06.188] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8888565850519657840:19:0) after 1m36s
[14:50:42.219] Handling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8888565850519657840:19:0)
[14:50:42.246] Build status: SCHEDULED
[14:50:42.246] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8888565850519657840:20:0) after 4m55s
[14:55:37.311] Handling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8888565850519657840:20:0)
[14:55:37.339] Build status: SCHEDULED
[14:55:37.339] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8888565850519657840:21:0) after 4m24s
[15:00:01.369] Handling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8888565850519657840:21:0)
[15:00:01.406] Build status: SCHEDULED
[15:00:01.406] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8888565850519657840:22:0) after 7m33s
[15:07:34.461] Handling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8888565850519657840:22:0)
[15:07:34.648] Build status: SCHEDULED
[15:07:34.648] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8888565850519657840:23:0) after 8m36s
[15:16:10.679] Handling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8888565850519657840:23:0)
[15:16:10.747] Build status: SCHEDULED
[15:16:10.747] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8888565850519657840:24:0) after 1m6s
[15:17:16.743] Handling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8888565850519657840:24:0)
[15:17:16.765] Build status: SCHEDULED
[15:17:16.765] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8888565850519657840:25:0) after 5m40s
[15:22:56.866] Handling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8888565850519657840:25:0)
[15:22:56.886] Build status: SCHEDULED
[15:22:56.886] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8888565850519657840:26:0) after 1m31s
[15:24:28.066] Handling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8888565850519657840:26:0)
[15:24:28.090] Build status: SCHEDULED
[15:24:28.090] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8888565850519657840:27:0) after 8m11s
[15:32:39.119] Handling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8888565850519657840:27:0)
[15:32:39.133] Build status: SCHEDULED
[15:32:39.133] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8888565850519657840:28:0) after 9m48s
[15:42:27.150] Handling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8888565850519657840:28:0)
[15:42:27.229] Build status: SCHEDULED
[15:42:27.230] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8888565850519657840:29:0) after 9m30s
[15:51:57.261] Handling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8888565850519657840:29:0)
[15:51:57.285] Build status: SCHEDULED
[15:51:57.285] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8888565850519657840:30:0) after 9m39s
[16:01:36.304] Handling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8888565850519657840:30:0)
[16:01:36.322] Build status: SCHEDULED
[16:01:36.322] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8888565850519657840:31:0) after 3m5s
[16:04:41.354] Handling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8888565850519657840:31:0)
[16:04:41.370] Build status: SCHEDULED
[16:04:41.370] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8888565850519657840:32:0) after 1m14s
[16:05:55.396] Handling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8888565850519657840:32:0)
[16:05:55.425] Build status: SCHEDULED
[16:05:55.425] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8888565850519657840:33:0) after 2m41s
[16:08:36.451] Handling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8888565850519657840:33:0)
[16:08:36.475] Build status: SCHEDULED
[16:08:36.475] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8888565850519657840:34:0) after 3m53s
[16:12:29.510] Handling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8888565850519657840:34:0)
[16:12:29.526] Build status: SCHEDULED
[16:12:29.526] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8888565850519657840:35:0) after 2m37s
[16:15:06.548] Handling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8888565850519657840:35:0)
[16:15:06.584] Build status: SCHEDULED
[16:15:06.584] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8888565850519657840:36:0) after 4m34s
[16:19:40.653] Handling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8888565850519657840:36:0)
[16:19:40.718] Build status: SCHEDULED
[16:19:40.718] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8888565850519657840:37:0) after 8m2s
[16:27:42.754] Handling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8888565850519657840:37:0)
[16:27:42.773] Build status: SCHEDULED
[16:27:42.773] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8888565850519657840:38:0) after 5m11s
[16:32:53.843] Handling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8888565850519657840:38:0)
[16:32:54.324] Build status: SCHEDULED
[16:32:54.324] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8888565850519657840:39:0) after 8m45s
[16:41:39.391] Handling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8888565850519657840:39:0)
[16:41:39.405] Build status: SCHEDULED
[16:41:39.405] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8888565850519657840:40:0) after 4m49s
[16:46:28.472] Handling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8888565850519657840:40:0)
[16:46:28.489] Build status: SCHEDULED
[16:46:28.489] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8888565850519657840:41:0) after 2m57s
[16:49:25.474] Handling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8888565850519657840:41:0)
[16:49:25.494] Build status: SCHEDULED
[16:49:25.494] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8888565850519657840:42:0) after 6m43s
[16:49:59.875] Received PubSub notification, asking Buildbucket for the build status
[16:49:59.973] Build status: STARTED
[16:56:08.523] Handling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8888565850519657840:42:0)
[16:56:08.544] Build status: STARTED
[16:56:08.544] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8888565850519657840:44:0) after 5m18s
[17:01:26.549] Handling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8888565850519657840:44:0)
[17:01:26.571] Build status: STARTED
[17:01:26.571] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8888565850519657840:45:0) after 8m51s
[17:10:17.599] Handling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8888565850519657840:45:0)
[17:10:17.617] Build status: STARTED
[17:10:17.618] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8888565850519657840:46:0) after 7m3s
[17:17:20.689] Handling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8888565850519657840:46:0)
[17:17:20.706] Build status: STARTED
[17:17:20.706] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8888565850519657840:47:0) after 3m2s
[17:19:48.635] Received PubSub notification, asking Buildbucket for the build status
[17:19:48.658] Build:
{
"id": "8723135787945102625",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTWindowsCoverage"
},
"number": 42039,
"createdBy": "project:chromium",
"createTime": "2025-02-12T13:21:02.720983336Z",
"startTime": "2025-02-12T16:49:58.476721895Z",
"endTime": "2025-02-12T17:19:48.327835492Z",
"updateTime": "2025-02-12T17:19:48.327835492Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "51ef4744f1cecabf72fdae9842ccf6450509af97",
"ref": "refs/heads/main"
}
}
}
[17:19:48.658] Invocation finished in 3h58m47.777464033s with status FAILED