[20:29:55.829] New invocation is queued and will start shortly
[20:29:56.991] Starting the invocation (attempt 1)
[20:29:57.029] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f523faf1c15e188b13d414bbc0fca36a7faef378"
[20:29:57.030] Popped gitiles commit info from properties and tags
[20:29:57.030] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:29:57.030] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:29:57.030] Buildbucket request:
{
"requestId": "8987561349760521776",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTWindowsCoverage"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "f523faf1c15e188b13d414bbc0fca36a7faef378",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8987561349760521776"
},
{
"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"
}
}
[20:29:57.629] Scheduled build:
{
"id": "8822131287747471121",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTWindowsCoverage"
},
"number": 8496,
"createdBy": "project:chromium",
"createTime": "2022-02-15T20:29:57.119864496Z",
"updateTime": "2022-02-15T20:29:57.119864496Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "f523faf1c15e188b13d414bbc0fca36a7faef378",
"ref": "refs/heads/main"
}
}
}
[20:29:57.629] Task URL: https://cr-buildbucket.appspot.com/build/8822131287747471121
[20:29:57.629] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8987561349760521776:2:0) after 9m26s
[20:39:24.100] Handling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8987561349760521776:2:0)
[20:39:24.119] Build status: SCHEDULED
[20:39:24.119] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8987561349760521776:3:0) after 6m38s
[20:46:02.137] Handling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8987561349760521776:3:0)
[20:46:02.156] Build status: SCHEDULED
[20:46:02.156] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8987561349760521776:4:0) after 2m57s
[20:48:59.176] Handling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8987561349760521776:4:0)
[20:48:59.209] Build status: SCHEDULED
[20:48:59.209] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8987561349760521776:5:0) after 5m12s
[20:53:55.606] Received PubSub notification, asking Buildbucket for the build status
[20:53:55.633] Build status: STARTED
[20:54:11.269] Handling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8987561349760521776:5:0)
[20:54:11.364] Build status: STARTED
[20:54:11.364] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8987561349760521776:7:0) after 6m41s
[21:00:52.449] Handling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8987561349760521776:7:0)
[21:00:52.480] Build status: STARTED
[21:00:52.480] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8987561349760521776:8:0) after 5m51s
[21:06:43.571] Handling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8987561349760521776:8:0)
[21:06:43.855] Build status: STARTED
[21:06:43.855] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8987561349760521776:9:0) after 1m12s
[21:07:56.017] Handling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8987561349760521776:9:0)
[21:07:56.043] Build status: STARTED
[21:07:56.043] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8987561349760521776:10:0) after 5m24s
[21:13:20.064] Handling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8987561349760521776:10:0)
[21:13:20.093] Build status: STARTED
[21:13:20.093] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8987561349760521776:11:0) after 8m2s
[21:21:22.227] Handling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8987561349760521776:11:0)
[21:21:22.274] Build status: STARTED
[21:21:22.274] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8987561349760521776:12:0) after 2m49s
[21:24:11.302] Handling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8987561349760521776:12:0)
[21:24:11.320] Build status: STARTED
[21:24:11.320] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8987561349760521776:13:0) after 5m32s
[21:29:43.738] Handling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8987561349760521776:13:0)
[21:29:43.760] Build status: STARTED
[21:29:43.760] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8987561349760521776:14:0) after 9m0s
[21:38:44.402] Handling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8987561349760521776:14:0)
[21:38:44.481] Build status: STARTED
[21:38:44.481] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8987561349760521776:15:0) after 3m50s
[21:42:34.513] Handling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8987561349760521776:15:0)
[21:42:34.534] Build status: STARTED
[21:42:34.534] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8987561349760521776:16:0) after 6m22s
[21:48:56.660] Handling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8987561349760521776:16:0)
[21:48:56.680] Build status: STARTED
[21:48:56.680] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8987561349760521776:17:0) after 1m2s
[21:49:58.669] Handling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8987561349760521776:17:0)
[21:49:58.701] Build status: STARTED
[21:49:58.701] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8987561349760521776:18:0) after 7m40s
[21:57:39.167] Handling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8987561349760521776:18:0)
[21:57:39.252] Build status: STARTED
[21:57:39.252] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWindowsCoverage:8987561349760521776:19:0) after 2m18s
[21:58:16.746] Received PubSub notification, asking Buildbucket for the build status
[21:58:16.775] Build:
{
"id": "8822131287747471121",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTWindowsCoverage"
},
"number": 8496,
"createdBy": "project:chromium",
"createTime": "2022-02-15T20:29:57.119864496Z",
"startTime": "2022-02-15T20:53:54.842549Z",
"endTime": "2022-02-15T21:58:16.475162645Z",
"updateTime": "2022-02-15T21:58:16.475162645Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "f523faf1c15e188b13d414bbc0fca36a7faef378",
"ref": "refs/heads/main"
}
}
}
[21:58:16.775] Invocation finished in 1h28m20.96012915s with status FAILED