[02:10:43.600] New invocation is queued and will start shortly
[02:10:44.789] Starting the invocation (attempt 1)
[02:10:44.823] Ignoring unrecognized buildset tag "buildset:commit/git/c72b2fd5d9264bf2c437e9ee9299965a7edc9843"
[02:10:44.823] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c72b2fd5d9264bf2c437e9ee9299965a7edc9843"
[02:10:44.823] Popped gitiles commit info from properties and tags
[02:10:44.823] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:10:44.824] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:10:44.824] Buildbucket request:
{
"requestId": "9017708698532820064",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTAndroid (dbg)"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "c72b2fd5d9264bf2c437e9ee9299965a7edc9843",
"ref": "refs/heads/master"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "9017708698532820064"
},
{
"key": "scheduler_job_id",
"value": "chromium/ToTAndroid (dbg)"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[02:10:45.662] Scheduled build:
{
"id": "8852278636450025504",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTAndroid (dbg)"
},
"number": 12142,
"createdBy": "project:chromium",
"createTime": "2021-03-20T02:10:44.953547Z",
"updateTime": "2021-03-20T02:10:45.527251Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "c72b2fd5d9264bf2c437e9ee9299965a7edc9843",
"ref": "refs/heads/master"
}
}
}
[02:10:45.662] Task URL: https://cr-buildbucket.appspot.com/build/8852278636450025504
[02:10:45.662] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9017708698532820064:2:0) after 5m44s
[02:11:47.161] Received PubSub notification, asking Buildbucket for the build status
[02:11:47.240] Build status: STARTED
[02:16:29.694] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9017708698532820064:2:0)
[02:16:29.694] Timer tick, asking Buildbucket for the build status
[02:16:29.890] Build 8852278636450025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:29.890] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9017708698532820064:4:0) after 1m0s
[02:17:29.891] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9017708698532820064:4:0)
[02:17:29.891] Timer tick, asking Buildbucket for the build status
[02:17:29.957] Build 8852278636450025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:29.957] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9017708698532820064:5:0) after 1m0s
[02:18:29.965] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9017708698532820064:5:0)
[02:18:29.965] Timer tick, asking Buildbucket for the build status
[02:18:30.029] Build 8852278636450025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:30.029] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9017708698532820064:6:0) after 1m0s
[02:19:30.052] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9017708698532820064:6:0)
[02:19:30.083] Build status: STARTED
[02:19:30.083] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9017708698532820064:7:0) after 6m46s
[02:26:16.110] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9017708698532820064:7:0)
[02:26:16.143] Build status: STARTED
[02:26:16.144] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9017708698532820064:8:0) after 6m46s
[02:33:02.224] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9017708698532820064:8:0)
[02:33:02.224] Timer tick, asking Buildbucket for the build status
[02:33:02.335] Build 8852278636450025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:02.335] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9017708698532820064:9:0) after 1m0s
[02:34:02.470] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9017708698532820064:9:0)
[02:34:02.470] Timer tick, asking Buildbucket for the build status
[02:34:02.557] Build 8852278636450025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:02.557] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9017708698532820064:10:0) after 1m0s
[02:35:02.580] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9017708698532820064:10:0)
[02:35:02.613] Build status: STARTED
[02:35:02.613] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9017708698532820064:11:0) after 7m2s
[02:42:04.632] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9017708698532820064:11:0)
[02:42:04.632] Timer tick, asking Buildbucket for the build status
[02:42:04.916] Build 8852278636450025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:04.916] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9017708698532820064:12:0) after 1m0s
[02:43:04.980] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9017708698532820064:12:0)
[02:43:05.011] Build status: STARTED
[02:43:05.011] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9017708698532820064:13:0) after 7m15s
[02:49:02.649] Received PubSub notification, asking Buildbucket for the build status
[02:49:02.673] Build:
{
"id": "8852278636450025504",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTAndroid (dbg)"
},
"number": 12142,
"createdBy": "project:chromium",
"createTime": "2021-03-20T02:10:44.953547Z",
"startTime": "2021-03-20T02:11:46.482536Z",
"endTime": "2021-03-20T02:49:01.728189715Z",
"updateTime": "2021-03-20T02:49:01.728590965Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "c72b2fd5d9264bf2c437e9ee9299965a7edc9843",
"ref": "refs/heads/master"
}
}
}
[02:49:02.673] Invocation finished in 38m19.089712158s with status FAILED