[16:49:58.081] New invocation is queued and will start shortly
[16:49:59.157] Starting the invocation (attempt 1)
[16:49:59.196] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b87ad819e8b9d0c235c7df48dc009702cf471edf"
[16:49:59.196] Popped gitiles commit info from properties and tags
[16:49:59.196] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:49:59.197] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:49:59.197] Buildbucket request:
{
"requestId": "8992739215688648448",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTWindowsCoverage"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "b87ad819e8b9d0c235c7df48dc009702cf471edf",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8992739215688648448"
},
{
"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"
}
}
[16:49:59.693] Scheduled build:
{
"id": "8827309153792716769",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTWindowsCoverage"
},
"number": 7763,
"createdBy": "project:chromium",
"createTime": "2021-12-20T16:49:59.258721463Z",
"updateTime": "2021-12-20T16:49:59.258721463Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "b87ad819e8b9d0c235c7df48dc009702cf471edf",
"ref": "refs/heads/main"
}
}
}
[16:49:59.693] Task URL: https://cr-buildbucket.appspot.com/build/8827309153792716769
[16:49:59.693] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8992739215688648448:2:0) after 6m53s
[16:56:52.856] Handling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8992739215688648448:2:0)
[16:56:52.890] Build status: SCHEDULED
[16:56:52.890] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8992739215688648448:3:0) after 6m25s
[17:03:17.905] Handling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8992739215688648448:3:0)
[17:03:17.926] Build status: SCHEDULED
[17:03:17.926] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8992739215688648448:4:0) after 1m4s
[17:04:21.945] Handling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8992739215688648448:4:0)
[17:04:21.987] Build status: SCHEDULED
[17:04:21.987] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8992739215688648448:5:0) after 9m5s
[17:13:27.007] Handling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8992739215688648448:5:0)
[17:13:27.028] Build status: SCHEDULED
[17:13:27.028] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8992739215688648448:6:0) after 4m20s
[17:17:47.188] Handling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8992739215688648448:6:0)
[17:17:47.205] Build status: SCHEDULED
[17:17:47.205] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8992739215688648448:7:0) after 5m57s
[17:23:44.230] Handling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8992739215688648448:7:0)
[17:23:44.247] Build status: SCHEDULED
[17:23:44.247] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8992739215688648448:8:0) after 2m44s
[17:26:28.270] Handling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8992739215688648448:8:0)
[17:26:28.384] Build status: SCHEDULED
[17:26:28.384] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8992739215688648448:9:0) after 9m44s
[17:36:12.479] Handling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8992739215688648448:9:0)
[17:36:12.497] Build status: SCHEDULED
[17:36:12.497] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8992739215688648448:10:0) after 9m4s
[17:45:16.678] Handling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8992739215688648448:10:0)
[17:45:16.697] Build status: SCHEDULED
[17:45:16.697] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8992739215688648448:11:0) after 6m36s
[17:51:52.720] Handling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8992739215688648448:11:0)
[17:51:52.737] Build status: SCHEDULED
[17:51:52.737] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8992739215688648448:12:0) after 9m45s
[18:01:37.817] Handling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8992739215688648448:12:0)
[18:01:37.846] Build status: SCHEDULED
[18:01:37.846] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8992739215688648448:13:0) after 2m49s
[18:04:27.053] Handling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8992739215688648448:13:0)
[18:04:27.085] Build status: SCHEDULED
[18:04:27.085] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8992739215688648448:14:0) after 1m58s
[18:06:25.108] Handling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8992739215688648448:14:0)
[18:06:25.138] Build status: SCHEDULED
[18:06:25.138] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8992739215688648448:15:0) after 3m20s
[18:09:45.156] Handling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8992739215688648448:15:0)
[18:09:45.185] Build status: SCHEDULED
[18:09:45.185] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8992739215688648448:16:0) after 2m5s
[18:11:50.335] Handling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8992739215688648448:16:0)
[18:11:50.365] Build status: SCHEDULED
[18:11:50.365] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8992739215688648448:17:0) after 5m38s
[18:17:28.340] Handling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8992739215688648448:17:0)
[18:17:28.359] Build status: SCHEDULED
[18:17:28.359] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8992739215688648448:18:0) after 8m3s
[18:18:56.528] Received PubSub notification, asking Buildbucket for the build status
[18:18:56.595] Build status: STARTED
[18:25:31.343] Handling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8992739215688648448:18:0)
[18:25:31.363] Build status: STARTED
[18:25:31.363] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8992739215688648448:20:0) after 9m30s
[18:35:01.556] Handling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8992739215688648448:20:0)
[18:35:01.583] Build status: STARTED
[18:35:01.583] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8992739215688648448:21:0) after 6m6s
[18:41:07.609] Handling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8992739215688648448:21:0)
[18:41:07.639] Build status: STARTED
[18:41:07.639] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8992739215688648448:22:0) after 4m13s
[18:45:20.772] Handling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8992739215688648448:22:0)
[18:45:20.872] Build status: STARTED
[18:45:20.872] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8992739215688648448:23:0) after 5m18s
[18:50:39.006] Handling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8992739215688648448:23:0)
[18:50:39.037] Build status: STARTED
[18:50:39.037] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8992739215688648448:24:0) after 4m15s
[18:54:54.138] Handling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8992739215688648448:24:0)
[18:54:54.170] Build status: STARTED
[18:54:54.170] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8992739215688648448:25:0) after 1m31s
[18:56:25.269] Handling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8992739215688648448:25:0)
[18:56:25.297] Build status: STARTED
[18:56:25.297] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8992739215688648448:26:0) after 1m39s
[18:58:04.314] Handling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8992739215688648448:26:0)
[18:58:04.340] Build status: STARTED
[18:58:04.340] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8992739215688648448:27:0) after 8m41s
[19:06:45.359] Handling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8992739215688648448:27:0)
[19:06:45.400] Build status: STARTED
[19:06:45.400] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8992739215688648448:28:0) after 6m10s
[19:11:02.978] Received PubSub notification, asking Buildbucket for the build status
[19:11:03.001] Build:
{
"id": "8827309153792716769",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTWindowsCoverage"
},
"number": 7763,
"createdBy": "project:chromium",
"createTime": "2021-12-20T16:49:59.258721463Z",
"startTime": "2021-12-20T18:18:55.775400Z",
"endTime": "2021-12-20T19:11:02.679228533Z",
"updateTime": "2021-12-20T19:11:02.679228533Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "b87ad819e8b9d0c235c7df48dc009702cf471edf",
"ref": "refs/heads/main"
}
}
}
[19:11:03.001] Invocation finished in 2h21m4.934966521s with status FAILED