[01:04:21.169] New invocation is queued and will start shortly
[01:04:22.422] Starting the invocation (attempt 1)
[01:04:22.455] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d64549a6f8e6d0493f2cf37b35e4966ff4ba32b8"
[01:04:22.455] Popped gitiles commit info from properties and tags
[01:04:22.455] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:04:22.455] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:04:22.455] Buildbucket request:
{
"requestId": "8994701244946300784",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTMacCoverage"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "d64549a6f8e6d0493f2cf37b35e4966ff4ba32b8",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8994701244946300784"
},
{
"key": "scheduler_job_id",
"value": "chromium/ToTMacCoverage"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[01:04:23.073] Scheduled build:
{
"id": "8829271182800483953",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTMacCoverage"
},
"number": 48140,
"createdBy": "project:chromium",
"createTime": "2021-11-29T01:04:22.585871899Z",
"updateTime": "2021-11-29T01:04:22.585871899Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "d64549a6f8e6d0493f2cf37b35e4966ff4ba32b8",
"ref": "refs/heads/main"
}
}
}
[01:04:23.073] Task URL: https://cr-buildbucket.appspot.com/build/8829271182800483953
[01:04:23.073] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacCoverage:8994701244946300784:2:0) after 5m39s
[01:10:02.275] Handling timer "check-buildbucket-build-status" (chromium/ToTMacCoverage:8994701244946300784:2:0)
[01:10:02.307] Build status: SCHEDULED
[01:10:02.307] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacCoverage:8994701244946300784:3:0) after 4m10s
[01:14:12.455] Handling timer "check-buildbucket-build-status" (chromium/ToTMacCoverage:8994701244946300784:3:0)
[01:14:12.485] Build status: SCHEDULED
[01:14:12.485] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacCoverage:8994701244946300784:4:0) after 1m46s
[01:15:58.604] Handling timer "check-buildbucket-build-status" (chromium/ToTMacCoverage:8994701244946300784:4:0)
[01:15:58.642] Build status: SCHEDULED
[01:15:58.642] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacCoverage:8994701244946300784:5:0) after 9m22s
[01:18:12.295] Received PubSub notification, asking Buildbucket for the build status
[01:18:12.322] Build status: STARTED
[01:25:20.666] Handling timer "check-buildbucket-build-status" (chromium/ToTMacCoverage:8994701244946300784:5:0)
[01:25:20.701] Build status: STARTED
[01:25:20.701] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacCoverage:8994701244946300784:7:0) after 5m20s
[01:30:40.720] Handling timer "check-buildbucket-build-status" (chromium/ToTMacCoverage:8994701244946300784:7:0)
[01:30:40.758] Build status: STARTED
[01:30:40.759] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacCoverage:8994701244946300784:8:0) after 5m22s
[01:36:02.778] Handling timer "check-buildbucket-build-status" (chromium/ToTMacCoverage:8994701244946300784:8:0)
[01:36:02.812] Build status: STARTED
[01:36:02.812] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacCoverage:8994701244946300784:9:0) after 8m26s
[01:44:28.852] Handling timer "check-buildbucket-build-status" (chromium/ToTMacCoverage:8994701244946300784:9:0)
[01:44:28.896] Build status: STARTED
[01:44:28.896] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacCoverage:8994701244946300784:10:0) after 7m46s
[01:52:15.046] Handling timer "check-buildbucket-build-status" (chromium/ToTMacCoverage:8994701244946300784:10:0)
[01:52:15.079] Build status: STARTED
[01:52:15.079] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacCoverage:8994701244946300784:11:0) after 8m23s
[02:00:38.096] Handling timer "check-buildbucket-build-status" (chromium/ToTMacCoverage:8994701244946300784:11:0)
[02:00:38.125] Build status: STARTED
[02:00:38.125] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacCoverage:8994701244946300784:12:0) after 5m32s
[02:06:10.142] Handling timer "check-buildbucket-build-status" (chromium/ToTMacCoverage:8994701244946300784:12:0)
[02:06:10.171] Build status: STARTED
[02:06:10.171] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacCoverage:8994701244946300784:13:0) after 1m49s
[02:07:59.186] Handling timer "check-buildbucket-build-status" (chromium/ToTMacCoverage:8994701244946300784:13:0)
[02:07:59.223] Build status: STARTED
[02:07:59.223] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacCoverage:8994701244946300784:14:0) after 3m58s
[02:11:57.250] Handling timer "check-buildbucket-build-status" (chromium/ToTMacCoverage:8994701244946300784:14:0)
[02:11:57.283] Build status: STARTED
[02:11:57.283] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacCoverage:8994701244946300784:15:0) after 9m27s
[02:15:03.512] Received PubSub notification, asking Buildbucket for the build status
[02:15:03.542] Build:
{
"id": "8829271182800483953",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTMacCoverage"
},
"number": 48140,
"createdBy": "project:chromium",
"createTime": "2021-11-29T01:04:22.585871899Z",
"startTime": "2021-11-29T01:18:11.804069Z",
"endTime": "2021-11-29T02:15:02.954473964Z",
"updateTime": "2021-11-29T02:15:02.954473964Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "d64549a6f8e6d0493f2cf37b35e4966ff4ba32b8",
"ref": "refs/heads/main"
}
}
}
[02:15:03.542] Invocation finished in 1h10m42.386768915s with status FAILED