[11:57:18.613] New invocation is queued and will start shortly
[11:57:19.841] Starting the invocation (attempt 1)
[11:57:19.890] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[11:57:19.895] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[11:57:19.895] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9029268205587367088",
"parameters_json": "{\"builder_name\":\"ToTiOSDevice\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"7c9032259c64a5800c1df6b83b1bd0e4c6e25a50\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:ToTiOSDevice",
"scheduler_invocation_id:9029268205587367088",
"scheduler_job_id:chromium/ToTiOSDevice",
"user_agent:luci-scheduler",
"buildset:commit/git/7c9032259c64a5800c1df6b83b1bd0e4c6e25a50",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7c9032259c64a5800c1df6b83b1bd0e4c6e25a50",
"gitiles_ref:refs/heads/master"
]
}
[11:57:20.559] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1605182239999473",
"id": "8863838143469461328",
"parameters_json": "{\"builder_name\": \"ToTiOSDevice\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"7c9032259c64a5800c1df6b83b1bd0e4c6e25a50\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1605182240385917",
"tags": [
"build_address:luci.chromium.ci/ToTiOSDevice/20523",
"builder:ToTiOSDevice",
"buildset:commit/git/7c9032259c64a5800c1df6b83b1bd0e4c6e25a50",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7c9032259c64a5800c1df6b83b1bd0e4c6e25a50",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9029268205587367088",
"scheduler_job_id:chromium/ToTiOSDevice",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8863838143469461328/+/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": "1605182240386020",
"url": "https://ci.chromium.org/b/8863838143469461328",
"utcnow_ts": "1605182240547527"
}
}
[11:57:20.559] Task URL: https://ci.chromium.org/b/8863838143469461328
[11:57:20.560] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:2:0) after 1m0s
[11:58:20.799] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:2:0)
[11:58:20.799] Timer tick, asking Buildbucket for the build status
[11:58:21.074] Build 8863838143469461328: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:58:21.074] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:3:0) after 1m0s
[11:59:21.114] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:3:0)
[11:59:21.114] Timer tick, asking Buildbucket for the build status
[11:59:21.222] Build 8863838143469461328: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:59:21.222] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:4:0) after 1m0s
[12:00:21.447] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:4:0)
[12:00:21.447] Timer tick, asking Buildbucket for the build status
[12:00:22.316] Build 8863838143469461328: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:00:22.316] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:5:0) after 1m0s
[12:00:57.327] Received PubSub notification, asking Buildbucket for the build status
[12:00:57.409] Build 8863838143469461328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:01:01.298] Received PubSub notification, asking Buildbucket for the build status
[12:01:01.993] Build 8863838143469461328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:01:22.955] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:5:0)
[12:01:22.955] Timer tick, asking Buildbucket for the build status
[12:01:23.198] Build 8863838143469461328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:01:23.198] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:8:0) after 1m0s
[12:02:23.757] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:8:0)
[12:02:23.757] Timer tick, asking Buildbucket for the build status
[12:02:24.649] Build 8863838143469461328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:02:24.649] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:9:0) after 1m0s
[12:03:24.731] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:9:0)
[12:03:24.731] Timer tick, asking Buildbucket for the build status
[12:03:24.896] Build 8863838143469461328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:03:24.896] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:10:0) after 1m0s
[12:04:24.940] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:10:0)
[12:04:24.940] Timer tick, asking Buildbucket for the build status
[12:04:25.111] Build 8863838143469461328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:04:25.111] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:11:0) after 1m0s
[12:05:25.099] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:11:0)
[12:05:25.099] Timer tick, asking Buildbucket for the build status
[12:05:25.189] Build 8863838143469461328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:05:25.189] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:12:0) after 1m0s
[12:06:25.232] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:12:0)
[12:06:25.232] Timer tick, asking Buildbucket for the build status
[12:06:25.299] Build 8863838143469461328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:06:25.299] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:13:0) after 1m0s
[12:07:25.349] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:13:0)
[12:07:25.349] Timer tick, asking Buildbucket for the build status
[12:07:25.515] Build 8863838143469461328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:07:25.515] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:14:0) after 1m0s
[12:08:25.597] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:14:0)
[12:08:25.597] Timer tick, asking Buildbucket for the build status
[12:08:25.695] Build 8863838143469461328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:08:25.695] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:15:0) after 1m0s
[12:09:25.726] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:15:0)
[12:09:25.726] Timer tick, asking Buildbucket for the build status
[12:09:26.083] Build 8863838143469461328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:09:26.083] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:16:0) after 1m0s
[12:10:26.164] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:16:0)
[12:10:26.164] Timer tick, asking Buildbucket for the build status
[12:10:26.318] Build 8863838143469461328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:10:26.318] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:17:0) after 1m0s
[12:11:26.356] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:17:0)
[12:11:26.356] Timer tick, asking Buildbucket for the build status
[12:11:26.946] Build 8863838143469461328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:11:26.946] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:18:0) after 1m0s
[12:12:27.045] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:18:0)
[12:12:27.045] Timer tick, asking Buildbucket for the build status
[12:12:27.158] Build 8863838143469461328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:12:27.158] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:19:0) after 1m0s
[12:13:27.189] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:19:0)
[12:13:27.189] Timer tick, asking Buildbucket for the build status
[12:13:27.255] Build 8863838143469461328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:13:27.255] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:20:0) after 1m0s
[12:14:27.279] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:20:0)
[12:14:27.279] Timer tick, asking Buildbucket for the build status
[12:14:27.341] Build 8863838143469461328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:14:27.341] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:21:0) after 1m0s
[12:15:27.383] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:21:0)
[12:15:27.383] Timer tick, asking Buildbucket for the build status
[12:15:27.601] Build 8863838143469461328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:15:27.601] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:22:0) after 1m0s
[12:16:27.632] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:22:0)
[12:16:27.632] Timer tick, asking Buildbucket for the build status
[12:16:27.765] Build 8863838143469461328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:16:27.766] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:23:0) after 1m0s
[12:17:27.797] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:23:0)
[12:17:27.797] Timer tick, asking Buildbucket for the build status
[12:17:27.922] Build 8863838143469461328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:17:27.923] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:24:0) after 1m0s
[12:18:28.044] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:24:0)
[12:18:28.044] Timer tick, asking Buildbucket for the build status
[12:18:28.687] Build 8863838143469461328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:18:28.687] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:25:0) after 1m0s
[12:19:28.761] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:25:0)
[12:19:28.761] Timer tick, asking Buildbucket for the build status
[12:19:28.827] Build 8863838143469461328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:19:28.828] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:26:0) after 1m0s
[12:20:28.883] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:26:0)
[12:20:28.883] Timer tick, asking Buildbucket for the build status
[12:20:29.053] Build 8863838143469461328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:20:29.053] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:27:0) after 1m0s
[12:21:29.124] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:27:0)
[12:21:29.124] Timer tick, asking Buildbucket for the build status
[12:21:29.196] Build 8863838143469461328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:21:29.196] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:28:0) after 1m0s
[12:22:29.254] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:28:0)
[12:22:29.254] Timer tick, asking Buildbucket for the build status
[12:22:29.468] Build 8863838143469461328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:22:29.468] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:29:0) after 1m0s
[12:23:29.516] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:29:0)
[12:23:29.516] Timer tick, asking Buildbucket for the build status
[12:23:29.593] Build 8863838143469461328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:23:29.593] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:30:0) after 1m0s
[12:24:29.677] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:30:0)
[12:24:29.677] Timer tick, asking Buildbucket for the build status
[12:24:29.881] Build 8863838143469461328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:24:29.882] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:31:0) after 1m0s
[12:25:29.949] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:31:0)
[12:25:29.949] Timer tick, asking Buildbucket for the build status
[12:25:30.034] Build 8863838143469461328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:25:30.034] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:32:0) after 1m0s
[12:26:30.062] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:32:0)
[12:26:30.062] Timer tick, asking Buildbucket for the build status
[12:26:30.344] Build 8863838143469461328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:26:30.344] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:33:0) after 1m0s
[12:27:30.371] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:33:0)
[12:27:30.371] Timer tick, asking Buildbucket for the build status
[12:27:30.521] Build 8863838143469461328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:27:30.521] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:34:0) after 1m0s
[12:28:30.565] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:34:0)
[12:28:30.565] Timer tick, asking Buildbucket for the build status
[12:28:30.939] Build 8863838143469461328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:28:30.939] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:35:0) after 1m0s
[12:29:30.975] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:35:0)
[12:29:30.975] Timer tick, asking Buildbucket for the build status
[12:29:31.107] Build 8863838143469461328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:29:31.107] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:36:0) after 1m0s
[12:30:31.170] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:36:0)
[12:30:31.170] Timer tick, asking Buildbucket for the build status
[12:30:31.244] Build 8863838143469461328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:30:31.244] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:37:0) after 1m0s
[12:31:31.231] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:37:0)
[12:31:31.231] Timer tick, asking Buildbucket for the build status
[12:31:31.298] Build 8863838143469461328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:31:31.298] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:38:0) after 1m0s
[12:32:31.324] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:38:0)
[12:32:31.324] Timer tick, asking Buildbucket for the build status
[12:32:31.812] Build 8863838143469461328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:32:31.812] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:39:0) after 1m0s
[12:33:31.889] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:39:0)
[12:33:31.889] Timer tick, asking Buildbucket for the build status
[12:33:32.309] Build 8863838143469461328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:33:32.309] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:40:0) after 1m0s
[12:34:32.350] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:40:0)
[12:34:32.350] Timer tick, asking Buildbucket for the build status
[12:34:32.411] Build 8863838143469461328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:34:32.411] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:41:0) after 1m0s
[12:35:32.493] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:41:0)
[12:35:32.493] Timer tick, asking Buildbucket for the build status
[12:35:32.559] Build 8863838143469461328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:35:32.559] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:42:0) after 1m0s
[12:36:32.589] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:42:0)
[12:36:32.589] Timer tick, asking Buildbucket for the build status
[12:36:32.949] Build 8863838143469461328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:36:32.949] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:43:0) after 1m0s
[12:37:33.001] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:43:0)
[12:37:33.001] Timer tick, asking Buildbucket for the build status
[12:37:33.131] Build 8863838143469461328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:37:33.131] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:44:0) after 1m0s
[12:38:33.204] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:44:0)
[12:38:33.205] Timer tick, asking Buildbucket for the build status
[12:38:33.274] Build 8863838143469461328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:38:33.274] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:45:0) after 1m0s
[12:39:33.382] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:45:0)
[12:39:33.382] Timer tick, asking Buildbucket for the build status
[12:39:33.444] Build 8863838143469461328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:39:33.445] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:46:0) after 1m0s
[12:40:33.495] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:46:0)
[12:40:33.495] Timer tick, asking Buildbucket for the build status
[12:40:33.589] Build 8863838143469461328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:40:33.589] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:47:0) after 1m0s
[12:41:33.613] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:47:0)
[12:41:33.614] Timer tick, asking Buildbucket for the build status
[12:41:33.806] Build 8863838143469461328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:41:33.806] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:48:0) after 1m0s
[12:42:33.851] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:48:0)
[12:42:33.851] Timer tick, asking Buildbucket for the build status
[12:42:33.958] Build 8863838143469461328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:42:33.958] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:49:0) after 1m0s
[12:43:33.999] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:49:0)
[12:43:33.999] Timer tick, asking Buildbucket for the build status
[12:43:34.123] Build 8863838143469461328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:43:34.123] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:50:0) after 1m0s
[12:44:34.186] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:50:0)
[12:44:34.186] Timer tick, asking Buildbucket for the build status
[12:44:34.273] Build 8863838143469461328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:44:34.273] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:51:0) after 1m0s
[12:45:34.343] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:51:0)
[12:45:34.343] Timer tick, asking Buildbucket for the build status
[12:45:35.628] Build 8863838143469461328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:45:35.628] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:52:0) after 1m0s
[12:46:35.678] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:52:0)
[12:46:35.678] Timer tick, asking Buildbucket for the build status
[12:46:35.753] Build 8863838143469461328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:46:35.753] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:53:0) after 1m0s
[12:47:35.815] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:53:0)
[12:47:35.815] Timer tick, asking Buildbucket for the build status
[12:47:35.922] Build 8863838143469461328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:47:35.923] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:54:0) after 1m0s
[12:48:35.992] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:54:0)
[12:48:35.992] Timer tick, asking Buildbucket for the build status
[12:48:36.056] Build 8863838143469461328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:48:36.056] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:55:0) after 1m0s
[12:49:36.033] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:55:0)
[12:49:36.033] Timer tick, asking Buildbucket for the build status
[12:49:36.121] Build 8863838143469461328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:36.121] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:56:0) after 1m0s
[12:50:36.122] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:56:0)
[12:50:36.122] Timer tick, asking Buildbucket for the build status
[12:50:36.333] Build 8863838143469461328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:36.333] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:57:0) after 1m0s
[12:51:36.399] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:57:0)
[12:51:36.399] Timer tick, asking Buildbucket for the build status
[12:51:36.700] Build 8863838143469461328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:36.700] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:58:0) after 1m0s
[12:52:36.847] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:58:0)
[12:52:36.847] Timer tick, asking Buildbucket for the build status
[12:52:37.123] Build 8863838143469461328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:37.123] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:59:0) after 1m0s
[12:53:37.195] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:59:0)
[12:53:37.195] Timer tick, asking Buildbucket for the build status
[12:53:37.267] Build 8863838143469461328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:53:37.267] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9029268205587367088:60:0) after 1m0s
[12:53:54.381] Received PubSub notification, asking Buildbucket for the build status
[12:53:54.456] Build 8863838143469461328: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[12:53:54.456] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1605185634057264",
"created_by": "project:chromium",
"created_ts": "1605182239999473",
"failure_reason": "BUILD_FAILURE",
"id": "8863838143469461328",
"parameters_json": "{\"builder_name\": \"ToTiOSDevice\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"7c9032259c64a5800c1df6b83b1bd0e4c6e25a50\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"Step('gclient runhooks') (retcode: 2)\"}, \"properties\": {\"$build/goma\": {\"use_luci_auth\": true}, \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"7c9032259c64a5800c1df6b83b1bd0e4c6e25a50\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"/opt/s/w/ir/cache\", \"temp_dir\": \"/opt/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"build716-m9\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.clang\", \"buildername\": \"ToTiOSDevice\", \"buildnumber\": 20523, \"got_angle_revision\": \"62c10c170bb33f1ad6c9eb13d0cbdf13f95fb27e\", \"got_dawn_revision\": \"b70a5b02e9712693ffb796c0b0d8cfa24ca5eba5\", \"got_nacl_revision\": \"f9e6f257d1a0934dbeed3c5c8f83b651dc4f55e1\", \"got_revision\": \"7c9032259c64a5800c1df6b83b1bd0e4c6e25a50\", \"got_revision_cp\": \"refs/heads/master@{#826729}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"b638dfe160a17f288c774fd98f725f212f843c2b\", \"got_v8_revision\": \"f3414dbc4f3adca0d966820894857e5255b3fc20\", \"got_v8_revision_cp\": \"refs/heads/8.8.293@{#1}\", \"got_webrtc_revision\": \"3ab1aba2f4d59ca7ce6f9e3fe615d16fc33de230\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32594}\", \"path_config\": \"generic\", \"perf_dashboard_machine_group\": \"ChromiumClang\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"7c9032259c64a5800c1df6b83b1bd0e4c6e25a50\", \"xcode_build_version\": \"12a7209\"}, \"swarming\": {\"bot_dimensions\": {\"builder\": [\"ToTiOS\", \"ToTiOSDevice\"], \"caches\": [\"builder_ae5debd901c74ee0906916cb62621bceb2a8b657b42c2e2e9e89a0f6a2e5e53b_v2\", \"git\", \"goma_v2\", \"vpython\", \"xcode_ios_12a7209\"], \"cores\": [\"12\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-i7-8700B\"], \"gce\": [\"0\"], \"gpu\": [\"8086\", \"8086:3e9b\"], \"hidpi\": [\"0\"], \"id\": [\"build716-m9\"], \"mac_model\": [\"Macmini8,1\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Mac\", \"Mac-10.15\", \"Mac-10.15.6\", \"Mac-10.15.6-19G2021\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.16\"], \"server_version\": [\"5626-39642e9\"], \"ssd\": [\"1\"], \"zone\": [\"us\", \"us-atl\", \"us-atl-golo\", \"us-atl-golo-m9\"]}}, \"ui\": {\"info\": \"Step('gclient runhooks') (retcode: 2)\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1605182455964354",
"status": "COMPLETED",
"status_changed_ts": "1605182456795383",
"tags": [
"build_address:luci.chromium.ci/ToTiOSDevice/20523",
"builder:ToTiOSDevice",
"buildset:commit/git/7c9032259c64a5800c1df6b83b1bd0e4c6e25a50",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7c9032259c64a5800c1df6b83b1bd0e4c6e25a50",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9029268205587367088",
"scheduler_job_id:chromium/ToTiOSDevice",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8863838143469461328/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:os:Mac",
"swarming_tag:recipe_name:chromium",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:4fd522bf8014bb10",
"user_agent:luci-scheduler"
],
"updated_ts": "1605185634121608",
"url": "https://ci.chromium.org/b/8863838143469461328",
"utcnow_ts": "1605185634445646"
}
}
[12:53:54.456] Invocation finished in 56m35.860924246s with status FAILED