[09:17:46.834] New invocation is queued and will start shortly
[09:17:48.309] Starting the invocation (attempt 1)
[09:17:48.357] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[09:17:48.357] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[09:17:48.357] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9076932246652799888",
"parameters_json": "{\"builder_name\":\"Site Isolation Android\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"5bb50560dd7bc607357d5789a36c47e27d8881b8\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Site Isolation Android",
"scheduler_invocation_id:9076932246652799888",
"scheduler_job_id:chromium/Site Isolation Android",
"user_agent:luci-scheduler",
"buildset:commit/git/5bb50560dd7bc607357d5789a36c47e27d8881b8",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5bb50560dd7bc607357d5789a36c47e27d8881b8",
"gitiles_ref:refs/heads/master"
]
}
[09:17:50.731] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1559726268759377",
"id": "8911502183967808000",
"parameters_json": "{\"builder_name\": \"Site Isolation Android\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"5bb50560dd7bc607357d5789a36c47e27d8881b8\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1559726270487682",
"tags": [
"build_address:luci.chromium.ci/Site Isolation Android/34586",
"builder:Site Isolation Android",
"buildset:commit/git/5bb50560dd7bc607357d5789a36c47e27d8881b8",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5bb50560dd7bc607357d5789a36c47e27d8881b8",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9076932246652799888",
"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/8911502183967808000/+/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": "1559726270487899",
"url": "https://ci.chromium.org/b/8911502183967808000",
"utcnow_ts": "1559726270706791"
}
}
[09:17:50.731] Task URL: https://ci.chromium.org/b/8911502183967808000
[09:17:50.731] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:2:0) after 1m0s
[09:18:50.827] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:2:0)
[09:18:50.827] Timer tick, asking Buildbucket for the build status
[09:18:51.613] Build 8911502183967808000: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:18:51.613] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:3:0) after 1m0s
[09:19:51.633] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:3:0)
[09:19:51.633] Timer tick, asking Buildbucket for the build status
[09:19:54.237] Build 8911502183967808000: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:19:54.237] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:4:0) after 1m0s
[09:19:56.056] Received PubSub notification, asking Buildbucket for the build status
[09:19:57.087] Build 8911502183967808000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:20:54.294] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:4:0)
[09:20:54.294] Timer tick, asking Buildbucket for the build status
[09:20:56.312] Build 8911502183967808000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:20:56.312] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:6:0) after 1m0s
[09:21:56.416] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:6:0)
[09:21:56.416] Timer tick, asking Buildbucket for the build status
[09:21:57.004] Build 8911502183967808000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:21:57.004] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:7:0) after 1m0s
[09:22:57.073] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:7:0)
[09:22:57.073] Timer tick, asking Buildbucket for the build status
[09:22:57.652] Build 8911502183967808000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:22:57.652] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:8:0) after 1m0s
[09:23:57.688] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:8:0)
[09:23:57.688] Timer tick, asking Buildbucket for the build status
[09:23:57.819] Build 8911502183967808000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:23:57.819] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:9:0) after 1m0s
[09:24:57.864] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:9:0)
[09:24:57.864] Timer tick, asking Buildbucket for the build status
[09:24:58.429] Build 8911502183967808000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:24:58.429] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:10:0) after 1m0s
[09:25:58.454] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:10:0)
[09:25:58.454] Timer tick, asking Buildbucket for the build status
[09:25:58.602] Build 8911502183967808000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:25:58.602] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:11:0) after 1m0s
[09:26:58.620] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:11:0)
[09:26:58.620] Timer tick, asking Buildbucket for the build status
[09:27:00.093] Build 8911502183967808000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:27:00.093] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:12:0) after 1m0s
[09:28:00.142] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:12:0)
[09:28:00.142] Timer tick, asking Buildbucket for the build status
[09:28:00.933] Build 8911502183967808000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:28:00.933] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:13:0) after 1m0s
[09:29:00.955] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:13:0)
[09:29:00.955] Timer tick, asking Buildbucket for the build status
[09:29:03.355] Build 8911502183967808000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:29:03.355] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:14:0) after 1m0s
[09:30:03.394] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:14:0)
[09:30:03.394] Timer tick, asking Buildbucket for the build status
[09:30:05.638] Build 8911502183967808000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:30:05.638] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:15:0) after 1m0s
[09:31:05.649] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:15:0)
[09:31:05.649] Timer tick, asking Buildbucket for the build status
[09:31:07.826] Build 8911502183967808000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:31:07.826] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:16:0) after 1m0s
[09:32:07.882] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:16:0)
[09:32:07.882] Timer tick, asking Buildbucket for the build status
[09:32:08.028] Build 8911502183967808000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:32:08.028] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:17:0) after 1m0s
[09:33:08.096] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:17:0)
[09:33:08.096] Timer tick, asking Buildbucket for the build status
[09:33:08.432] Build 8911502183967808000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:33:08.432] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:18:0) after 1m0s
[09:34:08.510] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:18:0)
[09:34:08.510] Timer tick, asking Buildbucket for the build status
[09:34:09.787] Build 8911502183967808000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:34:09.787] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:19:0) after 1m0s
[09:35:09.912] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:19:0)
[09:35:09.912] Timer tick, asking Buildbucket for the build status
[09:35:10.266] Build 8911502183967808000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:35:10.266] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:20:0) after 1m0s
[09:36:10.368] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:20:0)
[09:36:10.368] Timer tick, asking Buildbucket for the build status
[09:36:13.121] Build 8911502183967808000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:36:13.121] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:21:0) after 1m0s
[09:37:13.122] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:21:0)
[09:37:13.122] Timer tick, asking Buildbucket for the build status
[09:37:15.348] Build 8911502183967808000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:37:15.348] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:22:0) after 1m0s
[09:38:15.482] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:22:0)
[09:38:15.482] Timer tick, asking Buildbucket for the build status
[09:38:15.653] Build 8911502183967808000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:38:15.653] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:23:0) after 1m0s
[09:39:15.658] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:23:0)
[09:39:15.658] Timer tick, asking Buildbucket for the build status
[09:39:17.109] Build 8911502183967808000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:39:17.109] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:24:0) after 1m0s
[09:40:17.141] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:24:0)
[09:40:17.141] Timer tick, asking Buildbucket for the build status
[09:40:18.262] Build 8911502183967808000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:40:18.262] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:25:0) after 1m0s
[09:41:18.351] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:25:0)
[09:41:18.351] Timer tick, asking Buildbucket for the build status
[09:41:18.507] Build 8911502183967808000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:41:18.507] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:26:0) after 1m0s
[09:42:18.543] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:26:0)
[09:42:18.543] Timer tick, asking Buildbucket for the build status
[09:42:19.297] Build 8911502183967808000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:42:19.297] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:27:0) after 1m0s
[09:43:19.330] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:27:0)
[09:43:19.330] Timer tick, asking Buildbucket for the build status
[09:43:19.556] Build 8911502183967808000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:43:19.556] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:28:0) after 1m0s
[09:44:19.554] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:28:0)
[09:44:19.554] Timer tick, asking Buildbucket for the build status
[09:44:19.648] Build 8911502183967808000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:44:19.648] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:29:0) after 1m0s
[09:45:19.669] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:29:0)
[09:45:19.669] Timer tick, asking Buildbucket for the build status
[09:45:19.984] Build 8911502183967808000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:45:19.984] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:30:0) after 1m0s
[09:46:20.005] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:30:0)
[09:46:20.005] Timer tick, asking Buildbucket for the build status
[09:46:22.558] Build 8911502183967808000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:46:22.558] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:31:0) after 1m0s
[09:47:22.589] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:31:0)
[09:47:22.589] Timer tick, asking Buildbucket for the build status
[09:47:22.920] Build 8911502183967808000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:47:22.920] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:32:0) after 1m0s
[09:48:23.049] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:32:0)
[09:48:23.049] Timer tick, asking Buildbucket for the build status
[09:48:23.774] Build 8911502183967808000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:48:23.774] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:33:0) after 1m0s
[09:49:23.794] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:33:0)
[09:49:23.794] Timer tick, asking Buildbucket for the build status
[09:49:23.991] Build 8911502183967808000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:49:23.991] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:34:0) after 1m0s
[09:50:24.076] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:34:0)
[09:50:24.076] Timer tick, asking Buildbucket for the build status
[09:50:24.187] Build 8911502183967808000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:50:24.187] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:35:0) after 1m0s
[09:51:24.255] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:35:0)
[09:51:24.255] Timer tick, asking Buildbucket for the build status
[09:51:24.509] Build 8911502183967808000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:51:24.509] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:36:0) after 1m0s
[09:52:24.613] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:36:0)
[09:52:24.613] Timer tick, asking Buildbucket for the build status
[09:52:26.238] Build 8911502183967808000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:52:26.238] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:37:0) after 1m0s
[09:53:26.268] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:37:0)
[09:53:26.268] Timer tick, asking Buildbucket for the build status
[09:53:26.550] Build 8911502183967808000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:53:26.550] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:38:0) after 1m0s
[09:54:26.720] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:38:0)
[09:54:26.720] Timer tick, asking Buildbucket for the build status
[09:54:27.696] Build 8911502183967808000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:54:27.696] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:39:0) after 1m0s
[09:55:27.792] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:39:0)
[09:55:27.792] Timer tick, asking Buildbucket for the build status
[09:55:27.984] Build 8911502183967808000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:55:27.984] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:40:0) after 1m0s
[09:56:28.004] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:40:0)
[09:56:28.004] Timer tick, asking Buildbucket for the build status
[09:56:29.593] Build 8911502183967808000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:56:29.593] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:41:0) after 1m0s
[09:57:29.663] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:41:0)
[09:57:29.663] Timer tick, asking Buildbucket for the build status
[09:57:30.383] Build 8911502183967808000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:57:30.383] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:42:0) after 1m0s
[09:58:30.526] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:42:0)
[09:58:30.526] Timer tick, asking Buildbucket for the build status
[09:58:31.110] Build 8911502183967808000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:58:31.110] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:43:0) after 1m0s
[09:59:31.179] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:43:0)
[09:59:31.179] Timer tick, asking Buildbucket for the build status
[09:59:33.388] Build 8911502183967808000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:59:33.388] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:44:0) after 1m0s
[10:00:33.407] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:44:0)
[10:00:33.407] Timer tick, asking Buildbucket for the build status
[10:00:35.038] Build 8911502183967808000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:00:35.038] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:45:0) after 1m0s
[10:01:35.091] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:45:0)
[10:01:35.091] Timer tick, asking Buildbucket for the build status
[10:01:35.856] Build 8911502183967808000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:01:35.856] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:46:0) after 1m0s
[10:02:35.876] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:46:0)
[10:02:35.876] Timer tick, asking Buildbucket for the build status
[10:02:36.558] Build 8911502183967808000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:02:36.558] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:47:0) after 1m0s
[10:03:36.594] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:47:0)
[10:03:36.594] Timer tick, asking Buildbucket for the build status
[10:03:37.276] Build 8911502183967808000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:03:37.276] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:48:0) after 1m0s
[10:04:37.331] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:48:0)
[10:04:37.331] Timer tick, asking Buildbucket for the build status
[10:04:37.475] Build 8911502183967808000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:04:37.475] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:49:0) after 1m0s
[10:05:37.505] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:49:0)
[10:05:37.505] Timer tick, asking Buildbucket for the build status
[10:05:38.251] Build 8911502183967808000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:05:38.251] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:50:0) after 1m0s
[10:06:38.289] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:50:0)
[10:06:38.289] Timer tick, asking Buildbucket for the build status
[10:06:39.851] Build 8911502183967808000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:06:39.851] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:51:0) after 1m0s
[10:07:39.927] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:51:0)
[10:07:39.927] Timer tick, asking Buildbucket for the build status
[10:07:40.215] Build 8911502183967808000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:07:40.215] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:52:0) after 1m0s
[10:08:40.327] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:52:0)
[10:08:40.327] Timer tick, asking Buildbucket for the build status
[10:08:40.749] Build 8911502183967808000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:08:40.749] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:53:0) after 1m0s
[10:09:40.804] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:53:0)
[10:09:40.804] Timer tick, asking Buildbucket for the build status
[10:09:43.587] Build 8911502183967808000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:09:43.587] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:54:0) after 1m0s
[10:10:43.957] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:54:0)
[10:10:43.957] Timer tick, asking Buildbucket for the build status
[10:10:44.583] Build 8911502183967808000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:10:44.583] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:55:0) after 1m0s
[10:11:44.607] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:55:0)
[10:11:44.607] Timer tick, asking Buildbucket for the build status
[10:11:44.881] Build 8911502183967808000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:11:44.881] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:56:0) after 1m0s
[10:12:44.899] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:56:0)
[10:12:44.899] Timer tick, asking Buildbucket for the build status
[10:12:46.251] Build 8911502183967808000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:12:46.251] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:57:0) after 1m0s
[10:13:46.254] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:57:0)
[10:13:46.254] Timer tick, asking Buildbucket for the build status
[10:13:48.310] Build 8911502183967808000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:13:48.310] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:58:0) after 1m0s
[10:14:48.393] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:58:0)
[10:14:48.393] Timer tick, asking Buildbucket for the build status
[10:14:49.352] Build 8911502183967808000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:14:49.352] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:59:0) after 1m0s
[10:15:49.364] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:59:0)
[10:15:49.364] Timer tick, asking Buildbucket for the build status
[10:15:51.581] Build 8911502183967808000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:15:51.581] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:60:0) after 1m0s
[10:16:51.803] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:60:0)
[10:16:51.803] Timer tick, asking Buildbucket for the build status
[10:16:53.962] Build 8911502183967808000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:16:53.962] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:61:0) after 1m0s
[10:17:53.986] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:61:0)
[10:17:53.986] Timer tick, asking Buildbucket for the build status
[10:17:54.260] Build 8911502183967808000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:17:54.260] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:62:0) after 1m0s
[10:18:55.007] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:62:0)
[10:18:55.007] Timer tick, asking Buildbucket for the build status
[10:18:55.348] Build 8911502183967808000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:18:55.348] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:63:0) after 1m0s
[10:19:55.374] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:63:0)
[10:19:55.374] Timer tick, asking Buildbucket for the build status
[10:19:55.517] Build 8911502183967808000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:19:55.517] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:64:0) after 1m0s
[10:20:55.501] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:64:0)
[10:20:55.501] Timer tick, asking Buildbucket for the build status
[10:20:55.626] Build 8911502183967808000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:20:55.626] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:65:0) after 1m0s
[10:21:55.722] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:65:0)
[10:21:55.722] Timer tick, asking Buildbucket for the build status
[10:21:57.114] Build 8911502183967808000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:21:57.114] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:66:0) after 1m0s
[10:22:57.196] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:66:0)
[10:22:57.196] Timer tick, asking Buildbucket for the build status
[10:22:57.330] Build 8911502183967808000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:22:57.330] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076932246652799888:67:0) after 1m0s
[10:23:41.088] Received PubSub notification, asking Buildbucket for the build status
[10:23:41.260] Build 8911502183967808000: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[10:23:41.260] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1559730220453655",
"created_by": "project:chromium",
"created_ts": "1559726268759377",
"failure_reason": "BUILD_FAILURE",
"id": "8911502183967808000",
"parameters_json": "{\"builder_name\": \"Site Isolation Android\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"5bb50560dd7bc607357d5789a36c47e27d8881b8\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"2 tests failed: ['site_per_process_components_unittests on Android device Nexus 5X', 'site_per_process_content_browsertests 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\": 34586, \"got_angle_revision\": \"4a75741655d330e257b2f20d7404cce1e51ef72e\", \"got_dawn_revision\": \"b632bc58ed373eb2020af65e0fdeaf07d46d1a9c\", \"got_nacl_revision\": \"0ddc033406886a709b901e0c312872529f9705e8\", \"got_revision\": \"5bb50560dd7bc607357d5789a36c47e27d8881b8\", \"got_revision_cp\": \"refs/heads/master@{#666203}\", \"got_swarming_client_revision\": \"9b1b0ed1f31dd42b128ae88ce50c2ab1f87ebaeb\", \"got_v8_revision\": \"82e7e7a361135770eeec51bdc7b329b414e9ba44\", \"got_v8_revision_cp\": \"refs/heads/7.7.17@{#1}\", \"got_webrtc_revision\": \"479a3c0f92b957a9ba1c242e7fe6e4d37b49ce32\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#28154}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.fyi\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"5bb50560dd7bc607357d5789a36c47e27d8881b8\", \"swarm_hashes\": {\"components_browsertests\": \"2f6dc17fe4c604d2e8a3fb1615a661bbe89db852\", \"components_unittests\": \"1fc7e09083f21688697e7e0a46f5f30f920b2a61\", \"content_browsertests\": \"fc34f658050c8a155e45c0fda64424d20bbbdef8\", \"content_unittests\": \"eb6448b602edbae7f0eebc61c5dd2b9b95f72767\", \"unit_tests\": \"1c16714ee50c0a3820a3c70b597e93b29681759a\"}, \"swarm_hashes_refs/heads/master(at){#666203}_without_patch\": {\"components_browsertests\": \"2f6dc17fe4c604d2e8a3fb1615a661bbe89db852\", \"components_unittests\": \"1fc7e09083f21688697e7e0a46f5f30f920b2a61\", \"content_browsertests\": \"fc34f658050c8a155e45c0fda64424d20bbbdef8\", \"content_unittests\": \"eb6448b602edbae7f0eebc61c5dd2b9b95f72767\", \"unit_tests\": \"1c16714ee50c0a3820a3c70b597e93b29681759a\"}}, \"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\": [\"4316-effbcfc\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-f\"]}}, \"ui\": {\"info\": \"2 tests failed: ['site_per_process_components_unittests on Android device Nexus 5X', 'site_per_process_content_browsertests on Android device Nexus 5X']\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1559726394888614",
"status": "COMPLETED",
"status_changed_ts": "1559726395550560",
"tags": [
"build_address:luci.chromium.ci/Site Isolation Android/34586",
"builder:Site Isolation Android",
"buildset:commit/git/5bb50560dd7bc607357d5789a36c47e27d8881b8",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5bb50560dd7bc607357d5789a36c47e27d8881b8",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9076932246652799888",
"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/8911502183967808000/+/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:453fbfb459ae4e10",
"user_agent:luci-scheduler"
],
"updated_ts": "1559730220576455",
"url": "https://ci.chromium.org/b/8911502183967808000",
"utcnow_ts": "1559730221245152"
}
}
[10:23:41.260] Invocation finished in 1h5m54.445565262s with status FAILED