[17:05:42.113] New invocation is queued and will start shortly
[17:05:43.298] Starting the invocation (attempt 1)
[17:05:43.330] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:05:43.330] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:05:43.330] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9076993404579518528",
"parameters_json": "{\"builder_name\":\"Site Isolation Android\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"abb1a367321240a7568efbc2e2ee5c9e87b76d89\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Site Isolation Android",
"scheduler_invocation_id:9076993404579518528",
"scheduler_job_id:chromium/Site Isolation Android",
"user_agent:luci-scheduler",
"buildset:commit/git/abb1a367321240a7568efbc2e2ee5c9e87b76d89",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/abb1a367321240a7568efbc2e2ee5c9e87b76d89",
"gitiles_ref:refs/heads/master"
]
}
[17:05:44.610] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:chromium",
"created_ts": "1559667943548940",
"id": "8911563342384278464",
"parameters_json": "{\"builder_name\": \"Site Isolation Android\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"abb1a367321240a7568efbc2e2ee5c9e87b76d89\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1559667944346212",
"tags": [
"build_address:luci.chromium.ci/Site Isolation Android/34568",
"builder:Site Isolation Android",
"buildset:commit/git/abb1a367321240a7568efbc2e2ee5c9e87b76d89",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/abb1a367321240a7568efbc2e2ee5c9e87b76d89",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9076993404579518528",
"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/8911563342384278464/+/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": "1559667944346347",
"url": "https://ci.chromium.org/b/8911563342384278464",
"utcnow_ts": "1559667944593008"
}
}
[17:05:44.610] Task URL: https://ci.chromium.org/b/8911563342384278464
[17:05:44.610] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:2:0) after 1m0s
[17:06:44.632] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:2:0)
[17:06:44.632] Timer tick, asking Buildbucket for the build status
[17:06:45.172] Build 8911563342384278464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:06:45.172] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:3:0) after 1m0s
[17:07:45.152] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:3:0)
[17:07:45.152] Timer tick, asking Buildbucket for the build status
[17:07:47.050] Build 8911563342384278464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:07:47.050] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:4:0) after 1m0s
[17:07:53.029] Received PubSub notification, asking Buildbucket for the build status
[17:07:54.211] Build 8911563342384278464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:47.126] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:4:0)
[17:08:47.126] Timer tick, asking Buildbucket for the build status
[17:08:47.658] Build 8911563342384278464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:47.658] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:6:0) after 1m0s
[17:09:47.740] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:6:0)
[17:09:47.740] Timer tick, asking Buildbucket for the build status
[17:09:48.265] Build 8911563342384278464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:09:48.265] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:7:0) after 1m0s
[17:10:48.305] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:7:0)
[17:10:48.305] Timer tick, asking Buildbucket for the build status
[17:10:50.097] Build 8911563342384278464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:50.097] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:8:0) after 1m0s
[17:11:50.150] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:8:0)
[17:11:50.151] Timer tick, asking Buildbucket for the build status
[17:11:51.242] Build 8911563342384278464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:11:51.242] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:9:0) after 1m0s
[17:12:51.625] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:9:0)
[17:12:51.625] Timer tick, asking Buildbucket for the build status
[17:12:51.943] Build 8911563342384278464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:12:51.943] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:10:0) after 1m0s
[17:13:52.087] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:10:0)
[17:13:52.087] Timer tick, asking Buildbucket for the build status
[17:13:52.318] Build 8911563342384278464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:13:52.318] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:11:0) after 1m0s
[17:14:52.370] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:11:0)
[17:14:52.370] Timer tick, asking Buildbucket for the build status
[17:14:52.931] Build 8911563342384278464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:14:52.931] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:12:0) after 1m0s
[17:15:52.984] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:12:0)
[17:15:52.984] Timer tick, asking Buildbucket for the build status
[17:15:53.199] Build 8911563342384278464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:15:53.199] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:13:0) after 1m0s
[17:16:53.225] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:13:0)
[17:16:53.225] Timer tick, asking Buildbucket for the build status
[17:16:53.405] Build 8911563342384278464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:16:53.405] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:14:0) after 1m0s
[17:17:53.584] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:14:0)
[17:17:53.584] Timer tick, asking Buildbucket for the build status
[17:17:53.707] Build 8911563342384278464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:53.707] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:15:0) after 1m0s
[17:18:53.712] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:15:0)
[17:18:53.712] Timer tick, asking Buildbucket for the build status
[17:18:53.851] Build 8911563342384278464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:18:53.851] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:16:0) after 1m0s
[17:19:53.883] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:16:0)
[17:19:53.883] Timer tick, asking Buildbucket for the build status
[17:19:56.323] Build 8911563342384278464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:19:56.323] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:17:0) after 1m0s
[17:21:13.904] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:17:0)
[17:21:13.904] Timer tick, asking Buildbucket for the build status
[17:21:14.047] Build 8911563342384278464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:21:14.047] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:18:0) after 1m0s
[17:22:14.172] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:18:0)
[17:22:14.172] Timer tick, asking Buildbucket for the build status
[17:22:15.688] Build 8911563342384278464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:15.688] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:19:0) after 1m0s
[17:23:15.956] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:19:0)
[17:23:15.956] Timer tick, asking Buildbucket for the build status
[17:23:17.399] Build 8911563342384278464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:23:17.399] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:20:0) after 1m0s
[17:24:17.753] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:20:0)
[17:24:17.753] Timer tick, asking Buildbucket for the build status
[17:24:17.898] Build 8911563342384278464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:24:17.898] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:21:0) after 1m0s
[17:25:17.939] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:21:0)
[17:25:17.939] Timer tick, asking Buildbucket for the build status
[17:25:18.191] Build 8911563342384278464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:25:18.191] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:22:0) after 1m0s
[17:26:18.322] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:22:0)
[17:26:18.322] Timer tick, asking Buildbucket for the build status
[17:26:18.627] Build 8911563342384278464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:26:18.627] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:23:0) after 1m0s
[17:27:18.900] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:23:0)
[17:27:18.900] Timer tick, asking Buildbucket for the build status
[17:27:19.749] Build 8911563342384278464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:27:19.749] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:24:0) after 1m0s
[17:28:46.698] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:24:0)
[17:28:46.698] Timer tick, asking Buildbucket for the build status
[17:28:49.699] Build 8911563342384278464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:28:49.700] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:25:0) after 1m0s
[17:29:50.634] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:25:0)
[17:29:50.634] Timer tick, asking Buildbucket for the build status
[17:29:51.746] Build 8911563342384278464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:29:51.746] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:26:0) after 1m0s
[17:30:51.856] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:26:0)
[17:30:51.856] Timer tick, asking Buildbucket for the build status
[17:30:53.243] Build 8911563342384278464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:30:53.243] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:27:0) after 1m0s
[17:31:53.380] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:27:0)
[17:31:53.380] Timer tick, asking Buildbucket for the build status
[17:31:53.569] Build 8911563342384278464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:31:53.569] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:28:0) after 1m0s
[17:32:53.682] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:28:0)
[17:32:53.682] Timer tick, asking Buildbucket for the build status
[17:32:54.198] Build 8911563342384278464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:32:54.198] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:29:0) after 1m0s
[17:33:54.204] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:29:0)
[17:33:54.204] Timer tick, asking Buildbucket for the build status
[17:33:54.759] Build 8911563342384278464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:33:54.759] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:30:0) after 1m0s
[17:34:55.575] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:30:0)
[17:34:55.575] Timer tick, asking Buildbucket for the build status
[17:34:55.729] Build 8911563342384278464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:34:55.729] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:31:0) after 1m0s
[17:35:55.875] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:31:0)
[17:35:55.875] Timer tick, asking Buildbucket for the build status
[17:35:56.419] Build 8911563342384278464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:35:56.419] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:32:0) after 1m0s
[17:36:56.653] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:32:0)
[17:36:56.654] Timer tick, asking Buildbucket for the build status
[17:36:57.285] Build 8911563342384278464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:36:57.285] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:33:0) after 1m0s
[17:37:57.335] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:33:0)
[17:37:57.335] Timer tick, asking Buildbucket for the build status
[17:37:57.567] Build 8911563342384278464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:37:57.567] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:34:0) after 1m0s
[17:38:57.617] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:34:0)
[17:38:57.617] Timer tick, asking Buildbucket for the build status
[17:38:57.890] Build 8911563342384278464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:38:57.890] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:35:0) after 1m0s
[17:39:57.982] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:35:0)
[17:39:57.982] Timer tick, asking Buildbucket for the build status
[17:39:59.683] Build 8911563342384278464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:39:59.683] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:36:0) after 1m0s
[17:40:59.722] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:36:0)
[17:40:59.722] Timer tick, asking Buildbucket for the build status
[17:41:00.029] Build 8911563342384278464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:41:00.029] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:37:0) after 1m0s
[17:42:00.161] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:37:0)
[17:42:00.161] Timer tick, asking Buildbucket for the build status
[17:42:00.494] Build 8911563342384278464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:42:00.494] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:38:0) after 1m0s
[17:43:09.650] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:38:0)
[17:43:09.650] Timer tick, asking Buildbucket for the build status
[17:43:09.847] Build 8911563342384278464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:43:09.847] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:39:0) after 1m0s
[17:44:09.879] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:39:0)
[17:44:09.879] Timer tick, asking Buildbucket for the build status
[17:44:10.394] Build 8911563342384278464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:44:10.394] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:40:0) after 1m0s
[17:45:10.419] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:40:0)
[17:45:10.419] Timer tick, asking Buildbucket for the build status
[17:45:10.674] Build 8911563342384278464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:45:10.674] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:41:0) after 1m0s
[17:46:10.784] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:41:0)
[17:46:10.784] Timer tick, asking Buildbucket for the build status
[17:46:11.089] Build 8911563342384278464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:46:11.089] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:42:0) after 1m0s
[17:47:11.202] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:42:0)
[17:47:11.202] Timer tick, asking Buildbucket for the build status
[17:47:11.813] Build 8911563342384278464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:47:11.813] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:43:0) after 1m0s
[17:48:13.607] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:43:0)
[17:48:13.607] Timer tick, asking Buildbucket for the build status
[17:48:13.698] Build 8911563342384278464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:48:13.698] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:44:0) after 1m0s
[17:49:14.093] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:44:0)
[17:49:14.093] Timer tick, asking Buildbucket for the build status
[17:49:14.508] Build 8911563342384278464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:49:14.508] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:45:0) after 1m0s
[17:50:14.662] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:45:0)
[17:50:14.662] Timer tick, asking Buildbucket for the build status
[17:50:15.188] Build 8911563342384278464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:50:15.188] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:46:0) after 1m0s
[17:51:15.223] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:46:0)
[17:51:15.223] Timer tick, asking Buildbucket for the build status
[17:51:15.920] Build 8911563342384278464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:51:15.920] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:47:0) after 1m0s
[17:52:16.176] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:47:0)
[17:52:16.176] Timer tick, asking Buildbucket for the build status
[17:52:16.498] Build 8911563342384278464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:52:16.498] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:48:0) after 1m0s
[17:53:16.766] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:48:0)
[17:53:16.766] Timer tick, asking Buildbucket for the build status
[17:53:16.898] Build 8911563342384278464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:53:16.898] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:49:0) after 1m0s
[17:54:17.181] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:49:0)
[17:54:17.181] Timer tick, asking Buildbucket for the build status
[17:54:17.342] Build 8911563342384278464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:54:17.342] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:50:0) after 1m0s
[17:55:17.667] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:50:0)
[17:55:17.667] Timer tick, asking Buildbucket for the build status
[17:55:18.257] Build 8911563342384278464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:55:18.257] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:51:0) after 1m0s
[17:56:19.141] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:51:0)
[17:56:19.141] Timer tick, asking Buildbucket for the build status
[17:56:19.408] Build 8911563342384278464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:56:19.408] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:52:0) after 1m0s
[17:57:29.339] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:52:0)
[17:57:29.339] Timer tick, asking Buildbucket for the build status
[17:57:30.397] Build 8911563342384278464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:57:30.397] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:53:0) after 1m0s
[17:58:30.526] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:53:0)
[17:58:30.526] Timer tick, asking Buildbucket for the build status
[17:58:32.573] Build 8911563342384278464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:58:32.573] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:54:0) after 1m0s
[17:59:32.625] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:54:0)
[17:59:32.625] Timer tick, asking Buildbucket for the build status
[17:59:33.574] Build 8911563342384278464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:59:33.574] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:55:0) after 1m0s
[18:00:33.608] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:55:0)
[18:00:33.608] Timer tick, asking Buildbucket for the build status
[18:00:35.915] Build 8911563342384278464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:35.915] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:56:0) after 1m0s
[18:01:35.992] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:56:0)
[18:01:35.992] Timer tick, asking Buildbucket for the build status
[18:01:36.197] Build 8911563342384278464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:01:36.197] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:57:0) after 1m0s
[18:02:36.217] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:57:0)
[18:02:36.217] Timer tick, asking Buildbucket for the build status
[18:02:38.056] Build 8911563342384278464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:02:38.056] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:58:0) after 1m0s
[18:03:52.336] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:58:0)
[18:03:52.336] Timer tick, asking Buildbucket for the build status
[18:03:53.727] Build 8911563342384278464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:03:53.727] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:59:0) after 1m0s
[18:04:53.843] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:59:0)
[18:04:53.843] Timer tick, asking Buildbucket for the build status
[18:04:54.203] Build 8911563342384278464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:04:54.203] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:60:0) after 1m0s
[18:05:54.258] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:60:0)
[18:05:54.258] Timer tick, asking Buildbucket for the build status
[18:05:54.354] Build 8911563342384278464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:05:54.354] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:61:0) after 1m0s
[18:06:54.367] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:61:0)
[18:06:54.367] Timer tick, asking Buildbucket for the build status
[18:06:54.900] Build 8911563342384278464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:06:54.900] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:62:0) after 1m0s
[18:07:55.836] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:62:0)
[18:07:55.836] Timer tick, asking Buildbucket for the build status
[18:07:56.351] Build 8911563342384278464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:07:56.352] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:63:0) after 1m0s
[18:08:56.533] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:63:0)
[18:08:56.533] Timer tick, asking Buildbucket for the build status
[18:08:57.156] Build 8911563342384278464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:08:57.156] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:64:0) after 1m0s
[18:09:57.253] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:64:0)
[18:09:57.253] Timer tick, asking Buildbucket for the build status
[18:09:57.819] Build 8911563342384278464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:09:57.819] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:65:0) after 1m0s
[18:10:58.057] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:65:0)
[18:10:58.057] Timer tick, asking Buildbucket for the build status
[18:10:58.298] Build 8911563342384278464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:10:58.298] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:66:0) after 1m0s
[18:11:58.421] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:66:0)
[18:11:58.421] Timer tick, asking Buildbucket for the build status
[18:12:01.506] Build 8911563342384278464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:12:01.506] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:67:0) after 1m0s
[18:13:01.602] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:67:0)
[18:13:01.602] Timer tick, asking Buildbucket for the build status
[18:13:02.231] Build 8911563342384278464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:13:02.231] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:68:0) after 1m0s
[18:14:02.316] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:68:0)
[18:14:02.316] Timer tick, asking Buildbucket for the build status
[18:14:04.383] Build 8911563342384278464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:14:04.383] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:69:0) after 1m0s
[18:15:04.576] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:69:0)
[18:15:04.576] Timer tick, asking Buildbucket for the build status
[18:15:04.805] Build 8911563342384278464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:04.805] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:70:0) after 1m0s
[18:16:04.891] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:70:0)
[18:16:04.891] Timer tick, asking Buildbucket for the build status
[18:16:06.359] Build 8911563342384278464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:16:06.359] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:71:0) after 1m0s
[18:17:06.433] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:71:0)
[18:17:06.433] Timer tick, asking Buildbucket for the build status
[18:17:07.237] Build 8911563342384278464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:17:07.237] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:72:0) after 1m0s
[18:18:07.359] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:72:0)
[18:18:07.359] Timer tick, asking Buildbucket for the build status
[18:18:07.791] Build 8911563342384278464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:18:07.791] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:73:0) after 1m0s
[18:19:07.892] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:73:0)
[18:19:07.892] Timer tick, asking Buildbucket for the build status
[18:19:09.950] Build 8911563342384278464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:19:09.950] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:74:0) after 1m0s
[18:20:10.287] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:74:0)
[18:20:10.287] Timer tick, asking Buildbucket for the build status
[18:20:10.378] Build 8911563342384278464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:20:10.378] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:75:0) after 1m0s
[18:21:10.547] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:75:0)
[18:21:10.547] Timer tick, asking Buildbucket for the build status
[18:21:12.178] Build 8911563342384278464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:21:12.178] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:76:0) after 1m0s
[18:22:12.235] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:76:0)
[18:22:12.235] Timer tick, asking Buildbucket for the build status
[18:22:12.365] Build 8911563342384278464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:22:12.365] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:77:0) after 1m0s
[18:23:12.456] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:77:0)
[18:23:12.456] Timer tick, asking Buildbucket for the build status
[18:23:13.728] Build 8911563342384278464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:23:13.728] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:78:0) after 1m0s
[18:24:13.884] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:78:0)
[18:24:13.884] Timer tick, asking Buildbucket for the build status
[18:24:14.357] Build 8911563342384278464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:24:14.357] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:79:0) after 1m0s
[18:27:39.440] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:79:0)
[18:27:39.440] Timer tick, asking Buildbucket for the build status
[18:27:39.527] Build 8911563342384278464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:27:39.527] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076993404579518528:80:0) after 1m0s
[18:31:56.114] Received PubSub notification, asking Buildbucket for the build status
[18:31:56.512] Build 8911563342384278464: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[18:31:56.512] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1559673114849321",
"created_by": "project:chromium",
"created_ts": "1559667943548940",
"failure_reason": "BUILD_FAILURE",
"id": "8911563342384278464",
"parameters_json": "{\"builder_name\": \"Site Isolation Android\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"abb1a367321240a7568efbc2e2ee5c9e87b76d89\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"4 tests failed: ['site_per_process_components_unittests on Android device Nexus 5X', 'site_per_process_content_browsertests on Android device Nexus 5X', 'site_per_process_content_unittests on Android device Nexus 5X', 'site_per_process_unit_tests on Android device Nexus 5X']\"}, \"properties\": {\"$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\": 34568, \"got_angle_revision\": \"4a75741655d330e257b2f20d7404cce1e51ef72e\", \"got_dawn_revision\": \"b632bc58ed373eb2020af65e0fdeaf07d46d1a9c\", \"got_nacl_revision\": \"0ddc033406886a709b901e0c312872529f9705e8\", \"got_revision\": \"abb1a367321240a7568efbc2e2ee5c9e87b76d89\", \"got_revision_cp\": \"refs/heads/master@{#665960}\", \"got_swarming_client_revision\": \"779c4f0f8488c64587b75dbb001d18c3c0c4cda9\", \"got_v8_revision\": \"9672bf6d0b325ae9a9157ca217db965bca7e76f9\", \"got_v8_revision_cp\": \"refs/heads/7.7.15@{#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\": \"abb1a367321240a7568efbc2e2ee5c9e87b76d89\", \"swarm_hashes\": {\"components_browsertests\": \"0840e653d0d3e306fbf9f73516bffddfad1adbca\", \"components_unittests\": \"7975065df9533d6e0f9a43cba4faa68ebfc5e37d\", \"content_browsertests\": \"398185e349f5e7acb55d4d0678499b878fc7474e\", \"content_unittests\": \"5d8543255f30149f417ff90a1554d83cb2a9635f\", \"unit_tests\": \"abc862008c3326a4f543770c9e81f29ea4cf790a\"}, \"swarm_hashes_refs/heads/master(at){#665960}_without_patch\": {\"components_browsertests\": \"0840e653d0d3e306fbf9f73516bffddfad1adbca\", \"components_unittests\": \"7975065df9533d6e0f9a43cba4faa68ebfc5e37d\", \"content_browsertests\": \"398185e349f5e7acb55d4d0678499b878fc7474e\", \"content_unittests\": \"5d8543255f30149f417ff90a1554d83cb2a9635f\", \"unit_tests\": \"abc862008c3326a4f543770c9e81f29ea4cf790a\"}}, \"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\": \"4 tests failed: ['site_per_process_components_unittests on Android device Nexus 5X', 'site_per_process_content_browsertests on Android device Nexus 5X', 'site_per_process_content_unittests on Android device Nexus 5X', 'site_per_process_unit_tests on Android device Nexus 5X']\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1559668071897027",
"status": "COMPLETED",
"status_changed_ts": "1559668072351873",
"tags": [
"build_address:luci.chromium.ci/Site Isolation Android/34568",
"builder:Site Isolation Android",
"buildset:commit/git/abb1a367321240a7568efbc2e2ee5c9e87b76d89",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/abb1a367321240a7568efbc2e2ee5c9e87b76d89",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9076993404579518528",
"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/8911563342384278464/+/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:453c45b7affc1e10",
"user_agent:luci-scheduler"
],
"updated_ts": "1559673115558078",
"url": "https://ci.chromium.org/b/8911563342384278464",
"utcnow_ts": "1559673116502650"
}
}
[18:31:56.513] Invocation finished in 1h26m14.44132108s with status FAILED