[18:07:26.504] New invocation is queued and will start shortly
[18:07:27.665] Starting the invocation (attempt 1)
[18:07:27.739] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:07:27.743] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:07:27.744] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9018101492056321840",
"parameters_json": "{\"builder_name\":\"ToTAndroid (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"63d5aa87d494fb832c898dce14350c120ed149d2\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:ToTAndroid (dbg)",
"scheduler_invocation_id:9018101492056321840",
"scheduler_job_id:chromium/ToTAndroid (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/63d5aa87d494fb832c898dce14350c120ed149d2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/63d5aa87d494fb832c898dce14350c120ed149d2",
"gitiles_ref:refs/heads/master"
]
}
[18:07:29.226] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1615831647942894",
"id": "8852671429885842208",
"parameters_json": "{\"builder_name\": \"ToTAndroid (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"63d5aa87d494fb832c898dce14350c120ed149d2\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1615831648977127",
"tags": [
"build_address:luci.chromium.ci/ToTAndroid (dbg)/12061",
"builder:ToTAndroid (dbg)",
"buildset:commit/git/63d5aa87d494fb832c898dce14350c120ed149d2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/63d5aa87d494fb832c898dce14350c120ed149d2",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9018101492056321840",
"scheduler_job_id:chromium/ToTAndroid (dbg)",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8852671429885842208/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:recipe_name:chromium",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:",
"user_agent:luci-scheduler"
],
"updated_ts": "1615831648977269",
"url": "https://ci.chromium.org/b/8852671429885842208",
"utcnow_ts": "1615831649215076"
}
}
[18:07:29.226] Task URL: https://ci.chromium.org/b/8852671429885842208
[18:07:29.227] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9018101492056321840:2:0) after 1m0s
[18:08:29.329] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9018101492056321840:2:0)
[18:08:29.329] Timer tick, asking Buildbucket for the build status
[18:08:31.127] Build 8852671429885842208: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:08:31.127] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9018101492056321840:3:0) after 1m0s
[18:09:31.182] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9018101492056321840:3:0)
[18:09:31.182] Timer tick, asking Buildbucket for the build status
[18:09:31.332] Build 8852671429885842208: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:09:31.332] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9018101492056321840:4:0) after 1m0s
[18:10:31.388] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9018101492056321840:4:0)
[18:10:31.388] Timer tick, asking Buildbucket for the build status
[18:10:31.656] Build 8852671429885842208: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:10:31.656] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9018101492056321840:5:0) after 1m0s
[18:11:31.658] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9018101492056321840:5:0)
[18:11:31.658] Timer tick, asking Buildbucket for the build status
[18:11:32.038] Build 8852671429885842208: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:11:32.038] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9018101492056321840:6:0) after 1m0s
[18:12:32.083] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9018101492056321840:6:0)
[18:12:32.083] Timer tick, asking Buildbucket for the build status
[18:12:32.283] Build 8852671429885842208: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:12:32.283] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9018101492056321840:7:0) after 1m0s
[18:12:41.755] Received PubSub notification, asking Buildbucket for the build status
[18:12:41.958] Build 8852671429885842208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:13:32.308] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9018101492056321840:7:0)
[18:13:32.308] Timer tick, asking Buildbucket for the build status
[18:13:32.410] Build 8852671429885842208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:13:32.410] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9018101492056321840:9:0) after 1m0s
[18:14:32.435] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9018101492056321840:9:0)
[18:14:32.435] Timer tick, asking Buildbucket for the build status
[18:14:32.572] Build 8852671429885842208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:14:32.572] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9018101492056321840:10:0) after 1m0s
[18:15:32.602] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9018101492056321840:10:0)
[18:15:32.602] Timer tick, asking Buildbucket for the build status
[18:15:32.737] Build 8852671429885842208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:32.737] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9018101492056321840:11:0) after 1m0s
[18:16:32.773] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9018101492056321840:11:0)
[18:16:32.773] Timer tick, asking Buildbucket for the build status
[18:16:32.840] Build 8852671429885842208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:16:32.840] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9018101492056321840:12:0) after 1m0s
[18:17:32.877] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9018101492056321840:12:0)
[18:17:32.877] Timer tick, asking Buildbucket for the build status
[18:17:33.028] Build 8852671429885842208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:17:33.028] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9018101492056321840:13:0) after 1m0s
[18:18:33.012] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9018101492056321840:13:0)
[18:18:33.012] Timer tick, asking Buildbucket for the build status
[18:18:34.071] Build 8852671429885842208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:18:34.071] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9018101492056321840:14:0) after 1m0s
[18:19:34.301] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9018101492056321840:14:0)
[18:19:34.301] Timer tick, asking Buildbucket for the build status
[18:19:34.475] Build 8852671429885842208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:19:34.475] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9018101492056321840:15:0) after 1m0s
[18:20:34.585] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9018101492056321840:15:0)
[18:20:34.585] Timer tick, asking Buildbucket for the build status
[18:20:34.819] Build 8852671429885842208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:20:34.819] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9018101492056321840:16:0) after 1m0s
[18:21:35.182] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9018101492056321840:16:0)
[18:21:35.182] Timer tick, asking Buildbucket for the build status
[18:21:35.563] Build 8852671429885842208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:21:35.563] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9018101492056321840:17:0) after 1m0s
[18:22:35.724] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9018101492056321840:17:0)
[18:22:35.724] Timer tick, asking Buildbucket for the build status
[18:22:36.077] Build 8852671429885842208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:22:36.077] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9018101492056321840:18:0) after 1m0s
[18:23:36.191] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9018101492056321840:18:0)
[18:23:36.191] Timer tick, asking Buildbucket for the build status
[18:23:36.453] Build 8852671429885842208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:23:36.453] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9018101492056321840:19:0) after 1m0s
[18:24:36.596] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9018101492056321840:19:0)
[18:24:36.596] Timer tick, asking Buildbucket for the build status
[18:24:36.804] Build 8852671429885842208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:24:36.804] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9018101492056321840:20:0) after 1m0s
[18:25:36.894] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9018101492056321840:20:0)
[18:25:36.894] Timer tick, asking Buildbucket for the build status
[18:25:36.969] Build 8852671429885842208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:25:36.969] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9018101492056321840:21:0) after 1m0s
[18:26:37.038] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9018101492056321840:21:0)
[18:26:37.038] Timer tick, asking Buildbucket for the build status
[18:26:37.212] Build 8852671429885842208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:26:37.212] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9018101492056321840:22:0) after 1m0s
[18:27:37.281] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9018101492056321840:22:0)
[18:27:37.281] Timer tick, asking Buildbucket for the build status
[18:27:38.255] Build 8852671429885842208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:27:38.255] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9018101492056321840:23:0) after 1m0s
[18:28:38.240] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9018101492056321840:23:0)
[18:28:38.240] Timer tick, asking Buildbucket for the build status
[18:28:38.321] Build 8852671429885842208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:28:38.321] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9018101492056321840:24:0) after 1m0s
[18:29:38.358] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9018101492056321840:24:0)
[18:29:38.358] Timer tick, asking Buildbucket for the build status
[18:29:38.448] Build 8852671429885842208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:29:38.448] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9018101492056321840:25:0) after 1m0s
[18:30:38.519] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9018101492056321840:25:0)
[18:30:38.519] Timer tick, asking Buildbucket for the build status
[18:30:38.687] Build 8852671429885842208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:30:38.687] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9018101492056321840:26:0) after 1m0s
[18:31:38.705] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9018101492056321840:26:0)
[18:31:38.705] Timer tick, asking Buildbucket for the build status
[18:31:38.779] Build 8852671429885842208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:31:38.779] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9018101492056321840:27:0) after 1m0s
[18:32:38.766] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9018101492056321840:27:0)
[18:32:38.766] Timer tick, asking Buildbucket for the build status
[18:32:39.132] Build 8852671429885842208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:32:39.132] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9018101492056321840:28:0) after 1m0s
[18:33:39.144] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9018101492056321840:28:0)
[18:33:39.144] Timer tick, asking Buildbucket for the build status
[18:33:39.289] Build 8852671429885842208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:33:39.289] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9018101492056321840:29:0) after 1m0s
[18:34:39.310] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9018101492056321840:29:0)
[18:34:39.310] Timer tick, asking Buildbucket for the build status
[18:34:39.462] Build 8852671429885842208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:39.462] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9018101492056321840:30:0) after 1m0s
[18:35:39.550] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9018101492056321840:30:0)
[18:35:39.550] Timer tick, asking Buildbucket for the build status
[18:35:39.912] Build 8852671429885842208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:35:39.912] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9018101492056321840:31:0) after 1m0s
[18:36:39.932] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9018101492056321840:31:0)
[18:36:39.932] Timer tick, asking Buildbucket for the build status
[18:36:40.283] Build 8852671429885842208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:36:40.283] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9018101492056321840:32:0) after 1m0s
[18:37:40.335] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9018101492056321840:32:0)
[18:37:40.335] Timer tick, asking Buildbucket for the build status
[18:37:40.433] Build 8852671429885842208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:37:40.433] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9018101492056321840:33:0) after 1m0s
[18:38:40.450] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9018101492056321840:33:0)
[18:38:40.450] Timer tick, asking Buildbucket for the build status
[18:38:40.856] Build 8852671429885842208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:38:40.856] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9018101492056321840:34:0) after 1m0s
[18:39:40.876] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9018101492056321840:34:0)
[18:39:40.876] Timer tick, asking Buildbucket for the build status
[18:39:40.993] Build 8852671429885842208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:39:40.993] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9018101492056321840:35:0) after 1m0s
[18:40:41.034] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9018101492056321840:35:0)
[18:40:41.034] Timer tick, asking Buildbucket for the build status
[18:40:41.317] Build 8852671429885842208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:40:41.317] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9018101492056321840:36:0) after 1m0s
[18:41:41.386] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9018101492056321840:36:0)
[18:41:41.386] Timer tick, asking Buildbucket for the build status
[18:41:41.472] Build 8852671429885842208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:41:41.472] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9018101492056321840:37:0) after 1m0s
[18:42:41.525] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9018101492056321840:37:0)
[18:42:41.525] Timer tick, asking Buildbucket for the build status
[18:42:43.748] Build 8852671429885842208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:42:43.748] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9018101492056321840:38:0) after 1m0s
[18:42:53.162] Received PubSub notification, asking Buildbucket for the build status
[18:42:53.191] Build:
{
"id": "8852671429885842208",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTAndroid (dbg)"
},
"number": 12061,
"createdBy": "project:chromium",
"createTime": "2021-03-15T18:07:27.942894Z",
"startTime": "2021-03-15T18:12:40.644576Z",
"endTime": "2021-03-15T18:42:52.786746479Z",
"updateTime": "2021-03-15T18:42:52.787370910Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "63d5aa87d494fb832c898dce14350c120ed149d2",
"ref": "refs/heads/master"
}
}
}
[18:42:53.191] Invocation finished in 35m26.702026035s with status FAILED