[10:46:36.704] New invocation is queued and will start shortly
[10:46:37.887] Starting the invocation (attempt 1)
[10:46:37.925] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[10:46:37.925] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[10:46:37.925] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9075024121591446672",
"parameters_json": "{\"builder_name\":\"Site Isolation Android\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"1d463a4dd0334e828f319e394113d6bcdc08b2ab\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Site Isolation Android",
"scheduler_invocation_id:9075024121591446672",
"scheduler_job_id:chromium/Site Isolation Android",
"user_agent:luci-scheduler",
"buildset:commit/git/1d463a4dd0334e828f319e394113d6bcdc08b2ab",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1d463a4dd0334e828f319e394113d6bcdc08b2ab",
"gitiles_ref:refs/heads/master"
]
}
[10:46:39.482] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1561545998463788",
"id": "8909594059074130496",
"parameters_json": "{\"builder_name\": \"Site Isolation Android\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"1d463a4dd0334e828f319e394113d6bcdc08b2ab\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1561545999195746",
"tags": [
"build_address:luci.chromium.ci/Site Isolation Android/35170",
"builder:Site Isolation Android",
"buildset:commit/git/1d463a4dd0334e828f319e394113d6bcdc08b2ab",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1d463a4dd0334e828f319e394113d6bcdc08b2ab",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9075024121591446672",
"scheduler_job_id:chromium/Site Isolation Android",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8909594059074130496/+/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": "1561545999195839",
"url": "https://ci.chromium.org/b/8909594059074130496",
"utcnow_ts": "1561545999464623"
}
}
[10:46:39.482] Task URL: https://ci.chromium.org/b/8909594059074130496
[10:46:39.482] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:2:0) after 1m0s
[10:47:39.485] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:2:0)
[10:47:39.485] Timer tick, asking Buildbucket for the build status
[10:47:39.745] Build 8909594059074130496: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:47:39.745] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:3:0) after 1m0s
[10:48:36.591] Received PubSub notification, asking Buildbucket for the build status
[10:48:36.753] Build 8909594059074130496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:48:39.839] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:3:0)
[10:48:39.844] Timer tick, asking Buildbucket for the build status
[10:48:40.239] Build 8909594059074130496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:48:40.239] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:5:0) after 1m0s
[10:49:40.265] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:5:0)
[10:49:40.265] Timer tick, asking Buildbucket for the build status
[10:49:40.662] Build 8909594059074130496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:49:40.662] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:6:0) after 1m0s
[10:50:40.753] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:6:0)
[10:50:40.753] Timer tick, asking Buildbucket for the build status
[10:50:41.975] Build 8909594059074130496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:50:41.975] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:7:0) after 1m0s
[10:51:42.001] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:7:0)
[10:51:42.001] Timer tick, asking Buildbucket for the build status
[10:51:43.059] Build 8909594059074130496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:51:43.059] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:8:0) after 1m0s
[10:52:43.111] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:8:0)
[10:52:43.111] Timer tick, asking Buildbucket for the build status
[10:52:43.401] Build 8909594059074130496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:52:43.401] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:9:0) after 1m0s
[10:53:43.439] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:9:0)
[10:53:43.439] Timer tick, asking Buildbucket for the build status
[10:53:43.720] Build 8909594059074130496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:53:43.720] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:10:0) after 1m0s
[10:54:43.780] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:10:0)
[10:54:43.780] Timer tick, asking Buildbucket for the build status
[10:54:44.422] Build 8909594059074130496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:54:44.422] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:11:0) after 1m0s
[10:55:44.468] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:11:0)
[10:55:44.468] Timer tick, asking Buildbucket for the build status
[10:55:44.710] Build 8909594059074130496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:55:44.710] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:12:0) after 1m0s
[10:56:44.739] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:12:0)
[10:56:44.739] Timer tick, asking Buildbucket for the build status
[10:56:45.069] Build 8909594059074130496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:56:45.069] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:13:0) after 1m0s
[10:57:45.095] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:13:0)
[10:57:45.095] Timer tick, asking Buildbucket for the build status
[10:57:45.313] Build 8909594059074130496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:57:45.313] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:14:0) after 1m0s
[10:58:45.316] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:14:0)
[10:58:45.316] Timer tick, asking Buildbucket for the build status
[10:58:45.744] Build 8909594059074130496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:58:45.744] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:15:0) after 1m0s
[10:59:45.784] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:15:0)
[10:59:45.784] Timer tick, asking Buildbucket for the build status
[10:59:46.286] Build 8909594059074130496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:59:46.286] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:16:0) after 1m0s
[11:00:46.332] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:16:0)
[11:00:46.332] Timer tick, asking Buildbucket for the build status
[11:00:46.470] Build 8909594059074130496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:00:46.470] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:17:0) after 1m0s
[11:01:46.517] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:17:0)
[11:01:46.517] Timer tick, asking Buildbucket for the build status
[11:01:46.640] Build 8909594059074130496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:01:46.640] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:18:0) after 1m0s
[11:02:46.666] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:18:0)
[11:02:46.666] Timer tick, asking Buildbucket for the build status
[11:02:47.500] Build 8909594059074130496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:02:47.500] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:19:0) after 1m0s
[11:03:47.514] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:19:0)
[11:03:47.514] Timer tick, asking Buildbucket for the build status
[11:03:47.789] Build 8909594059074130496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:03:47.789] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:20:0) after 1m0s
[11:04:47.862] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:20:0)
[11:04:47.862] Timer tick, asking Buildbucket for the build status
[11:04:48.136] Build 8909594059074130496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:04:48.136] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:21:0) after 1m0s
[11:05:48.223] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:21:0)
[11:05:48.223] Timer tick, asking Buildbucket for the build status
[11:05:49.670] Build 8909594059074130496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:05:49.670] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:22:0) after 1m0s
[11:06:49.650] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:22:0)
[11:06:49.650] Timer tick, asking Buildbucket for the build status
[11:06:49.989] Build 8909594059074130496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:06:49.989] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:23:0) after 1m0s
[11:07:50.053] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:23:0)
[11:07:50.053] Timer tick, asking Buildbucket for the build status
[11:07:50.328] Build 8909594059074130496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:07:50.328] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:24:0) after 1m0s
[11:08:50.364] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:24:0)
[11:08:50.364] Timer tick, asking Buildbucket for the build status
[11:08:50.687] Build 8909594059074130496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:08:50.687] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:25:0) after 1m0s
[11:09:50.712] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:25:0)
[11:09:50.712] Timer tick, asking Buildbucket for the build status
[11:09:51.624] Build 8909594059074130496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:09:51.624] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:26:0) after 1m0s
[11:10:51.685] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:26:0)
[11:10:51.685] Timer tick, asking Buildbucket for the build status
[11:10:52.250] Build 8909594059074130496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:10:52.250] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:27:0) after 1m0s
[11:11:52.304] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:27:0)
[11:11:52.304] Timer tick, asking Buildbucket for the build status
[11:11:52.413] Build 8909594059074130496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:11:52.413] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:28:0) after 1m0s
[11:12:52.445] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:28:0)
[11:12:52.445] Timer tick, asking Buildbucket for the build status
[11:12:53.034] Build 8909594059074130496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:12:53.034] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:29:0) after 1m0s
[11:13:53.049] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:29:0)
[11:13:53.049] Timer tick, asking Buildbucket for the build status
[11:13:53.550] Build 8909594059074130496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:13:53.550] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:30:0) after 1m0s
[11:14:53.643] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:30:0)
[11:14:53.643] Timer tick, asking Buildbucket for the build status
[11:14:54.058] Build 8909594059074130496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:14:54.058] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:31:0) after 1m0s
[11:15:54.050] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:31:0)
[11:15:54.050] Timer tick, asking Buildbucket for the build status
[11:15:54.234] Build 8909594059074130496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:15:54.234] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:32:0) after 1m0s
[11:16:54.295] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:32:0)
[11:16:54.295] Timer tick, asking Buildbucket for the build status
[11:16:54.587] Build 8909594059074130496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:16:54.587] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:33:0) after 1m0s
[11:17:54.642] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:33:0)
[11:17:54.642] Timer tick, asking Buildbucket for the build status
[11:17:54.802] Build 8909594059074130496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:17:54.802] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:34:0) after 1m0s
[11:18:54.821] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:34:0)
[11:18:54.821] Timer tick, asking Buildbucket for the build status
[11:18:55.093] Build 8909594059074130496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:18:55.093] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:35:0) after 1m0s
[11:19:55.164] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:35:0)
[11:19:55.164] Timer tick, asking Buildbucket for the build status
[11:19:55.489] Build 8909594059074130496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:19:55.489] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:36:0) after 1m0s
[11:20:55.616] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:36:0)
[11:20:55.616] Timer tick, asking Buildbucket for the build status
[11:20:56.104] Build 8909594059074130496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:20:56.104] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:37:0) after 1m0s
[11:21:56.222] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:37:0)
[11:21:56.222] Timer tick, asking Buildbucket for the build status
[11:21:57.361] Build 8909594059074130496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:21:57.361] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:38:0) after 1m0s
[11:22:57.383] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:38:0)
[11:22:57.383] Timer tick, asking Buildbucket for the build status
[11:22:57.693] Build 8909594059074130496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:22:57.693] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:39:0) after 1m0s
[11:23:57.683] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:39:0)
[11:23:57.684] Timer tick, asking Buildbucket for the build status
[11:23:57.943] Build 8909594059074130496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:23:57.943] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:40:0) after 1m0s
[11:24:57.978] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:40:0)
[11:24:57.978] Timer tick, asking Buildbucket for the build status
[11:24:58.138] Build 8909594059074130496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:24:58.138] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:41:0) after 1m0s
[11:25:58.157] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:41:0)
[11:25:58.157] Timer tick, asking Buildbucket for the build status
[11:25:58.376] Build 8909594059074130496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:25:58.376] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:42:0) after 1m0s
[11:26:58.371] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:42:0)
[11:26:58.371] Timer tick, asking Buildbucket for the build status
[11:27:00.967] Build 8909594059074130496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:27:00.967] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:43:0) after 1m0s
[11:28:01.004] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:43:0)
[11:28:01.004] Timer tick, asking Buildbucket for the build status
[11:28:01.642] Build 8909594059074130496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:28:01.642] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:44:0) after 1m0s
[11:29:01.669] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:44:0)
[11:29:01.669] Timer tick, asking Buildbucket for the build status
[11:29:03.122] Build 8909594059074130496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:29:03.122] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:45:0) after 1m0s
[11:30:03.158] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:45:0)
[11:30:03.158] Timer tick, asking Buildbucket for the build status
[11:30:03.548] Build 8909594059074130496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:30:03.548] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:46:0) after 1m0s
[11:31:03.612] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:46:0)
[11:31:03.612] Timer tick, asking Buildbucket for the build status
[11:31:03.953] Build 8909594059074130496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:31:03.953] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:47:0) after 1m0s
[11:32:03.974] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:47:0)
[11:32:03.974] Timer tick, asking Buildbucket for the build status
[11:32:05.571] Build 8909594059074130496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:32:05.571] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:48:0) after 1m0s
[11:33:05.613] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:48:0)
[11:33:05.613] Timer tick, asking Buildbucket for the build status
[11:33:05.966] Build 8909594059074130496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:33:05.966] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:49:0) after 1m0s
[11:34:06.039] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:49:0)
[11:34:06.039] Timer tick, asking Buildbucket for the build status
[11:34:07.549] Build 8909594059074130496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:34:07.550] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:50:0) after 1m0s
[11:35:07.582] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:50:0)
[11:35:07.582] Timer tick, asking Buildbucket for the build status
[11:35:07.742] Build 8909594059074130496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:35:07.742] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:51:0) after 1m0s
[11:36:07.805] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:51:0)
[11:36:07.805] Timer tick, asking Buildbucket for the build status
[11:36:09.083] Build 8909594059074130496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:36:09.083] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:52:0) after 1m0s
[11:37:09.104] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:52:0)
[11:37:09.104] Timer tick, asking Buildbucket for the build status
[11:37:09.303] Build 8909594059074130496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:37:09.303] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:53:0) after 1m0s
[11:38:09.400] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:53:0)
[11:38:09.400] Timer tick, asking Buildbucket for the build status
[11:38:09.934] Build 8909594059074130496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:38:09.934] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:54:0) after 1m0s
[11:39:09.987] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:54:0)
[11:39:09.987] Timer tick, asking Buildbucket for the build status
[11:39:10.416] Build 8909594059074130496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:39:10.416] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:55:0) after 1m0s
[11:40:10.489] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:55:0)
[11:40:10.489] Timer tick, asking Buildbucket for the build status
[11:40:10.858] Build 8909594059074130496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:40:10.858] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:56:0) after 1m0s
[11:41:10.942] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:56:0)
[11:41:10.942] Timer tick, asking Buildbucket for the build status
[11:41:11.251] Build 8909594059074130496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:41:11.251] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:57:0) after 1m0s
[11:42:11.354] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:57:0)
[11:42:11.355] Timer tick, asking Buildbucket for the build status
[11:42:11.550] Build 8909594059074130496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:42:11.550] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:58:0) after 1m0s
[11:43:11.616] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:58:0)
[11:43:11.616] Timer tick, asking Buildbucket for the build status
[11:43:11.881] Build 8909594059074130496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:43:11.881] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:59:0) after 1m0s
[11:44:11.954] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:59:0)
[11:44:11.954] Timer tick, asking Buildbucket for the build status
[11:44:13.023] Build 8909594059074130496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:44:13.023] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:60:0) after 1m0s
[11:45:13.182] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:60:0)
[11:45:13.182] Timer tick, asking Buildbucket for the build status
[11:45:13.731] Build 8909594059074130496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:45:13.731] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:61:0) after 1m0s
[11:46:13.878] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:61:0)
[11:46:13.878] Timer tick, asking Buildbucket for the build status
[11:46:14.003] Build 8909594059074130496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:46:14.003] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:62:0) after 1m0s
[11:47:14.275] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:62:0)
[11:47:14.275] Timer tick, asking Buildbucket for the build status
[11:47:14.627] Build 8909594059074130496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:47:14.627] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:63:0) after 1m0s
[11:48:14.608] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:63:0)
[11:48:14.608] Timer tick, asking Buildbucket for the build status
[11:48:15.249] Build 8909594059074130496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:48:15.249] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:64:0) after 1m0s
[11:49:15.379] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:64:0)
[11:49:15.379] Timer tick, asking Buildbucket for the build status
[11:49:15.530] Build 8909594059074130496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:49:15.531] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9075024121591446672:65:0) after 1m0s
[11:49:28.294] Received PubSub notification, asking Buildbucket for the build status
[11:49:28.911] Build 8909594059074130496: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[11:49:28.911] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1561549767772398",
"created_by": "project:chromium",
"created_ts": "1561545998463788",
"failure_reason": "BUILD_FAILURE",
"id": "8909594059074130496",
"parameters_json": "{\"builder_name\": \"Site Isolation Android\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"1d463a4dd0334e828f319e394113d6bcdc08b2ab\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 tests failed: ['site_per_process_unit_tests on Android device Nexus 5X']\"}, \"properties\": {\"$build/goma\": {\"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\"}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/swarming/w/ir/cache\", \"temp_dir\": \"/b/swarming/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"swarm2384-c4\", \"branch\": \"refs/heads/master\", \"buildername\": \"Site Isolation Android\", \"buildnumber\": 35170, \"got_angle_revision\": \"166f0ab34656b09cdbb1086c27c4d218ab1711d8\", \"got_dawn_revision\": \"751252e3724b07062858af91e72dadb6379244ff\", \"got_nacl_revision\": \"c4cf6f0621b94cca4053b19fa01ef329f5663880\", \"got_revision\": \"1d463a4dd0334e828f319e394113d6bcdc08b2ab\", \"got_revision_cp\": \"refs/heads/master@{#672467}\", \"got_swarming_client_revision\": \"96f125709acfd0b48fc1e5dae7d6ea42291726ac\", \"got_v8_revision\": \"948f4cb240c3ec4c09a798cc4d6e72bd6548221b\", \"got_v8_revision_cp\": \"refs/heads/7.7.149@{#1}\", \"got_webrtc_revision\": \"f97f342fd352633ef1b2ced5cf1b340f994be515\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#28377}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.fyi\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"1d463a4dd0334e828f319e394113d6bcdc08b2ab\", \"swarm_hashes\": {\"components_browsertests\": \"7138d1cc8bb095e1dc1ec2f37389ea9b0b83d117\", \"components_unittests\": \"aa170514c5a20f1e4abdcc68f58aea669c4e8f69\", \"content_browsertests\": \"85865bfaf7933c23c0c2189513be5f5c11b6d0c4\", \"content_unittests\": \"b26dbb34dc0c0cdbac222fccc17149bce242eb65\", \"unit_tests\": \"3ad833c6c7d7f02e2e8a5daf0556a53b1030b510\"}, \"swarm_hashes_refs/heads/master(at){#672467}_without_patch\": {\"components_browsertests\": \"7138d1cc8bb095e1dc1ec2f37389ea9b0b83d117\", \"components_unittests\": \"aa170514c5a20f1e4abdcc68f58aea669c4e8f69\", \"content_browsertests\": \"85865bfaf7933c23c0c2189513be5f5c11b6d0c4\", \"content_unittests\": \"b26dbb34dc0c0cdbac222fccc17149bce242eb65\", \"unit_tests\": \"3ad833c6c7d7f02e2e8a5daf0556a53b1030b510\"}}, \"swarming\": {\"bot_dimensions\": {\"builder\": [\"Site Isolation Android\"], \"caches\": [\"builder_e961d9d7e9bca679490a4aaa8599bd975f9b7e0b713a988ed6895a059d04d2ac_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Ivy_Bridge_GCE\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"swarm2384-c4\"], \"inside_docker\": [\"0\"], \"kvm\": [\"0\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-14.04\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.6\"], \"server_version\": [\"4339-6285c57\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-f\"]}}, \"ui\": {\"info\": \"1 tests failed: ['site_per_process_unit_tests on Android device Nexus 5X']\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1561546115657121",
"status": "COMPLETED",
"status_changed_ts": "1561546116205436",
"tags": [
"build_address:luci.chromium.ci/Site Isolation Android/35170",
"builder:Site Isolation Android",
"buildset:commit/git/1d463a4dd0334e828f319e394113d6bcdc08b2ab",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1d463a4dd0334e828f319e394113d6bcdc08b2ab",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9075024121591446672",
"scheduler_job_id:chromium/Site Isolation Android",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8909594059074130496/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:os:Linux",
"swarming_tag:recipe_name:chromium",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:45ac36b16c08bd10",
"user_agent:luci-scheduler"
],
"updated_ts": "1561549767855748",
"url": "https://ci.chromium.org/b/8909594059074130496",
"utcnow_ts": "1561549768873615"
}
}
[11:49:28.911] Invocation finished in 1h2m52.231927955s with status FAILED